CUPS: Can't get Zebra LP 2844 label printer to work!

[Oskar Andersson]

Hello,
I've tried now for several hours to get my Zebra LP 2844 label printer to work with Linux and CUPS. I have tried with all of the drivers in CUPS (there are three; one EPL1, one EPL2 and one ZPL). I've checked so the printer works OK when it is connected to a windows machine and everything is OK, there is labels in it. It is connected to the Linux computer with an USB cable.

First I added the printer to cups, following the normal procedure. Then, when I click "print test page" I get the error "No pages found!". When I try to restart the test page print job the error changes to "/usr/lib/cups/filter/rastertolabel failed". Regardless of what driver I test with, and regardless of if I restart the printer.

To me it seams like it can't find any papers by first look (but I'm not good at these parts of linux). I really need help to fix this as the label printer is very important for me to be able to use so I really appreciate all help.. If you reply with a working solution and send me your Paypal email I might also send you some compensation : ) but I'm just an individual who is really  frustrated about this issuse so I don't tell me to hire someone.

With CUPS in normal mode I get the following in the error log:
I [05/Oct/2007:22:06:21 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=7942)
I [05/Oct/2007:22:06:23 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=7943)
I [05/Oct/2007:22:06:28 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=7944)
I [05/Oct/2007:22:06:28 +0200] Adding start banner page "none" to job 21.
I [05/Oct/2007:22:06:28 +0200] Adding end banner page "none" to job 21.
I [05/Oct/2007:22:06:28 +0200] Job 21 queued on "Zebra_LP2844_USB_1" by "root".
I [05/Oct/2007:22:06:28 +0200] Started filter /usr/lib/cups/filter/pstops (PID 7945) for job 21.
I [05/Oct/2007:22:06:28 +0200] Started filter /usr/lib/cups/filter/pstoraster (PID 7946) for job 21.
I [05/Oct/2007:22:06:28 +0200] Started filter /usr/lib/cups/filter/rastertolabel (PID 7948) for job 21.
I [05/Oct/2007:22:06:28 +0200] Started backend /usr/lib/cups/backend/usb (PID 7949) for job 21.
E [05/Oct/2007:22:06:28 +0200] PID 7946 (/usr/lib/cups/filter/pstoraster) stopped with status 127!
I [05/Oct/2007:22:06:28 +0200] Hint: Try setting the LogLevel to "debug" to find out more.
E [05/Oct/2007:22:06:29 +0200] [Job 21] No pages found!
E [05/Oct/2007:22:06:29 +0200] PID 7948 (/usr/lib/cups/filter/rastertolabel) stopped with status 1!
I [05/Oct/2007:22:06:29 +0200] Hint: Try setting the LogLevel to "debug" to find out more.
I [05/Oct/2007:22:06:30 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=7950)

With CUPS in debug mode I get a lot of information from the error log:
D [05/Oct/2007:22:03:24 +0200] cupsdAcceptClient: skipping getpeercon()
D [05/Oct/2007:22:03:24 +0200] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [05/Oct/2007:22:03:24 +0200] cupsdReadClient: 9 POST / HTTP/1.1
D [05/Oct/2007:22:03:24 +0200] cupsdAuthorize: No authentication data provided.
D [05/Oct/2007:22:03:24 +0200] CUPS-Get-Printers
D [05/Oct/2007:22:03:24 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [05/Oct/2007:22:03:24 +0200] cupsdReadClient: 9 POST / HTTP/1.1
D [05/Oct/2007:22:03:24 +0200] cupsdAuthorize: No authentication data provided.
D [05/Oct/2007:22:03:24 +0200] CUPS-Get-Classes
D [05/Oct/2007:22:03:24 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [05/Oct/2007:22:03:24 +0200] cupsdCloseClient: 9
D [05/Oct/2007:22:03:28 +0200] cupsdAcceptClient: skipping getpeercon()
D [05/Oct/2007:22:03:28 +0200] cupsdAcceptClient: 9 from 192.168.1.2:631 (IPv4)
D [05/Oct/2007:22:03:28 +0200] cupsdReadClient: 9 GET /printers/Zebra_LP2844_USB_1?op=print-test-page HTTP/1.1
D [05/Oct/2007:22:03:28 +0200] cupsdReadClient: 9 Browser asked for language "sv.utf-8"...
D [05/Oct/2007:22:03:28 +0200] cupsdAuthorize: username="root"
D [05/Oct/2007:22:03:28 +0200] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 7880
I [05/Oct/2007:22:03:28 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=7880)
D [05/Oct/2007:22:03:28 +0200] cupsdSendCommand: 9 file=10
D [05/Oct/2007:22:03:29 +0200] cupsdAcceptClient: skipping getpeercon()
D [05/Oct/2007:22:03:29 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [05/Oct/2007:22:03:29 +0200] cupsdReadClient: 12 POST /printers/Zebra_LP2844_USB_1 HTTP/1.1
D [05/Oct/2007:22:03:29 +0200] cupsdAuthorize: No authentication data provided.
D [05/Oct/2007:22:03:29 +0200] Print-Job ipp://localhost:631/printers/Zebra_LP2844_USB_1
D [05/Oct/2007:22:03:29 +0200] print_job: request file type is application/postscript.
D [05/Oct/2007:22:03:29 +0200] add_job: setting context of job to UNKNOWN SL
D [05/Oct/2007:22:03:29 +0200] add_job: requesting-user-name="root"
D [05/Oct/2007:22:03:29 +0200] Adding default job-sheets values "none,none"...
I [05/Oct/2007:22:03:29 +0200] Adding start banner page "none" to job 18.
D [05/Oct/2007:22:03:29 +0200] Discarding unused job-created event...
I [05/Oct/2007:22:03:29 +0200] Adding end banner page "none" to job 18.
I [05/Oct/2007:22:03:29 +0200] Job 18 queued on "Zebra_LP2844_USB_1" by "root".
D [05/Oct/2007:22:03:29 +0200] Job 18 hold_until = 0
D [05/Oct/2007:22:03:29 +0200] Discarding unused printer-state-changed event...
D [05/Oct/2007:22:03:29 +0200] job-sheets=none,none
D [05/Oct/2007:22:03:29 +0200] banner_page = 0
D [05/Oct/2007:22:03:29 +0200] [Job 18] argv[0]="Zebra_LP2844_USB_1"
D [05/Oct/2007:22:03:29 +0200] [Job 18] argv[1]="18"
D [05/Oct/2007:22:03:29 +0200] [Job 18] argv[2]="root"
D [05/Oct/2007:22:03:29 +0200] [Job 18] argv[3]="Test Page"
D [05/Oct/2007:22:03:29 +0200] [Job 18] argv[4]="1"
D [05/Oct/2007:22:03:29 +0200] [Job 18] argv[5]="job-uuid=urn:uuid:a8626057-a5ba-398f-6b70-d169c8cefdd5"
D [05/Oct/2007:22:03:29 +0200] [Job 18] argv[6]="/var/spool/cups/d00018-001"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups-1.2.4"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[9]="SERVER_ADMIN=root at localhost.localdomain"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[10]="SOFTWARE=CUPS/1.2.4"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[12]="USER=root"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[14]="CUPS_ENCRYPTION=IfRequested"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[15]="IPP_PORT=631"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[16]="CHARSET=utf-8"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[17]="LANG=C"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[18]="PPD=/etc/cups/ppd/Zebra_LP2844_USB_1.ppd"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[19]="RIP_MAX_CACHE=8m"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[20]="CONTENT_TYPE=application/postscript"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[21]="DEVICE_URI=usb://Zebra%20/LP2844"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[22]="PRINTER=Zebra_LP2844_USB_1"
D [05/Oct/2007:22:03:29 +0200] [Job 18] envp[23]="FINAL_CONTENT_TYPE=printer/Zebra_LP2844_USB_1"
I [05/Oct/2007:22:03:29 +0200] Started filter /usr/lib/cups/filter/pstops (PID 7881) for job 18.
I [05/Oct/2007:22:03:29 +0200] Started filter /usr/lib/cups/filter/pstoraster (PID 7882) for job 18.
I [05/Oct/2007:22:03:29 +0200] Started filter /usr/lib/cups/filter/rastertolabel (PID 7884) for job 18.
I [05/Oct/2007:22:03:29 +0200] Started backend /usr/lib/cups/backend/usb (PID 7885) for job 18.
D [05/Oct/2007:22:03:29 +0200] Discarding unused job-state event...
D [05/Oct/2007:22:03:29 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
E [05/Oct/2007:22:03:29 +0200] PID 7882 (/usr/lib/cups/filter/pstoraster) stopped with status 127!
D [05/Oct/2007:22:03:29 +0200] [Job 18] /usr/lib/cups/filter/pstoraster: line 55: /usr/bin/gs: No such file or directory
D [05/Oct/2007:22:03:29 +0200] PID 7880 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [05/Oct/2007:22:03:29 +0200] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [05/Oct/2007:22:03:29 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:29 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/header.tmpl"...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:29 +0200] [CGI] "{title}" at 205...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting "{refresh_page?" at 375, result=1...
D [05/Oct/2007:22:03:29 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 375...
D [05/Oct/2007:22:03:29 +0200] [CGI] "{refresh_page}" at 425...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 428 on character ':'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 428...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 429 on character '}'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Finished "{refresh_page?", out=0xe274c0...
D [05/Oct/2007:22:03:29 +0200] [CGI] "{title}" at 672...
D [05/Oct/2007:22:03:29 +0200] [CGI] "{title}" at 953...
D [05/Oct/2007:22:03:29 +0200] cupsdCloseClient: 12
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting "{SECTION=admin" at 1411, result=0...
D [05/Oct/2007:22:03:29 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 1411...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 1412 on character ':'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 1412...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 1415 on character '}'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Finished "{SECTION=admin", out=0xe274c0...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [05/Oct/2007:22:03:29 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 1678...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 1679 on character ':'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 1679...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 1682 on character '}'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Finished "{SECTION=classes", out=0xe274c0...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [05/Oct/2007:22:03:29 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 1938...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 1939 on character ':'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 1939...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 1942 on character '}'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Finished "{SECTION=help", out=0xe274c0...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting "{SECTION=jobs" at 2208, result=0...
D [05/Oct/2007:22:03:29 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 2208...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 2209 on character ':'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 2209...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 2212 on character '}'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Finished "{SECTION=jobs", out=0xe274c0...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting "{SECTION=printers" at 2466, result=1...
D [05/Oct/2007:22:03:29 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 2466...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 2467 on character ':'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 2467...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 2470 on character '}'...
D [05/Oct/2007:22:03:29 +0200] [CGI] Finished "{SECTION=printers", out=0xe274c0...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 2832 on EOF...
D [05/Oct/2007:22:03:29 +0200] [CGI] cgiCopyTemplateLang(tmpl="test-page.tmpl")
D [05/Oct/2007:22:03:29 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:29 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/test-page.tmpl"...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:29 +0200] [CGI] "{SECTION}" at 52...
D [05/Oct/2007:22:03:29 +0200] [CGI] "{printer_name}" at 67...
D [05/Oct/2007:22:03:29 +0200] [CGI] "{printer_name}" at 84...
D [05/Oct/2007:22:03:29 +0200] [CGI] "{job_id}" at 93...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 103 on EOF...
D [05/Oct/2007:22:03:29 +0200] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [05/Oct/2007:22:03:29 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:29 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/trailer.tmpl"...
D [05/Oct/2007:22:03:29 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:29 +0200] [CGI] Returning at file position 622 on EOF...
D [05/Oct/2007:22:03:29 +0200] [Job 18] Page = 288x360; 0,0 to 288,360
D [05/Oct/2007:22:03:29 +0200] [Job 18] slow_collate=0, slow_duplex=0, slow_order=0
D [05/Oct/2007:22:03:29 +0200] [Job 18] Before copy_comments - %!PS-Adobe-3.0
D [05/Oct/2007:22:03:29 +0200] [Job 18] %!PS-Adobe-3.0
D [05/Oct/2007:22:03:29 +0200] [Job 18] %%BoundingBox: 0 0 612 792
D [05/Oct/2007:22:03:29 +0200] [Job 18] %%Pages: 1
D [05/Oct/2007:22:03:29 +0200] [Job 18] %%LanguageLevel: 1
D [05/Oct/2007:22:03:29 +0200] [Job 18] %%DocumentData: Clean7Bit
D [05/Oct/2007:22:03:29 +0200] [Job 18] %%DocumentSuppliedResources: procset testprint/1.2
D [05/Oct/2007:22:03:29 +0200] [Job 18] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [05/Oct/2007:22:03:29 +0200] [Job 18] %%Creator: Michael Sweet, Easy Software Products
D [05/Oct/2007:22:03:29 +0200] [Job 18] %%CreationDate: D:20060114093000+0500
D [05/Oct/2007:22:03:29 +0200] [Job 18] %%Title: Test Page
D [05/Oct/2007:22:03:29 +0200] [Job 18] %%EndComments
D [05/Oct/2007:22:03:29 +0200] [Job 18] Before copy_prolog - %%BeginProlog
D [05/Oct/2007:22:03:29 +0200] [Job 18] Before copy_setup - %%Page: 1 1
D [05/Oct/2007:22:03:29 +0200] [Job 18] Before page loop - %%Page: 1 1
D [05/Oct/2007:22:03:29 +0200] [Job 18] Copying page 1...
D [05/Oct/2007:22:03:29 +0200] [Job 18] pagew = 288.0, pagel = 360.0
D [05/Oct/2007:22:03:29 +0200] [Job 18] bboxw = 288, bboxl = 360
D [05/Oct/2007:22:03:29 +0200] [Job 18] PageLeft = 0.0, PageRight = 288.0
D [05/Oct/2007:22:03:29 +0200] [Job 18] PageTop = 360.0, PageBottom = 0.0
D [05/Oct/2007:22:03:29 +0200] [Job 18] PageWidth = 288.0, PageLength = 360.0
D [05/Oct/2007:22:03:29 +0200] [Job 18] Wrote 1 pages...
D [05/Oct/2007:22:03:29 +0200] PID 7881 (/usr/lib/cups/filter/pstops) exited with no errors.
E [05/Oct/2007:22:03:29 +0200] [Job 18] No pages found!
E [05/Oct/2007:22:03:29 +0200] PID 7884 (/usr/lib/cups/filter/rastertolabel) stopped with status 1!
D [05/Oct/2007:22:03:29 +0200] Discarding unused printer-state-changed event...
D [05/Oct/2007:22:03:29 +0200] [Job 18] Printer using device file "/dev/usb/lp0"...
D [05/Oct/2007:22:03:29 +0200] Discarding unused printer-state-changed event...
D [05/Oct/2007:22:03:29 +0200] [Job 18] backendRunLoop(print_fd=0, device_fd=4, use_bc=1)
D [05/Oct/2007:22:03:29 +0200] PID 7885 (/usr/lib/cups/backend/usb) exited with no errors.
D [05/Oct/2007:22:03:29 +0200] [Job 18] File 0 is complete.
D [05/Oct/2007:22:03:29 +0200] Discarding unused printer-state-changed event...
D [05/Oct/2007:22:03:29 +0200] Discarding unused job-stopped event...
D [05/Oct/2007:22:03:30 +0200] Unloading job 18...
D [05/Oct/2007:22:03:30 +0200] cupsdReadClient: 9 GET /printers/Zebra_LP2844_USB_1 HTTP/1.1
D [05/Oct/2007:22:03:30 +0200] cupsdReadClient: 9 Browser asked for language "sv.utf-8"...
D [05/Oct/2007:22:03:30 +0200] cupsdAuthorize: username="root"
D [05/Oct/2007:22:03:30 +0200] CGI /usr/lib/cups/cgi-bin/printers.cgi started - PID = 7886
I [05/Oct/2007:22:03:30 +0200] Started "/usr/lib/cups/cgi-bin/printers.cgi" (pid=7886)
D [05/Oct/2007:22:03:30 +0200] cupsdSendCommand: 9 file=10
D [05/Oct/2007:22:03:32 +0200] cupsdAcceptClient: skipping getpeercon()
D [05/Oct/2007:22:03:32 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [05/Oct/2007:22:03:32 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [05/Oct/2007:22:03:32 +0200] cupsdAuthorize: No authentication data provided.
D [05/Oct/2007:22:03:32 +0200] CUPS-Get-Default
D [05/Oct/2007:22:03:32 +0200] CUPS-Get-Default client-error-not-found: No default printer
D [05/Oct/2007:22:03:32 +0200] cupsdProcessIPPRequest: 12 status_code=406 (client-error-not-found)
D [05/Oct/2007:22:03:32 +0200] [CGI] show_printer(http=0x9dd21f8, printer="Zebra_LP2844_USB_1")
D [05/Oct/2007:22:03:32 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [05/Oct/2007:22:03:32 +0200] cupsdAuthorize: No authentication data provided.
D [05/Oct/2007:22:03:32 +0200] Get-Printer-Attributes ipp://localhost/printers/Zebra_LP2844_USB_1
D [05/Oct/2007:22:03:32 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [05/Oct/2007:22:03:32 +0200] [CGI] cgiCopyTemplateLang(tmpl="header.tmpl")
D [05/Oct/2007:22:03:32 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/header.tmpl"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{title}" at 205...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{refresh_page?" at 375, result=0...
D [05/Oct/2007:22:03:32 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [05/Oct/2007:22:03:32 +0200] cupsdAuthorize: No authentication data provided.
D [05/Oct/2007:22:03:32 +0200] Get-Jobs ipp://localhost:631/printers/Zebra_LP2844_USB_1
D [05/Oct/2007:22:03:32 +0200] Loading attributes for job 17...
D [05/Oct/2007:22:03:32 +0200] Loading attributes for job 18...
D [05/Oct/2007:22:03:32 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 375...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{refresh_page}" at 425...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 428 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 428...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 429 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{refresh_page?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{title}" at 672...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{title}" at 953...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{SECTION=admin" at 1411, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1411...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1412 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1412...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1415 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{SECTION=admin", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{SECTION=classes" at 1678, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1678...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1679 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1679...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1682 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{SECTION=classes", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{SECTION=help" at 1938, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1938...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1939 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1939...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1942 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{SECTION=help", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{SECTION=jobs" at 2208, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 2208...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 2209 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 2209...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 2212 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{SECTION=jobs", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{SECTION=printers" at 2466, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 2466...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 2467 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 2467...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 2470 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{SECTION=printers", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 2832 on EOF...
D [05/Oct/2007:22:03:32 +0200] [CGI] cgiCopyTemplateLang(tmpl="printers.tmpl")
D [05/Oct/2007:22:03:32 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/printers.tmpl"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{printer_type?" at 14, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 14...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 15 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 15...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 16 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{printer_type?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{#printer_name=0" at 33, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 33...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 34 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 34...
D [05/Oct/2007:22:03:32 +0200] [CGI] Looping on "printer_name" at 50, count=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 50...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 101...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 117...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{default_name=Zebra_LP2844_USB_1" at 150, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 150...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 170 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 170...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 171 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{default_name=Zebra_LP2844_USB_1", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?printer_state_message=" at 197, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 197...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 198 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 198...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_state_message}" at 244...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 253 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?printer_state_message=", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 350...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 407...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{printer_state=3" at 453, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 453...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 458 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 458...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{printer_state=4" at 475, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 475...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 486 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 486...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 494 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{printer_state=4", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 495 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{printer_state=3", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_info}" at 585...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_location}" at 622...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_make_and_model}" at 682...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{printer_state=3" at 723, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 723...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 732 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 732...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{printer_state=4" at 749, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 749...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 759 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 759...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 767 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{printer_state=4", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 768 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{printer_state=3", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{printer_is_accepting_jobs=0" at 799, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 799...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 812 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 812...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 828 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{printer_is_accepting_jobs=0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{printer_is_shared=0" at 851, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 851...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 856 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 856...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 857 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{printer_is_shared=0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?device_uri=" at 884, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 884...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 885 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 885...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{device_uri}" at 920...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 921 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?device_uri=", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 959...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?cupscommand=1" at 1087, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1087...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 1119...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 1272...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1400 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1400...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1401 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?cupscommand=1", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{printer_state=5" at 1419, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1419...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 1440...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 1490...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1580 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1580...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 1601...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 1650...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1739 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{printer_state=5", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{printer_is_accepting_jobs=0" at 1769, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1769...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 1790...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 1838...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1925 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1925...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 1946...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 1994...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 2078 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{printer_is_accepting_jobs=0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 2111...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 2157...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 2263...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 2310...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{printer_is_shared=0" at 2423, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 2423...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 2444...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 2492...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 2600 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 2600...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 2621...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 2669...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 2781 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{printer_is_shared=0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 2802...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 2853...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 2966...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 3022...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 3149...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 3200...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 3311...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 3362...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{admin_uri}" at 3479...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_name}" at 3533...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 3668 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished looping on "printer_name"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 3669 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{#printer_name=0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 3670 on EOF...
D [05/Oct/2007:22:03:32 +0200] [CGI] cgiCopyTemplateLang(tmpl="printer-jobs-header.tmpl")
D [05/Oct/2007:22:03:32 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/printer-jobs-header.tmpl"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 28 on EOF...
D [05/Oct/2007:22:03:32 +0200] PID 7886 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [05/Oct/2007:22:03:32 +0200] cupsdCloseClient: 12
D [05/Oct/2007:22:03:32 +0200] [CGI] cgiCopyTemplateLang(tmpl="search.tmpl")
D [05/Oct/2007:22:03:32 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/search.tmpl"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{SECTION}" at 24...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{?SEARCH_DEST}" at 39...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{WHICH_JOBS?" at 67, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 67...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{WHICH_JOBS}" at 125...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 128 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 128...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 129 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{WHICH_JOBS?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{ORDER?" at 137, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 137...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{ORDER}" at 185...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 188 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 188...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 189 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{ORDER?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{SEARCH_DEST?" at 232, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 232...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{SEARCH_DEST}" at 245...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 246 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 246...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{SECTION=classes" at 263, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 263...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 271 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 271...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{SECTION=jobs" at 285, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 285...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 290 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 290...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 299 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{SECTION=jobs", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 300 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{SECTION=classes", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 301 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{SEARCH_DEST?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{?QUERY}" at 354...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{SECTION}" at 450...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{?SEARCH_DEST}" at 465...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{WHICH_JOBS?" at 477, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 477...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{WHICH_JOBS}" at 501...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{ORDER?" at 508, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 508...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{ORDER}" at 526...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 527 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 527...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 528 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{ORDER?", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 529 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 529...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{ORDER?" at 536, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 536...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{ORDER}" at 550...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 551 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 551...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 552 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{ORDER?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 553 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{WHICH_JOBS?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 635 on EOF...
D [05/Oct/2007:22:03:32 +0200] [CGI] cgiCopyTemplateLang(tmpl="jobs-header.tmpl")
D [05/Oct/2007:22:03:32 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/jobs-header.tmpl"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?which_jobs=" at 17, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 17...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?printer_name=" at 42, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 42...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 48 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 48...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 71...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 72 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?printer_name=", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?printer_name=" at 210, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 210...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 216 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 216...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 239...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 240 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?printer_name=", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 333 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 333...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{which_jobs=all" at 349, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 349...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?printer_name=" at 374, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 374...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 380 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 380...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 403...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 404 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?printer_name=" at 542, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 542...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 548 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 548...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 571...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 572 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 655 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 655...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?printer_name=" at 680, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 680...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 686 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 686...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 709...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 710 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?printer_name=" at 822, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 822...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 828 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 828...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{printer_uri_supported}" at 851...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 852 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?printer_name=", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 945 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{which_jobs=all", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 946 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?which_jobs=", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{total=0" at 983, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 983...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 993 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 993...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{#job_id}" at 1008...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{total}" at 1019...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?which_jobs=" at 1034, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1034...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1041 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1041...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{which_jobs=all" at 1057, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1057...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1058 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1058...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1067 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{which_jobs=all", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1068 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?which_jobs=", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{total=1" at 1082, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1082...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1083 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1083...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1085 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{total=1", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1086 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{total=0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1092 on EOF...
D [05/Oct/2007:22:03:32 +0200] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [05/Oct/2007:22:03:32 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/pager.tmpl"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{PREVURL?" at 87, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 87...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{PREVURL}" at 105...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 195 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 195...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 202 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{PREVURL?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{ORDER=dec" at 251, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 251...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{THISURL}" at 269...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 373 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 373...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{THISURL}" at 391...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 496 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{ORDER=dec", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{NEXTURL?" at 542, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 542...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{NEXTURL}" at 560...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 640 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 640...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 647 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{NEXTURL?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 668 on EOF...
D [05/Oct/2007:22:03:32 +0200] [CGI] cgiCopyTemplateLang(tmpl="jobs.tmpl")
D [05/Oct/2007:22:03:32 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/jobs.tmpl"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{#job_id=0" at 11, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 11...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 12 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 12...
D [05/Oct/2007:22:03:32 +0200] [CGI] Looping on "job_id" at 292, count=2...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 292...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_uri}" at 354...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_name}" at 374...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 387...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?job_name=" at 415, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 415...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 422 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 422...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_name}" at 432...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 433 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?job_name=", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_originating_user_name}" at 476...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_k_octets}" at 506...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_media_sheets_completed=0" at 553, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 553...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 560 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 560...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{?job_media_sheets_completed}" at 589...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 590 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_media_sheets_completed=0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=3" at 613, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 613...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{time_at_creation}" at 648...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 649 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 649...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=4" at 662, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 662...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{time_at_creation}" at 701...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 702 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 702...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=5" at 716, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 716...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{time_at_processing}" at 755...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 756 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 756...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=6" at 769, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 769...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 777 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 777...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=7" at 791, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 791...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{time_at_completed}" at 826...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 827 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 827...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=8" at 840, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 840...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 848 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 848...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{time_at_completed}" at 881...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 882 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=8", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 883 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=7", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 884 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=6", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 885 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=5", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 886 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=4", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 887 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=3", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_preserved>0" at 921, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 921...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 972...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_uri}" at 1010...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1100 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1100...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1101 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_preserved>0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=4" at 1115, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1115...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 1166...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_uri}" at 1204...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1287 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1287...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1288 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=4", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=3" at 1302, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1302...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 1350...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_uri}" at 1388...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1472 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1472...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1473 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=3", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state<7" at 1487, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1487...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 1537...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_uri}" at 1575...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 1704...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1783 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1783...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1784 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state<7", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1804 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 292...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_uri}" at 354...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_name}" at 374...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 387...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{?job_name=" at 415, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 415...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 422 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 422...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_name}" at 432...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 433 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{?job_name=", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_originating_user_name}" at 476...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_k_octets}" at 506...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_media_sheets_completed=0" at 553, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 553...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 560 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 560...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{?job_media_sheets_completed}" at 589...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 590 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_media_sheets_completed=0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=3" at 613, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 613...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{time_at_creation}" at 648...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 649 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 649...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=4" at 662, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 662...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{time_at_creation}" at 701...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 702 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 702...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=5" at 716, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 716...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{time_at_processing}" at 755...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 756 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 756...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=6" at 769, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 769...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 777 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 777...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=7" at 791, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 791...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{time_at_completed}" at 826...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 827 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 827...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=8" at 840, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 840...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 848 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 848...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{time_at_completed}" at 881...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 882 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=8", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 883 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=7", out=(nil)...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 884 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=6", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 885 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=5", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 886 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=4", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 887 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=3", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_preserved>0" at 921, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 921...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 972...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_uri}" at 1010...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1100 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1100...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1101 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_preserved>0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=4" at 1115, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1115...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 1166...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_uri}" at 1204...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1287 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1287...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1288 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=4", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state=3" at 1302, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1302...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 1350...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_uri}" at 1388...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1472 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1472...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1473 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state=3", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{job_state<7" at 1487, result=1...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1487...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 1537...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_printer_uri}" at 1575...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{job_id}" at 1704...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1783 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 1783...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1784 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{job_state<7", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1804 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished looping on "job_id"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1815 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{#job_id=0", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 1816 on EOF...
D [05/Oct/2007:22:03:32 +0200] [CGI] cgiCopyTemplateLang(tmpl="pager.tmpl")
D [05/Oct/2007:22:03:32 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/pager.tmpl"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{PREVURL?" at 87, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 87...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{PREVURL}" at 105...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 195 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 195...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 202 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{PREVURL?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{ORDER=dec" at 251, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 251...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{THISURL}" at 269...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 373 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 373...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{THISURL}" at 391...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 496 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{ORDER=dec", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting "{NEXTURL?" at 542, result=0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Skip first part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 542...
D [05/Oct/2007:22:03:32 +0200] [CGI] "{NEXTURL}" at 560...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 640 on character ':'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Output second part...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 640...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 647 on character '}'...
D [05/Oct/2007:22:03:32 +0200] [CGI] Finished "{NEXTURL?", out=0x2964c0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 668 on EOF...
D [05/Oct/2007:22:03:32 +0200] [CGI] cgiCopyTemplateLang(tmpl="trailer.tmpl")
D [05/Oct/2007:22:03:32 +0200] [CGI] locale="sv_"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Template file is "/usr/share/cups/templates/sv/trailer.tmpl"...
D [05/Oct/2007:22:03:32 +0200] [CGI] Starting at file position 0...
D [05/Oct/2007:22:03:32 +0200] [CGI] Returning at file position 622 on EOF...
D [05/Oct/2007:22:03:38 +0200] cupsdReadClient: 9 GET /admin/log/error_log HTTP/1.1
D [05/Oct/2007:22:03:38 +0200] cupsdReadClient: 9 Browser asked for language "sv.utf-8"...
D [05/Oct/2007:22:03:38 +0200] cupsdAuthorize: username="root"

Thanks in advance,
Oskar R