I [11/Sep/2006:12:50:39 +0200] Saving job cache file "/var/cache/cups/job.cache"...
I [11/Sep/2006:12:50:40 +0200] Listening to 127.0.0.1:631 (IPv4)
I [11/Sep/2006:12:50:40 +0200] Loaded configuration file "/etc/cups/cupsd.conf"
I [11/Sep/2006:12:50:40 +0200] Using default TempDir of /var/spool/cups/tmp...
I [11/Sep/2006:12:50:40 +0200] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [11/Sep/2006:12:50:40 +0200] Configured for up to 100 clients.
I [11/Sep/2006:12:50:40 +0200] Allowing up to 100 client connections per host.
I [11/Sep/2006:12:50:40 +0200] Using policy "default" as the default!
I [11/Sep/2006:12:50:40 +0200] Full reload is required.
I [11/Sep/2006:12:50:40 +0200] Loaded MIME database from '/etc/cups': 34 types, 39 filters...
D [11/Sep/2006:12:50:40 +0200] Loading printer EPSON1290...
D [11/Sep/2006:12:50:41 +0200] Loading printer LexmarkOptra...
I [11/Sep/2006:12:50:41 +0200] Loading job cache file "/var/cache/cups/job.cache"...
D [11/Sep/2006:12:50:41 +0200] Loading job 10 from cache...
D [11/Sep/2006:12:50:41 +0200] Loading attributes for job 10...
I [11/Sep/2006:12:50:41 +0200] Full reload complete.
I [11/Sep/2006:12:50:41 +0200] Listening to 127.0.0.1:631 on fd 2...
E [11/Sep/2006:12:50:41 +0200] Unable to set ACLs on root certificate "/var/run/cups/certs/0" - Operation not supported
D [11/Sep/2006:12:50:42 +0200] cupsdAcceptClient: 6 from localhost:631 (IPv4)
D [11/Sep/2006:12:50:42 +0200] cupsdReadClient: 6 GET /admin/?op=start-printer&printer_name=LexmarkOptra HTTP/1.1
D [11/Sep/2006:12:50:42 +0200] cupsdReadClient: 6 Browser asked for language "en-us.utf-8"...
D [11/Sep/2006:12:50:42 +0200] cupsdAuthorize: username="root"
D [11/Sep/2006:12:50:42 +0200] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 5733
I [11/Sep/2006:12:50:42 +0200] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5733)
D [11/Sep/2006:12:50:42 +0200] cupsdSendCommand: 6 file=7
D [11/Sep/2006:12:50:42 +0200] [CGI] admin.cgi started...
D [11/Sep/2006:12:50:42 +0200] [CGI] http=0x8077860
D [11/Sep/2006:12:50:42 +0200] [CGI] op="start-printer"...
D [11/Sep/2006:12:50:42 +0200] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [11/Sep/2006:12:50:42 +0200] cupsdReadClient: 9 POST /admin/ HTTP/1.1
D [11/Sep/2006:12:50:42 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:50:42 +0200] Resume-Printer ipp://localhost/printers/LexmarkOptra
E [11/Sep/2006:12:50:42 +0200] Resume-Printer: Unauthorized
D [11/Sep/2006:12:50:42 +0200] cupsdSendError: 9 code=401 (Unauthorized)
D [11/Sep/2006:12:50:43 +0200] cupsdCloseClient: 9
D [11/Sep/2006:12:50:43 +0200] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [11/Sep/2006:12:50:43 +0200] cupsdReadClient: 9 POST /admin/ HTTP/1.1
D [11/Sep/2006:12:50:43 +0200] cupsdAuthorize: username="root"
D [11/Sep/2006:12:50:43 +0200] Resume-Printer ipp://localhost/printers/LexmarkOptra
D [11/Sep/2006:12:50:43 +0200] Discarding unused printer-state-changed event...
I [11/Sep/2006:12:50:43 +0200] Saving printers.conf...
I [11/Sep/2006:12:50:43 +0200] Printer "LexmarkOptra" started by "root".
D [11/Sep/2006:12:50:43 +0200] Discarding unused printer-state-changed event...
D [11/Sep/2006:12:50:43 +0200] job-sheets=none,none
D [11/Sep/2006:12:50:43 +0200] banner_page = 0
D [11/Sep/2006:12:50:43 +0200] [Job 10] argv[0]="LexmarkOptra"
D [11/Sep/2006:12:50:43 +0200] [Job 10] argv[1]="10"
D [11/Sep/2006:12:50:43 +0200] [Job 10] argv[2]="root"
D [11/Sep/2006:12:50:43 +0200] [Job 10] argv[3]="Test Page"
D [11/Sep/2006:12:50:43 +0200] [Job 10] argv[4]="1"
D [11/Sep/2006:12:50:43 +0200] [Job 10] argv[5]="job-uuid=urn:uuid:367a68a5-eaa2-3300-78ae-d18a45ca5f6f"
D [11/Sep/2006:12:50:43 +0200] [Job 10] argv[6]="/var/spool/cups/d00010-001"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[9]="SERVER_ADMIN=root@localhost.localdomain"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[10]="SOFTWARE=CUPS/1.2.3"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[12]="USER=root"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[13]="CUPS_SERVER=localhost"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[14]="CUPS_ENCRYPTION=IfRequested"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[15]="IPP_PORT=631"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[16]="CHARSET=utf-8"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[17]="LANG=en_US"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[18]="PPD=/etc/cups/ppd/LexmarkOptra.ppd"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[19]="RIP_MAX_CACHE=8m"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[20]="CONTENT_TYPE=application/postscript"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[21]="DEVICE_URI=lpd://145.44.64.30/o_zw1/"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[22]="PRINTER=LexmarkOptra"
D [11/Sep/2006:12:50:43 +0200] [Job 10] envp[23]="FINAL_CONTENT_TYPE=printer/LexmarkOptra"
I [11/Sep/2006:12:50:43 +0200] Started filter /usr/lib/cups/filter/pstops (PID 5734) for job 10.
I [11/Sep/2006:12:50:43 +0200] Started filter /usr/lib/cups/filter/foomatic-rip (PID 5735) for job 10.
I [11/Sep/2006:12:50:43 +0200] Started backend /usr/lib/cups/backend/lpd (PID 5736) for job 10.
D [11/Sep/2006:12:50:43 +0200] Discarding unused job-state event...
D [11/Sep/2006:12:50:43 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [11/Sep/2006:12:50:43 +0200] PID 5734 (/usr/lib/cups/filter/pstops) exited with no errors.
D [11/Sep/2006:12:50:43 +0200] [Job 10] Page = 595x842; 0,0 to 595,842
D [11/Sep/2006:12:50:43 +0200] [Job 10] slow_collate=0, slow_duplex=0, slow_order=0
D [11/Sep/2006:12:50:43 +0200] [Job 10] Before copy_comments - %!PS-Adobe-3.0
D [11/Sep/2006:12:50:43 +0200] [Job 10] %!PS-Adobe-3.0
D [11/Sep/2006:12:50:43 +0200] [Job 10] %%BoundingBox: 0 0 612 792
D [11/Sep/2006:12:50:43 +0200] [Job 10] %%Pages: 1
D [11/Sep/2006:12:50:43 +0200] [Job 10] %%LanguageLevel: 1
D [11/Sep/2006:12:50:43 +0200] [Job 10] %%DocumentData: Clean7Bit
D [11/Sep/2006:12:50:43 +0200] [Job 10] %%DocumentSuppliedResources: procset testprint/1.2
D [11/Sep/2006:12:50:43 +0200] [Job 10] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [11/Sep/2006:12:50:43 +0200] [Job 10] %%Creator: Michael Sweet, Easy Software Products
D [11/Sep/2006:12:50:43 +0200] [Job 10] %%CreationDate: D:20060114093000+0500
D [11/Sep/2006:12:50:43 +0200] [Job 10] %%Title: Test Page
D [11/Sep/2006:12:50:43 +0200] [Job 10] %%EndComments
D [11/Sep/2006:12:50:43 +0200] [Job 10] Before copy_prolog - %%BeginProlog
D [11/Sep/2006:12:50:43 +0200] [Job 10] Before copy_setup - %%Page: 1 1
D [11/Sep/2006:12:50:43 +0200] [Job 10] Before page loop - %%Page: 1 1
D [11/Sep/2006:12:50:43 +0200] [Job 10] Copying page 1...
D [11/Sep/2006:12:50:43 +0200] [Job 10] pagew = 595.0, pagel = 842.0
D [11/Sep/2006:12:50:43 +0200] [Job 10] bboxw = 595, bboxl = 842
D [11/Sep/2006:12:50:43 +0200] [Job 10] PageLeft = 0.0, PageRight = 595.0
D [11/Sep/2006:12:50:43 +0200] [Job 10] PageTop = 842.0, PageBottom = 0.0
D [11/Sep/2006:12:50:43 +0200] [Job 10] PageWidth = 595.0, PageLength = 842.0
D [11/Sep/2006:12:50:43 +0200] [Job 10] Wrote 1 pages...
D [11/Sep/2006:12:50:43 +0200] PID 5733 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [11/Sep/2006:12:50:43 +0200] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [11/Sep/2006:12:50:43 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:43 +0200] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:43 +0200] [CGI] "{title}" at 205...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting "{refresh_page?" at 374, result=1...
D [11/Sep/2006:12:50:43 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 374...
D [11/Sep/2006:12:50:43 +0200] [CGI] "{refresh_page}" at 424...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 427 on character ':'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 427...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 428 on character '}'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Finished "{refresh_page?", out=0xb7e0f4c0...
D [11/Sep/2006:12:50:43 +0200] [CGI] "{title}" at 671...
D [11/Sep/2006:12:50:43 +0200] [CGI] "{title}" at 952...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting "{SECTION=admin" at 1411, result=1...
D [11/Sep/2006:12:50:43 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 1411...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 1412 on character ':'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 1412...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 1415 on character '}'...
D [11/Sep/2006:12:50:43 +0200] cupsdCloseClient: 9
D [11/Sep/2006:12:50:43 +0200] [CGI] Finished "{SECTION=admin", out=0xb7e0f4c0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 1678...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 1679 on character ':'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 1679...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 1682 on character '}'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Finished "{SECTION=classes", out=0xb7e0f4c0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 1938...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 1939 on character ':'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 1939...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 1942 on character '}'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Finished "{SECTION=help", out=0xb7e0f4c0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting "{SECTION=jobs" at 2206, result=0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 2206...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 2207 on character ':'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 2207...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 2210 on character '}'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Finished "{SECTION=jobs", out=0xb7e0f4c0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting "{SECTION=printers" at 2464, result=0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 2464...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 2465 on character ':'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 2465...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 2468 on character '}'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Finished "{SECTION=printers", out=0xb7e0f4c0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 2830 on EOF...
D [11/Sep/2006:12:50:43 +0200] [CGI] cgiCopyTemplateLang(tmpl="printer-start.tmpl")
D [11/Sep/2006:12:50:43 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:43 +0200] [CGI] Template file is "/usr/share/cups/templates/printer-start.tmpl"...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting "{is_class?" at 13, result=0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 13...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 19 on character ':'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 19...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 27 on character '}'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Finished "{is_class?", out=0xb7e0f4c0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting "{is_class?" at 48, result=0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 48...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 56 on character ':'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 56...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 65 on character '}'...
D [11/Sep/2006:12:50:43 +0200] [CGI] Finished "{is_class?", out=0xb7e0f4c0...
D [11/Sep/2006:12:50:43 +0200] [CGI] "{printer_name}" at 80...
D [11/Sep/2006:12:50:43 +0200] [CGI] "{printer_name}" at 96...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 123 on EOF...
D [11/Sep/2006:12:50:43 +0200] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [11/Sep/2006:12:50:43 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:43 +0200] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [11/Sep/2006:12:50:43 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:43 +0200] [CGI] Returning at file position 614 on EOF...
D [11/Sep/2006:12:50:44 +0200] [Job 10] foomatic-rip version $Revision: 3.48 $ running...
D [11/Sep/2006:12:50:44 +0200] [Job 10] Parsing PPD file ...
D [11/Sep/2006:12:50:44 +0200] [Job 10] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [11/Sep/2006:12:50:44 +0200] [Job 10] Added option ColorSpace
D [11/Sep/2006:12:50:44 +0200] [Job 10] Added option PageSize
D [11/Sep/2006:12:50:44 +0200] [Job 10] Added option PageRegion
D [11/Sep/2006:12:50:44 +0200] [Job 10] Added option ImageableArea
D [11/Sep/2006:12:50:44 +0200] [Job 10] Added option PaperDimension
D [11/Sep/2006:12:50:44 +0200] [Job 10] Added option Resolution
D [11/Sep/2006:12:50:44 +0200] [Job 10] Added option InputSlot
D [11/Sep/2006:12:50:44 +0200] [Job 10] Added option Duplex
D [11/Sep/2006:12:50:44 +0200] [Job 10] Added option PreFilter
D [11/Sep/2006:12:50:44 +0200] [Job 10] Added option Font
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] Parameter Summary
D [11/Sep/2006:12:50:44 +0200] [Job 10] -----------------
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] Spooler: cups
D [11/Sep/2006:12:50:44 +0200] [Job 10] Printer: LexmarkOptra
D [11/Sep/2006:12:50:44 +0200] [Job 10] PPD file: /etc/cups/ppd/LexmarkOptra.ppd
D [11/Sep/2006:12:50:44 +0200] [Job 10] Printer model: Lexmark Optra S 1855 Foomatic/Postscript (recommended)
D [11/Sep/2006:12:50:44 +0200] [Job 10] Job title: Test Page
D [11/Sep/2006:12:50:44 +0200] [Job 10] File(s) to be printed: 
D [11/Sep/2006:12:50:44 +0200] [Job 10] <STDIN>
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] Pondering option 'job-uuid=urn:uuid:367a68a5-eaa2-3300-78ae-d18a45ca5f6f'
D [11/Sep/2006:12:50:44 +0200] [Job 10] Unknown option job-uuid=urn:uuid:367a68a5-eaa2-3300-78ae-d18a45ca5f6f.
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] ================================================
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] File: <STDIN>
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] ================================================
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] Reading PostScript input ...
D [11/Sep/2006:12:50:44 +0200] [Job 10] --> This document is DSC-conforming!
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] -----------
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%BeginProlog
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%EndProlog
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] -----------
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%BeginSetup
D [11/Sep/2006:12:50:44 +0200] [Job 10] Inserting PostScript code for CUPS' page accounting
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%BeginFeature: *PageRegion A4
D [11/Sep/2006:12:50:44 +0200] [Job 10] Option: PageRegion=A4 --> Option will be set by PostScript interpreter
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%BeginFeature: *PreFilter No
D [11/Sep/2006:12:50:44 +0200] [Job 10] Option: PreFilter=No --> Setting option
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %% FoomaticRIPOptionSetting: PreFilter=No
D [11/Sep/2006:12:50:44 +0200] [Job 10] Option: PreFilter=No --> Setting option
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%BeginFeature: *Resolution default
D [11/Sep/2006:12:50:44 +0200] [Job 10] Option: Resolution=default --> Setting option
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %% FoomaticRIPOptionSetting: Resolution=default
D [11/Sep/2006:12:50:44 +0200] [Job 10] Option: Resolution=default --> Setting option
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%BeginFeature: *InputSlot Default
D [11/Sep/2006:12:50:44 +0200] [Job 10] Option: InputSlot=Default --> Setting option
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %% FoomaticRIPOptionSetting: InputSlot=Default
D [11/Sep/2006:12:50:44 +0200] [Job 10] Option: InputSlot=Default --> Setting option
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%BeginFeature: *Duplex None
D [11/Sep/2006:12:50:44 +0200] [Job 10] Option: Duplex=None --> Setting option
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %% FoomaticRIPOptionSetting: Duplex=None
D [11/Sep/2006:12:50:44 +0200] [Job 10] Option: Duplex=None --> Setting option
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%EndSetup
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] -----------
D [11/Sep/2006:12:50:44 +0200] [Job 10] New page:  1 1
D [11/Sep/2006:12:50:44 +0200] [Job 10] Inserting option code into "PageSetup" section.
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%BeginPageSetup
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found: %%EndPageSetup
D [11/Sep/2006:12:50:44 +0200] [Job 10] End of page header
D [11/Sep/2006:12:50:44 +0200] [Job 10] Stopping search for page header options
D [11/Sep/2006:12:50:44 +0200] [Job 10] Found:
D [11/Sep/2006:12:50:44 +0200] [Job 10] wheelSize -0.4 mul 0 rlineto	% Left side...
D [11/Sep/2006:12:50:44 +0200] [Job 10] --> Output goes directly to the renderer now.
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] Starting renderer
D [11/Sep/2006:12:50:44 +0200] [Job 10] JCL: <job data> 
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] renderer PID kid4=5738
D [11/Sep/2006:12:50:44 +0200] [Job 10] renderer command: level=0; /usr/bin/printf "%%!\n%%%% %%%%\n<</ManualFeed false>>setpagedevice\n<</Duplex false>>setpagedevice\n"; if [ $level -gt 0 ]; then if [ $level -lt 99 ]; then level=" -dLanguageLevel=$level"; else level=""; fi; gs -q -dPARANOIDSAFER -dNOPAUSE -dBATCH -sDEVICE=pswrite$level -sOutputFile=- -; else cat; fi
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] Closing renderer
D [11/Sep/2006:12:50:44 +0200] [Job 10] KID3 exited with status 0
D [11/Sep/2006:12:50:44 +0200] [Job 10] tail process done writing data to STDOUT
D [11/Sep/2006:12:50:44 +0200] [Job 10] KID4 exited with status 0
D [11/Sep/2006:12:50:44 +0200] [Job 10] Renderer exit stat: 0
D [11/Sep/2006:12:50:44 +0200] [Job 10] KID4 finished
D [11/Sep/2006:12:50:44 +0200] [Job 10] KID3 finished
D [11/Sep/2006:12:50:44 +0200] [Job 10] Renderer process finished
D [11/Sep/2006:12:50:44 +0200] [Job 10] 
D [11/Sep/2006:12:50:44 +0200] [Job 10] Closing foomatic-rip.
D [11/Sep/2006:12:50:44 +0200] PID 5735 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [11/Sep/2006:12:50:44 +0200] [Job 10] Connected on ports 515 (local 0)...
D [11/Sep/2006:12:50:44 +0200] [Job 10] lpd_command 02 o_zw1/
D [11/Sep/2006:12:50:44 +0200] [Job 10] Sending command string (8 bytes)...
D [11/Sep/2006:12:50:44 +0200] [Job 10] Reading command status...
D [11/Sep/2006:12:50:44 +0200] [Job 10] lpd_command returning 1
E [11/Sep/2006:12:50:44 +0200] PID 5736 (/usr/lib/cups/backend/lpd) stopped with status 1!
D [11/Sep/2006:12:50:44 +0200] [Job 10] File 0 is complete.
I [11/Sep/2006:12:50:44 +0200] [Job 10] Backend returned status 1 (failed)
D [11/Sep/2006:12:50:44 +0200] Discarding unused printer-state-changed event...
I [11/Sep/2006:12:50:44 +0200] Saving printers.conf...
D [11/Sep/2006:12:50:48 +0200] cupsdReadClient: 6 GET /admin/?OP=redirect&URL=/printers/LexmarkOptra HTTP/1.1
D [11/Sep/2006:12:50:48 +0200] cupsdReadClient: 6 Browser asked for language "en-us.utf-8"...
D [11/Sep/2006:12:50:48 +0200] cupsdAuthorize: username="root"
D [11/Sep/2006:12:50:48 +0200] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 5742
I [11/Sep/2006:12:50:48 +0200] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5742)
D [11/Sep/2006:12:50:48 +0200] cupsdSendCommand: 6 file=7
D [11/Sep/2006:12:50:48 +0200] [CGI] admin.cgi started...
D [11/Sep/2006:12:50:49 +0200] PID 5742 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [11/Sep/2006:12:50:49 +0200] [CGI] http=0x8077860
D [11/Sep/2006:12:50:49 +0200] [CGI] op="redirect"...
D [11/Sep/2006:12:50:49 +0200] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [11/Sep/2006:12:50:49 +0200] cupsdCloseClient: 9
D [11/Sep/2006:12:50:49 +0200] cupsdAcceptClient: 7 from localhost:631 (IPv4)
D [11/Sep/2006:12:50:49 +0200] cupsdReadClient: 7 GET /printers/LexmarkOptra HTTP/1.1
D [11/Sep/2006:12:50:49 +0200] cupsdReadClient: 7 Browser asked for language "en-us.utf-8"...
D [11/Sep/2006:12:50:49 +0200] cupsdAuthorize: username="root"
D [11/Sep/2006:12:50:49 +0200] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 5743
I [11/Sep/2006:12:50:49 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5743)
D [11/Sep/2006:12:50:49 +0200] cupsdSendCommand: 7 file=9
D [11/Sep/2006:12:50:49 +0200] cupsdAcceptClient: 10 from localhost:631 (IPv4)
D [11/Sep/2006:12:50:49 +0200] cupsdReadClient: 10 POST / HTTP/1.1
D [11/Sep/2006:12:50:49 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:50:49 +0200] CUPS-Get-Default
D [11/Sep/2006:12:50:49 +0200] CUPS-Get-Default client-error-not-found: No default printer
D [11/Sep/2006:12:50:49 +0200] cupsdProcessIPPRequest: 10 status_code=406 (client-error-not-found)
D [11/Sep/2006:12:50:49 +0200] [CGI] show_printer(http=0x80711b8, printer="LexmarkOptra")
D [11/Sep/2006:12:50:49 +0200] cupsdReadClient: 10 POST / HTTP/1.1
D [11/Sep/2006:12:50:49 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:50:49 +0200] Get-Printer-Attributes ipp://localhost/printers/LexmarkOptra
D [11/Sep/2006:12:50:49 +0200] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [11/Sep/2006:12:50:49 +0200] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [11/Sep/2006:12:50:49 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:49 +0200] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [11/Sep/2006:12:50:49 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:49 +0200] [CGI] "{title}" at 205...
D [11/Sep/2006:12:50:49 +0200] [CGI] Starting "{refresh_page?" at 374, result=0...
D [11/Sep/2006:12:50:50 +0200] cupsdReadClient: 10 POST / HTTP/1.1
D [11/Sep/2006:12:50:50 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:50:50 +0200] Get-Jobs ipp://localhost:631/printers/LexmarkOptra
D [11/Sep/2006:12:50:50 +0200] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 374...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{refresh_page}" at 424...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 427 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 427...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 428 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{refresh_page?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{title}" at 671...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{title}" at 952...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{SECTION=admin" at 1411, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1411...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1412 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1412...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1415 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{SECTION=admin", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1678...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1679 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1679...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1682 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{SECTION=classes", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1938...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1939 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1939...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1942 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{SECTION=help", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{SECTION=jobs" at 2206, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 2206...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 2207 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 2207...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 2210 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{SECTION=jobs", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{SECTION=printers" at 2464, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 2464...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 2465 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 2465...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 2468 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{SECTION=printers", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 2830 on EOF...
D [11/Sep/2006:12:50:50 +0200] [CGI] cgiCopyTemplateLang(tmpl="printers.tmpl")
D [11/Sep/2006:12:50:50 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Template file is "/usr/share/cups/templates/printers.tmpl"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{printer_type?" at 14, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 14...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 15 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 15...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 16 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{printer_type?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{#printer_name=0" at 33, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 33...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 34 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 34...
D [11/Sep/2006:12:50:50 +0200] [CGI] Looping on "printer_name" at 50, count=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 50...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 101...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 117...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{default_name=LexmarkOptra" at 150, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 150...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 169 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 169...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 170 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{default_name=LexmarkOptra", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?printer_state_message=" at 196, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 196...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 197 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 197...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_state_message}" at 243...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 252 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?printer_state_message=", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 349...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 406...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{printer_state=3" at 452, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 452...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 457 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 457...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{printer_state=4" at 474, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 474...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 485 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 485...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 493 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{printer_state=4", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 494 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{printer_state=3", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_info}" at 584...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_location}" at 624...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_make_and_model}" at 676...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{printer_state=3" at 720, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 720...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 725 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 725...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{printer_state=4" at 742, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 742...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 753 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 753...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 761 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{printer_state=4", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 762 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{printer_state=3", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{printer_is_accepting_jobs=0" at 793, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 793...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 808 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 808...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 823 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{printer_is_shared=0" at 846, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 846...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 850 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 850...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 851 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{printer_is_shared=0", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?device_uri=" at 877, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 877...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 878 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 878...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{device_uri}" at 913...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 914 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?device_uri=", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 952...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?cupscommand=1" at 1078, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1078...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 1110...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 1258...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1383 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1383...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1384 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?cupscommand=1", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{printer_state=5" at 1402, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1402...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 1423...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 1473...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1561 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1561...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 1582...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 1631...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1717 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{printer_state=5", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{printer_is_accepting_jobs=0" at 1747, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1747...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 1768...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 1816...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1900 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1900...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 1921...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 1969...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 2053 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 2086...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 2132...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 2235...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 2282...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{printer_is_shared=0" at 2394, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 2394...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 2415...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 2463...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 2568 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 2568...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 2589...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 2637...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 2746 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{printer_is_shared=0", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 2767...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 2818...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 2927...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 2983...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 3102...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 3153...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 3262...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 3313...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{admin_uri}" at 3422...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_name}" at 3476...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 3598 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished looping on "printer_name"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 3599 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{#printer_name=0", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 3600 on EOF...
D [11/Sep/2006:12:50:50 +0200] [CGI] cgiCopyTemplateLang(tmpl="printer-jobs-header.tmpl")
D [11/Sep/2006:12:50:50 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Template file is "/usr/share/cups/templates/printer-jobs-header.tmpl"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 28 on EOF...
D [11/Sep/2006:12:50:50 +0200] PID 5743 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [11/Sep/2006:12:50:50 +0200] [CGI] cgiCopyTemplateLang(tmpl="search.tmpl")
D [11/Sep/2006:12:50:50 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Template file is "/usr/share/cups/templates/search.tmpl"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{SECTION}" at 24...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{?SEARCH_DEST}" at 39...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{WHICH_JOBS?" at 67, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 67...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{WHICH_JOBS}" at 125...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 128 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] cupsdCloseClient: 10
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 128...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 129 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{WHICH_JOBS?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{ORDER?" at 137, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 137...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{ORDER}" at 185...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 188 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 188...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 189 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{ORDER?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{SEARCH_DEST?" at 235, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 235...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{SEARCH_DEST}" at 248...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 249 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 249...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{SECTION=classes" at 266, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 266...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 274 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 274...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{SECTION=jobs" at 288, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 288...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 293 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 293...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 302 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{SECTION=jobs", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 303 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{SECTION=classes", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 304 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{SEARCH_DEST?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{?QUERY}" at 357...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{SECTION}" at 455...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{?SEARCH_DEST}" at 470...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{WHICH_JOBS?" at 482, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 482...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{WHICH_JOBS}" at 506...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{ORDER?" at 513, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 513...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{ORDER}" at 531...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 532 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 532...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 533 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{ORDER?", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 534 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 534...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{ORDER?" at 541, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 541...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{ORDER}" at 555...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 556 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 556...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 557 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{ORDER?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 558 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{WHICH_JOBS?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 641 on EOF...
D [11/Sep/2006:12:50:50 +0200] [CGI] cgiCopyTemplateLang(tmpl="jobs-header.tmpl")
D [11/Sep/2006:12:50:50 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Template file is "/usr/share/cups/templates/jobs-header.tmpl"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?which_jobs=" at 17, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 17...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?printer_name=" at 42, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 42...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 48 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 48...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 71...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 72 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?printer_name=", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?printer_name=" at 211, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 211...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 217 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 217...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 240...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 241 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?printer_name=", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 333 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 333...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{which_jobs=all" at 349, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 349...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?printer_name=" at 374, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 374...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 380 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 380...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 403...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 404 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?printer_name=" at 543, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 543...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 549 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 549...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 572...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 573 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 656 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 656...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?printer_name=" at 681, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 681...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 687 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 687...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 710...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 711 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?printer_name=" at 823, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 823...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 829 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 829...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{printer_uri_supported}" at 852...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 853 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 945 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{which_jobs=all", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 946 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?which_jobs=", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{total=0" at 983, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 983...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 991 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 991...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{#job_id}" at 1008...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{total}" at 1019...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?which_jobs=" at 1034, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1034...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1041 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1041...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{which_jobs=all" at 1057, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1057...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1058 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1058...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1068 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{which_jobs=all", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1069 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?which_jobs=", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{total=1" at 1082, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1082...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1083 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1083...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1085 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{total=1", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1086 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{total=0", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1092 on EOF...
D [11/Sep/2006:12:50:50 +0200] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [11/Sep/2006:12:50:50 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{PREVURL?" at 87, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 87...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{PREVURL}" at 105...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 191 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 191...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 198 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{PREVURL?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 247...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{THISURL}" at 265...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 367 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 367...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{THISURL}" at 385...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 489 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{ORDER=dec", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 535...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{NEXTURL}" at 553...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 631 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 631...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 638 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{NEXTURL?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 659 on EOF...
D [11/Sep/2006:12:50:50 +0200] [CGI] cgiCopyTemplateLang(tmpl="jobs.tmpl")
D [11/Sep/2006:12:50:50 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Template file is "/usr/share/cups/templates/jobs.tmpl"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{#job_id=0" at 11, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 11...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 12 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 12...
D [11/Sep/2006:12:50:50 +0200] [CGI] Looping on "job_id" at 277, count=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 277...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_printer_uri}" at 339...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_printer_name}" at 359...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_id}" at 372...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{?job_name=" at 400, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 400...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 408 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 408...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_name}" at 418...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 419 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{?job_name=", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_originating_user_name}" at 462...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_k_octets}" at 492...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 539...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 547 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 547...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{?job_media_sheets_completed}" at 576...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 577 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_state=3" at 600, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 600...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{time_at_creation}" at 635...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 636 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 636...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_state=4" at 649, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 649...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{time_at_creation}" at 681...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 682 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 682...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_state=5" at 696, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 696...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{time_at_processing}" at 736...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 737 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 737...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_state=6" at 750, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 750...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 758 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 758...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_state=7" at 772, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 772...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{time_at_completed}" at 806...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 807 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 807...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_state=8" at 820, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 820...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 828 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 828...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{time_at_completed}" at 863...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 864 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_state=8", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 865 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_state=7", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 866 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_state=6", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 867 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_state=5", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 868 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_state=4", out=(nil)...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 869 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_state=3", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_preserved>0" at 903, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 903...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_id}" at 954...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_printer_uri}" at 992...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1075 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1075...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1076 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_preserved>0", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_state=4" at 1090, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1090...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_id}" at 1141...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_printer_uri}" at 1179...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1262 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1262...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1263 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_state=4", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_state=3" at 1277, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1277...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_id}" at 1325...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_printer_uri}" at 1363...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1440 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1440...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1441 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_state=3", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{job_state<7" at 1455, result=1...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1455...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_id}" at 1505...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_printer_uri}" at 1543...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{job_id}" at 1671...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1747 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 1747...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1748 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{job_state<7", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1768 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished looping on "job_id"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1779 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{#job_id=0", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 1780 on EOF...
D [11/Sep/2006:12:50:50 +0200] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [11/Sep/2006:12:50:50 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{PREVURL?" at 87, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 87...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{PREVURL}" at 105...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 191 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 191...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 198 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{PREVURL?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 247...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{THISURL}" at 265...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 367 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 367...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{THISURL}" at 385...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 489 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{ORDER=dec", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 535...
D [11/Sep/2006:12:50:50 +0200] [CGI] "{NEXTURL}" at 553...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 631 on character ':'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Output second part...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 631...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 638 on character '}'...
D [11/Sep/2006:12:50:50 +0200] [CGI] Finished "{NEXTURL?", out=0xb7dc44c0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 659 on EOF...
D [11/Sep/2006:12:50:50 +0200] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [11/Sep/2006:12:50:50 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [11/Sep/2006:12:50:50 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:50:50 +0200] [CGI] Returning at file position 614 on EOF...
D [11/Sep/2006:12:52:03 +0200] cupsdCloseClient: 6
D [11/Sep/2006:12:52:03 +0200] cupsdCloseClient: 7
D [11/Sep/2006:12:55:40 +0200] cupsdAcceptClient: 6 from localhost:631 (IPv4)
D [11/Sep/2006:12:55:40 +0200] cupsdReadClient: 6 GET /admin/?op=set-as-default&printer_name=LexmarkOptra HTTP/1.1
D [11/Sep/2006:12:55:40 +0200] cupsdReadClient: 6 Browser asked for language "en-us.utf-8"...
D [11/Sep/2006:12:55:40 +0200] cupsdAuthorize: username="root"
D [11/Sep/2006:12:55:40 +0200] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 5758
I [11/Sep/2006:12:55:40 +0200] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5758)
D [11/Sep/2006:12:55:40 +0200] cupsdSendCommand: 6 file=7
D [11/Sep/2006:12:55:40 +0200] [CGI] admin.cgi started...
D [11/Sep/2006:12:55:40 +0200] [CGI] http=0x8077860
D [11/Sep/2006:12:55:40 +0200] [CGI] op="set-as-default"...
D [11/Sep/2006:12:55:40 +0200] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [11/Sep/2006:12:55:40 +0200] cupsdReadClient: 9 POST /admin/ HTTP/1.1
D [11/Sep/2006:12:55:40 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:55:40 +0200] CUPS-Set-Default ipp://localhost/printers/LexmarkOptra
E [11/Sep/2006:12:55:40 +0200] CUPS-Set-Default: Unauthorized
D [11/Sep/2006:12:55:40 +0200] cupsdSendError: 9 code=401 (Unauthorized)
D [11/Sep/2006:12:55:41 +0200] cupsdAcceptClient: 10 from localhost:631 (IPv4)
D [11/Sep/2006:12:55:41 +0200] cupsdCloseClient: 9
D [11/Sep/2006:12:55:41 +0200] cupsdReadClient: 10 POST /admin/ HTTP/1.1
D [11/Sep/2006:12:55:41 +0200] cupsdAuthorize: username="root"
D [11/Sep/2006:12:55:41 +0200] CUPS-Set-Default ipp://localhost/printers/LexmarkOptra
I [11/Sep/2006:12:55:41 +0200] Saving printers.conf...
I [11/Sep/2006:12:55:41 +0200] Saving classes.conf...
I [11/Sep/2006:12:55:41 +0200] Default destination set to "LexmarkOptra" by "root".
D [11/Sep/2006:12:55:41 +0200] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [11/Sep/2006:12:55:41 +0200] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [11/Sep/2006:12:55:41 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:41 +0200] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:41 +0200] [CGI] "{title}" at 205...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting "{refresh_page?" at 374, result=1...
D [11/Sep/2006:12:55:41 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 374...
D [11/Sep/2006:12:55:41 +0200] [CGI] "{refresh_page}" at 424...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 427 on character ':'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 427...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 428 on character '}'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Finished "{refresh_page?", out=0xb7e364c0...
D [11/Sep/2006:12:55:41 +0200] [CGI] "{title}" at 671...
D [11/Sep/2006:12:55:41 +0200] [CGI] "{title}" at 952...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting "{SECTION=admin" at 1411, result=1...
D [11/Sep/2006:12:55:41 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 1411...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 1412 on character ':'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 1412...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 1415 on character '}'...
D [11/Sep/2006:12:55:41 +0200] PID 5758 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [11/Sep/2006:12:55:41 +0200] [CGI] Finished "{SECTION=admin", out=0xb7e364c0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 1678...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 1679 on character ':'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 1679...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 1682 on character '}'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Finished "{SECTION=classes", out=0xb7e364c0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 1938...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 1939 on character ':'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 1939...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 1942 on character '}'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Finished "{SECTION=help", out=0xb7e364c0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting "{SECTION=jobs" at 2206, result=0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 2206...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 2207 on character ':'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:41 +0200] cupsdCloseClient: 10
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 2207...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 2210 on character '}'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Finished "{SECTION=jobs", out=0xb7e364c0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting "{SECTION=printers" at 2464, result=0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 2464...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 2465 on character ':'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 2465...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 2468 on character '}'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Finished "{SECTION=printers", out=0xb7e364c0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 2830 on EOF...
D [11/Sep/2006:12:55:41 +0200] [CGI] cgiCopyTemplateLang(tmpl="printer-default.tmpl")
D [11/Sep/2006:12:55:41 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:41 +0200] [CGI] Template file is "/usr/share/cups/templates/printer-default.tmpl"...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting "{is_class?" at 13, result=0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 13...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 19 on character ':'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 19...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 27 on character '}'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Finished "{is_class?", out=0xb7e364c0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting "{is_class?" at 48, result=0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 48...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 56 on character ':'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 56...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 65 on character '}'...
D [11/Sep/2006:12:55:41 +0200] [CGI] Finished "{is_class?", out=0xb7e364c0...
D [11/Sep/2006:12:55:41 +0200] [CGI] "{printer_name}" at 80...
D [11/Sep/2006:12:55:41 +0200] [CGI] "{printer_name}" at 96...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 299 on EOF...
D [11/Sep/2006:12:55:41 +0200] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [11/Sep/2006:12:55:41 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:41 +0200] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [11/Sep/2006:12:55:41 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:41 +0200] [CGI] Returning at file position 614 on EOF...
D [11/Sep/2006:12:55:45 +0200] cupsdAcceptClient: 7 from localhost:631 (IPv4)
D [11/Sep/2006:12:55:45 +0200] cupsdReadClient: 7 POST / HTTP/1.1
D [11/Sep/2006:12:55:45 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:55:45 +0200] CUPS-Get-Printers
D [11/Sep/2006:12:55:45 +0200] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [11/Sep/2006:12:55:45 +0200] cupsdReadClient: 7 POST / HTTP/1.1
D [11/Sep/2006:12:55:45 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:55:45 +0200] CUPS-Get-Classes
D [11/Sep/2006:12:55:45 +0200] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [11/Sep/2006:12:55:45 +0200] cupsdReadClient: 7 POST / HTTP/1.1
D [11/Sep/2006:12:55:45 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:55:45 +0200] CUPS-Get-Default
D [11/Sep/2006:12:55:45 +0200] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [11/Sep/2006:12:55:45 +0200] cupsdReadClient: 7 POST / HTTP/1.1
D [11/Sep/2006:12:55:45 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:55:45 +0200] Get-Printer-Attributes ipp://localhost/printers/LexmarkOptra
D [11/Sep/2006:12:55:45 +0200] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [11/Sep/2006:12:55:45 +0200] cupsdReadClient: 7 POST / HTTP/1.1
D [11/Sep/2006:12:55:45 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:55:45 +0200] Get-Jobs ipp://localhost/printers/LexmarkOptra
D [11/Sep/2006:12:55:45 +0200] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [11/Sep/2006:12:55:45 +0200] cupsdCloseClient: 7
D [11/Sep/2006:12:55:46 +0200] cupsdReadClient: 6 GET /admin/?OP=redirect&URL=/printers/LexmarkOptra HTTP/1.1
D [11/Sep/2006:12:55:46 +0200] cupsdReadClient: 6 Browser asked for language "en-us.utf-8"...
D [11/Sep/2006:12:55:46 +0200] cupsdAuthorize: username="root"
D [11/Sep/2006:12:55:46 +0200] CGI /usr/lib/cups/cgi-bin/admin.cgi started - PID = 5761
I [11/Sep/2006:12:55:46 +0200] Started "/usr/lib/cups/cgi-bin/admin.cgi" (pid=5761)
D [11/Sep/2006:12:55:46 +0200] cupsdSendCommand: 6 file=7
D [11/Sep/2006:12:55:46 +0200] [CGI] admin.cgi started...
D [11/Sep/2006:12:55:46 +0200] PID 5761 (/usr/lib/cups/cgi-bin/admin.cgi) exited with no errors.
D [11/Sep/2006:12:55:46 +0200] [CGI] http=0x8077860
D [11/Sep/2006:12:55:46 +0200] [CGI] op="redirect"...
D [11/Sep/2006:12:55:46 +0200] cupsdAcceptClient: 7 from localhost:631 (IPv4)
D [11/Sep/2006:12:55:46 +0200] cupsdCloseClient: 7
D [11/Sep/2006:12:55:46 +0200] cupsdReadClient: 6 GET /printers/LexmarkOptra HTTP/1.1
D [11/Sep/2006:12:55:46 +0200] cupsdReadClient: 6 Browser asked for language "en-us.utf-8"...
D [11/Sep/2006:12:55:46 +0200] cupsdAuthorize: username="root"
D [11/Sep/2006:12:55:46 +0200] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 5762
I [11/Sep/2006:12:55:46 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5762)
D [11/Sep/2006:12:55:46 +0200] cupsdSendCommand: 6 file=7
D [11/Sep/2006:12:55:46 +0200] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [11/Sep/2006:12:55:46 +0200] cupsdReadClient: 9 POST / HTTP/1.1
D [11/Sep/2006:12:55:46 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:55:46 +0200] CUPS-Get-Default
D [11/Sep/2006:12:55:46 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [11/Sep/2006:12:55:47 +0200] cupsdReadClient: 9 POST / HTTP/1.1
D [11/Sep/2006:12:55:47 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:55:47 +0200] Get-Printer-Attributes ipp://localhost/printers/LexmarkOptra
D [11/Sep/2006:12:55:47 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [11/Sep/2006:12:55:47 +0200] [CGI] show_printer(http=0x80711b8, printer="LexmarkOptra")
D [11/Sep/2006:12:55:47 +0200] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [11/Sep/2006:12:55:47 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{title}" at 205...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{refresh_page?" at 374, result=0...
D [11/Sep/2006:12:55:47 +0200] cupsdReadClient: 9 POST / HTTP/1.1
D [11/Sep/2006:12:55:47 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:55:47 +0200] Get-Jobs ipp://localhost:631/printers/LexmarkOptra
D [11/Sep/2006:12:55:47 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 374...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{refresh_page}" at 424...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 427 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 427...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 428 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{refresh_page?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{title}" at 671...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{title}" at 952...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{SECTION=admin" at 1411, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1411...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1412 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1412...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1415 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{SECTION=admin", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1678...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1679 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1679...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1682 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{SECTION=classes", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1938...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1939 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1939...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1942 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{SECTION=help", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{SECTION=jobs" at 2206, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 2206...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 2207 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 2207...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 2210 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{SECTION=jobs", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{SECTION=printers" at 2464, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 2464...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 2465 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 2465...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 2468 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{SECTION=printers", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 2830 on EOF...
D [11/Sep/2006:12:55:47 +0200] [CGI] cgiCopyTemplateLang(tmpl="printers.tmpl")
D [11/Sep/2006:12:55:47 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Template file is "/usr/share/cups/templates/printers.tmpl"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{printer_type?" at 14, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 14...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 15 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 15...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 16 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{printer_type?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{#printer_name=0" at 33, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 33...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 34 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 34...
D [11/Sep/2006:12:55:47 +0200] [CGI] Looping on "printer_name" at 50, count=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 50...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 101...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 117...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{default_name=LexmarkOptra" at 150, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 150...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 169 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 169...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 170 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{default_name=LexmarkOptra", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?printer_state_message=" at 196, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 196...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 197 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 197...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_state_message}" at 243...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 252 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?printer_state_message=", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 349...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 406...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{printer_state=3" at 452, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 452...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 457 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 457...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{printer_state=4" at 474, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 474...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 485 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 485...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 493 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{printer_state=4", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 494 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{printer_state=3", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_info}" at 584...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_location}" at 624...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_make_and_model}" at 676...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{printer_state=3" at 720, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 720...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 725 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 725...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{printer_state=4" at 742, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 742...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 753 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 753...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 761 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{printer_state=4", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 762 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{printer_state=3", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{printer_is_accepting_jobs=0" at 793, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 793...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 808 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 808...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 823 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{printer_is_shared=0" at 846, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 846...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 850 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 850...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 851 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{printer_is_shared=0", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?device_uri=" at 877, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 877...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 878 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 878...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{device_uri}" at 913...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 914 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?device_uri=", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 952...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?cupscommand=1" at 1078, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1078...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 1110...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 1258...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1383 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1383...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1384 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?cupscommand=1", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{printer_state=5" at 1402, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1402...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 1423...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 1473...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1561 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1561...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 1582...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 1631...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1717 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{printer_state=5", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{printer_is_accepting_jobs=0" at 1747, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1747...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 1768...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 1816...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1900 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1900...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 1921...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 1969...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 2053 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 2086...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 2132...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 2235...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 2282...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{printer_is_shared=0" at 2394, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 2394...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 2415...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 2463...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 2568 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 2568...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 2589...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 2637...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 2746 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{printer_is_shared=0", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 2767...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 2818...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 2927...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 2983...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 3102...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 3153...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 3262...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 3313...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{admin_uri}" at 3422...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_name}" at 3476...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 3598 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished looping on "printer_name"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 3599 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{#printer_name=0", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 3600 on EOF...
D [11/Sep/2006:12:55:47 +0200] [CGI] cgiCopyTemplateLang(tmpl="printer-jobs-header.tmpl")
D [11/Sep/2006:12:55:47 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Template file is "/usr/share/cups/templates/printer-jobs-header.tmpl"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 28 on EOF...
D [11/Sep/2006:12:55:47 +0200] [CGI] cgiCopyTemplateLang(tmpl="search.tmpl")
D [11/Sep/2006:12:55:47 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Template file is "/usr/share/cups/templates/search.tmpl"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{SECTION}" at 24...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{?SEARCH_DEST}" at 39...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{WHICH_JOBS?" at 67, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 67...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{WHICH_JOBS}" at 125...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 128 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] cupsdCloseClient: 9
D [11/Sep/2006:12:55:47 +0200] PID 5762 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 128...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 129 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{WHICH_JOBS?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{ORDER?" at 137, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 137...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{ORDER}" at 185...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 188 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 188...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 189 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{ORDER?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{SEARCH_DEST?" at 235, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 235...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{SEARCH_DEST}" at 248...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 249 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 249...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{SECTION=classes" at 266, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 266...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 274 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 274...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{SECTION=jobs" at 288, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 288...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 293 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 293...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 302 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{SECTION=jobs", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 303 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{SECTION=classes", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 304 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{SEARCH_DEST?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{?QUERY}" at 357...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{SECTION}" at 455...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{?SEARCH_DEST}" at 470...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{WHICH_JOBS?" at 482, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 482...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{WHICH_JOBS}" at 506...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{ORDER?" at 513, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 513...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{ORDER}" at 531...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 532 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 532...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 533 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{ORDER?", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 534 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 534...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{ORDER?" at 541, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 541...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{ORDER}" at 555...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 556 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 556...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 557 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{ORDER?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 558 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{WHICH_JOBS?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 641 on EOF...
D [11/Sep/2006:12:55:47 +0200] [CGI] cgiCopyTemplateLang(tmpl="jobs-header.tmpl")
D [11/Sep/2006:12:55:47 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Template file is "/usr/share/cups/templates/jobs-header.tmpl"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?which_jobs=" at 17, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 17...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?printer_name=" at 42, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 42...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 48 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 48...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 71...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 72 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?printer_name=", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?printer_name=" at 211, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 211...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 217 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 217...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 240...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 241 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?printer_name=", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 333 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 333...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{which_jobs=all" at 349, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 349...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?printer_name=" at 374, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 374...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 380 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 380...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 403...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 404 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?printer_name=" at 543, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 543...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 549 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 549...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 572...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 573 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 656 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 656...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?printer_name=" at 681, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 681...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 687 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 687...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 710...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 711 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?printer_name=" at 823, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 823...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 829 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 829...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{printer_uri_supported}" at 852...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 853 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 945 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{which_jobs=all", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 946 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?which_jobs=", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{total=0" at 983, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 983...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 991 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 991...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{#job_id}" at 1008...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{total}" at 1019...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?which_jobs=" at 1034, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1034...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1041 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1041...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{which_jobs=all" at 1057, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1057...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1058 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1058...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1068 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{which_jobs=all", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1069 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?which_jobs=", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{total=1" at 1082, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1082...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1083 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1083...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1085 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{total=1", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1086 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{total=0", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1092 on EOF...
D [11/Sep/2006:12:55:47 +0200] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [11/Sep/2006:12:55:47 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{PREVURL?" at 87, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 87...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{PREVURL}" at 105...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 191 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 191...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 198 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{PREVURL?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 247...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{THISURL}" at 265...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 367 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 367...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{THISURL}" at 385...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 489 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{ORDER=dec", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 535...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{NEXTURL}" at 553...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 631 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 631...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 638 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{NEXTURL?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 659 on EOF...
D [11/Sep/2006:12:55:47 +0200] [CGI] cgiCopyTemplateLang(tmpl="jobs.tmpl")
D [11/Sep/2006:12:55:47 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Template file is "/usr/share/cups/templates/jobs.tmpl"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{#job_id=0" at 11, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 11...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 12 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 12...
D [11/Sep/2006:12:55:47 +0200] [CGI] Looping on "job_id" at 277, count=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 277...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_printer_uri}" at 339...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_printer_name}" at 359...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_id}" at 372...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{?job_name=" at 400, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 400...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 408 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 408...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_name}" at 418...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 419 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{?job_name=", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_originating_user_name}" at 462...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_k_octets}" at 492...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_media_sheets_completed=0" at 539, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 539...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 547 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 547...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{?job_media_sheets_completed}" at 576...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 577 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_media_sheets_completed=0", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_state=3" at 600, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 600...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{time_at_creation}" at 635...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 636 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 636...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_state=4" at 649, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 649...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{time_at_creation}" at 681...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 682 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 682...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_state=5" at 696, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 696...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{time_at_processing}" at 736...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 737 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 737...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_state=6" at 750, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 750...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 758 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 758...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_state=7" at 772, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 772...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{time_at_completed}" at 806...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 807 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 807...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_state=8" at 820, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 820...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 828 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 828...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{time_at_completed}" at 863...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 864 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_state=8", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 865 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_state=7", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 866 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_state=6", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 867 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_state=5", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 868 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_state=4", out=(nil)...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 869 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_state=3", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_preserved>0" at 903, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 903...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_id}" at 954...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_printer_uri}" at 992...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1075 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1075...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1076 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_preserved>0", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_state=4" at 1090, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1090...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_id}" at 1141...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_printer_uri}" at 1179...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1262 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1262...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1263 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_state=4", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_state=3" at 1277, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1277...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_id}" at 1325...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_printer_uri}" at 1363...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1440 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1440...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1441 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_state=3", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{job_state<7" at 1455, result=1...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1455...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_id}" at 1505...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_printer_uri}" at 1543...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{job_id}" at 1671...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1747 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 1747...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1748 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{job_state<7", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1768 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished looping on "job_id"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1779 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{#job_id=0", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 1780 on EOF...
D [11/Sep/2006:12:55:47 +0200] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [11/Sep/2006:12:55:47 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{PREVURL?" at 87, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 87...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{PREVURL}" at 105...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 191 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 191...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 198 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{PREVURL?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 247...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{THISURL}" at 265...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 367 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 367...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{THISURL}" at 385...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 489 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{ORDER=dec", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 535...
D [11/Sep/2006:12:55:47 +0200] [CGI] "{NEXTURL}" at 553...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 631 on character ':'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Output second part...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 631...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 638 on character '}'...
D [11/Sep/2006:12:55:47 +0200] [CGI] Finished "{NEXTURL?", out=0xb7e014c0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 659 on EOF...
D [11/Sep/2006:12:55:47 +0200] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [11/Sep/2006:12:55:47 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [11/Sep/2006:12:55:47 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:55:47 +0200] [CGI] Returning at file position 614 on EOF...
D [11/Sep/2006:12:56:48 +0200] cupsdCloseClient: 6
D [11/Sep/2006:12:59:47 +0200] cupsdAcceptClient: 6 from localhost:631 (IPv4)
D [11/Sep/2006:12:59:47 +0200] cupsdReadClient: 6 GET /printers/ HTTP/1.1
D [11/Sep/2006:12:59:47 +0200] cupsdReadClient: 6 Browser asked for language "en-us.utf-8"...
D [11/Sep/2006:12:59:47 +0200] cupsdAuthorize: username="root"
D [11/Sep/2006:12:59:47 +0200] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 5787
I [11/Sep/2006:12:59:47 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=5787)
D [11/Sep/2006:12:59:47 +0200] cupsdSendCommand: 6 file=7
D [11/Sep/2006:12:59:47 +0200] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [11/Sep/2006:12:59:47 +0200] cupsdReadClient: 9 POST / HTTP/1.1
D [11/Sep/2006:12:59:47 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:59:47 +0200] CUPS-Get-Default
D [11/Sep/2006:12:59:47 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [11/Sep/2006:12:59:48 +0200] [CGI] show_all_printers(http=0x80711b8, user="root")
D [11/Sep/2006:12:59:48 +0200] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [11/Sep/2006:12:59:48 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Template file is "/usr/share/cups/templates/header.tmpl"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{title}" at 205...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{refresh_page?" at 374, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 374...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{refresh_page}" at 424...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 427 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 427...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 428 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{refresh_page?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{title}" at 671...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{title}" at 952...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{SECTION=admin" at 1411, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1411...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1412 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1412...
D [11/Sep/2006:12:59:48 +0200] cupsdReadClient: 9 POST / HTTP/1.1
D [11/Sep/2006:12:59:48 +0200] cupsdAuthorize: No authentication data provided.
D [11/Sep/2006:12:59:48 +0200] CUPS-Get-Printers
D [11/Sep/2006:12:59:48 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1415 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{SECTION=admin", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1678...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1679 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1679...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1682 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{SECTION=classes", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1938...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1939 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1939...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1942 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{SECTION=help", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{SECTION=jobs" at 2206, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 2206...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2207 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 2207...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2210 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{SECTION=jobs", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{SECTION=printers" at 2464, result=1...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 2464...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2465 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 2465...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2468 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{SECTION=printers", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2830 on EOF...
D [11/Sep/2006:12:59:48 +0200] PID 5787 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [11/Sep/2006:12:59:48 +0200] cupsdCloseClient: 9
D [11/Sep/2006:12:59:48 +0200] [CGI] cgiCopyTemplateLang(tmpl="search.tmpl")
D [11/Sep/2006:12:59:48 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Template file is "/usr/share/cups/templates/search.tmpl"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{SECTION}" at 24...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{?SEARCH_DEST}" at 39...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{WHICH_JOBS?" at 67, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 67...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{WHICH_JOBS}" at 125...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 128 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 128...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 129 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{WHICH_JOBS?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{ORDER?" at 137, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 137...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{ORDER}" at 185...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 188 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 188...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 189 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{ORDER?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{SEARCH_DEST?" at 235, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 235...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{SEARCH_DEST}" at 248...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 249 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 249...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{SECTION=classes" at 266, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 266...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 274 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 274...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{SECTION=jobs" at 288, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 288...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 293 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 293...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 302 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{SECTION=jobs", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 303 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{SECTION=classes", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 304 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{SEARCH_DEST?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{?QUERY}" at 357...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{SECTION}" at 455...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{?SEARCH_DEST}" at 470...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{WHICH_JOBS?" at 482, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 482...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{WHICH_JOBS}" at 506...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{ORDER?" at 513, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 513...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{ORDER}" at 531...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 532 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 532...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 533 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{ORDER?", out=(nil)...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 534 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 534...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{ORDER?" at 541, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 541...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{ORDER}" at 555...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 556 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 556...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 557 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{ORDER?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 558 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{WHICH_JOBS?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 641 on EOF...
D [11/Sep/2006:12:59:48 +0200] [CGI] cgiCopyTemplateLang(tmpl="printers-header.tmpl")
D [11/Sep/2006:12:59:48 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Template file is "/usr/share/cups/templates/printers-header.tmpl"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{total=0" at 27, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 27...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 39 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 39...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{#printer_name}" at 62...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{total}" at 73...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{total=1" at 90, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 90...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 91 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 91...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 93 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{total=1", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 94 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{total=0", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 100 on EOF...
D [11/Sep/2006:12:59:48 +0200] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [11/Sep/2006:12:59:48 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{PREVURL?" at 87, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 87...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{PREVURL}" at 105...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 191 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 191...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 198 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{PREVURL?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 247...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{THISURL}" at 265...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 367 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 367...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{THISURL}" at 385...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 489 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{ORDER=dec", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 535...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{NEXTURL}" at 553...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 631 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 631...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 638 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{NEXTURL?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 659 on EOF...
D [11/Sep/2006:12:59:48 +0200] [CGI] cgiCopyTemplateLang(tmpl="printers.tmpl")
D [11/Sep/2006:12:59:48 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Template file is "/usr/share/cups/templates/printers.tmpl"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_type?" at 14, result=1...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 14...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 15 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 15...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 16 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_type?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{#printer_name=0" at 33, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 33...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 34 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 34...
D [11/Sep/2006:12:59:48 +0200] [CGI] Looping on "printer_name" at 50, count=2...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 50...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 101...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 117...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{default_name=EPSON1290" at 150, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 150...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 169 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 169...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 170 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{default_name=EPSON1290", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{?printer_state_message=" at 196, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 196...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 197 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 197...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_state_message}" at 243...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 252 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{?printer_state_message=", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 349...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 406...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_state=3" at 452, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 452...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 457 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 457...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_state=4" at 474, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 474...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 485 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 485...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 493 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_state=4", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 494 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_state=3", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_info}" at 584...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_location}" at 624...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_make_and_model}" at 676...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_state=3" at 720, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 720...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 725 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 725...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_state=4" at 742, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 742...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 753 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 753...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 761 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_state=4", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 762 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_state=3", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_is_accepting_jobs=0" at 793, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 793...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 808 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 808...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 823 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_is_shared=0" at 846, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 846...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 850 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 850...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 851 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_is_shared=0", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{?device_uri=" at 877, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 877...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 878 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 878...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{device_uri}" at 913...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 914 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{?device_uri=", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 952...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{?cupscommand=1" at 1078, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1078...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 1110...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 1258...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1383 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1383...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1384 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{?cupscommand=1", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_state=5" at 1402, result=1...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1402...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 1423...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 1473...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1561 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1561...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 1582...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 1631...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1717 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_state=5", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_is_accepting_jobs=0" at 1747, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1747...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 1768...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 1816...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1900 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1900...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 1921...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 1969...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2053 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 2086...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2132...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 2235...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2282...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_is_shared=0" at 2394, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 2394...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 2415...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2463...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2568 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 2568...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 2589...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2637...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2746 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_is_shared=0", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 2767...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2818...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 2927...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2983...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 3102...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 3153...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 3262...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 3313...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 3422...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 3476...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 3598 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 50...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 101...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 117...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{default_name=LexmarkOptra" at 150, result=1...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 150...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 169 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 169...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 170 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{default_name=LexmarkOptra", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{?printer_state_message=" at 196, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 196...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 197 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 197...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_state_message}" at 243...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 252 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{?printer_state_message=", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 349...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 406...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_state=3" at 452, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 452...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 457 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 457...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_state=4" at 474, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 474...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 485 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 485...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 493 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_state=4", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 494 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_state=3", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_info}" at 584...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_location}" at 624...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_make_and_model}" at 676...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_state=3" at 720, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 720...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 725 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 725...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_state=4" at 742, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 742...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 753 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 753...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 761 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_state=4", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 762 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_state=3", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_is_accepting_jobs=0" at 793, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 793...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 808 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 808...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 823 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_is_shared=0" at 846, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 846...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 850 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 850...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 851 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_is_shared=0", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{?device_uri=" at 877, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 877...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 878 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 878...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{device_uri}" at 913...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 914 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{?device_uri=", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 952...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{?cupscommand=1" at 1078, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1078...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 1110...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 1258...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1383 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1383...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1384 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{?cupscommand=1", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_state=5" at 1402, result=1...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1402...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 1423...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 1473...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1561 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1561...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 1582...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 1631...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1717 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_state=5", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_is_accepting_jobs=0" at 1747, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1747...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 1768...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 1816...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 1900 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 1900...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 1921...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 1969...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2053 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_is_accepting_jobs=0", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_uri_supported}" at 2086...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2132...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 2235...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2282...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{printer_is_shared=0" at 2394, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 2394...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 2415...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2463...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2568 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 2568...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 2589...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2637...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 2746 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{printer_is_shared=0", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 2767...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2818...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 2927...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 2983...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 3102...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 3153...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 3262...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 3313...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{admin_uri}" at 3422...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{printer_name}" at 3476...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 3598 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished looping on "printer_name"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 3599 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{#printer_name=0", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 3600 on EOF...
D [11/Sep/2006:12:59:48 +0200] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [11/Sep/2006:12:59:48 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Template file is "/usr/share/cups/templates/pager.tmpl"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{PREVURL?" at 87, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 87...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{PREVURL}" at 105...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 191 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 191...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 198 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{PREVURL?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{ORDER=dec" at 247, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 247...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{THISURL}" at 265...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 367 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 367...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{THISURL}" at 385...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 489 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{ORDER=dec", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting "{NEXTURL?" at 535, result=0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Skip first part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 535...
D [11/Sep/2006:12:59:48 +0200] [CGI] "{NEXTURL}" at 553...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 631 on character ':'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Output second part...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 631...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 638 on character '}'...
D [11/Sep/2006:12:59:48 +0200] [CGI] Finished "{NEXTURL?", out=0xb7d6a4c0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 659 on EOF...
D [11/Sep/2006:12:59:48 +0200] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [11/Sep/2006:12:59:48 +0200] [CGI] locale="en_us"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Template file is "/usr/share/cups/templates/trailer.tmpl"...
D [11/Sep/2006:12:59:48 +0200] [CGI] Starting at file position 0...
D [11/Sep/2006:12:59:48 +0200] [CGI] Returning at file position 614 on EOF...
D [11/Sep/2006:13:00:48 +0200] cupsdCloseClient: 6