[cups.general] Quota Limit Problem on debian System

[Steve-Kai Vyska]

Hi everyone,

I'm using Cups to print documents on my router. This worked good until i 
updated my debian system within the etch Distribution. After that printing 
from the linux Computer is not possible anymore. The Windows Clients can 
print without Problems.

Whenever i send a Document to the Printer, the printer starts warming up but 
does not print. Sending a testfile through the interface, i get

lp (Standarddrucker) "Sending data file (0 bytes)"

and no printjobs are listed anymore. After several minutes, the Printjobs are 
printed again telling me that the Job was stopped because of no value

The Errorlog File Provides the information below.
Anybody an Idea why it is not working?


Thanks

Steve

D [01/Dec/2006:10:09:37 +0100] cupsdReadClient: 13 
GET /printers/lp?op=print-test-page HTTP/1.1
D [01/Dec/2006:10:09:37 +0100] cupsdReadClient: 13 Browser asked for 
language "de-de.utf-8"...
D [01/Dec/2006:10:09:37 +0100] cupsdAuthorize: username="root"
D [01/Dec/2006:10:09:37 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi 
started - PID = 5979
I [01/Dec/2006:10:09:37 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" 
(pid=5979)
D [01/Dec/2006:10:09:37 +0100] cupsdSendCommand: 13 file=8
D [01/Dec/2006:10:09:37 +0100] cupsdAcceptClient: 11 from localhost (Domain)
D [01/Dec/2006:10:09:37 +0100] cupsdReadClient: 11 POST /printers/lp HTTP/1.1
D [01/Dec/2006:10:09:37 +0100] cupsdAuthorize: No authentication data 
provided.
D [01/Dec/2006:10:09:37 +0100] Print-Job ipp://localhost:631/printers/lp
D [01/Dec/2006:10:09:37 +0100] print_job: request file type is 
application/postscript.
D [01/Dec/2006:10:09:37 +0100] add_job: requesting-user-name="root"
D [01/Dec/2006:10:09:37 +0100] Adding default job-sheets values "none,none"...
I [01/Dec/2006:10:09:37 +0100] Adding start banner page "none" to job 93.
D [01/Dec/2006:10:09:37 +0100] Discarding unused job-created event...
I [01/Dec/2006:10:09:37 +0100] Adding end banner page "none" to job 93.
I [01/Dec/2006:10:09:37 +0100] Job 93 queued on "lp" by "root".
D [01/Dec/2006:10:09:37 +0100] Job 93 hold_until = 0
D [01/Dec/2006:10:09:37 +0100] Discarding unused printer-state-changed 
event...
D [01/Dec/2006:10:09:37 +0100] job-sheets=none,none
D [01/Dec/2006:10:09:37 +0100] banner_page = 0
D [01/Dec/2006:10:09:37 +0100] [Job 93] argv[0]="lp"
D [01/Dec/2006:10:09:37 +0100] [Job 93] argv[1]="93"
D [01/Dec/2006:10:09:37 +0100] [Job 93] argv[2]="root"
D [01/Dec/2006:10:09:37 +0100] [Job 93] argv[3]="Test Page"
D [01/Dec/2006:10:09:37 +0100] [Job 93] argv[4]="1"
D [01/Dec/2006:10:09:37 +0100] [Job 93] 
argv[5]="job-uuid=urn:uuid:4974753e-b7ef-32d2-5c79-9270833145c0"
D [01/Dec/2006:10:09:37 +0100] [Job 93] argv[6]="/var/spool/cups/d00093-001"
D [01/Dec/2006:10:09:37 +0100] [Job 93] 
envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [01/Dec/2006:10:09:37 +0100] [Job 93] 
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [01/Dec/2006:10:09:37 +0100] [Job 93] 
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [01/Dec/2006:10:09:37 +0100] [Job 93] 
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [01/Dec/2006:10:09:37 +0100] [Job 93] 
envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[9]="SERVER_ADMIN=root at localhost"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[10]="SOFTWARE=CUPS/1.2.5"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[12]="TZ=Europe/Berlin"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[13]="USER=root"
D [01/Dec/2006:10:09:37 +0100] [Job 93] 
envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[16]="IPP_PORT=631"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[17]="CHARSET=utf-8"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[18]="LANG=de_DE"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[19]="PPD=/etc/cups/ppd/lp.ppd"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[20]="RIP_MAX_CACHE=8m"
D [01/Dec/2006:10:09:37 +0100] [Job 93] 
envp[21]="CONTENT_TYPE=application/postscript"
D [01/Dec/2006:10:09:37 +0100] [Job 93] 
envp[22]="DEVICE_URI=lpd://192.168.8.1/LPRServer"
D [01/Dec/2006:10:09:37 +0100] [Job 93] envp[23]="PRINTER=lp"
D [01/Dec/2006:10:09:37 +0100] [Job 93] 
envp[24]="FINAL_CONTENT_TYPE=printer/lp"
I [01/Dec/2006:10:09:37 +0100] Started filter /usr/lib/cups/filter/pstops (PID 
5980) for job 93.
I [01/Dec/2006:10:09:37 +0100] Started filter /usr/lib/cups/filter/ppmtospl2 
(PID 5981) for job 93.
I [01/Dec/2006:10:09:37 +0100] Started backend /usr/lib/cups/backend/lpd (PID 
5982) for job 93.
D [01/Dec/2006:10:09:37 +0100] Discarding unused job-state event...
D [01/Dec/2006:10:09:37 +0100] cupsdProcessIPPRequest: 11 status_code=0 
(successful-ok)
D [01/Dec/2006:10:09:37 +0100] PID 5980 (/usr/lib/cups/filter/pstops) exited 
with no errors.
D [01/Dec/2006:10:09:37 +0100] [Job 93] Page = 595x842; 12,12 to 582,830
D [01/Dec/2006:10:09:37 +0100] [Job 93] slow_collate=0, slow_duplex=0, 
slow_order=0
D [01/Dec/2006:10:09:37 +0100] [Job 93] Before copy_comments - %!PS-Adobe-3.0
D [01/Dec/2006:10:09:37 +0100] [Job 93] %!PS-Adobe-3.0
D [01/Dec/2006:10:09:37 +0100] [Job 93] %%BoundingBox: 0 0 612 792
D [01/Dec/2006:10:09:37 +0100] [Job 93] %%Pages: 1
D [01/Dec/2006:10:09:37 +0100] [Job 93] %%LanguageLevel: 1
D [01/Dec/2006:10:09:37 +0100] [Job 93] %%DocumentData: Clean7Bit
D [01/Dec/2006:10:09:37 +0100] [Job 93] %%DocumentSuppliedResources: procset 
testprint/1.2
D [01/Dec/2006:10:09:37 +0100] [Job 93] %%DocumentNeededResources: font 
Helvetica Helvetica-Bold Times-Roman
D [01/Dec/2006:10:09:37 +0100] [Job 93] %%Creator: Michael Sweet, Easy 
Software Products
D [01/Dec/2006:10:09:37 +0100] [Job 93] %%CreationDate: D:20060114093000+0500
D [01/Dec/2006:10:09:37 +0100] [Job 93] %%Title: Test Page
D [01/Dec/2006:10:09:37 +0100] [Job 93] %%EndComments
D [01/Dec/2006:10:09:37 +0100] [Job 93] Before copy_prolog - %%BeginProlog
D [01/Dec/2006:10:09:37 +0100] [Job 93] Before copy_setup - %%Page: 1 1
D [01/Dec/2006:10:09:37 +0100] [Job 93] Before page loop - %%Page: 1 1
D [01/Dec/2006:10:09:37 +0100] [Job 93] Copying page 1...
D [01/Dec/2006:10:09:37 +0100] [Job 93] pagew = 570.0, pagel = 817.0
D [01/Dec/2006:10:09:37 +0100] [Job 93] bboxw = 595, bboxl = 842
D [01/Dec/2006:10:09:37 +0100] [Job 93] PageLeft = 12.5, PageRight = 582.5
D [01/Dec/2006:10:09:37 +0100] [Job 93] PageTop = 829.5, PageBottom = 12.5
D [01/Dec/2006:10:09:37 +0100] [Job 93] PageWidth = 595.0, PageLength = 842.0
D [01/Dec/2006:10:09:37 +0100] [Job 93] Wrote 1 pages...
D [01/Dec/2006:10:09:37 +0100] PID 5979 (/usr/lib/cups/cgi-bin/printers.cgi) 
exited with no errors.
D [01/Dec/2006:10:09:37 +0100] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [01/Dec/2006:10:09:37 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:37 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/header.tmpl"...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:37 +0100] [CGI] "{title}" at 205...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting "{refresh_page?" at 374, 
result=1...
D [01/Dec/2006:10:09:37 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 374...
D [01/Dec/2006:10:09:37 +0100] [CGI] "{refresh_page}" at 424...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 427 on 
character ':'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 427...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 428 on 
character '}'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Finished "{refresh_page?", 
out=0xa7e923a0...
D [01/Dec/2006:10:09:37 +0100] [CGI] "{title}" at 671...
D [01/Dec/2006:10:09:37 +0100] [CGI] "{title}" at 952...
D [01/Dec/2006:10:09:37 +0100] cupsdCloseClient: 11
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting "{SECTION=admin" at 1417, 
result=0...
D [01/Dec/2006:10:09:37 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 1417...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 1418 on 
character ':'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 1418...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 1421 on 
character '}'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Finished "{SECTION=admin", 
out=0xa7e923a0...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting "{SECTION=classes" at 1680, 
result=0...
D [01/Dec/2006:10:09:37 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 1680...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 1681 on 
character ':'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 1681...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 1684 on 
character '}'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Finished "{SECTION=classes", 
out=0xa7e923a0...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting "{SECTION=help" at 1940, 
result=0...
D [01/Dec/2006:10:09:37 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 1940...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 1941 on 
character ':'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 1941...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 1944 on 
character '}'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Finished "{SECTION=help", 
out=0xa7e923a0...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting "{SECTION=jobs" at 2209, 
result=0...
D [01/Dec/2006:10:09:37 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 2209...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 2210 on 
character ':'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 2210...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 2213 on 
character '}'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Finished "{SECTION=jobs", 
out=0xa7e923a0...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting "{SECTION=printers" at 2476, 
result=1...
D [01/Dec/2006:10:09:37 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 2476...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 2477 on 
character ':'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 2477...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 2480 on 
character '}'...
D [01/Dec/2006:10:09:37 +0100] [CGI] Finished "{SECTION=printers", 
out=0xa7e923a0...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 2841 on EOF...
D [01/Dec/2006:10:09:37 +0100] [CGI] 
cgiCopyTemplateLang(tmpl="test-page.tmpl")
D [01/Dec/2006:10:09:37 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:37 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/test-page.tmpl"...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:37 +0100] [CGI] "{SECTION}" at 58...
D [01/Dec/2006:10:09:37 +0100] [CGI] "{printer_name}" at 73...
D [01/Dec/2006:10:09:37 +0100] [CGI] "{printer_name}" at 90...
D [01/Dec/2006:10:09:37 +0100] [CGI] "{job_id}" at 99...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 109 on EOF...
D [01/Dec/2006:10:09:37 +0100] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [01/Dec/2006:10:09:37 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:37 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/trailer.tmpl"...
D [01/Dec/2006:10:09:37 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:37 +0100] [CGI] Returning at file position 643 on EOF...
D [01/Dec/2006:10:09:37 +0100] cupsdAcceptClient: 8 from localhost:631 (IPv4)
D [01/Dec/2006:10:09:37 +0100] cupsdReadClient: 8 POST / HTTP/1.1
D [01/Dec/2006:10:09:37 +0100] cupsdAuthorize: No authentication data 
provided.
D [01/Dec/2006:10:09:37 +0100] CUPS-Get-Printers
D [01/Dec/2006:10:09:37 +0100] cupsdProcessIPPRequest: 8 status_code=0 
(successful-ok)
D [01/Dec/2006:10:09:37 +0100] cupsdCloseClient: 8
D [01/Dec/2006:10:09:37 +0100] cupsdAcceptClient: 8 from localhost:631 (IPv4)
D [01/Dec/2006:10:09:37 +0100] cupsdReadClient: 8 POST / HTTP/1.1
D [01/Dec/2006:10:09:37 +0100] cupsdAuthorize: No authentication data 
provided.
D [01/Dec/2006:10:09:37 +0100] Get-Jobs ipp://localhost:631/jobs/
D [01/Dec/2006:10:09:37 +0100] cupsdProcessIPPRequest: 8 status_code=0 
(successful-ok)
D [01/Dec/2006:10:09:37 +0100] cupsdCloseClient: 8
D [01/Dec/2006:10:09:37 +0100] cupsdAcceptClient: 8 from localhost:631 (IPv4)
D [01/Dec/2006:10:09:37 +0100] cupsdReadClient: 8 POST / HTTP/1.1
D [01/Dec/2006:10:09:37 +0100] cupsdAuthorize: No authentication data 
provided.
D [01/Dec/2006:10:09:37 +0100] CUPS-Get-Printers
D [01/Dec/2006:10:09:37 +0100] cupsdProcessIPPRequest: 8 status_code=0 
(successful-ok)
D [01/Dec/2006:10:09:37 +0100] cupsdCloseClient: 8
D [01/Dec/2006:10:09:37 +0100] cupsdAcceptClient: 8 from localhost:631 (IPv4)
D [01/Dec/2006:10:09:37 +0100] cupsdReadClient: 8 POST / HTTP/1.1
D [01/Dec/2006:10:09:37 +0100] cupsdAuthorize: No authentication data 
provided.
D [01/Dec/2006:10:09:37 +0100] Get-Jobs ipp://localhost:631/jobs/
D [01/Dec/2006:10:09:37 +0100] cupsdProcessIPPRequest: 8 status_code=0 
(successful-ok)
D [01/Dec/2006:10:09:37 +0100] cupsdCloseClient: 8
D [01/Dec/2006:10:09:38 +0100] [Job 93] Caught fatal signal 11!! Aborting.
D [01/Dec/2006:10:09:38 +0100] cupsdNetIFUpdate: "lo" = localhost...
D [01/Dec/2006:10:09:38 +0100] cupsdNetIFUpdate: "eth0" = 192.168.6.2...
D [01/Dec/2006:10:09:38 +0100] cupsdNetIFUpdate: "lo" = localhost...
D [01/Dec/2006:10:09:38 +0100] cupsdNetIFUpdate: "eth0" = 
fe80::211:2fff:fe4e:217b%eth0...
D [01/Dec/2006:10:09:38 +0100] [Job 93] Process 5981 Stack dump:
D [01/Dec/2006:10:09:38 +0100] [Job 93]
D [01/Dec/2006:10:09:38 +0100] [Job 93] lp(__strtod_internal+0x4dc1) 
[0x804e845]
D [01/Dec/2006:10:09:38 +0100] [0xffffe420]
D [01/Dec/2006:10:09:38 +0100] [Job 93] lp(__dcgettext+0x5f4) [0x804a648]
D [01/Dec/2006:10:09:38 +0100] [Job 93] /lib/tls/i686/cmov/libc.so.6
(__libc_start_main+0xc8) [0xa7dd7ea8]
D [01/Dec/2006:10:09:38 +0100] [Job 93] lp(setgrent+0x9d) [0x804a2e1]
D [01/Dec/2006:10:09:38 +0100] [Job 93]
E [01/Dec/2006:10:09:38 +0100] PID 5981 (/usr/lib/cups/filter/ppmtospl2) 
stopped with status 111!
D [01/Dec/2006:10:09:38 +0100] Discarding unused printer-state-changed 
event...
D [01/Dec/2006:10:09:38 +0100] Discarding unused printer-state-changed 
event...
D [01/Dec/2006:10:09:38 +0100] [Job 93] Connected on ports 515 (local 1023)...
D [01/Dec/2006:10:09:38 +0100] [Job 93] lpd_command 02 LPRServer
D [01/Dec/2006:10:09:38 +0100] [Job 93] Sending command string (11 bytes)...
D [01/Dec/2006:10:09:38 +0100] [Job 93] Reading command status...
D [01/Dec/2006:10:09:38 +0100] [Job 93] lpd_command returning 0
D [01/Dec/2006:10:09:38 +0100] [Job 93] Control file is:
D [01/Dec/2006:10:09:38 +0100] [Job 93] Hlocalhost
D [01/Dec/2006:10:09:38 +0100] [Job 93] Proot
D [01/Dec/2006:10:09:38 +0100] [Job 93] JTest Page
D [01/Dec/2006:10:09:38 +0100] [Job 93] ldfA982localhost
D [01/Dec/2006:10:09:38 +0100] [Job 93] UdfA982localhost
D [01/Dec/2006:10:09:38 +0100] [Job 93] NTest Page
D [01/Dec/2006:10:09:38 +0100] [Job 93] lpd_command 02 73 cfA982localhost
D [01/Dec/2006:10:09:38 +0100] [Job 93] Sending command string (20 bytes)...
D [01/Dec/2006:10:09:38 +0100] [Job 93] Reading command status...
D [01/Dec/2006:10:09:38 +0100] [Job 93] lpd_command returning 0
D [01/Dec/2006:10:09:39 +0100] cupsdReadClient: 13 GET /printers/lp HTTP/1.1
D [01/Dec/2006:10:09:39 +0100] cupsdReadClient: 13 Browser asked for 
language "de-de.utf-8"...
D [01/Dec/2006:10:09:39 +0100] cupsdAuthorize: username="root"
D [01/Dec/2006:10:09:39 +0100] CGI /usr/lib/cups/cgi-bin/printers.cgi 
started - PID = 5984
I [01/Dec/2006:10:09:39 +0100] Started "/usr/lib/cups/cgi-bin/printers.cgi" 
(pid=5984)
D [01/Dec/2006:10:09:39 +0100] cupsdSendCommand: 13 file=8
D [01/Dec/2006:10:09:39 +0100] cupsdAcceptClient: 11 from localhost (Domain)
D [01/Dec/2006:10:09:39 +0100] cupsdReadClient: 11 POST / HTTP/1.1
D [01/Dec/2006:10:09:39 +0100] cupsdAuthorize: No authentication data 
provided.
D [01/Dec/2006:10:09:39 +0100] CUPS-Get-Default
D [01/Dec/2006:10:09:39 +0100] cupsdProcessIPPRequest: 11 status_code=0 
(successful-ok)
D [01/Dec/2006:10:09:40 +0100] [CGI] show_printer(http=0x8070990, 
printer="lp")
D [01/Dec/2006:10:09:40 +0100] cupsdReadClient: 11 POST / HTTP/1.1
D [01/Dec/2006:10:09:40 +0100] cupsdAuthorize: No authentication data 
provided.
D [01/Dec/2006:10:09:40 +0100] Get-Printer-Attributes 
ipp://localhost/printers/lp
D [01/Dec/2006:10:09:40 +0100] cupsdProcessIPPRequest: 11 status_code=0 
(successful-ok)
D [01/Dec/2006:10:09:40 +0100] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [01/Dec/2006:10:09:40 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/header.tmpl"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{title}" at 205...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{refresh_page?" at 374, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] cupsdReadClient: 11 POST / HTTP/1.1
D [01/Dec/2006:10:09:40 +0100] cupsdAuthorize: No authentication data 
provided.
D [01/Dec/2006:10:09:40 +0100] Get-Jobs ipp://localhost:631/printers/lp
D [01/Dec/2006:10:09:40 +0100] cupsdProcessIPPRequest: 11 status_code=0 
(successful-ok)
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 374...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{refresh_page}" at 424...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 427 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 427...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 428 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{refresh_page?", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{title}" at 671...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{title}" at 952...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{SECTION=admin" at 1417, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1417...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1418 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1418...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1421 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{SECTION=admin", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{SECTION=classes" at 1680, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1680...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1681 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1681...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1684 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{SECTION=classes", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{SECTION=help" at 1940, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1940...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1941 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1941...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1944 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{SECTION=help", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{SECTION=jobs" at 2209, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 2209...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 2210 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 2210...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 2213 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{SECTION=jobs", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{SECTION=printers" at 2476, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 2476...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 2477 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 2477...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 2480 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{SECTION=printers", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 2841 on EOF...
D [01/Dec/2006:10:09:40 +0100] [CGI] cgiCopyTemplateLang(tmpl="printers.tmpl")
D [01/Dec/2006:10:09:40 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/printers.tmpl"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{printer_type?" at 14, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 14...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 15 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 15...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 16 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{printer_type?", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{#printer_name=0" at 33, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 33...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 34 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 34...
D [01/Dec/2006:10:09:40 +0100] [CGI] Looping on "printer_name" at 50, 
count=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 50...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 101...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 117...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{default_name=lp" at 150, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 150...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 169 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 169...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 170 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{default_name=lp", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?printer_state_message=" at 
196, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 196...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 197 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 197...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_state_message}" at 243...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 252 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?printer_state_message=", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 349...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 406...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{printer_state=3" at 452, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 452...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 457 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 457...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{printer_state=4" at 474, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 474...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 485 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 485...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 493 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{printer_state=4", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 494 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{printer_state=3", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_info}" at 585...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_location}" at 620...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_make_and_model}" at 674...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{printer_state=3" at 718, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 718...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 723 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 723...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{printer_state=4" at 740, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 740...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 753 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 753...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 762 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{printer_state=4", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 763 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{printer_state=3", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{printer_is_accepting_jobs=0" 
at 794, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 794...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 817 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 817...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 843 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{printer_is_accepting_jobs=0", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{printer_is_shared=0" at 866, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 866...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 872 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 872...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 873 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{printer_is_shared=0", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?device_uri=" at 900, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 900...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 901 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 901...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{device_uri}" at 941...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 942 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?device_uri=", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 980...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?cupscommand=1" at 1108, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1108...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 1140...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 1295...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1424 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1424...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1425 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?cupscommand=1", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{printer_state=5" at 1443, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1443...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 1464...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 1514...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1604 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1604...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 1625...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 1674...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1763 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{printer_state=5", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{printer_is_accepting_jobs=0" 
at 1793, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1793...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 1814...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 1862...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1960 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1960...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 1981...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 2029...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 2124 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{printer_is_accepting_jobs=0", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 2157...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 2203...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 2323...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 2370...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{printer_is_shared=0" at 2490, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 2490...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 2511...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 2559...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 2668 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 2668...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 2689...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 2737...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 2856 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{printer_is_shared=0", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 2877...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 2928...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 3042...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 3098...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 3228...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 3279...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 3394...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 3445...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{admin_uri}" at 3562...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_name}" at 3616...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 3748 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished looping on "printer_name"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 3749 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{#printer_name=0", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 3750 on EOF...
D [01/Dec/2006:10:09:40 +0100] [CGI] 
cgiCopyTemplateLang(tmpl="printer-jobs-header.tmpl")
D [01/Dec/2006:10:09:40 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/printer-jobs-header.tmpl"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 37 on EOF...
D [01/Dec/2006:10:09:40 +0100] PID 5984 (/usr/lib/cups/cgi-bin/printers.cgi) 
exited with no errors.
D [01/Dec/2006:10:09:40 +0100] [CGI] cgiCopyTemplateLang(tmpl="search.tmpl")
D [01/Dec/2006:10:09:40 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/search.tmpl"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{SECTION}" at 24...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{?SEARCH_DEST}" at 39...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{WHICH_JOBS?" at 67, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 67...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{WHICH_JOBS}" at 125...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 128 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 128...
D [01/Dec/2006:10:09:40 +0100] cupsdCloseClient: 11
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 129 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{WHICH_JOBS?", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{ORDER?" at 137, result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 137...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{ORDER}" at 185...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 188 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 188...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 189 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{ORDER?", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{SEARCH_DEST?" at 234, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 234...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{SEARCH_DEST}" at 247...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 248 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 248...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{SECTION=classes" at 265, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 265...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 273 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 273...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{SECTION=jobs" at 287, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 287...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 301 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 301...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 309 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{SECTION=jobs", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 310 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{SECTION=classes", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 311 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{SEARCH_DEST?", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{?QUERY}" at 364...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{SECTION}" at 461...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{?SEARCH_DEST}" at 476...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{WHICH_JOBS?" at 488, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 488...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{WHICH_JOBS}" at 512...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{ORDER?" at 519, result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 519...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{ORDER}" at 537...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 538 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 538...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 539 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{ORDER?", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 540 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 540...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{ORDER?" at 547, result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 547...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{ORDER}" at 561...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 562 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 562...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 563 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{ORDER?", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 564 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{WHICH_JOBS?", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 648 on EOF...
D [01/Dec/2006:10:09:40 +0100] [CGI] 
cgiCopyTemplateLang(tmpl="jobs-header.tmpl")
D [01/Dec/2006:10:09:40 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/jobs-header.tmpl"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?which_jobs=" at 17, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 17...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?printer_name=" at 42, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 42...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 48 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 48...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 71...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 72 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?printer_name=", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?printer_name=" at 222, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 222...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 228 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 228...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 251...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 252 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?printer_name=", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 358 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 358...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{which_jobs=all" at 374, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 374...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?printer_name=" at 399, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 399...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 405 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 405...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 428...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 429 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?printer_name=" at 579, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 579...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 585 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 585...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 608...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 609 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 705 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 705...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?printer_name=" at 730, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 730...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 736 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 736...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 759...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 760 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?printer_name=" at 885, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 885...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 891 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 891...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{printer_uri_supported}" at 914...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 915 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?printer_name=", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1021 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{which_jobs=all", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1022 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?which_jobs=", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{total=0" at 1059, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1059...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1088 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1088...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{#job_id}" at 1103...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{total}" at 1115...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?which_jobs=" at 1130, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1130...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1137 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1137...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{which_jobs=all" at 1153, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1153...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1154 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1154...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1163 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{which_jobs=all", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1164 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?which_jobs=", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{total=1" at 1174, result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1174...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1182 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1182...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1197 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{total=1", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1198 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{total=0", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1204 on EOF...
D [01/Dec/2006:10:09:40 +0100] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [01/Dec/2006:10:09:40 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/pager.tmpl"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{PREVURL?" at 87, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 87...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{PREVURL}" at 105...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 196 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 196...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 203 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{PREVURL?", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{ORDER=dec" at 252, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 252...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{THISURL}" at 270...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 379 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 379...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{THISURL}" at 397...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 506 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{ORDER=dec", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{NEXTURL?" at 552, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 552...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{NEXTURL}" at 570...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 660 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 660...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 667 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{NEXTURL?", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 688 on EOF...
D [01/Dec/2006:10:09:40 +0100] [CGI] cgiCopyTemplateLang(tmpl="jobs.tmpl")
D [01/Dec/2006:10:09:40 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/jobs.tmpl"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{#job_id=0" at 11, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 11...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 12 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 12...
D [01/Dec/2006:10:09:40 +0100] [CGI] Looping on "job_id" at 302, count=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 302...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_printer_uri}" at 364...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_printer_name}" at 384...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_id}" at 397...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{?job_name=" at 425, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 425...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 435 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 435...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_name}" at 445...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 446 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{?job_name=", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_originating_user_name}" at 489...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_k_octets}" at 519...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_media_sheets_completed=0" 
at 566, result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 566...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 576 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 576...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{?job_media_sheets_completed}" at 605...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 606 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_media_sheets_completed=0", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_state=3" at 629, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 629...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{time_at_creation}" at 665...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 666 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 666...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_state=4" at 679, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 679...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{time_at_creation}" at 716...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 717 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 717...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_state=5" at 731, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 731...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{time_at_processing}" at 771...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 772 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 772...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_state=6" at 785, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 785...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{time_at_completed}" at 819...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 820 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 820...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_state=7" at 834, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 834...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{time_at_completed}" at 871...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 872 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 872...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_state=8" at 885, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 885...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 893 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 893...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{time_at_completed}" at 926...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 927 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_state=8", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 928 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_state=7", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 929 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_state=6", out=(nil)...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 930 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_state=5", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 931 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_state=4", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 932 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_state=3", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_preserved>0" at 966, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 966...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_id}" at 1017...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_printer_uri}" at 1055...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1146 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1146...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1147 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_preserved>0", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_state=4" at 1161, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1161...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_id}" at 1212...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_printer_uri}" at 1250...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1339 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1339...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1340 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_state=4", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_state=3" at 1354, 
result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1354...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_id}" at 1402...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_printer_uri}" at 1440...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1525 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1525...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1526 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_state=3", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{job_state<7" at 1540, 
result=1...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1540...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_id}" at 1590...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_printer_uri}" at 1628...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{job_id}" at 1763...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1850 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 1850...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1851 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{job_state<7", 
out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1871 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished looping on "job_id"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1882 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{#job_id=0", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 1883 on EOF...
D [01/Dec/2006:10:09:40 +0100] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [01/Dec/2006:10:09:40 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/pager.tmpl"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{PREVURL?" at 87, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 87...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{PREVURL}" at 105...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 196 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 196...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 203 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{PREVURL?", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{ORDER=dec" at 252, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 252...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{THISURL}" at 270...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 379 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 379...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{THISURL}" at 397...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 506 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{ORDER=dec", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting "{NEXTURL?" at 552, result=0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Skip first part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 552...
D [01/Dec/2006:10:09:40 +0100] [CGI] "{NEXTURL}" at 570...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 660 on 
character ':'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Output second part...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 660...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 667 on 
character '}'...
D [01/Dec/2006:10:09:40 +0100] [CGI] Finished "{NEXTURL?", out=0xa7e0d3a0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 688 on EOF...
D [01/Dec/2006:10:09:40 +0100] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [01/Dec/2006:10:09:40 +0100] [CGI] locale="de_de"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Template file 
is "/usr/share/cups/templates/de/trailer.tmpl"...
D [01/Dec/2006:10:09:40 +0100] [CGI] Starting at file position 0...
D [01/Dec/2006:10:09:40 +0100] [CGI] Returning at file position 643 on EOF...
D [01/Dec/2006:10:09:48 +0100] [Job 93] lpd_command 03 0 dfA982localhost
D [01/Dec/2006:10:09:48 +0100] [Job 93] Sending command string (19 bytes)...
D [01/Dec/2006:10:09:48 +0100] [Job 93] Reading command status...
D [01/Dec/2006:10:09:48 +0100] [Job 93] lpd_command returning 0