D [15/Aug/2007:14:11:24 +0300] cupsdReadClient: 9 GET /printers/lj1000?op=print-test-page HTTP/1.1 D [15/Aug/2007:14:11:24 +0300] cupsdReadClient: 9 Browser asked for language "en-us.utf-8"... D [15/Aug/2007:14:11:24 +0300] cupsdAuthorize: username="root" I [15/Aug/2007:14:11:24 +0300] commptr="?op=print-test-page" D [15/Aug/2007:14:11:24 +0300] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 9340 I [15/Aug/2007:14:11:24 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9340) D [15/Aug/2007:14:11:24 +0300] cupsdSendCommand: 9 file=6 D [15/Aug/2007:14:11:24 +0300] cupsdAcceptClient: 14 from localhost:631 (IPv4) D [15/Aug/2007:14:11:24 +0300] cupsdReadClient: 14 POST /printers/lj1000 HTTP/1.1 D [15/Aug/2007:14:11:24 +0300] cupsdAuthorize: No authentication data provided. D [15/Aug/2007:14:11:24 +0300] Print-Job ipp://localhost:631/printers/lj1000 D [15/Aug/2007:14:11:24 +0300] print_job: request file type is application/postscript. D [15/Aug/2007:14:11:24 +0300] add_job: requesting-user-name="root" D [15/Aug/2007:14:11:24 +0300] Adding default job-sheets values "none,none"... I [15/Aug/2007:14:11:24 +0300] Adding start banner page "none" to job 19. D [15/Aug/2007:14:11:24 +0300] Discarding unused job-created event... I [15/Aug/2007:14:11:24 +0300] Adding end banner page "none" to job 19. I [15/Aug/2007:14:11:24 +0300] Job 19 queued on "lj1000" by "root". D [15/Aug/2007:14:11:24 +0300] Job 19 hold_until = 0 D [15/Aug/2007:14:11:24 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:24 +0300] job-sheets=none,none D [15/Aug/2007:14:11:24 +0300] banner_page = 0 D [15/Aug/2007:14:11:24 +0300] [Job 19] argv[0]="lj1000" D [15/Aug/2007:14:11:24 +0300] [Job 19] argv[1]="19" D [15/Aug/2007:14:11:24 +0300] [Job 19] argv[2]="root" D [15/Aug/2007:14:11:24 +0300] [Job 19] argv[3]="Test Page" D [15/Aug/2007:14:11:24 +0300] [Job 19] argv[4]="1" D [15/Aug/2007:14:11:24 +0300] [Job 19] argv[5]="job-uuid=urn:uuid:680c4cdb-60e0-3786-4e04-c6385b464b75" D [15/Aug/2007:14:11:24 +0300] [Job 19] argv[6]="/var/spool/cups/d00019-001" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[1]="CUPS_DATADIR=/usr/share/cups" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[6]="CUPS_SERVERROOT=/etc/cups" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[7]="CUPS_STATEDIR=/var/run/cups" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[9]="SERVER_ADMIN=root@dogmatist" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[10]="SOFTWARE=CUPS/1.2.12" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[11]="TMPDIR=/var/spool/cups/tmp" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[12]="USER=root" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[13]="CUPS_SERVER=localhost" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[14]="CUPS_ENCRYPTION=IfRequested" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[15]="IPP_PORT=631" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[16]="CHARSET=utf-8" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[17]="LANG=en_US" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[18]="PPD=/etc/cups/ppd/lj1000.ppd" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[19]="RIP_MAX_CACHE=8m" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[20]="CONTENT_TYPE=application/postscript" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[21]="DEVICE_URI=lpd://192.168.0.109/hpLaserJ" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[22]="PRINTER=lj1000" D [15/Aug/2007:14:11:24 +0300] [Job 19] envp[23]="FINAL_CONTENT_TYPE=printer/lj1000" I [15/Aug/2007:14:11:24 +0300] Started filter /usr/lib/cups/filter/pstops (PID 9341) for job 19. I [15/Aug/2007:14:11:24 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 9342) for job 19. I [15/Aug/2007:14:11:24 +0300] Started backend /usr/lib/cups/backend/lpd (PID 9343) for job 19. D [15/Aug/2007:14:11:24 +0300] Discarding unused job-state event... D [15/Aug/2007:14:11:24 +0300] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [15/Aug/2007:14:11:24 +0300] PID 9341 (/usr/lib/cups/filter/pstops) exited with no errors. D [15/Aug/2007:14:11:24 +0300] [Job 19] Page = 4736x6817; 11,11 to 4725,6806 D [15/Aug/2007:14:11:24 +0300] [Job 19] slow_collate=0, slow_duplex=0, slow_order=0 D [15/Aug/2007:14:11:24 +0300] [Job 19] Before copy_comments - %!PS-Adobe-3.0 D [15/Aug/2007:14:11:24 +0300] [Job 19] %!PS-Adobe-3.0 D [15/Aug/2007:14:11:24 +0300] [Job 19] %%BoundingBox: 0 0 612 792 D [15/Aug/2007:14:11:24 +0300] [Job 19] %%Pages: 1 D [15/Aug/2007:14:11:24 +0300] [Job 19] %%LanguageLevel: 1 D [15/Aug/2007:14:11:24 +0300] [Job 19] %%DocumentData: Clean7Bit D [15/Aug/2007:14:11:24 +0300] [Job 19] %%DocumentSuppliedResources: procset testprint/1.2 D [15/Aug/2007:14:11:24 +0300] [Job 19] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman D [15/Aug/2007:14:11:24 +0300] [Job 19] %%Creator: Michael Sweet, Easy Software Products D [15/Aug/2007:14:11:24 +0300] [Job 19] %%CreationDate: D:20070220113000+0500 D [15/Aug/2007:14:11:24 +0300] [Job 19] %%Title: Test Page D [15/Aug/2007:14:11:24 +0300] [Job 19] %%EndComments D [15/Aug/2007:14:11:24 +0300] [Job 19] Before copy_prolog - %%BeginProlog D [15/Aug/2007:14:11:24 +0300] [Job 19] Before copy_setup - %%Page: 1 1 D [15/Aug/2007:14:11:24 +0300] [Job 19] Before page loop - %%Page: 1 1 D [15/Aug/2007:14:11:24 +0300] [Job 19] Copying page 1... D [15/Aug/2007:14:11:24 +0300] [Job 19] pagew = 4713.3, pagel = 6794.3 D [15/Aug/2007:14:11:24 +0300] [Job 19] bboxw = 4736, bboxl = 6817 D [15/Aug/2007:14:11:24 +0300] [Job 19] PageLeft = 11.3, PageRight = 4724.7 D [15/Aug/2007:14:11:24 +0300] [Job 19] PageTop = 6805.7, PageBottom = 11.3 D [15/Aug/2007:14:11:24 +0300] [Job 19] PageWidth = 4736.0, PageLength = 6817.0 D [15/Aug/2007:14:11:24 +0300] [Job 19] Wrote 1 pages... D [15/Aug/2007:14:11:24 +0300] PID 9340 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [15/Aug/2007:14:11:24 +0300] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl") D [15/Aug/2007:14:11:24 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:24 +0300] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"... D [15/Aug/2007:14:11:24 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:24 +0300] [CGI] "{title}" at 205... D [15/Aug/2007:14:11:24 +0300] [CGI] Starting "{refresh_page?" at 375, result=1... D [15/Aug/2007:14:11:24 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:24 +0300] [CGI] Starting at file position 375... D [15/Aug/2007:14:11:24 +0300] [CGI] "{refresh_page}" at 425... D [15/Aug/2007:14:11:24 +0300] [CGI] Returning at file position 428 on character ':'... D [15/Aug/2007:14:11:24 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:24 +0300] [CGI] Starting at file position 428... D [15/Aug/2007:14:11:24 +0300] [CGI] Returning at file position 429 on character '}'... D [15/Aug/2007:14:11:24 +0300] [CGI] Finished "{refresh_page?", out=0xb7dfa4c0... D [15/Aug/2007:14:11:24 +0300] [CGI] "{title}" at 672... D [15/Aug/2007:14:11:24 +0300] [CGI] "{title}" at 953... D [15/Aug/2007:14:11:25 +0300] cupsdCloseClient: 14 D [15/Aug/2007:14:11:25 +0300] [CGI] Starting "{SECTION=admin" at 1412, result=0... D [15/Aug/2007:14:11:25 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 1412... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 1413 on character ':'... D [15/Aug/2007:14:11:25 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 1413... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 1416 on character '}'... D [15/Aug/2007:14:11:25 +0300] [CGI] Finished "{SECTION=admin", out=0xb7dfa4c0... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting "{SECTION=classes" at 1679, result=0... D [15/Aug/2007:14:11:25 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 1679... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 1680 on character ':'... D [15/Aug/2007:14:11:25 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 1680... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 1683 on character '}'... D [15/Aug/2007:14:11:25 +0300] [CGI] Finished "{SECTION=classes", out=0xb7dfa4c0... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting "{SECTION=help" at 1939, result=0... D [15/Aug/2007:14:11:25 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 1939... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 1940 on character ':'... D [15/Aug/2007:14:11:25 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 1940... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 1943 on character '}'... D [15/Aug/2007:14:11:25 +0300] [CGI] Finished "{SECTION=help", out=0xb7dfa4c0... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting "{SECTION=jobs" at 2207, result=0... D [15/Aug/2007:14:11:25 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 2207... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 2208 on character ':'... D [15/Aug/2007:14:11:25 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 2208... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 2211 on character '}'... D [15/Aug/2007:14:11:25 +0300] [CGI] Finished "{SECTION=jobs", out=0xb7dfa4c0... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting "{SECTION=printers" at 2465, result=1... D [15/Aug/2007:14:11:25 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 2465... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 2466 on character ':'... D [15/Aug/2007:14:11:25 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 2466... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 2469 on character '}'... D [15/Aug/2007:14:11:25 +0300] [CGI] Finished "{SECTION=printers", out=0xb7dfa4c0... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 2831 on EOF... D [15/Aug/2007:14:11:25 +0300] [CGI] cgiCopyTemplateLang(tmpl="test-page.tmpl") D [15/Aug/2007:14:11:25 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:25 +0300] [CGI] Template file is "/usr/share/cups/templates/test-page.tmpl"... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:25 +0300] [CGI] "{SECTION}" at 48... D [15/Aug/2007:14:11:25 +0300] [CGI] "{printer_name}" at 63... D [15/Aug/2007:14:11:25 +0300] [CGI] "{printer_name}" at 80... D [15/Aug/2007:14:11:25 +0300] [CGI] "{job_id}" at 89... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 99 on EOF... D [15/Aug/2007:14:11:25 +0300] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl") D [15/Aug/2007:14:11:25 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:25 +0300] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"... D [15/Aug/2007:14:11:25 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:25 +0300] [CGI] Returning at file position 614 on EOF... D [15/Aug/2007:14:11:25 +0300] [Job 19] foomatic-rip version $Revision$ running... D [15/Aug/2007:14:11:25 +0300] [Job 19] Parsing PPD file ... D [15/Aug/2007:14:11:25 +0300] [Job 19] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip" D [15/Aug/2007:14:11:25 +0300] [Job 19] Added option ColorSpace D [15/Aug/2007:14:11:25 +0300] [Job 19] Added option PageSize D [15/Aug/2007:14:11:25 +0300] [Job 19] Added option PageRegion D [15/Aug/2007:14:11:25 +0300] [Job 19] Added option ImageableArea D [15/Aug/2007:14:11:25 +0300] [Job 19] Added option PaperDimension D [15/Aug/2007:14:11:25 +0300] [Job 19] Added option Font D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] Parameter Summary D [15/Aug/2007:14:11:25 +0300] [Job 19] ----------------- D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] Spooler: cups D [15/Aug/2007:14:11:25 +0300] [Job 19] Printer: lj1000 D [15/Aug/2007:14:11:25 +0300] [Job 19] Shell: /bin/sh D [15/Aug/2007:14:11:25 +0300] [Job 19] PPD file: /etc/cups/ppd/lj1000.ppd D [15/Aug/2007:14:11:25 +0300] [Job 19] ATTR file: D [15/Aug/2007:14:11:25 +0300] [Job 19] Printer model: HP LaserJet 1000 Foomatic/pbmtozjs (recommended) D [15/Aug/2007:14:11:25 +0300] [Job 19] Job title: Test Page D [15/Aug/2007:14:11:25 +0300] [Job 19] File(s) to be printed: D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts D [15/Aug/2007:14:11:25 +0300] [Job 19] Pondering option 'job-uuid=urn:uuid:680c4cdb-60e0-3786-4e04-c6385b464b75' D [15/Aug/2007:14:11:25 +0300] [Job 19] Unknown option job-uuid=urn:uuid:680c4cdb-60e0-3786-4e04-c6385b464b75. D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] ================================================ D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] File: D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] ================================================ D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] Reading PostScript input ... D [15/Aug/2007:14:11:25 +0300] [Job 19] --> This document is DSC-conforming! D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] ----------- D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%BeginProlog D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%EndProlog D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] ----------- D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%BeginSetup D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%BeginFeature: *PageSize A4 D [15/Aug/2007:14:11:25 +0300] [Job 19] Option: PageSize=A4 --> Setting option D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %% FoomaticRIPOptionSetting: PageSize=A4 D [15/Aug/2007:14:11:25 +0300] [Job 19] Option: PageSize=A4 --> Setting option D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%EndSetup D [15/Aug/2007:14:11:25 +0300] [Job 19] Inserting PostScript code for CUPS' page accounting D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] ----------- D [15/Aug/2007:14:11:25 +0300] [Job 19] New page: 1 1 D [15/Aug/2007:14:11:25 +0300] [Job 19] Inserting option code into "PageSetup" section. D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%BeginPageSetup D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%EndPageSetup D [15/Aug/2007:14:11:25 +0300] [Job 19] End of page header D [15/Aug/2007:14:11:25 +0300] [Job 19] Stopping search for page header options D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: lineto % Move there... D [15/Aug/2007:14:11:25 +0300] [Job 19] --> Output goes directly to the renderer now. D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] Starting renderer D [15/Aug/2007:14:11:25 +0300] [Job 19] JCL: D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] renderer PID kid4=9348 D [15/Aug/2007:14:11:25 +0300] [Job 19] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=pbmraw -r600 -g4736x6817 -sOutputFile=- - | pbmtozjs D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%Trailer D [15/Aug/2007:14:11:25 +0300] [Job 19] --> Continue DSC parsing now. D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%Pages: 1 D [15/Aug/2007:14:11:25 +0300] [Job 19] --> Continue DSC parsing now. D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%BoundingBox: 0 0 612 792 D [15/Aug/2007:14:11:25 +0300] [Job 19] --> Continue DSC parsing now. D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] Found: %%EOF D [15/Aug/2007:14:11:25 +0300] [Job 19] --> Continue DSC parsing now. D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] D [15/Aug/2007:14:11:25 +0300] [Job 19] Closing renderer D [15/Aug/2007:14:11:25 +0300] [Job 19] sh: pbmtozjs: command not found D [15/Aug/2007:14:11:26 +0300] [Job 19] foomatic-gswrapper: gs '-sstdout=%stderr' '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=pbmraw' '-r600' '-g4736x6817' '-sOutputFile=%stdout' '-' D [15/Aug/2007:14:11:26 +0300] Discarding unused job-progress event... E [15/Aug/2007:14:11:26 +0300] [Job 19] /ioerror in --.outputpage-- D [15/Aug/2007:14:11:26 +0300] [Job 19] Operand stack: D [15/Aug/2007:14:11:26 +0300] [Job 19] 1 true D [15/Aug/2007:14:11:26 +0300] [Job 19] Execution stack: D [15/Aug/2007:14:11:26 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:26 +0300] [Job 19] %interp_exit .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- --nostringval-- --nostringval-- false 1 %stopped_push 1 3 %oparray_pop 1 3 %oparray_pop 1 3 %oparray_pop 1 3 %oparray_pop .runexec2 --nostringval-- --nostringval-- --nostringval-- 2 %stopped_push --nostringval-- 0 3 %oparray_pop --nostringval-- --nostringval-- D [15/Aug/2007:14:11:26 +0300] [Job 19] Dictionary stack: D [15/Aug/2007:14:11:26 +0300] [Job 19] --dict:1125/1686(ro)(G)-- --dict:1/20(G)-- --dict:132/200(L)-- D [15/Aug/2007:14:11:26 +0300] [Job 19] Current allocation mode is local D [15/Aug/2007:14:11:26 +0300] [Job 19] Last OS error: 32 D [15/Aug/2007:14:11:26 +0300] [Job 19] ESP Ghostscript 815.04: Unrecoverable error, exit code 1 D [15/Aug/2007:14:11:26 +0300] [Job 19] renderer return value: 127 D [15/Aug/2007:14:11:26 +0300] [Job 19] renderer received signal: 127 D [15/Aug/2007:14:11:26 +0300] [Job 19] Process dying with "The renderer command line returned an unrecognized error code 127.", exit stat: 1 D [15/Aug/2007:14:11:26 +0300] [Job 19] error: Illegal seek (29) D [15/Aug/2007:14:11:26 +0300] [Job 19] The renderer command line returned an unrecognized error code 127. D [15/Aug/2007:14:11:26 +0300] [Job 19] tail process done writing data to STDOUT D [15/Aug/2007:14:11:26 +0300] [Job 19] KID4 finished D [15/Aug/2007:14:11:26 +0300] [Job 19] KID3 exited with status 1 D [15/Aug/2007:14:11:26 +0300] [Job 19] Renderer exit stat: 1 D [15/Aug/2007:14:11:26 +0300] [Job 19] Renderer process finished D [15/Aug/2007:14:11:26 +0300] [Job 19] Killing process 9347 (KID3) D [15/Aug/2007:14:11:26 +0300] [Job 19] Process dying with "Error closing renderer", exit stat: 1 D [15/Aug/2007:14:11:26 +0300] [Job 19] error: Illegal seek (29) D [15/Aug/2007:14:11:26 +0300] [Job 19] Error closing renderer E [15/Aug/2007:14:11:26 +0300] PID 9342 (/usr/lib/cups/filter/foomatic-rip) stopped with status 1! D [15/Aug/2007:14:11:26 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:26 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:26 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:26 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:26 +0300] [Job 19] Connected to 192.168.0.109:515 (IPv4) (local port 1023)... D [15/Aug/2007:14:11:26 +0300] [Job 19] lpd_command 02 hpLaserJ D [15/Aug/2007:14:11:26 +0300] [Job 19] Sending command string (10 bytes)... D [15/Aug/2007:14:11:26 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:11:26 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:11:26 +0300] [Job 19] Control file is: D [15/Aug/2007:14:11:26 +0300] [Job 19] Hdogmatist D [15/Aug/2007:14:11:26 +0300] [Job 19] Proot D [15/Aug/2007:14:11:26 +0300] [Job 19] JTest Page D [15/Aug/2007:14:11:26 +0300] [Job 19] ldfA343dogmatist D [15/Aug/2007:14:11:26 +0300] [Job 19] UdfA343dogmatist D [15/Aug/2007:14:11:26 +0300] [Job 19] NTest Page D [15/Aug/2007:14:11:26 +0300] [Job 19] lpd_command 02 73 cfA343dogmatist D [15/Aug/2007:14:11:26 +0300] [Job 19] Sending command string (20 bytes)... D [15/Aug/2007:14:11:26 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:11:26 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:11:26 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:26 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:26 +0300] [Job 19] lpd_command 03 0 dfA343dogmatist D [15/Aug/2007:14:11:26 +0300] [Job 19] Sending command string (19 bytes)... D [15/Aug/2007:14:11:26 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:11:26 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:11:26 +0300] Discarding unused printer-state-changed event... E [15/Aug/2007:14:11:26 +0300] [Job 19] Remote host did not accept data file (1) D [15/Aug/2007:14:11:26 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:27 +0300] cupsdReadClient: 19 GET /printers/lj1000 HTTP/1.1 D [15/Aug/2007:14:11:27 +0300] cupsdReadClient: 19 Browser asked for language "en-us.utf-8"... D [15/Aug/2007:14:11:27 +0300] cupsdAuthorize: username="root" I [15/Aug/2007:14:11:27 +0300] commptr="" D [15/Aug/2007:14:11:27 +0300] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 9354 I [15/Aug/2007:14:11:27 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9354) D [15/Aug/2007:14:11:27 +0300] cupsdSendCommand: 19 file=6 D [15/Aug/2007:14:11:27 +0300] cupsdAcceptClient: 14 from localhost:631 (IPv4) D [15/Aug/2007:14:11:27 +0300] cupsdReadClient: 14 POST / HTTP/1.1 D [15/Aug/2007:14:11:27 +0300] cupsdAuthorize: No authentication data provided. D [15/Aug/2007:14:11:27 +0300] CUPS-Get-Default D [15/Aug/2007:14:11:27 +0300] CUPS-Get-Default client-error-not-found: No default printer D [15/Aug/2007:14:11:27 +0300] cupsdProcessIPPRequest: 14 status_code=406 (client-error-not-found) D [15/Aug/2007:14:11:27 +0300] cupsdReadClient: 13 GET /printers/ HTTP/1.1 D [15/Aug/2007:14:11:27 +0300] cupsdReadClient: 13 Browser asked for language "en-us.utf-8"... D [15/Aug/2007:14:11:27 +0300] cupsdAuthorize: username="root" I [15/Aug/2007:14:11:27 +0300] commptr="" D [15/Aug/2007:14:11:27 +0300] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 9355 I [15/Aug/2007:14:11:27 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9355) D [15/Aug/2007:14:11:27 +0300] cupsdSendCommand: 13 file=15 D [15/Aug/2007:14:11:27 +0300] cupsdAcceptClient: 16 from localhost:631 (IPv4) D [15/Aug/2007:14:11:27 +0300] cupsdReadClient: 16 POST / HTTP/1.1 D [15/Aug/2007:14:11:27 +0300] cupsdAuthorize: No authentication data provided. D [15/Aug/2007:14:11:27 +0300] CUPS-Get-Default D [15/Aug/2007:14:11:27 +0300] CUPS-Get-Default client-error-not-found: No default printer D [15/Aug/2007:14:11:27 +0300] cupsdProcessIPPRequest: 16 status_code=406 (client-error-not-found) D [15/Aug/2007:14:11:27 +0300] [CGI] show_printer(http=0x8071270, printer="lj1000") D [15/Aug/2007:14:11:27 +0300] cupsdReadClient: 14 POST / HTTP/1.1 D [15/Aug/2007:14:11:27 +0300] cupsdAuthorize: No authentication data provided. D [15/Aug/2007:14:11:27 +0300] Get-Printer-Attributes ipp://localhost/printers/lj1000 D [15/Aug/2007:14:11:27 +0300] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [15/Aug/2007:14:11:27 +0300] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl") D [15/Aug/2007:14:11:27 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:27 +0300] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:27 +0300] [CGI] "{title}" at 205... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{refresh_page?" at 375, result=1... D [15/Aug/2007:14:11:27 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:27 +0300] cupsdCloseClient: 19 D [15/Aug/2007:14:11:27 +0300] cupsdReadClient: 14 POST / HTTP/1.1 D [15/Aug/2007:14:11:27 +0300] cupsdAuthorize: No authentication data provided. D [15/Aug/2007:14:11:27 +0300] Get-Jobs ipp://localhost:631/printers/lj1000 D [15/Aug/2007:14:11:27 +0300] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) E [15/Aug/2007:14:11:27 +0300] PID 9354 (/usr/lib/cups/cgi-bin/printers.cgi) crashed on signal 9! D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 375... D [15/Aug/2007:14:11:27 +0300] [CGI] "{refresh_page}" at 425... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 428 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 428... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 429 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{refresh_page?", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] "{title}" at 672... D [15/Aug/2007:14:11:27 +0300] [CGI] "{title}" at 953... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{SECTION=admin" at 1412, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1412... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1413 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1413... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1416 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{SECTION=admin", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{SECTION=classes" at 1679, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1679... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1680 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1680... D [15/Aug/2007:14:11:27 +0300] cupsdCloseClient: 14 D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1683 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{SECTION=classes", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{SECTION=help" at 1939, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1939... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1940 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1940... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1943 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{SECTION=help", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{SECTION=jobs" at 2207, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 2207... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 2208 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 2208... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 2211 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{SECTION=jobs", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{SECTION=printers" at 2465, result=1... D [15/Aug/2007:14:11:27 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 2465... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 2466 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 2466... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 2469 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{SECTION=printers", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 2831 on EOF... D [15/Aug/2007:14:11:27 +0300] [CGI] cgiCopyTemplateLang(tmpl="printers.tmpl") D [15/Aug/2007:14:11:27 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:27 +0300] [CGI] Template file is "/usr/share/cups/templates/printers.tmpl"... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{printer_type?" at 14, result=1... D [15/Aug/2007:14:11:27 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 14... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 15 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 15... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 16 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{printer_type?", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{#printer_name=0" at 33, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 33... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 34 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 34... D [15/Aug/2007:14:11:27 +0300] [CGI] Looping on "printer_name" at 50, count=1... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 50... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_uri_supported}" at 101... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 117... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{default_name=lj1000" at 150, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 150... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 169 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 169... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 170 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{default_name=lj1000", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{?printer_state_message=" at 196, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 196... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 197 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 197... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_state_message}" at 243... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 252 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{?printer_state_message=", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 349... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_uri_supported}" at 406... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{printer_state=3" at 452, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 452... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 457 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 457... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{printer_state=4" at 474, result=1... D [15/Aug/2007:14:11:27 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 474... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 485 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 485... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 493 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{printer_state=4", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 494 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{printer_state=3", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_info}" at 584... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_location}" at 624... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_make_and_model}" at 676... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{printer_state=3" at 720, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 720... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 725 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 725... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{printer_state=4" at 742, result=1... D [15/Aug/2007:14:11:27 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 742... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 753 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 753... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 761 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{printer_state=4", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 762 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{printer_state=3", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{printer_is_accepting_jobs=0" at 793, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 793... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 808 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 808... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 823 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{printer_is_shared=0" at 846, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 846... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 850 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 850... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 851 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{printer_is_shared=0", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{?device_uri=" at 877, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 877... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 878 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 878... D [15/Aug/2007:14:11:27 +0300] [CGI] "{device_uri}" at 913... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 914 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{?device_uri=", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_uri_supported}" at 952... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{?cupscommand=1" at 1078, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1078... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_uri_supported}" at 1110... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_uri_supported}" at 1258... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1383 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1383... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1384 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{?cupscommand=1", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{printer_state=5" at 1402, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1402... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 1423... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 1473... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1561 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1561... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 1582... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 1631... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1717 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{printer_state=5", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{printer_is_accepting_jobs=0" at 1747, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1747... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 1768... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 1816... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 1900 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 1900... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 1921... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 1969... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 2053 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_uri_supported}" at 2086... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 2132... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 2235... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 2282... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting "{printer_is_shared=0" at 2394, result=0... D [15/Aug/2007:14:11:27 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 2394... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 2415... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 2463... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 2568 on character ':'... D [15/Aug/2007:14:11:27 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 2568... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 2589... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 2637... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 2746 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{printer_is_shared=0", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 2767... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 2818... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 2927... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 2983... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 3102... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 3153... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 3262... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 3313... D [15/Aug/2007:14:11:27 +0300] [CGI] "{admin_uri}" at 3422... D [15/Aug/2007:14:11:27 +0300] [CGI] "{printer_name}" at 3476... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 3598 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished looping on "printer_name"... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 3599 on character '}'... D [15/Aug/2007:14:11:27 +0300] [CGI] Finished "{#printer_name=0", out=0xb7dd84c0... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 3600 on EOF... D [15/Aug/2007:14:11:27 +0300] [CGI] cgiCopyTemplateLang(tmpl="printer-jobs-header.tmpl") D [15/Aug/2007:14:11:27 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:27 +0300] [CGI] Template file is "/usr/share/cups/templates/printer-jobs-header.tmpl"... D [15/Aug/2007:14:11:27 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:27 +0300] [CGI] Returning at file position 28 on EOF... D [15/Aug/2007:14:11:28 +0300] [CGI] show_all_printers(http=0x8071270, user="root") D [15/Aug/2007:14:11:28 +0300] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl") D [15/Aug/2007:14:11:28 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:28 +0300] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"... D [15/Aug/2007:14:11:28 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:28 +0300] [CGI] "{title}" at 205... D [15/Aug/2007:14:11:28 +0300] [CGI] Starting "{refresh_page?" at 375, result=0... D [15/Aug/2007:14:11:28 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:28 +0300] [CGI] Starting at file position 375... D [15/Aug/2007:14:11:28 +0300] [CGI] "{refresh_page}" at 425... D [15/Aug/2007:14:11:28 +0300] [CGI] Returning at file position 428 on character ':'... D [15/Aug/2007:14:11:28 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:28 +0300] [CGI] Starting at file position 428... D [15/Aug/2007:14:11:28 +0300] [CGI] Returning at file position 429 on character '}'... D [15/Aug/2007:14:11:28 +0300] [CGI] Finished "{refresh_page?", out=0xb7e054c0... D [15/Aug/2007:14:11:28 +0300] [CGI] "{title}" at 672... D [15/Aug/2007:14:11:28 +0300] [CGI] "{title}" at 953... D [15/Aug/2007:14:11:28 +0300] [CGI] Starting "{SECTION=admin" at 1412, result=0... D [15/Aug/2007:14:11:28 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:28 +0300] [CGI] Starting at file position 1412... D [15/Aug/2007:14:11:28 +0300] [CGI] Returning at file position 1413 on character ':'... D [15/Aug/2007:14:11:28 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:28 +0300] [CGI] Starting at file position 1413... D [15/Aug/2007:14:11:28 +0300] cupsdReadClient: 16 POST / HTTP/1.1 D [15/Aug/2007:14:11:29 +0300] cupsdAuthorize: No authentication data provided. D [15/Aug/2007:14:11:29 +0300] CUPS-Get-Printers D [15/Aug/2007:14:11:29 +0300] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok) D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 1416 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{SECTION=admin", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{SECTION=classes" at 1679, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 1679... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 1680 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 1680... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 1683 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{SECTION=classes", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{SECTION=help" at 1939, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 1939... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 1940 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 1940... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 1943 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{SECTION=help", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{SECTION=jobs" at 2207, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 2207... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 2208 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 2208... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 2211 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{SECTION=jobs", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{SECTION=printers" at 2465, result=1... D [15/Aug/2007:14:11:29 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 2465... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 2466 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 2466... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 2469 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{SECTION=printers", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 2831 on EOF... D [15/Aug/2007:14:11:29 +0300] PID 9355 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors. D [15/Aug/2007:14:11:29 +0300] [CGI] cgiCopyTemplateLang(tmpl="search.tmpl") D [15/Aug/2007:14:11:29 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:29 +0300] [CGI] Template file is "/usr/share/cups/templates/search.tmpl"... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:29 +0300] [CGI] "{SECTION}" at 24... D [15/Aug/2007:14:11:29 +0300] [CGI] "{?SEARCH_DEST}" at 39... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{WHICH_JOBS?" at 67, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 67... D [15/Aug/2007:14:11:29 +0300] [CGI] "{WHICH_JOBS}" at 125... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 128 on character ':'... D [15/Aug/2007:14:11:29 +0300] cupsdCloseClient: 16 D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 128... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 129 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{WHICH_JOBS?", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{ORDER?" at 137, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 137... D [15/Aug/2007:14:11:29 +0300] [CGI] "{ORDER}" at 185... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 188 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 188... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 189 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{ORDER?", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{SEARCH_DEST?" at 235, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 235... D [15/Aug/2007:14:11:29 +0300] [CGI] "{SEARCH_DEST}" at 248... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 249 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 249... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{SECTION=classes" at 266, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 266... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 274 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 274... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{SECTION=jobs" at 288, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 288... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 293 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 293... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 302 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{SECTION=jobs", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 303 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{SECTION=classes", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 304 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{SEARCH_DEST?", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] "{?QUERY}" at 357... D [15/Aug/2007:14:11:29 +0300] [CGI] "{SECTION}" at 455... D [15/Aug/2007:14:11:29 +0300] [CGI] "{?SEARCH_DEST}" at 470... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{WHICH_JOBS?" at 482, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 482... D [15/Aug/2007:14:11:29 +0300] [CGI] "{WHICH_JOBS}" at 506... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{ORDER?" at 513, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 513... D [15/Aug/2007:14:11:29 +0300] [CGI] "{ORDER}" at 531... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 532 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 532... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 533 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{ORDER?", out=(nil)... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 534 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 534... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{ORDER?" at 541, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 541... D [15/Aug/2007:14:11:29 +0300] [CGI] "{ORDER}" at 555... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 556 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 556... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 557 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{ORDER?", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 558 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{WHICH_JOBS?", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 641 on EOF... D [15/Aug/2007:14:11:29 +0300] [CGI] cgiCopyTemplateLang(tmpl="printers-header.tmpl") D [15/Aug/2007:14:11:29 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:29 +0300] [CGI] Template file is "/usr/share/cups/templates/printers-header.tmpl"... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{total=0" at 27, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 27... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 39 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 39... D [15/Aug/2007:14:11:29 +0300] [CGI] "{#printer_name}" at 62... D [15/Aug/2007:14:11:29 +0300] [CGI] "{total}" at 73... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{total=1" at 90, result=1... D [15/Aug/2007:14:11:29 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 90... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 91 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 91... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 93 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{total=1", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 94 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{total=0", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 100 on EOF... D [15/Aug/2007:14:11:29 +0300] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl") D [15/Aug/2007:14:11:29 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:29 +0300] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{PREVURL?" at 87, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 87... D [15/Aug/2007:14:11:29 +0300] [CGI] "{PREVURL}" at 105... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 191 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 191... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 198 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{PREVURL?", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{ORDER=dec" at 247, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 247... D [15/Aug/2007:14:11:29 +0300] [CGI] "{THISURL}" at 265... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 367 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 367... D [15/Aug/2007:14:11:29 +0300] [CGI] "{THISURL}" at 385... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 489 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{ORDER=dec", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{NEXTURL?" at 535, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 535... D [15/Aug/2007:14:11:29 +0300] [CGI] "{NEXTURL}" at 553... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 631 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 631... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 638 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{NEXTURL?", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 659 on EOF... D [15/Aug/2007:14:11:29 +0300] [CGI] cgiCopyTemplateLang(tmpl="printers.tmpl") D [15/Aug/2007:14:11:29 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:29 +0300] [CGI] Template file is "/usr/share/cups/templates/printers.tmpl"... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{printer_type?" at 14, result=1... D [15/Aug/2007:14:11:29 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 14... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 15 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 15... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 16 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{printer_type?", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{#printer_name=0" at 33, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 33... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 34 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 34... D [15/Aug/2007:14:11:29 +0300] [CGI] Looping on "printer_name" at 50, count=1... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 50... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_uri_supported}" at 101... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 117... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{default_name=lj1000" at 150, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 150... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 169 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 169... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 170 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{default_name=lj1000", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{?printer_state_message=" at 196, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 196... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 197 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 197... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_state_message}" at 243... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 252 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{?printer_state_message=", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 349... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_uri_supported}" at 406... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{printer_state=3" at 452, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 452... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 457 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 457... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{printer_state=4" at 474, result=1... D [15/Aug/2007:14:11:29 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 474... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 485 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 485... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 493 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{printer_state=4", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 494 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{printer_state=3", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_info}" at 584... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_location}" at 624... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_make_and_model}" at 676... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{printer_state=3" at 720, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 720... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 725 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 725... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{printer_state=4" at 742, result=1... D [15/Aug/2007:14:11:29 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 742... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 753 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 753... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 761 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{printer_state=4", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 762 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{printer_state=3", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{printer_is_accepting_jobs=0" at 793, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 793... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 808 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 808... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 823 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{printer_is_shared=0" at 846, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 846... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 850 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 850... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 851 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{printer_is_shared=0", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{?device_uri=" at 877, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 877... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 878 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 878... D [15/Aug/2007:14:11:29 +0300] [CGI] "{device_uri}" at 913... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 914 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{?device_uri=", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_uri_supported}" at 952... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{?cupscommand=1" at 1078, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 1078... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_uri_supported}" at 1110... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_uri_supported}" at 1258... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 1383 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 1383... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 1384 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{?cupscommand=1", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{printer_state=5" at 1402, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 1402... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 1423... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 1473... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 1561 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 1561... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 1582... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 1631... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 1717 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{printer_state=5", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{printer_is_accepting_jobs=0" at 1747, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 1747... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 1768... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 1816... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 1900 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 1900... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 1921... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 1969... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 2053 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_uri_supported}" at 2086... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 2132... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 2235... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 2282... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{printer_is_shared=0" at 2394, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 2394... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 2415... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 2463... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 2568 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 2568... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 2589... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 2637... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 2746 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{printer_is_shared=0", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 2767... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 2818... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 2927... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 2983... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 3102... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 3153... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 3262... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 3313... D [15/Aug/2007:14:11:29 +0300] [CGI] "{admin_uri}" at 3422... D [15/Aug/2007:14:11:29 +0300] [CGI] "{printer_name}" at 3476... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 3598 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished looping on "printer_name"... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 3599 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{#printer_name=0", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 3600 on EOF... D [15/Aug/2007:14:11:29 +0300] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl") D [15/Aug/2007:14:11:29 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:29 +0300] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{PREVURL?" at 87, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 87... D [15/Aug/2007:14:11:29 +0300] [CGI] "{PREVURL}" at 105... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 191 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 191... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 198 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{PREVURL?", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{ORDER=dec" at 247, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 247... D [15/Aug/2007:14:11:29 +0300] [CGI] "{THISURL}" at 265... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 367 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 367... D [15/Aug/2007:14:11:29 +0300] [CGI] "{THISURL}" at 385... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 489 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{ORDER=dec", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting "{NEXTURL?" at 535, result=0... D [15/Aug/2007:14:11:29 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 535... D [15/Aug/2007:14:11:29 +0300] [CGI] "{NEXTURL}" at 553... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 631 on character ':'... D [15/Aug/2007:14:11:29 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 631... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 638 on character '}'... D [15/Aug/2007:14:11:29 +0300] [CGI] Finished "{NEXTURL?", out=0xb7e054c0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 659 on EOF... D [15/Aug/2007:14:11:29 +0300] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl") D [15/Aug/2007:14:11:29 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:29 +0300] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"... D [15/Aug/2007:14:11:29 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:29 +0300] [CGI] Returning at file position 614 on EOF... D [15/Aug/2007:14:11:34 +0300] cupsdReadClient: 12 GET /jobs/ HTTP/1.1 D [15/Aug/2007:14:11:34 +0300] cupsdReadClient: 12 Browser asked for language "en-us.utf-8"... D [15/Aug/2007:14:11:34 +0300] cupsdAuthorize: username="root" I [15/Aug/2007:14:11:34 +0300] commptr="" D [15/Aug/2007:14:11:34 +0300] CGI /usr/lib/cups/cgi-bin/jobs.cgi started - PID = 9357 I [15/Aug/2007:14:11:34 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=9357) D [15/Aug/2007:14:11:34 +0300] cupsdSendCommand: 12 file=6 D [15/Aug/2007:14:11:34 +0300] cupsdCloseClient: 17 D [15/Aug/2007:14:11:34 +0300] cupsdCloseClient: 18 D [15/Aug/2007:14:11:34 +0300] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl") D [15/Aug/2007:14:11:34 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:34 +0300] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:34 +0300] [CGI] "{title}" at 205... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting "{refresh_page?" at 375, result=0... D [15/Aug/2007:14:11:34 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 375... D [15/Aug/2007:14:11:34 +0300] [CGI] "{refresh_page}" at 425... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 428 on character ':'... D [15/Aug/2007:14:11:34 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 428... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 429 on character '}'... D [15/Aug/2007:14:11:34 +0300] [CGI] Finished "{refresh_page?", out=0xb7e4e4c0... D [15/Aug/2007:14:11:34 +0300] [CGI] "{title}" at 672... D [15/Aug/2007:14:11:34 +0300] [CGI] "{title}" at 953... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting "{SECTION=admin" at 1412, result=0... D [15/Aug/2007:14:11:34 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 1412... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 1413 on character ':'... D [15/Aug/2007:14:11:34 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 1413... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 1416 on character '}'... D [15/Aug/2007:14:11:34 +0300] cupsdAcceptClient: 14 from localhost:631 (IPv4) D [15/Aug/2007:14:11:34 +0300] [CGI] Finished "{SECTION=admin", out=0xb7e4e4c0... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting "{SECTION=classes" at 1679, result=0... D [15/Aug/2007:14:11:34 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 1679... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 1680 on character ':'... D [15/Aug/2007:14:11:34 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 1680... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 1683 on character '}'... D [15/Aug/2007:14:11:34 +0300] [CGI] Finished "{SECTION=classes", out=0xb7e4e4c0... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting "{SECTION=help" at 1939, result=0... D [15/Aug/2007:14:11:34 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 1939... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 1940 on character ':'... D [15/Aug/2007:14:11:34 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 1940... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 1943 on character '}'... D [15/Aug/2007:14:11:34 +0300] [CGI] Finished "{SECTION=help", out=0xb7e4e4c0... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting "{SECTION=jobs" at 2207, result=1... D [15/Aug/2007:14:11:34 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 2207... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 2208 on character ':'... D [15/Aug/2007:14:11:34 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:34 +0300] cupsdReadClient: 14 POST / HTTP/1.1 D [15/Aug/2007:14:11:34 +0300] cupsdAuthorize: No authentication data provided. D [15/Aug/2007:14:11:34 +0300] Get-Jobs ipp://localhost/jobs D [15/Aug/2007:14:11:34 +0300] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 2208... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 2211 on character '}'... D [15/Aug/2007:14:11:34 +0300] [CGI] Finished "{SECTION=jobs", out=0xb7e4e4c0... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting "{SECTION=printers" at 2465, result=0... D [15/Aug/2007:14:11:34 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 2465... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 2466 on character ':'... D [15/Aug/2007:14:11:34 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 2466... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 2469 on character '}'... D [15/Aug/2007:14:11:34 +0300] [CGI] Finished "{SECTION=printers", out=0xb7e4e4c0... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 2831 on EOF... D [15/Aug/2007:14:11:34 +0300] PID 9357 (/usr/lib/cups/cgi-bin/jobs.cgi) exited with no errors. D [15/Aug/2007:14:11:34 +0300] [CGI] cgiCopyTemplateLang(tmpl="search.tmpl") D [15/Aug/2007:14:11:34 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:34 +0300] [CGI] Template file is "/usr/share/cups/templates/search.tmpl"... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:34 +0300] [CGI] "{SECTION}" at 24... D [15/Aug/2007:14:11:34 +0300] [CGI] "{?SEARCH_DEST}" at 39... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting "{WHICH_JOBS?" at 67, result=0... D [15/Aug/2007:14:11:34 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:34 +0300] [CGI] Starting at file position 67... D [15/Aug/2007:14:11:34 +0300] [CGI] "{WHICH_JOBS}" at 125... D [15/Aug/2007:14:11:34 +0300] [CGI] Returning at file position 128 on character ':'... D [15/Aug/2007:14:11:34 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] cupsdCloseClient: 14 D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 128... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 129 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{WHICH_JOBS?", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{ORDER?" at 137, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 137... D [15/Aug/2007:14:11:35 +0300] [CGI] "{ORDER}" at 185... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 188 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 188... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 189 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{ORDER?", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{SEARCH_DEST?" at 235, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 235... D [15/Aug/2007:14:11:35 +0300] [CGI] "{SEARCH_DEST}" at 248... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 249 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 249... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{SECTION=classes" at 266, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 266... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 274 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 274... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{SECTION=jobs" at 288, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 288... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 293 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 293... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 302 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{SECTION=jobs", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 303 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{SECTION=classes", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 304 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{SEARCH_DEST?", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] "{?QUERY}" at 357... D [15/Aug/2007:14:11:35 +0300] [CGI] "{SECTION}" at 455... D [15/Aug/2007:14:11:35 +0300] [CGI] "{?SEARCH_DEST}" at 470... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{WHICH_JOBS?" at 482, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 482... D [15/Aug/2007:14:11:35 +0300] [CGI] "{WHICH_JOBS}" at 506... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{ORDER?" at 513, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 513... D [15/Aug/2007:14:11:35 +0300] [CGI] "{ORDER}" at 531... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 532 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 532... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 533 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{ORDER?", out=(nil)... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 534 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 534... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{ORDER?" at 541, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 541... D [15/Aug/2007:14:11:35 +0300] [CGI] "{ORDER}" at 555... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 556 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 556... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 557 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{ORDER?", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 558 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{WHICH_JOBS?", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 641 on EOF... D [15/Aug/2007:14:11:35 +0300] [CGI] cgiCopyTemplateLang(tmpl="jobs-header.tmpl") D [15/Aug/2007:14:11:35 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:35 +0300] [CGI] Template file is "/usr/share/cups/templates/jobs-header.tmpl"... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{?which_jobs=" at 17, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 17... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{?printer_name=" at 42, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 42... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 48 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 48... D [15/Aug/2007:14:11:35 +0300] [CGI] "{printer_uri_supported}" at 71... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 72 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{?printer_name=", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{?printer_name=" at 211, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 211... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 217 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 217... D [15/Aug/2007:14:11:35 +0300] [CGI] "{printer_uri_supported}" at 240... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 241 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{?printer_name=", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 333 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 333... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{which_jobs=all" at 349, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 349... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{?printer_name=" at 374, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 374... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 380 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 380... D [15/Aug/2007:14:11:35 +0300] [CGI] "{printer_uri_supported}" at 403... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 404 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{?printer_name=", out=(nil)... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{?printer_name=" at 543, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 543... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 549 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 549... D [15/Aug/2007:14:11:35 +0300] [CGI] "{printer_uri_supported}" at 572... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 573 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{?printer_name=", out=(nil)... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 656 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 656... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{?printer_name=" at 681, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 681... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 687 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 687... D [15/Aug/2007:14:11:35 +0300] [CGI] "{printer_uri_supported}" at 710... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 711 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{?printer_name=", out=(nil)... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{?printer_name=" at 823, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 823... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 829 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 829... D [15/Aug/2007:14:11:35 +0300] [CGI] "{printer_uri_supported}" at 852... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 853 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{?printer_name=", out=(nil)... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 945 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{which_jobs=all", out=(nil)... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 946 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{?which_jobs=", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{total=0" at 983, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 983... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 991 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 991... D [15/Aug/2007:14:11:35 +0300] [CGI] "{#job_id}" at 1008... D [15/Aug/2007:14:11:35 +0300] [CGI] "{total}" at 1019... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{?which_jobs=" at 1034, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1034... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1041 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1041... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{which_jobs=all" at 1057, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1057... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1058 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1058... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1068 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{which_jobs=all", out=(nil)... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1069 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{?which_jobs=", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{total=1" at 1082, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1082... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1083 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1083... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1085 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{total=1", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1086 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{total=0", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1092 on EOF... D [15/Aug/2007:14:11:35 +0300] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl") D [15/Aug/2007:14:11:35 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:35 +0300] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{PREVURL?" at 87, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 87... D [15/Aug/2007:14:11:35 +0300] [CGI] "{PREVURL}" at 105... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 191 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 191... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 198 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{PREVURL?", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{ORDER=dec" at 247, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 247... D [15/Aug/2007:14:11:35 +0300] [CGI] "{THISURL}" at 265... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 367 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 367... D [15/Aug/2007:14:11:35 +0300] [CGI] "{THISURL}" at 385... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 489 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{ORDER=dec", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{NEXTURL?" at 535, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 535... D [15/Aug/2007:14:11:35 +0300] [CGI] "{NEXTURL}" at 553... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 631 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 631... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 638 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{NEXTURL?", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 659 on EOF... D [15/Aug/2007:14:11:35 +0300] [CGI] cgiCopyTemplateLang(tmpl="jobs.tmpl") D [15/Aug/2007:14:11:35 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:35 +0300] [CGI] Template file is "/usr/share/cups/templates/jobs.tmpl"... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{#job_id=0" at 11, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 11... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 12 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 12... D [15/Aug/2007:14:11:35 +0300] [CGI] Looping on "job_id" at 277, count=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 277... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_printer_uri}" at 339... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_printer_name}" at 359... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_id}" at 372... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{?job_name=" at 400, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 400... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 408 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 408... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_name}" at 418... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 419 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{?job_name=", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_originating_user_name}" at 462... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_k_octets}" at 492... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 539... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 547 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 547... D [15/Aug/2007:14:11:35 +0300] [CGI] "{?job_media_sheets_completed}" at 576... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 577 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_state=3" at 606, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 606... D [15/Aug/2007:14:11:35 +0300] [CGI] "{time_at_creation}" at 641... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 642 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 642... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_state=4" at 655, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 655... D [15/Aug/2007:14:11:35 +0300] [CGI] "{time_at_creation}" at 687... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 688 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 688... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_state=5" at 702, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 702... D [15/Aug/2007:14:11:35 +0300] [CGI] "{time_at_processing}" at 742... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 743 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 743... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_state=6" at 756, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 756... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 764 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 764... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_state=7" at 778, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 778... D [15/Aug/2007:14:11:35 +0300] [CGI] "{time_at_completed}" at 812... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 813 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 813... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_state=8" at 826, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 826... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 834 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 834... D [15/Aug/2007:14:11:35 +0300] [CGI] "{time_at_completed}" at 869... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 870 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_state=8", out=(nil)... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 871 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_state=7", out=(nil)... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 872 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_state=6", out=(nil)... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 873 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_state=5", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 874 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_state=4", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 875 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_state=3", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_preserved>0" at 909, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 909... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_id}" at 960... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_printer_uri}" at 998... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1081 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1081... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1082 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_preserved>0", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_state=4" at 1096, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1096... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_id}" at 1147... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_printer_uri}" at 1185... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1268 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1268... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1269 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_state=4", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_state=3" at 1283, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1283... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_id}" at 1331... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_printer_uri}" at 1369... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1446 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1446... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1447 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_state=3", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{job_state<7" at 1461, result=1... D [15/Aug/2007:14:11:35 +0300] [CGI] Output first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1461... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_id}" at 1511... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_printer_uri}" at 1549... D [15/Aug/2007:14:11:35 +0300] [CGI] "{job_id}" at 1677... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1753 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 1753... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1754 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{job_state<7", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1774 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished looping on "job_id"... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1785 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{#job_id=0", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 1786 on EOF... D [15/Aug/2007:14:11:35 +0300] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl") D [15/Aug/2007:14:11:35 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:35 +0300] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{PREVURL?" at 87, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 87... D [15/Aug/2007:14:11:35 +0300] [CGI] "{PREVURL}" at 105... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 191 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 191... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 198 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{PREVURL?", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{ORDER=dec" at 247, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 247... D [15/Aug/2007:14:11:35 +0300] [CGI] "{THISURL}" at 265... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 367 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 367... D [15/Aug/2007:14:11:35 +0300] [CGI] "{THISURL}" at 385... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 489 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{ORDER=dec", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting "{NEXTURL?" at 535, result=0... D [15/Aug/2007:14:11:35 +0300] [CGI] Skip first part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 535... D [15/Aug/2007:14:11:35 +0300] [CGI] "{NEXTURL}" at 553... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 631 on character ':'... D [15/Aug/2007:14:11:35 +0300] [CGI] Output second part... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 631... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 638 on character '}'... D [15/Aug/2007:14:11:35 +0300] [CGI] Finished "{NEXTURL?", out=0xb7e4e4c0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 659 on EOF... D [15/Aug/2007:14:11:35 +0300] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl") D [15/Aug/2007:14:11:35 +0300] [CGI] locale="en_us"... D [15/Aug/2007:14:11:35 +0300] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"... D [15/Aug/2007:14:11:35 +0300] [CGI] Starting at file position 0... D [15/Aug/2007:14:11:35 +0300] [CGI] Returning at file position 614 on EOF... D [15/Aug/2007:14:11:41 +0300] cupsdCloseClient: 10 D [15/Aug/2007:14:11:56 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:56 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:56 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:56 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:56 +0300] [Job 19] Connected to 192.168.0.109:515 (IPv4) (local port 1023)... D [15/Aug/2007:14:11:56 +0300] [Job 19] lpd_command 02 hpLaserJ D [15/Aug/2007:14:11:56 +0300] [Job 19] Sending command string (10 bytes)... D [15/Aug/2007:14:11:56 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:11:56 +0300] cupsdCloseClient: 11 D [15/Aug/2007:14:11:57 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:11:57 +0300] [Job 19] Control file is: D [15/Aug/2007:14:11:57 +0300] [Job 19] Hdogmatist D [15/Aug/2007:14:11:57 +0300] [Job 19] Proot D [15/Aug/2007:14:11:57 +0300] [Job 19] JTest Page D [15/Aug/2007:14:11:57 +0300] [Job 19] UdfA343dogmatist D [15/Aug/2007:14:11:57 +0300] [Job 19] NTest Page D [15/Aug/2007:14:11:57 +0300] [Job 19] lpd_command 02 56 cfA343dogmatist D [15/Aug/2007:14:11:57 +0300] [Job 19] Sending command string (20 bytes)... D [15/Aug/2007:14:11:57 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:11:57 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:11:57 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:57 +0300] [Job 19] lpd_command 03 0 dfA343dogmatist D [15/Aug/2007:14:11:57 +0300] [Job 19] Sending command string (19 bytes)... D [15/Aug/2007:14:11:57 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:11:57 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:11:57 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:11:57 +0300] Discarding unused printer-state-changed event... E [15/Aug/2007:14:11:57 +0300] [Job 19] Remote host did not accept data file (1) D [15/Aug/2007:14:11:57 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:26 +0300] cupsdCloseClient: 9 D [15/Aug/2007:14:12:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:27 +0300] [Job 19] Connected to 192.168.0.109:515 (IPv4) (local port 1023)... D [15/Aug/2007:14:12:27 +0300] [Job 19] lpd_command 02 hpLaserJ D [15/Aug/2007:14:12:27 +0300] [Job 19] Sending command string (10 bytes)... D [15/Aug/2007:14:12:27 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:12:27 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:12:27 +0300] [Job 19] Control file is: D [15/Aug/2007:14:12:27 +0300] [Job 19] Hdogmatist D [15/Aug/2007:14:12:27 +0300] [Job 19] Proot D [15/Aug/2007:14:12:27 +0300] [Job 19] JTest Page D [15/Aug/2007:14:12:27 +0300] [Job 19] UdfA343dogmatist D [15/Aug/2007:14:12:27 +0300] [Job 19] NTest Page D [15/Aug/2007:14:12:27 +0300] [Job 19] lpd_command 02 56 cfA343dogmatist D [15/Aug/2007:14:12:27 +0300] [Job 19] Sending command string (20 bytes)... D [15/Aug/2007:14:12:27 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:12:27 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:12:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:27 +0300] [Job 19] lpd_command 03 0 dfA343dogmatist D [15/Aug/2007:14:12:27 +0300] [Job 19] Sending command string (19 bytes)... D [15/Aug/2007:14:12:27 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:12:27 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:12:27 +0300] Discarding unused printer-state-changed event... E [15/Aug/2007:14:12:27 +0300] [Job 19] Remote host did not accept data file (1) D [15/Aug/2007:14:12:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:41 +0300] cupsdCloseClient: 12 D [15/Aug/2007:14:12:41 +0300] cupsdCloseClient: 13 D [15/Aug/2007:14:12:57 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:57 +0300] [Job 19] Connected to 192.168.0.109:515 (IPv4) (local port 1023)... D [15/Aug/2007:14:12:57 +0300] [Job 19] lpd_command 02 hpLaserJ D [15/Aug/2007:14:12:57 +0300] [Job 19] Sending command string (10 bytes)... D [15/Aug/2007:14:12:57 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:12:57 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:57 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:12:57 +0300] [Job 19] Control file is: D [15/Aug/2007:14:12:57 +0300] [Job 19] Hdogmatist D [15/Aug/2007:14:12:57 +0300] [Job 19] Proot D [15/Aug/2007:14:12:57 +0300] [Job 19] JTest Page D [15/Aug/2007:14:12:57 +0300] [Job 19] UdfA343dogmatist D [15/Aug/2007:14:12:57 +0300] [Job 19] NTest Page D [15/Aug/2007:14:12:57 +0300] [Job 19] lpd_command 02 56 cfA343dogmatist D [15/Aug/2007:14:12:57 +0300] [Job 19] Sending command string (20 bytes)... D [15/Aug/2007:14:12:57 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:12:57 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:12:57 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:57 +0300] [Job 19] lpd_command 03 0 dfA343dogmatist D [15/Aug/2007:14:12:57 +0300] [Job 19] Sending command string (19 bytes)... D [15/Aug/2007:14:12:57 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:12:57 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:12:57 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:12:57 +0300] Discarding unused printer-state-changed event... E [15/Aug/2007:14:12:57 +0300] [Job 19] Remote host did not accept data file (1) D [15/Aug/2007:14:12:57 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:13:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:13:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:13:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:13:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:13:27 +0300] [Job 19] Connected to 192.168.0.109:515 (IPv4) (local port 1023)... D [15/Aug/2007:14:13:27 +0300] [Job 19] lpd_command 02 hpLaserJ D [15/Aug/2007:14:13:27 +0300] [Job 19] Sending command string (10 bytes)... D [15/Aug/2007:14:13:27 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:13:27 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:13:27 +0300] [Job 19] Control file is: D [15/Aug/2007:14:13:27 +0300] [Job 19] Hdogmatist D [15/Aug/2007:14:13:27 +0300] [Job 19] Proot D [15/Aug/2007:14:13:27 +0300] [Job 19] JTest Page D [15/Aug/2007:14:13:27 +0300] [Job 19] UdfA343dogmatist D [15/Aug/2007:14:13:27 +0300] [Job 19] NTest Page D [15/Aug/2007:14:13:27 +0300] [Job 19] lpd_command 02 56 cfA343dogmatist D [15/Aug/2007:14:13:27 +0300] [Job 19] Sending command string (20 bytes)... D [15/Aug/2007:14:13:27 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:13:27 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:13:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:13:27 +0300] Discarding unused printer-state-changed event... D [15/Aug/2007:14:13:27 +0300] [Job 19] lpd_command 03 0 dfA343dogmatist D [15/Aug/2007:14:13:27 +0300] [Job 19] Sending command string (19 bytes)... D [15/Aug/2007:14:13:27 +0300] [Job 19] Reading command status... D [15/Aug/2007:14:13:27 +0300] [Job 19] lpd_command returning 0 D [15/Aug/2007:14:13:27 +0300] Discarding unused printer-state-changed event... E [15/Aug/2007:14:13:27 +0300] [Job 19] Remote host did not accept data file (1) D [15/Aug/2007:14:13:27 +0300] Discarding unused printer-state-changed event... I [15/Aug/2007:14:13:55 +0300] Scheduler shutting down normally. I [15/Aug/2007:14:13:55 +0300] Saving remote.cache... D [15/Aug/2007:14:13:55 +0300] Discarding unused printer-state-changed event... I [15/Aug/2007:14:13:55 +0300] Saving job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:14:19:42 +0300] Listening to 127.0.0.1:631 (IPv4) I [15/Aug/2007:14:19:42 +0300] Loaded configuration file "/etc/cups/cupsd.conf" I [15/Aug/2007:14:19:42 +0300] Using default TempDir of /var/spool/cups/tmp... I [15/Aug/2007:14:19:42 +0300] Cleaning out old temporary files in "/var/spool/cups/tmp"... I [15/Aug/2007:14:19:42 +0300] Configured for up to 100 clients. I [15/Aug/2007:14:19:42 +0300] Allowing up to 100 client connections per host. I [15/Aug/2007:14:19:42 +0300] Using policy "default" as the default! I [15/Aug/2007:14:19:42 +0300] Full reload is required. I [15/Aug/2007:14:19:42 +0300] Loaded MIME database from '/etc/cups': 34 types, 39 filters... I [15/Aug/2007:14:19:42 +0300] Loading job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:14:19:42 +0300] Full reload complete. I [15/Aug/2007:14:19:42 +0300] Listening to 127.0.0.1:631 on fd 2... E [15/Aug/2007:14:19:42 +0300] Unable to set ACLs on root certificate "/var/run/cups/certs/0" - Operation not supported I [15/Aug/2007:14:19:42 +0300] Started filter /usr/lib/cups/filter/pstops (PID 9531) for job 19. I [15/Aug/2007:14:19:42 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 9532) for job 19. I [15/Aug/2007:14:19:42 +0300] Started backend /usr/lib/cups/backend/lpd (PID 9533) for job 19. E [15/Aug/2007:14:19:43 +0300] [Job 19] /ioerror in --.outputpage-- E [15/Aug/2007:14:19:43 +0300] PID 9532 (/usr/lib/cups/filter/foomatic-rip) stopped with status 1! I [15/Aug/2007:14:19:43 +0300] Hint: Try setting the LogLevel to "debug" to find out more. E [15/Aug/2007:14:19:43 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:20:13 +0300] [Job 19] Remote host did not accept data file (1) I [15/Aug/2007:14:20:37 +0300] commptr="" I [15/Aug/2007:14:20:37 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9588) E [15/Aug/2007:14:20:43 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:21:14 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:21:44 +0300] [Job 19] Remote host did not accept data file (1) I [15/Aug/2007:14:21:52 +0300] commptr="?op=modify-printer&printer_name=lj1000" I [15/Aug/2007:14:21:52 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9594) I [15/Aug/2007:14:21:55 +0300] commptr="" I [15/Aug/2007:14:21:55 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9596) I [15/Aug/2007:14:21:56 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:14:21:56 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=9597) I [15/Aug/2007:14:22:03 +0300] commptr="" I [15/Aug/2007:14:22:03 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9611) I [15/Aug/2007:14:22:05 +0300] commptr="" I [15/Aug/2007:14:22:05 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9612) I [15/Aug/2007:14:22:06 +0300] commptr="list+1+0+requested-attributes='ppd-make'" I [15/Aug/2007:14:22:06 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=9613) E [15/Aug/2007:14:22:15 +0300] [Job 19] Remote host did not accept data file (1) I [15/Aug/2007:14:22:25 +0300] commptr="" I [15/Aug/2007:14:22:25 +0300] Started "/usr/lib/cups/cgi-bin/help.cgi" (pid=9620) E [15/Aug/2007:14:22:45 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:23:15 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:23:45 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:24:16 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:24:46 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:25:17 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:25:47 +0300] [Job 19] Remote host did not accept data file (1) I [15/Aug/2007:14:26:15 +0300] commptr="" I [15/Aug/2007:14:26:15 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9677) E [15/Aug/2007:14:26:18 +0300] [Job 19] Remote host did not accept data file (1) I [15/Aug/2007:14:26:34 +0300] commptr="" I [15/Aug/2007:14:26:34 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9680) I [15/Aug/2007:14:26:34 +0300] commptr="list+1+0+requested-attributes='ppd-make'" I [15/Aug/2007:14:26:34 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=9681) E [15/Aug/2007:14:26:48 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:27:19 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:27:49 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:28:19 +0300] [Job 19] Remote host did not accept data file (1) I [15/Aug/2007:14:28:36 +0300] commptr="" I [15/Aug/2007:14:28:36 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9715) I [15/Aug/2007:14:28:37 +0300] commptr="list+1+0+requested-attributes=all%20ppd-make='HP'" I [15/Aug/2007:14:28:37 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=9716) E [15/Aug/2007:14:28:49 +0300] [Job 19] Remote host did not accept data file (1) E [15/Aug/2007:14:29:20 +0300] [Job 19] Remote host did not accept data file (1) I [15/Aug/2007:14:29:23 +0300] commptr="" I [15/Aug/2007:14:29:23 +0300] Started "/usr/lib/cups/cgi-bin/help.cgi" (pid=9722) I [15/Aug/2007:14:29:28 +0300] commptr="?QUERY=foomatic&x=0&y=0" I [15/Aug/2007:14:29:28 +0300] Started "/usr/lib/cups/cgi-bin/help.cgi" (pid=9723) I [15/Aug/2007:14:29:48 +0300] commptr="" I [15/Aug/2007:14:29:48 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9725) E [15/Aug/2007:14:29:49 +0300] CUPS-Add-Modify-Printer: Unauthorized I [15/Aug/2007:14:29:49 +0300] Setting lj1000 device-uri to "smb://192.168.0.109/hpLaserJ" (was "lpd://192.168.0.109/hpLaserJ".) I [15/Aug/2007:14:29:49 +0300] Setting lj1000 printer-is-accepting-jobs to 1 (was 1.) I [15/Aug/2007:14:29:49 +0300] Setting lj1000 printer-state to 3 (was 4.) I [15/Aug/2007:14:29:49 +0300] Saving printers.conf... I [15/Aug/2007:14:29:49 +0300] Started filter /usr/lib/cups/filter/pstops (PID 9727) for job 19. I [15/Aug/2007:14:29:49 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 9728) for job 19. I [15/Aug/2007:14:29:49 +0300] Started backend /usr/lib/cups/backend/smb (PID 9729) for job 19. I [15/Aug/2007:14:29:49 +0300] Printer "lj1000" modified by "root". E [15/Aug/2007:14:29:49 +0300] PID 9533 (/usr/lib/cups/backend/lpd) crashed on signal 9! I [15/Aug/2007:14:29:49 +0300] Hint: Try setting the LogLevel to "debug" to find out more. E [15/Aug/2007:14:29:50 +0300] [Job 19] No ticket cache found for userid=0 E [15/Aug/2007:14:29:50 +0300] [Job 19] Can not get the ticket cache for root E [15/Aug/2007:14:29:50 +0300] [Job 19] /ioerror in --.outputpage-- E [15/Aug/2007:14:29:50 +0300] PID 9728 (/usr/lib/cups/filter/foomatic-rip) stopped with status 1! I [15/Aug/2007:14:29:50 +0300] Hint: Try setting the LogLevel to "debug" to find out more. I [15/Aug/2007:14:29:54 +0300] commptr="" I [15/Aug/2007:14:29:54 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9740) I [15/Aug/2007:14:38:48 +0300] commptr="?op=modify-printer&printer_name=lj1000" I [15/Aug/2007:14:38:48 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9777) I [15/Aug/2007:14:38:52 +0300] commptr="" I [15/Aug/2007:14:38:52 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9778) I [15/Aug/2007:14:38:52 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:14:38:52 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=9779) I [15/Aug/2007:14:38:55 +0300] commptr="" I [15/Aug/2007:14:38:55 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9792) I [15/Aug/2007:14:38:57 +0300] commptr="" I [15/Aug/2007:14:38:57 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9793) I [15/Aug/2007:14:38:58 +0300] commptr="list+1+0+requested-attributes='ppd-make'" I [15/Aug/2007:14:38:58 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=9794) I [15/Aug/2007:14:40:14 +0300] commptr="" I [15/Aug/2007:14:40:14 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9812) E [15/Aug/2007:14:40:14 +0300] CUPS-Add-Modify-Printer: Unauthorized I [15/Aug/2007:14:40:15 +0300] Setting lj1000 device-uri to "smb://192.168.0.109/hpLaserJ" (was "smb://192.168.0.109/hpLaserJ".) I [15/Aug/2007:14:40:15 +0300] Setting lj1000 printer-is-accepting-jobs to 1 (was 1.) I [15/Aug/2007:14:40:15 +0300] Setting lj1000 printer-state to 3 (was 3.) I [15/Aug/2007:14:40:15 +0300] Saving printers.conf... I [15/Aug/2007:14:40:15 +0300] Printer "lj1000" modified by "root". I [15/Aug/2007:14:40:18 +0300] commptr="" I [15/Aug/2007:14:40:18 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9817) I [15/Aug/2007:14:40:20 +0300] commptr="?op=print-test-page" I [15/Aug/2007:14:40:20 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9820) I [15/Aug/2007:14:40:21 +0300] Adding start banner page "none" to job 20. I [15/Aug/2007:14:40:21 +0300] Adding end banner page "none" to job 20. I [15/Aug/2007:14:40:21 +0300] Job 20 queued on "lj1000" by "root". I [15/Aug/2007:14:40:21 +0300] Started filter /usr/lib/cups/filter/pstops (PID 9821) for job 20. I [15/Aug/2007:14:40:21 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 9822) for job 20. I [15/Aug/2007:14:40:21 +0300] Started backend /usr/lib/cups/backend/smb (PID 9823) for job 20. E [15/Aug/2007:14:40:22 +0300] [Job 20] No ticket cache found for userid=0 E [15/Aug/2007:14:40:22 +0300] [Job 20] Can not get the ticket cache for root E [15/Aug/2007:14:40:22 +0300] [Job 20] /ioerror in --.outputpage-- E [15/Aug/2007:14:40:23 +0300] PID 9822 (/usr/lib/cups/filter/foomatic-rip) stopped with status 1! I [15/Aug/2007:14:40:23 +0300] Hint: Try setting the LogLevel to "debug" to find out more. I [15/Aug/2007:14:40:23 +0300] commptr="" I [15/Aug/2007:14:40:23 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9834) I [15/Aug/2007:14:40:28 +0300] commptr="?op=cancel-job&job_id=19&job_printer_uri=/printers/lj1000" I [15/Aug/2007:14:40:28 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=9835) I [15/Aug/2007:14:40:28 +0300] Job 19 was canceled by "root". I [15/Aug/2007:14:40:30 +0300] commptr="" I [15/Aug/2007:14:40:30 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9836) I [15/Aug/2007:14:40:33 +0300] commptr="" I [15/Aug/2007:14:40:33 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=9838) I [15/Aug/2007:14:40:36 +0300] commptr="?op=cancel-job&job_id=20&job_printer_uri=/printers/lj1000" I [15/Aug/2007:14:40:36 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=9839) I [15/Aug/2007:14:40:36 +0300] Job 20 was canceled by "root". I [15/Aug/2007:14:40:40 +0300] commptr="" I [15/Aug/2007:14:40:40 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9840) I [15/Aug/2007:14:41:05 +0300] commptr="?op=modify-printer&printer_name=lj1000" I [15/Aug/2007:14:41:05 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9849) I [15/Aug/2007:14:41:07 +0300] commptr="" I [15/Aug/2007:14:41:07 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9850) I [15/Aug/2007:14:41:09 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:14:41:09 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=9851) I [15/Aug/2007:14:41:16 +0300] commptr="" I [15/Aug/2007:14:41:16 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9864) I [15/Aug/2007:14:41:20 +0300] commptr="" I [15/Aug/2007:14:41:20 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9865) I [15/Aug/2007:14:41:20 +0300] commptr="list+1+0+requested-attributes='ppd-make'" I [15/Aug/2007:14:41:20 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=9866) I [15/Aug/2007:14:42:01 +0300] commptr="" I [15/Aug/2007:14:42:01 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=9877) E [15/Aug/2007:14:42:02 +0300] CUPS-Add-Modify-Printer: Unauthorized I [15/Aug/2007:14:42:02 +0300] Setting lj1000 device-uri to "smb://192.168.0.109/hpLaserJ" (was "smb://192.168.0.109/hpLaserJ".) I [15/Aug/2007:14:42:02 +0300] Setting lj1000 printer-is-accepting-jobs to 1 (was 1.) I [15/Aug/2007:14:42:02 +0300] Setting lj1000 printer-state to 3 (was 3.) I [15/Aug/2007:14:42:02 +0300] Saving printers.conf... I [15/Aug/2007:14:42:02 +0300] Printer "lj1000" modified by "root". I [15/Aug/2007:14:42:05 +0300] commptr="" I [15/Aug/2007:14:42:05 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=9878) I [15/Aug/2007:14:46:44 +0300] commptr="?op=modify-printer&printer_name=lj1000" I [15/Aug/2007:14:46:44 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=10602) I [15/Aug/2007:14:46:46 +0300] commptr="" I [15/Aug/2007:14:46:46 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=10604) I [15/Aug/2007:14:46:47 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:14:46:47 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=10605) I [15/Aug/2007:14:46:51 +0300] commptr="" I [15/Aug/2007:14:46:51 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=10618) I [15/Aug/2007:14:46:54 +0300] commptr="" I [15/Aug/2007:14:46:54 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=10619) I [15/Aug/2007:14:46:55 +0300] commptr="list+1+0+requested-attributes='ppd-make'" I [15/Aug/2007:14:46:55 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=10620) I [15/Aug/2007:14:47:15 +0300] commptr="" I [15/Aug/2007:14:47:15 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=10627) I [15/Aug/2007:14:47:15 +0300] commptr="list+1+0+requested-attributes=all%20ppd-make='HP'" I [15/Aug/2007:14:47:15 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=10628) I [15/Aug/2007:14:48:14 +0300] commptr="" I [15/Aug/2007:14:48:14 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=10648) E [15/Aug/2007:14:48:15 +0300] CUPS-Add-Modify-Printer: Unauthorized I [15/Aug/2007:14:48:15 +0300] Setting lj1000 device-uri to "smb://192.168.0.109/hpLaserJ" (was "smb://192.168.0.109/hpLaserJ".) I [15/Aug/2007:14:48:15 +0300] Setting lj1000 printer-is-accepting-jobs to 1 (was 1.) I [15/Aug/2007:14:48:15 +0300] Setting lj1000 printer-state to 3 (was 3.) I [15/Aug/2007:14:48:15 +0300] Saving printers.conf... I [15/Aug/2007:14:48:15 +0300] Printer "lj1000" modified by "root". I [15/Aug/2007:14:48:17 +0300] commptr="" I [15/Aug/2007:14:48:17 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=10650) I [15/Aug/2007:14:48:32 +0300] commptr="?op=modify-printer&printer_name=lj1000" I [15/Aug/2007:14:48:32 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=10652) E [15/Aug/2007:15:36:42 +0300] CUPS-Add-Modify-Printer: Unauthorized I [15/Aug/2007:15:36:42 +0300] Setting hpLaserJ printer-is-accepting-jobs to 1 (was 0.) I [15/Aug/2007:15:36:42 +0300] Setting hpLaserJ printer-state to 3 (was 5.) I [15/Aug/2007:15:36:42 +0300] Saving printers.conf... I [15/Aug/2007:15:36:42 +0300] New printer "hpLaserJ" added by "root". I [15/Aug/2007:15:36:42 +0300] Saving printers.conf... I [15/Aug/2007:15:36:42 +0300] Printer "hpLaserJ" modified by "root". I [15/Aug/2007:15:36:42 +0300] Saving printers.conf... I [15/Aug/2007:15:36:42 +0300] Printer "hpLaserJ" modified by "root". I [15/Aug/2007:15:36:42 +0300] Setting hpLaserJ device-uri to "smb://192.168.0.109/" (was "file:/dev/null".) I [15/Aug/2007:15:36:42 +0300] Saving printers.conf... I [15/Aug/2007:15:36:42 +0300] Printer "hpLaserJ" modified by "root". I [15/Aug/2007:15:37:32 +0300] commptr="" I [15/Aug/2007:15:37:32 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=11100) I [15/Aug/2007:15:37:33 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:15:37:33 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=11101) I [15/Aug/2007:15:37:38 +0300] commptr="" I [15/Aug/2007:15:37:38 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=11115) I [15/Aug/2007:15:37:47 +0300] commptr="?op=print-test-page" I [15/Aug/2007:15:37:47 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=11116) I [15/Aug/2007:15:37:47 +0300] Adding start banner page "none" to job 21. I [15/Aug/2007:15:37:47 +0300] Adding end banner page "none" to job 21. I [15/Aug/2007:15:37:47 +0300] Job 21 queued on "hpLaserJ" by "root". I [15/Aug/2007:15:37:47 +0300] Started filter /usr/lib/cups/filter/pstops (PID 11117) for job 21. I [15/Aug/2007:15:37:47 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 11118) for job 21. I [15/Aug/2007:15:37:47 +0300] Started backend /usr/lib/cups/backend/smb (PID 11119) for job 21. E [15/Aug/2007:15:37:48 +0300] [Job 21] No ticket cache found for userid=0 E [15/Aug/2007:15:37:48 +0300] [Job 21] Can not get the ticket cache for root E [15/Aug/2007:15:37:48 +0300] [Job 21] Tree connect failed (NT_STATUS_BAD_NETWORK_NAME) E [15/Aug/2007:15:37:48 +0300] [Job 21] Tree connect failed (NT_STATUS_BAD_NETWORK_NAME) E [15/Aug/2007:15:37:48 +0300] [Job 21] Unable to connect to CIFS host, will retry in 60 seconds... E [15/Aug/2007:15:37:49 +0300] PID 11118 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3! I [15/Aug/2007:15:37:49 +0300] Hint: Try setting the LogLevel to "debug" to find out more. I [15/Aug/2007:15:37:49 +0300] commptr="" I [15/Aug/2007:15:37:49 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=11129) I [15/Aug/2007:15:37:50 +0300] commptr="" I [15/Aug/2007:15:37:50 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=11130) E [15/Aug/2007:15:37:50 +0300] PID 11129 (/usr/lib/cups/cgi-bin/printers.cgi) crashed on signal 9! I [15/Aug/2007:15:37:50 +0300] Hint: Try setting the LogLevel to "debug" to find out more. E [15/Aug/2007:15:38:48 +0300] [Job 21] No ticket cache found for userid=0 E [15/Aug/2007:15:38:48 +0300] [Job 21] Can not get the ticket cache for root E [15/Aug/2007:15:38:48 +0300] [Job 21] Tree connect failed (NT_STATUS_BAD_NETWORK_NAME) E [15/Aug/2007:15:38:48 +0300] [Job 21] Tree connect failed (NT_STATUS_BAD_NETWORK_NAME) E [15/Aug/2007:15:38:48 +0300] [Job 21] Unable to connect to CIFS host, will retry in 60 seconds... I [15/Aug/2007:15:39:07 +0300] commptr="?op=modify-printer&printer_name=hpLaserJ" I [15/Aug/2007:15:39:07 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=11145) I [15/Aug/2007:15:39:09 +0300] commptr="" I [15/Aug/2007:15:39:09 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=11147) I [15/Aug/2007:15:39:11 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:15:39:11 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=11148) I [15/Aug/2007:15:39:15 +0300] commptr="" I [15/Aug/2007:15:39:15 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=11161) I [15/Aug/2007:15:39:23 +0300] commptr="" I [15/Aug/2007:15:39:23 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=11163) I [15/Aug/2007:15:39:24 +0300] commptr="list+1+0+requested-attributes='ppd-make'" I [15/Aug/2007:15:39:24 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=11164) I [15/Aug/2007:15:39:43 +0300] commptr="" I [15/Aug/2007:15:39:43 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=11170) I [15/Aug/2007:15:39:44 +0300] commptr="list+1+0+requested-attributes=all%20ppd-make='HP'" I [15/Aug/2007:15:39:44 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=11171) E [15/Aug/2007:15:39:48 +0300] [Job 21] No ticket cache found for userid=0 E [15/Aug/2007:15:39:48 +0300] [Job 21] Can not get the ticket cache for root E [15/Aug/2007:15:39:49 +0300] [Job 21] Tree connect failed (NT_STATUS_BAD_NETWORK_NAME) E [15/Aug/2007:15:39:49 +0300] [Job 21] Tree connect failed (NT_STATUS_BAD_NETWORK_NAME) E [15/Aug/2007:15:39:49 +0300] [Job 21] Unable to connect to CIFS host, will retry in 60 seconds... I [15/Aug/2007:15:40:16 +0300] commptr="" I [15/Aug/2007:15:40:16 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=11177) E [15/Aug/2007:15:40:16 +0300] CUPS-Add-Modify-Printer: Unauthorized I [15/Aug/2007:15:40:16 +0300] Setting hpLaserJ device-uri to "smb://192.168.0.109/hpLaserJ" (was "smb://192.168.0.109/".) I [15/Aug/2007:15:40:16 +0300] Setting hpLaserJ printer-is-accepting-jobs to 1 (was 1.) I [15/Aug/2007:15:40:16 +0300] Setting hpLaserJ printer-state to 3 (was 4.) I [15/Aug/2007:15:40:24 +0300] Saving printers.conf... I [15/Aug/2007:15:40:24 +0300] Started filter /usr/lib/cups/filter/pstops (PID 11189) for job 21. I [15/Aug/2007:15:40:24 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 11190) for job 21. I [15/Aug/2007:15:40:24 +0300] Started backend /usr/lib/cups/backend/smb (PID 11191) for job 21. I [15/Aug/2007:15:40:24 +0300] Printer "hpLaserJ" modified by "root". E [15/Aug/2007:15:40:24 +0300] PID 11119 (/usr/lib/cups/backend/smb) crashed on signal 9! I [15/Aug/2007:15:40:24 +0300] Hint: Try setting the LogLevel to "debug" to find out more. E [15/Aug/2007:15:40:25 +0300] [Job 21] No ticket cache found for userid=0 E [15/Aug/2007:15:40:25 +0300] [Job 21] Can not get the ticket cache for root E [15/Aug/2007:15:40:26 +0300] PID 11190 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3! I [15/Aug/2007:15:40:26 +0300] Hint: Try setting the LogLevel to "debug" to find out more. I [15/Aug/2007:15:40:27 +0300] commptr="" I [15/Aug/2007:15:40:27 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=11201) I [15/Aug/2007:15:40:29 +0300] commptr="" I [15/Aug/2007:15:40:29 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=11202) I [15/Aug/2007:15:40:35 +0300] commptr="?op=cancel-job&job_id=21&job_printer_uri=/printers/hpLaserJ" I [15/Aug/2007:15:40:35 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=11203) I [15/Aug/2007:15:40:35 +0300] Job 21 was canceled by "root". I [15/Aug/2007:15:40:37 +0300] commptr="" I [15/Aug/2007:15:40:37 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=11204) I [15/Aug/2007:15:40:41 +0300] commptr="?op=print-test-page" I [15/Aug/2007:15:40:41 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=11205) I [15/Aug/2007:15:40:41 +0300] Adding start banner page "none" to job 22. I [15/Aug/2007:15:40:41 +0300] Adding end banner page "none" to job 22. I [15/Aug/2007:15:40:41 +0300] Job 22 queued on "hpLaserJ" by "root". I [15/Aug/2007:15:40:41 +0300] Started filter /usr/lib/cups/filter/pstops (PID 11206) for job 22. I [15/Aug/2007:15:40:41 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 11207) for job 22. I [15/Aug/2007:15:40:41 +0300] Started backend /usr/lib/cups/backend/smb (PID 11208) for job 22. E [15/Aug/2007:15:40:42 +0300] [Job 22] No ticket cache found for userid=0 E [15/Aug/2007:15:40:42 +0300] [Job 22] Can not get the ticket cache for root E [15/Aug/2007:15:40:43 +0300] PID 11207 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3! I [15/Aug/2007:15:40:43 +0300] Hint: Try setting the LogLevel to "debug" to find out more. I [15/Aug/2007:15:40:44 +0300] commptr="" I [15/Aug/2007:15:40:44 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=11218) I [15/Aug/2007:15:41:00 +0300] commptr="" I [15/Aug/2007:15:41:00 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=11220) I [15/Aug/2007:15:41:08 +0300] commptr="?op=cancel-job&job_id=22&job_printer_uri=/printers/hpLaserJ" I [15/Aug/2007:15:41:08 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=11221) I [15/Aug/2007:15:41:08 +0300] Job 22 was canceled by "root". I [15/Aug/2007:15:41:09 +0300] commptr="" I [15/Aug/2007:15:41:09 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=11223) I [15/Aug/2007:15:42:48 +0300] Adding start banner page "none" to job 23. I [15/Aug/2007:15:42:48 +0300] Adding end banner page "none" to job 23. I [15/Aug/2007:15:42:48 +0300] Job 23 queued on "hpLaserJ" by "dogma". I [15/Aug/2007:15:42:48 +0300] Started filter /usr/lib/cups/filter/pstops (PID 11250) for job 23. I [15/Aug/2007:15:42:48 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 11251) for job 23. I [15/Aug/2007:15:42:48 +0300] Started backend /usr/lib/cups/backend/smb (PID 11252) for job 23. E [15/Aug/2007:15:42:48 +0300] [Job 23] No ticket cache found for userid=1000 E [15/Aug/2007:15:42:48 +0300] [Job 23] Can not get the ticket cache for dogma E [15/Aug/2007:15:42:49 +0300] PID 11251 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3! I [15/Aug/2007:15:42:49 +0300] Hint: Try setting the LogLevel to "debug" to find out more. I [15/Aug/2007:15:43:21 +0300] Adding start banner page "none" to job 24. I [15/Aug/2007:15:43:21 +0300] Adding end banner page "none" to job 24. I [15/Aug/2007:15:43:21 +0300] Job 24 queued on "hpLaserJ" by "dogma". I [15/Aug/2007:15:43:21 +0300] Started filter /usr/lib/cups/filter/pstops (PID 11313) for job 24. I [15/Aug/2007:15:43:21 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 11314) for job 24. I [15/Aug/2007:15:43:21 +0300] Started backend /usr/lib/cups/backend/smb (PID 11315) for job 24. E [15/Aug/2007:15:43:21 +0300] [Job 24] No ticket cache found for userid=1000 E [15/Aug/2007:15:43:21 +0300] [Job 24] Can not get the ticket cache for dogma E [15/Aug/2007:15:43:22 +0300] PID 11314 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3! I [15/Aug/2007:15:43:22 +0300] Hint: Try setting the LogLevel to "debug" to find out more. I [15/Aug/2007:15:43:25 +0300] Job 23 was canceled by "dogma". I [15/Aug/2007:15:43:27 +0300] Job 24 was canceled by "dogma". I [15/Aug/2007:15:52:40 +0300] Scheduler shutting down normally. I [15/Aug/2007:15:52:40 +0300] Saving remote.cache... I [15/Aug/2007:15:52:40 +0300] Saving job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:15:52:41 +0300] Listening to 127.0.0.1:631 (IPv4) I [15/Aug/2007:15:52:41 +0300] Loaded configuration file "/etc/cups/cupsd.conf" I [15/Aug/2007:15:52:41 +0300] Using default TempDir of /var/spool/cups/tmp... I [15/Aug/2007:15:52:41 +0300] Cleaning out old temporary files in "/var/spool/cups/tmp"... I [15/Aug/2007:15:52:41 +0300] Configured for up to 100 clients. I [15/Aug/2007:15:52:41 +0300] Allowing up to 100 client connections per host. I [15/Aug/2007:15:52:41 +0300] Using policy "default" as the default! I [15/Aug/2007:15:52:41 +0300] Full reload is required. I [15/Aug/2007:15:52:41 +0300] Loaded MIME database from '/etc/cups': 34 types, 39 filters... I [15/Aug/2007:15:52:41 +0300] Loading job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:15:52:41 +0300] Full reload complete. I [15/Aug/2007:15:52:41 +0300] Listening to 127.0.0.1:631 on fd 2... E [15/Aug/2007:15:52:41 +0300] Unable to set ACLs on root certificate "/var/run/cups/certs/0" - Operation not supported I [15/Aug/2007:15:53:29 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:15:53:29 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=11801) I [15/Aug/2007:15:53:35 +0300] commptr="list+1+0+requested-attributes='ppd-name','ppd-make','ppd-make-and-model'" I [15/Aug/2007:15:53:35 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=11819) I [15/Aug/2007:17:08:44 +0300] Scheduler shutting down normally. I [15/Aug/2007:17:08:44 +0300] Saving remote.cache... I [15/Aug/2007:17:08:44 +0300] Saving job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:17:08:45 +0300] Listening to 127.0.0.1:631 (IPv4) I [15/Aug/2007:17:08:45 +0300] Loaded configuration file "/etc/cups/cupsd.conf" I [15/Aug/2007:17:08:45 +0300] Using default TempDir of /var/spool/cups/tmp... I [15/Aug/2007:17:08:45 +0300] Cleaning out old temporary files in "/var/spool/cups/tmp"... I [15/Aug/2007:17:08:45 +0300] Configured for up to 100 clients. I [15/Aug/2007:17:08:45 +0300] Allowing up to 100 client connections per host. I [15/Aug/2007:17:08:45 +0300] Using policy "default" as the default! I [15/Aug/2007:17:08:45 +0300] Full reload is required. I [15/Aug/2007:17:08:45 +0300] Loaded MIME database from '/etc/cups': 34 types, 38 filters... I [15/Aug/2007:17:08:46 +0300] Loading job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:17:08:46 +0300] Full reload complete. I [15/Aug/2007:17:08:46 +0300] Listening to 127.0.0.1:631 on fd 2... E [15/Aug/2007:17:08:46 +0300] Unable to set ACLs on root certificate "/var/run/cups/certs/0" - Operation not supported I [15/Aug/2007:17:10:15 +0300] commptr="" I [15/Aug/2007:17:10:15 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=4613) I [15/Aug/2007:17:10:26 +0300] commptr="?op=print-test-page" I [15/Aug/2007:17:10:26 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=4615) I [15/Aug/2007:17:10:27 +0300] Adding start banner page "none" to job 25. I [15/Aug/2007:17:10:27 +0300] Adding end banner page "none" to job 25. I [15/Aug/2007:17:10:27 +0300] Job 25 queued on "lj1000" by "root". I [15/Aug/2007:17:10:27 +0300] Started filter /usr/lib/cups/filter/pstops (PID 4616) for job 25. I [15/Aug/2007:17:10:27 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4617) for job 25. I [15/Aug/2007:17:10:27 +0300] Started backend /usr/lib/cups/backend/smb (PID 4618) for job 25. E [15/Aug/2007:17:10:28 +0300] [Job 25] No ticket cache found for userid=0 E [15/Aug/2007:17:10:28 +0300] [Job 25] Can not get the ticket cache for root I [15/Aug/2007:17:10:30 +0300] commptr="" I [15/Aug/2007:17:10:30 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=4629) E [15/Aug/2007:17:10:33 +0300] PID 4617 (/usr/lib/cups/filter/foomatic-rip) stopped with status 1! I [15/Aug/2007:17:10:33 +0300] Hint: Try setting the LogLevel to "debug" to find out more. I [15/Aug/2007:17:10:42 +0300] commptr="" I [15/Aug/2007:17:10:42 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=4631) I [15/Aug/2007:17:16:30 +0300] commptr="?OP=add-printer" I [15/Aug/2007:17:16:30 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=4746) I [15/Aug/2007:17:16:42 +0300] commptr="" I [15/Aug/2007:17:16:42 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=4747) I [15/Aug/2007:17:16:50 +0300] commptr="" I [15/Aug/2007:17:16:50 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=4749) I [15/Aug/2007:17:16:50 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:17:16:50 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=4750) I [15/Aug/2007:17:28:42 +0300] Scheduler shutting down normally. I [15/Aug/2007:17:28:42 +0300] Saving remote.cache... I [15/Aug/2007:17:28:42 +0300] Saving job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:17:28:43 +0300] Listening to 127.0.0.1:631 (IPv4) I [15/Aug/2007:17:28:43 +0300] Loaded configuration file "/etc/cups/cupsd.conf" I [15/Aug/2007:17:28:43 +0300] Using default TempDir of /var/spool/cups/tmp... I [15/Aug/2007:17:28:43 +0300] Cleaning out old temporary files in "/var/spool/cups/tmp"... I [15/Aug/2007:17:28:43 +0300] Configured for up to 100 clients. I [15/Aug/2007:17:28:43 +0300] Allowing up to 100 client connections per host. I [15/Aug/2007:17:28:43 +0300] Using policy "default" as the default! I [15/Aug/2007:17:28:43 +0300] Full reload is required. I [15/Aug/2007:17:28:43 +0300] Loaded MIME database from '/etc/cups': 34 types, 39 filters... E [15/Aug/2007:17:28:43 +0300] Filter "foomatic-rip" for printer "hpLaserJ" not available: No such file or directory E [15/Aug/2007:17:28:43 +0300] Filter "foomatic-rip" for printer "lj1000" not available: No such file or directory I [15/Aug/2007:17:28:43 +0300] Loading job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:17:28:43 +0300] Full reload complete. I [15/Aug/2007:17:28:43 +0300] Listening to 127.0.0.1:631 on fd 2... E [15/Aug/2007:17:28:43 +0300] Unable to set ACLs on root certificate "/var/run/cups/certs/0" - Operation not supported I [15/Aug/2007:17:29:25 +0300] commptr="?OP=add-printer" I [15/Aug/2007:17:29:25 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5091) I [15/Aug/2007:17:29:29 +0300] commptr="" I [15/Aug/2007:17:29:29 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5094) I [15/Aug/2007:17:29:32 +0300] commptr="?op=delete-printer&printer_name=hpLaserJ" I [15/Aug/2007:17:29:32 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5095) I [15/Aug/2007:17:29:34 +0300] commptr="?op=delete-printer&printer_name=hpLaserJ&confirm=yes" I [15/Aug/2007:17:29:34 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5096) E [15/Aug/2007:17:29:34 +0300] CUPS-Delete-Printer: Unauthorized E [15/Aug/2007:17:29:37 +0300] cupsdAuthorize: Empty Basic password! I [15/Aug/2007:17:29:37 +0300] commptr="?op=delete-printer&printer_name=hpLaserJ&confirm=yes" I [15/Aug/2007:17:29:37 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5097) E [15/Aug/2007:17:29:37 +0300] CUPS-Delete-Printer: Unauthorized I [15/Aug/2007:17:29:48 +0300] commptr="?op=delete-printer&printer_name=hpLaserJ&confirm=yes" I [15/Aug/2007:17:29:48 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5099) E [15/Aug/2007:17:29:48 +0300] CUPS-Delete-Printer: Unauthorized I [15/Aug/2007:17:29:48 +0300] Printer "hpLaserJ" deleted by "root". I [15/Aug/2007:17:29:48 +0300] Saving printers.conf... I [15/Aug/2007:17:29:51 +0300] commptr="" I [15/Aug/2007:17:29:51 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5100) I [15/Aug/2007:17:29:53 +0300] commptr="?op=delete-printer&printer_name=lj1000" I [15/Aug/2007:17:29:53 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5101) I [15/Aug/2007:17:29:55 +0300] commptr="?op=delete-printer&printer_name=lj1000&confirm=yes" I [15/Aug/2007:17:29:55 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5102) E [15/Aug/2007:17:29:55 +0300] CUPS-Delete-Printer: Unauthorized I [15/Aug/2007:17:29:56 +0300] Printer "lj1000" deleted by "root". I [15/Aug/2007:17:29:56 +0300] Saving printers.conf... I [15/Aug/2007:17:29:58 +0300] commptr="" I [15/Aug/2007:17:29:58 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5103) I [15/Aug/2007:17:30:04 +0300] commptr="?OP=add-printer" I [15/Aug/2007:17:30:04 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5104) I [15/Aug/2007:17:30:27 +0300] commptr="" I [15/Aug/2007:17:30:27 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5110) I [15/Aug/2007:17:30:27 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:17:30:27 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=5111) I [15/Aug/2007:17:30:34 +0300] commptr="" I [15/Aug/2007:17:30:34 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5125) I [15/Aug/2007:17:30:49 +0300] commptr="" I [15/Aug/2007:17:30:49 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5127) I [15/Aug/2007:17:30:49 +0300] commptr="list+1+0+requested-attributes='ppd-make'" I [15/Aug/2007:17:30:49 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=5128) I [15/Aug/2007:17:31:51 +0300] commptr="" I [15/Aug/2007:17:31:51 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5138) I [15/Aug/2007:17:31:51 +0300] commptr="list+1+0+requested-attributes=all%20ppd-make='HP'" I [15/Aug/2007:17:31:51 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=5139) I [15/Aug/2007:17:32:37 +0300] commptr="" I [15/Aug/2007:17:32:37 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5144) E [15/Aug/2007:17:32:37 +0300] CUPS-Add-Modify-Printer: Unauthorized I [15/Aug/2007:17:32:37 +0300] Setting HP-LaserJet-1000 device-uri to "smb://192.168.0.109/hpLaserJ" (was "file:/dev/null".) I [15/Aug/2007:17:32:37 +0300] Setting HP-LaserJet-1000 printer-is-accepting-jobs to 1 (was 0.) I [15/Aug/2007:17:32:37 +0300] Setting HP-LaserJet-1000 printer-state to 3 (was 5.) I [15/Aug/2007:17:32:37 +0300] Saving printers.conf... I [15/Aug/2007:17:32:37 +0300] New printer "HP-LaserJet-1000" added by "root". I [15/Aug/2007:17:32:41 +0300] commptr="" I [15/Aug/2007:17:32:41 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5146) I [15/Aug/2007:17:32:44 +0300] commptr="?op=print-test-page" I [15/Aug/2007:17:32:44 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5147) I [15/Aug/2007:17:32:45 +0300] Adding start banner page "none" to job 26. I [15/Aug/2007:17:32:45 +0300] Adding end banner page "none" to job 26. I [15/Aug/2007:17:32:45 +0300] Job 26 queued on "HP-LaserJet-1000" by "root". I [15/Aug/2007:17:32:45 +0300] Started filter /usr/lib/cups/filter/pstops (PID 5148) for job 26. I [15/Aug/2007:17:32:45 +0300] Started filter /usr/lib/cups/filter/pstoraster (PID 5149) for job 26. I [15/Aug/2007:17:32:45 +0300] Started filter /usr/lib/cups/filter/rastertogutenprint.5.0 (PID 5150) for job 26. I [15/Aug/2007:17:32:45 +0300] Started backend /usr/lib/cups/backend/smb (PID 5151) for job 26. E [15/Aug/2007:17:32:46 +0300] [Job 26] No ticket cache found for userid=0 E [15/Aug/2007:17:32:46 +0300] [Job 26] Can not get the ticket cache for root I [15/Aug/2007:17:32:48 +0300] commptr="" I [15/Aug/2007:17:32:48 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5152) I [15/Aug/2007:17:32:59 +0300] commptr="" I [15/Aug/2007:17:32:59 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5153) I [15/Aug/2007:17:36:16 +0300] Scheduler shutting down normally. I [15/Aug/2007:17:36:16 +0300] Saving remote.cache... I [15/Aug/2007:17:36:16 +0300] Saving job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:17:36:17 +0300] Listening to 127.0.0.1:631 (IPv4) I [15/Aug/2007:17:36:17 +0300] Loaded configuration file "/etc/cups/cupsd.conf" I [15/Aug/2007:17:36:17 +0300] Using default TempDir of /var/spool/cups/tmp... I [15/Aug/2007:17:36:17 +0300] Cleaning out old temporary files in "/var/spool/cups/tmp"... I [15/Aug/2007:17:36:17 +0300] Configured for up to 100 clients. I [15/Aug/2007:17:36:17 +0300] Allowing up to 100 client connections per host. I [15/Aug/2007:17:36:17 +0300] Using policy "default" as the default! I [15/Aug/2007:17:36:17 +0300] Full reload is required. I [15/Aug/2007:17:36:17 +0300] Loaded MIME database from '/etc/cups': 34 types, 39 filters... I [15/Aug/2007:17:36:17 +0300] Loading job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:17:36:17 +0300] Full reload complete. I [15/Aug/2007:17:36:17 +0300] Listening to 127.0.0.1:631 on fd 2... E [15/Aug/2007:17:36:17 +0300] Unable to set ACLs on root certificate "/var/run/cups/certs/0" - Operation not supported I [15/Aug/2007:17:36:20 +0300] commptr="" I [15/Aug/2007:17:36:20 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5196) I [15/Aug/2007:17:36:24 +0300] commptr="?op=print-test-page" I [15/Aug/2007:17:36:24 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5198) I [15/Aug/2007:17:36:24 +0300] Adding start banner page "none" to job 27. I [15/Aug/2007:17:36:24 +0300] Adding end banner page "none" to job 27. I [15/Aug/2007:17:36:24 +0300] Job 27 queued on "HP-LaserJet-1000" by "root". I [15/Aug/2007:17:36:24 +0300] Started filter /usr/lib/cups/filter/pstops (PID 5199) for job 27. I [15/Aug/2007:17:36:24 +0300] Started filter /usr/lib/cups/filter/pstoraster (PID 5200) for job 27. I [15/Aug/2007:17:36:24 +0300] Started filter /usr/lib/cups/filter/rastertogutenprint.5.0 (PID 5201) for job 27. I [15/Aug/2007:17:36:24 +0300] Started backend /usr/lib/cups/backend/smb (PID 5202) for job 27. E [15/Aug/2007:17:36:25 +0300] [Job 27] No ticket cache found for userid=0 E [15/Aug/2007:17:36:25 +0300] [Job 27] Can not get the ticket cache for root I [15/Aug/2007:17:36:27 +0300] commptr="" I [15/Aug/2007:17:36:27 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5203) I [15/Aug/2007:17:36:38 +0300] commptr="" I [15/Aug/2007:17:36:38 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5205) I [15/Aug/2007:17:38:29 +0300] commptr="?op=stop-printer&printer_name=HP-LaserJet-1000" I [15/Aug/2007:17:38:29 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5249) E [15/Aug/2007:17:38:29 +0300] Pause-Printer: Unauthorized I [15/Aug/2007:17:38:29 +0300] Saving printers.conf... I [15/Aug/2007:17:38:29 +0300] Printer "HP-LaserJet-1000" stopped by "root". I [15/Aug/2007:17:38:32 +0300] commptr="" I [15/Aug/2007:17:38:32 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5251) I [15/Aug/2007:17:38:34 +0300] commptr="?op=start-printer&printer_name=HP-LaserJet-1000" I [15/Aug/2007:17:38:34 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5252) E [15/Aug/2007:17:38:34 +0300] Resume-Printer: Unauthorized I [15/Aug/2007:17:38:35 +0300] Saving printers.conf... I [15/Aug/2007:17:38:35 +0300] Printer "HP-LaserJet-1000" started by "root". I [15/Aug/2007:17:38:36 +0300] commptr="" I [15/Aug/2007:17:38:36 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5254) I [15/Aug/2007:17:38:42 +0300] commptr="?op=delete-printer&printer_name=HP-LaserJet-1000" I [15/Aug/2007:17:38:42 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5257) I [15/Aug/2007:17:38:47 +0300] commptr="?op=modify-printer&printer_name=HP-LaserJet-1000" I [15/Aug/2007:17:38:47 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5258) I [15/Aug/2007:17:38:49 +0300] commptr="" I [15/Aug/2007:17:38:49 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5259) I [15/Aug/2007:17:38:49 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:17:38:49 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=5260) I [15/Aug/2007:17:45:54 +0300] commptr="" I [15/Aug/2007:17:45:54 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5330) I [15/Aug/2007:17:45:57 +0300] commptr="?op=print-test-page" I [15/Aug/2007:17:45:57 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5332) I [15/Aug/2007:17:45:57 +0300] Adding start banner page "none" to job 28. I [15/Aug/2007:17:45:57 +0300] Adding end banner page "none" to job 28. I [15/Aug/2007:17:45:57 +0300] Job 28 queued on "HP-LaserJet-1000" by "root". I [15/Aug/2007:17:45:57 +0300] Started filter /usr/lib/cups/filter/pstops (PID 5333) for job 28. I [15/Aug/2007:17:45:57 +0300] Started filter /usr/lib/cups/filter/pstoraster (PID 5334) for job 28. I [15/Aug/2007:17:45:57 +0300] Started filter /usr/lib/cups/filter/rastertogutenprint.5.0 (PID 5335) for job 28. I [15/Aug/2007:17:45:57 +0300] Started backend /usr/lib/cups/backend/smb (PID 5336) for job 28. E [15/Aug/2007:17:45:58 +0300] [Job 28] No ticket cache found for userid=0 E [15/Aug/2007:17:45:58 +0300] [Job 28] Can not get the ticket cache for root I [15/Aug/2007:17:46:00 +0300] commptr="" I [15/Aug/2007:17:46:00 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5337) I [15/Aug/2007:17:46:11 +0300] commptr="" I [15/Aug/2007:17:46:11 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5339) I [15/Aug/2007:17:47:20 +0300] commptr="?op=delete-printer&printer_name=HP-LaserJet-1000" I [15/Aug/2007:17:47:22 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5347) I [15/Aug/2007:17:47:27 +0300] commptr="?op=delete-printer&printer_name=HP-LaserJet-1000&confirm=yes" I [15/Aug/2007:17:47:27 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5354) E [15/Aug/2007:17:47:27 +0300] CUPS-Delete-Printer: Unauthorized I [15/Aug/2007:17:47:27 +0300] Printer "HP-LaserJet-1000" deleted by "root". I [15/Aug/2007:17:47:27 +0300] Saving printers.conf... I [15/Aug/2007:17:47:29 +0300] commptr="" I [15/Aug/2007:17:47:29 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5355) I [15/Aug/2007:17:47:32 +0300] commptr="" I [15/Aug/2007:17:47:32 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5356) I [15/Aug/2007:17:47:32 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:17:47:32 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=5357) I [15/Aug/2007:17:47:37 +0300] commptr="?OP=add-printer" I [15/Aug/2007:17:47:37 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5373) I [15/Aug/2007:17:47:44 +0300] commptr="" I [15/Aug/2007:17:47:44 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5374) I [15/Aug/2007:17:47:44 +0300] commptr="1+0+2+requested-attributes=all" I [15/Aug/2007:17:47:44 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=5375) I [15/Aug/2007:17:47:53 +0300] Scheduler shutting down normally. I [15/Aug/2007:17:47:53 +0300] Saving remote.cache... I [15/Aug/2007:17:47:53 +0300] Saving job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:17:47:54 +0300] Listening to 127.0.0.1:631 (IPv4) I [15/Aug/2007:17:47:54 +0300] Loaded configuration file "/etc/cups/cupsd.conf" I [15/Aug/2007:17:47:54 +0300] Using default TempDir of /var/spool/cups/tmp... I [15/Aug/2007:17:47:54 +0300] Cleaning out old temporary files in "/var/spool/cups/tmp"... I [15/Aug/2007:17:47:54 +0300] Configured for up to 100 clients. I [15/Aug/2007:17:47:54 +0300] Allowing up to 100 client connections per host. I [15/Aug/2007:17:47:54 +0300] Using policy "default" as the default! I [15/Aug/2007:17:47:54 +0300] Full reload is required. I [15/Aug/2007:17:47:54 +0300] Loaded MIME database from '/etc/cups': 34 types, 39 filters... I [15/Aug/2007:17:47:54 +0300] Loading job cache file "/var/cache/cups/job.cache"... I [15/Aug/2007:17:47:54 +0300] Full reload complete. I [15/Aug/2007:17:47:54 +0300] Listening to 127.0.0.1:631 on fd 2... E [15/Aug/2007:17:47:54 +0300] Unable to set ACLs on root certificate "/var/run/cups/certs/0" - Operation not supported I [15/Aug/2007:17:48:52 +0300] Scheduler shutting down normally. I [15/Aug/2007:17:48:52 +0300] Saving remote.cache... I [15/Aug/2007:17:48:52 +0300] Saving job cache file "/var/cache/cups/job.cache"... I [16/Aug/2007:09:52:42 +0300] Listening to 127.0.0.1:631 (IPv4) I [16/Aug/2007:09:52:42 +0300] Loaded configuration file "/etc/cups/cupsd.conf" I [16/Aug/2007:09:52:42 +0300] Using default TempDir of /var/spool/cups/tmp... I [16/Aug/2007:09:52:42 +0300] Cleaning out old temporary files in "/var/spool/cups/tmp"... I [16/Aug/2007:09:52:42 +0300] Configured for up to 100 clients. I [16/Aug/2007:09:52:42 +0300] Allowing up to 100 client connections per host. I [16/Aug/2007:09:52:42 +0300] Using policy "default" as the default! I [16/Aug/2007:09:52:42 +0300] Full reload is required. I [16/Aug/2007:09:52:42 +0300] Loaded MIME database from '/etc/cups': 34 types, 39 filters... I [16/Aug/2007:09:52:42 +0300] Loading job cache file "/var/cache/cups/job.cache"... I [16/Aug/2007:09:52:42 +0300] Full reload complete. I [16/Aug/2007:09:52:42 +0300] Listening to 127.0.0.1:631 on fd 2... E [16/Aug/2007:09:52:42 +0300] Unable to set ACLs on root certificate "/var/run/cups/certs/0" - Operation not supported I [16/Aug/2007:10:09:37 +0300] commptr="" I [16/Aug/2007:10:09:37 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5110) I [16/Aug/2007:10:09:42 +0300] commptr="?OP=add-printer" I [16/Aug/2007:10:09:42 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5111) I [16/Aug/2007:10:09:50 +0300] commptr="" I [16/Aug/2007:10:09:50 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5113) I [16/Aug/2007:10:09:50 +0300] commptr="1+0+2+requested-attributes=all" I [16/Aug/2007:10:09:50 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=5114) I [16/Aug/2007:10:09:58 +0300] commptr="" I [16/Aug/2007:10:09:58 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5130) I [16/Aug/2007:10:12:50 +0300] commptr="" I [16/Aug/2007:10:12:50 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5234) I [16/Aug/2007:10:12:50 +0300] commptr="list+1+0+requested-attributes='ppd-make'" I [16/Aug/2007:10:12:50 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=5235) I [16/Aug/2007:10:15:45 +0300] commptr="" I [16/Aug/2007:10:15:45 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5391) I [16/Aug/2007:10:15:45 +0300] commptr="list+1+0+requested-attributes=all%20ppd-make='HP'" I [16/Aug/2007:10:15:45 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=5392) I [16/Aug/2007:10:17:48 +0300] commptr="" I [16/Aug/2007:10:17:48 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5439) E [16/Aug/2007:10:17:48 +0300] CUPS-Add-Modify-Printer: Unauthorized I [16/Aug/2007:10:17:55 +0300] commptr="" I [16/Aug/2007:10:17:55 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5440) E [16/Aug/2007:10:17:55 +0300] CUPS-Add-Modify-Printer: Unauthorized I [16/Aug/2007:10:17:55 +0300] Setting hpLaserJ device-uri to "smb://192.168.0.109/hpLaserJ" (was "file:/dev/null".) I [16/Aug/2007:10:17:55 +0300] Setting hpLaserJ printer-is-accepting-jobs to 1 (was 0.) I [16/Aug/2007:10:17:55 +0300] Setting hpLaserJ printer-state to 3 (was 5.) I [16/Aug/2007:10:17:55 +0300] Saving printers.conf... I [16/Aug/2007:10:17:55 +0300] New printer "hpLaserJ" added by "root". I [16/Aug/2007:10:18:00 +0300] commptr="?OP=set-printer-options&PRINTER_NAME=hpLaserJ" I [16/Aug/2007:10:18:01 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5457) I [16/Aug/2007:10:20:04 +0300] commptr="" I [16/Aug/2007:10:20:04 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5622) E [16/Aug/2007:10:20:04 +0300] CUPS-Add-Modify-Printer: Unauthorized I [16/Aug/2007:10:20:04 +0300] Saving printers.conf... I [16/Aug/2007:10:20:04 +0300] Printer "hpLaserJ" modified by "root". I [16/Aug/2007:10:20:09 +0300] commptr="?OP=redirect&URL=/printers/hpLaserJ" I [16/Aug/2007:10:20:09 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5624) I [16/Aug/2007:10:20:09 +0300] commptr="" I [16/Aug/2007:10:20:10 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5626) I [16/Aug/2007:10:21:33 +0300] commptr="?op=print-test-page" I [16/Aug/2007:10:21:33 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5801) I [16/Aug/2007:10:21:34 +0300] Adding start banner page "none" to job 29. I [16/Aug/2007:10:21:34 +0300] Adding end banner page "none" to job 29. I [16/Aug/2007:10:21:34 +0300] Job 29 queued on "hpLaserJ" by "guest". I [16/Aug/2007:10:21:34 +0300] Started filter /usr/lib/cups/filter/pstops (PID 5802) for job 29. I [16/Aug/2007:10:21:34 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 5803) for job 29. I [16/Aug/2007:10:21:34 +0300] Started backend /usr/lib/cups/backend/smb (PID 5804) for job 29. I [16/Aug/2007:10:21:36 +0300] commptr="" I [16/Aug/2007:10:21:36 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5815) I [16/Aug/2007:10:21:40 +0300] commptr="" I [16/Aug/2007:10:21:40 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=5816) E [16/Aug/2007:10:21:43 +0300] [Job 29] /ioerror in --.outputpage-- E [16/Aug/2007:10:21:43 +0300] PID 5803 (/usr/lib/cups/filter/foomatic-rip) stopped with status 1! I [16/Aug/2007:10:21:43 +0300] Hint: Try setting the LogLevel to "debug" to find out more. I [16/Aug/2007:10:22:21 +0300] commptr="" I [16/Aug/2007:10:22:21 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=5835) I [16/Aug/2007:10:22:25 +0300] commptr="" I [16/Aug/2007:10:22:25 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5836) I [16/Aug/2007:10:26:41 +0300] commptr="" I [16/Aug/2007:10:26:41 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=6065) I [16/Aug/2007:10:26:44 +0300] commptr="1+0+2+requested-attributes=all" I [16/Aug/2007:10:26:44 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=6066) I [16/Aug/2007:10:26:48 +0300] commptr="?op=purge-jobs&printer_name=hpLaserJ" I [16/Aug/2007:10:26:48 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=6078) E [16/Aug/2007:10:26:48 +0300] Purge-Jobs: Unauthorized I [16/Aug/2007:10:26:57 +0300] commptr="?op=purge-jobs&printer_name=hpLaserJ" I [16/Aug/2007:10:26:57 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=6082) E [16/Aug/2007:10:26:57 +0300] Purge-Jobs: Unauthorized I [16/Aug/2007:10:26:57 +0300] All jobs on "hpLaserJ" were purged by "root". I [16/Aug/2007:10:27:03 +0300] commptr="?OP=add-printer" I [16/Aug/2007:10:27:03 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=6084) I [16/Aug/2007:10:36:11 +0300] commptr="" I [16/Aug/2007:10:36:11 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=6957) I [16/Aug/2007:10:36:11 +0300] commptr="1+0+2+requested-attributes=all" I [16/Aug/2007:10:36:11 +0300] Started "/usr/lib/cups/daemon/cups-deviced" (pid=6958) I [16/Aug/2007:10:36:21 +0300] commptr="" I [16/Aug/2007:10:36:21 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=6980) I [16/Aug/2007:10:36:42 +0300] commptr="" I [16/Aug/2007:10:36:42 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=6993) I [16/Aug/2007:10:36:42 +0300] commptr="list+1+0+requested-attributes='ppd-make'" I [16/Aug/2007:10:36:42 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=6994) I [16/Aug/2007:10:38:04 +0300] commptr="" I [16/Aug/2007:10:38:04 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=7056) I [16/Aug/2007:10:38:04 +0300] commptr="list+1+0+requested-attributes=all%20ppd-make='HP'" I [16/Aug/2007:10:38:04 +0300] Started "/usr/lib/cups/daemon/cups-driverd" (pid=7057) I [16/Aug/2007:10:56:31 +0300] commptr="" I [16/Aug/2007:10:56:31 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=8281) E [16/Aug/2007:10:56:31 +0300] CUPS-Add-Modify-Printer: Unauthorized I [16/Aug/2007:10:56:31 +0300] commptr="" I [16/Aug/2007:10:56:31 +0300] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=8282) E [16/Aug/2007:10:56:31 +0300] CUPS-Add-Modify-Printer: Unauthorized I [16/Aug/2007:10:56:31 +0300] Setting hppsc110 device-uri to "smb://192.168.0.119/hppsc110" (was "file:/dev/null".) I [16/Aug/2007:10:56:31 +0300] Setting hppsc110 printer-is-accepting-jobs to 1 (was 0.) I [16/Aug/2007:10:56:31 +0300] Setting hppsc110 printer-state to 3 (was 5.) I [16/Aug/2007:10:56:31 +0300] Saving printers.conf... I [16/Aug/2007:10:56:31 +0300] New printer "hppsc110" added by "root". I [16/Aug/2007:10:56:34 +0300] commptr="" I [16/Aug/2007:10:56:34 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8285) I [16/Aug/2007:10:56:39 +0300] commptr="?op=print-test-page" I [16/Aug/2007:10:56:39 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8290) I [16/Aug/2007:10:56:40 +0300] Adding start banner page "none" to job 30. I [16/Aug/2007:10:56:40 +0300] Adding end banner page "none" to job 30. I [16/Aug/2007:10:56:40 +0300] Job 30 queued on "hppsc110" by "root". I [16/Aug/2007:10:56:40 +0300] Started filter /usr/lib/cups/filter/pstops (PID 8291) for job 30. I [16/Aug/2007:10:56:40 +0300] Started filter /usr/lib/cups/filter/foomatic-rip (PID 8292) for job 30. I [16/Aug/2007:10:56:40 +0300] Started backend /usr/lib/cups/backend/smb (PID 8293) for job 30. E [16/Aug/2007:10:56:41 +0300] [Job 30] No ticket cache found for userid=0 E [16/Aug/2007:10:56:41 +0300] [Job 30] Can not get the ticket cache for root I [16/Aug/2007:10:56:42 +0300] commptr="" I [16/Aug/2007:10:56:42 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8302) I [16/Aug/2007:10:56:43 +0300] commptr="" I [16/Aug/2007:10:56:43 +0300] Started "/usr/lib/cups/cgi-bin/jobs.cgi" (pid=8303) E [16/Aug/2007:10:56:43 +0300] PID 8302 (/usr/lib/cups/cgi-bin/printers.cgi) crashed on signal 9! I [16/Aug/2007:10:56:43 +0300] Hint: Try setting the LogLevel to "debug" to find out more. E [16/Aug/2007:10:56:45 +0300] PID 8292 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3! I [16/Aug/2007:10:56:45 +0300] Hint: Try setting the LogLevel to "debug" to find out more. I [16/Aug/2007:10:56:50 +0300] commptr="" I [16/Aug/2007:10:56:50 +0300] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=8305)