I [12/May/2009:07:40:46 +0200] Listening to ::1:631 (IPv6)
I [12/May/2009:07:40:46 +0200] Listening to 127.0.0.1:631 (IPv4)
I [12/May/2009:07:40:46 +0200] Listening to /var/run/cups/cups.sock (Domain)
I [12/May/2009:07:40:46 +0200] Loaded configuration file "/etc/cups/cupsd.conf"
I [12/May/2009:07:40:46 +0200] Using default TempDir of /var/spool/cups/tmp...
I [12/May/2009:07:40:46 +0200] Configured for up to 100 clients.
I [12/May/2009:07:40:46 +0200] Allowing up to 100 client connections per host.
I [12/May/2009:07:40:46 +0200] Using policy "default" as the default!
I [12/May/2009:07:40:46 +0200] Full reload is required.
D [12/May/2009:07:40:46 +0200] Discarding unused printer-state-changed event...
I [12/May/2009:07:40:46 +0200] Saving job cache file "/var/cache/cups/job.cache"...
D [12/May/2009:07:40:46 +0200] Discarding unused printer-stopped event...
D [12/May/2009:07:40:46 +0200] Discarding unused printer-stopped event...
I [12/May/2009:07:40:47 +0200] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 37 types, 65 filters...
D [12/May/2009:07:40:47 +0200] Loading printer HL-5040-series...
D [12/May/2009:07:40:47 +0200] Loading printer magicolor-2530-DL...
D [12/May/2009:07:40:47 +0200] cupsdLoadRemoteCache: Not loading remote cache.
I [12/May/2009:07:40:47 +0200] Loading job cache file "/var/cache/cups/job.cache"...
D [12/May/2009:07:40:47 +0200] [Job 1] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 2] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 3] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 4] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 5] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 6] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 7] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 8] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 9] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 10] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 11] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 12] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 13] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 14] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 15] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 16] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 17] Loading from cache...
D [12/May/2009:07:40:47 +0200] [Job 17] Loading attributes...
D [12/May/2009:07:40:47 +0200] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [12/May/2009:07:40:47 +0200] Full reload complete.
I [12/May/2009:07:40:47 +0200] Listening to ::1:631 on fd 2...
I [12/May/2009:07:40:47 +0200] Listening to 127.0.0.1:631 on fd 4...
I [12/May/2009:07:40:47 +0200] Listening to /var/run/cups/cups.sock on fd 5...
I [12/May/2009:07:40:47 +0200] Resuming new connection processing...
D [12/May/2009:07:40:47 +0200] Discarding unused server-restarted event...
D [12/May/2009:07:40:47 +0200] cupsdAcceptClient: 8 from localhost (Domain)
E [12/May/2009:07:40:47 +0200] PID 5167 (/usr/lib/cups/filter/foomatic-rip) crashed on signal 9!
E [12/May/2009:07:40:47 +0200] PID 5168 (/usr/lib/cups/backend/socket) crashed on signal 9!
D [12/May/2009:07:40:47 +0200] cupsdReadClient: 8 POST / HTTP/1.1
D [12/May/2009:07:40:47 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:40:47 +0200] Get-Notifications /
D [12/May/2009:07:40:47 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:07:40:47 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [12/May/2009:07:40:47 +0200] cupsdCloseClient: 8
I [12/May/2009:07:40:59 +0200] Saving subscriptions.conf...
D [12/May/2009:07:40:59 +0200] [Job 17] job-sheets=none,none
D [12/May/2009:07:40:59 +0200] [Job 17] banner_page = 0
D [12/May/2009:07:40:59 +0200] [Job 17] argv[0]="magicolor-2530-DL"
D [12/May/2009:07:40:59 +0200] [Job 17] argv[1]="17"
D [12/May/2009:07:40:59 +0200] [Job 17] argv[2]="laurent"
D [12/May/2009:07:40:59 +0200] [Job 17] argv[3]="out.pnm"
D [12/May/2009:07:40:59 +0200] [Job 17] argv[4]="1"
D [12/May/2009:07:40:59 +0200] [Job 17] argv[5]="Resolution=600x600dpi Quality=normal MediaType=plain NupOrient=port ColorMode=ICM PageSize=A4 Nup=1up number-up=1 ICM=km2530-jc Duplex=None InputSlot=Auto job-uuid=urn:uuid:8f662eb9-b7f4-3052-4e04-a43bd7ad6759"
D [12/May/2009:07:40:59 +0200] [Job 17] argv[6]="/var/spool/cups/d00017-001"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[9]="SERVER_ADMIN=root@boromir"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[10]="SOFTWARE=CUPS/1.3.9"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[12]="TZ=Europe/Paris"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[13]="USER=root"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[16]="IPP_PORT=631"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[17]="CHARSET=utf-8"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[18]="LANG=fr_FR.UTF8"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[20]="RIP_MAX_CACHE=8m"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[21]="CONTENT_TYPE=application/pdf"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[22]="DEVICE_URI=socket://192.168.1.117:9100"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[23]="PRINTER=magicolor-2530-DL"
D [12/May/2009:07:40:59 +0200] [Job 17] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL"
I [12/May/2009:07:40:59 +0200] [Job 17] Started filter /usr/lib/cups/filter/pdftopdf (PID 5818)
I [12/May/2009:07:40:59 +0200] [Job 17] Started filter /usr/lib/cups/filter/foomatic-rip (PID 5819)
I [12/May/2009:07:40:59 +0200] [Job 17] Started backend /usr/lib/cups/backend/socket (PID 5821)
I [12/May/2009:07:40:59 +0200] Saving subscriptions.conf...
D [12/May/2009:07:40:59 +0200] [Job 17] Getting input from file 
D [12/May/2009:07:40:59 +0200] [Job 17] foomatic-rip version 4.0.0.195 running...
D [12/May/2009:07:40:59 +0200] [Job 17] Parsing PPD file ...
D [12/May/2009:07:40:59 +0200] [Job 17] Added option PageSize
D [12/May/2009:07:40:59 +0200] [Job 17] Added option Quality
D [12/May/2009:07:40:59 +0200] [Job 17] Added option PrinterType
D [12/May/2009:07:40:59 +0200] [Job 17] Added option ColorMode
D [12/May/2009:07:40:59 +0200] [Job 17] Added option Resolution
D [12/May/2009:07:40:59 +0200] [Job 17] Added option ImageableArea
D [12/May/2009:07:40:59 +0200] [Job 17] Added option PaperDimension
D [12/May/2009:07:40:59 +0200] [Job 17] Added option InputSlot
D [12/May/2009:07:40:59 +0200] [Job 17] Added option MediaType
D [12/May/2009:07:40:59 +0200] [Job 17] Added option Duplex
D [12/May/2009:07:40:59 +0200] [Job 17] Added option Copies
D [12/May/2009:07:40:59 +0200] [Job 17] Added option ICM
D [12/May/2009:07:40:59 +0200] [Job 17] Added option Nup
D [12/May/2009:07:40:59 +0200] [Job 17] Added option NupOrient
D [12/May/2009:07:40:59 +0200] [Job 17] Added option Font
D [12/May/2009:07:40:59 +0200] [Job 17] 
D [12/May/2009:07:40:59 +0200] [Job 17] Parameter Summary
D [12/May/2009:07:40:59 +0200] [Job 17] -----------------
D [12/May/2009:07:40:59 +0200] [Job 17] 
D [12/May/2009:07:40:59 +0200] [Job 17] Spooler: cups
D [12/May/2009:07:40:59 +0200] [Job 17] Printer: magicolor-2530-DL
D [12/May/2009:07:40:59 +0200] [Job 17] Shell: /bin/bash
D [12/May/2009:07:40:59 +0200] [Job 17] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd
D [12/May/2009:07:40:59 +0200] [Job 17] ATTR file: 
D [12/May/2009:07:40:59 +0200] [Job 17] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended)
D [12/May/2009:07:40:59 +0200] [Job 17] Job title: out.pnm
D [12/May/2009:07:40:59 +0200] [Job 17] File(s) to be printed:
D [12/May/2009:07:40:59 +0200] [Job 17] <STDIN>
D [12/May/2009:07:40:59 +0200] [Job 17] 
D [12/May/2009:07:40:59 +0200] [Job 17] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [12/May/2009:07:40:59 +0200] [Job 17] Printing system options:
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'number-up=1'
D [12/May/2009:07:40:59 +0200] [Job 17] Unknown option number-up=1.
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'job-uuid=urn:uuid:8f662eb9-b7f4-3052-4e04-a43bd7ad6759'
D [12/May/2009:07:40:59 +0200] [Job 17] Unknown option job-uuid=urn:uuid:8f662eb9-b7f4-3052-4e04-a43bd7ad6759.
D [12/May/2009:07:40:59 +0200] [Job 17] Options from the PPD file:
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'Resolution=600x600dpi'
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'Quality=normal'
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'MediaType=plain'
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'NupOrient=port'
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'ColorMode=ICM'
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'PageSize=A4'
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'Nup=1up'
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'ICM=km2530-jc'
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'Duplex=None'
D [12/May/2009:07:40:59 +0200] [Job 17] Pondering option 'InputSlot=Auto'
D [12/May/2009:07:40:59 +0200] [Job 17] 
D [12/May/2009:07:40:59 +0200] [Job 17] ================================================
D [12/May/2009:07:40:59 +0200] [Job 17] 
D [12/May/2009:07:40:59 +0200] [Job 17] File: <STDIN>
D [12/May/2009:07:40:59 +0200] [Job 17] 
D [12/May/2009:07:40:59 +0200] [Job 17] ================================================
D [12/May/2009:07:40:59 +0200] [Job 17] 
D [12/May/2009:07:40:59 +0200] [Job 17] Filetype: PDF
D [12/May/2009:07:40:59 +0200] [Job 17] Driver does not understand PDF input, converting to PostScript
D [12/May/2009:07:40:59 +0200] [Job 17] Starting process "pdf-to-ps" (generation 1)
D [12/May/2009:07:40:59 +0200] [Job 17] Connected to 192.168.1.117:9100 (IPv4)...
D [12/May/2009:07:40:59 +0200] [Job 17] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb7f17fc0)
I [12/May/2009:07:40:59 +0200] Saving subscriptions.conf...
D [12/May/2009:07:40:59 +0200] cupsdAcceptClient: 8 from localhost (Domain)
D [12/May/2009:07:40:59 +0200] cupsdReadClient: 8 POST / HTTP/1.1
D [12/May/2009:07:40:59 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:40:59 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:07:40:59 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [12/May/2009:07:40:59 +0200] cupsdCloseClient: 8
D [12/May/2009:07:40:59 +0200] cupsdAcceptClient: 8 from localhost (Domain)
D [12/May/2009:07:40:59 +0200] cupsdReadClient: 8 POST / HTTP/1.1
D [12/May/2009:07:40:59 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:40:59 +0200] Get-Notifications /
D [12/May/2009:07:40:59 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:07:40:59 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok)
D [12/May/2009:07:40:59 +0200] cupsdCloseClient: 8
D [12/May/2009:07:41:10 +0200] PID 5818 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [12/May/2009:07:41:10 +0200] [Job 17] Filetype: PostScript
D [12/May/2009:07:41:10 +0200] [Job 17] Reading PostScript input ...
D [12/May/2009:07:41:10 +0200] [Job 17] --> This document is DSC-conforming!
D [12/May/2009:07:41:10 +0200] [Job 17] 
D [12/May/2009:07:41:10 +0200] [Job 17] -----------
D [12/May/2009:07:41:10 +0200] [Job 17] Found: %%BeginProlog
D [12/May/2009:07:41:10 +0200] [Job 17] Inserting option code into "Prolog" section.
D [12/May/2009:07:41:10 +0200] [Job 17] Found: %%EndProlog
D [12/May/2009:07:41:10 +0200] [Job 17] 
D [12/May/2009:07:41:10 +0200] [Job 17] -----------
D [12/May/2009:07:41:10 +0200] [Job 17] New page: %%Page: 1 1
D [12/May/2009:07:41:10 +0200] [Job 17] "Setup" section is missing, inserting it.
D [12/May/2009:07:41:10 +0200] [Job 17] Inserting PostScript code for CUPS' page accounting
D [12/May/2009:07:41:10 +0200] [Job 17] Inserting option code into "Setup" section.
D [12/May/2009:07:41:10 +0200] [Job 17] No page header or page header not DSC-conforming
D [12/May/2009:07:41:10 +0200] [Job 17] "PageSetup" section is missing, inserting it.
D [12/May/2009:07:41:10 +0200] [Job 17] Inserting option code into "PageSetup" section.
D [12/May/2009:07:41:10 +0200] [Job 17] Stopping search for page header options
D [12/May/2009:07:41:10 +0200] [Job 17] Found: 253 254 r3
D [12/May/2009:07:41:10 +0200] [Job 17] --> Output goes directly to the renderer now.
D [12/May/2009:07:41:10 +0200] [Job 17] 
D [12/May/2009:07:41:10 +0200] Report: clients=0
D [12/May/2009:07:41:10 +0200] Report: jobs=17
D [12/May/2009:07:41:10 +0200] Report: jobs-active=1
D [12/May/2009:07:41:10 +0200] Report: printers=2
D [12/May/2009:07:41:10 +0200] Report: printers-implicit=0
D [12/May/2009:07:41:10 +0200] Report: stringpool-string-count=637
D [12/May/2009:07:41:10 +0200] Report: stringpool-alloc-bytes=9456
D [12/May/2009:07:41:10 +0200] Report: stringpool-total-bytes=13144
D [12/May/2009:07:41:11 +0200] [Job 17] 
D [12/May/2009:07:41:11 +0200] [Job 17] Starting renderer with command: "foo2lava-wrapper -z0   -c -C10 -r600x600 -p26 -m0 -s255   -d1 -Gkm2530-jconner-d50.icm "
D [12/May/2009:07:41:11 +0200] [Job 17] Starting process "kid3" (generation 1)
D [12/May/2009:07:41:11 +0200] [Job 17] Starting process "kid4" (generation 2)
D [12/May/2009:07:41:11 +0200] [Job 17] JCL: %-12345X@PJL
D [12/May/2009:07:41:11 +0200] [Job 17] <job data> 
D [12/May/2009:07:41:11 +0200] [Job 17] 
D [12/May/2009:07:41:11 +0200] [Job 17] Starting process "renderer" (generation 2)
D [12/May/2009:07:41:11 +0200] [Job 17] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:07:41:11 +0200] [Job 17] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:07:41:11 +0200] [Job 17] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:07:41:12 +0200] [Job 17] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:07:42:19 +0200] Report: clients=0
D [12/May/2009:07:42:19 +0200] Report: jobs=17
D [12/May/2009:07:42:19 +0200] Report: jobs-active=1
D [12/May/2009:07:42:19 +0200] Report: printers=2
D [12/May/2009:07:42:19 +0200] Report: printers-implicit=0
D [12/May/2009:07:42:19 +0200] Report: stringpool-string-count=637
D [12/May/2009:07:42:19 +0200] Report: stringpool-alloc-bytes=9456
D [12/May/2009:07:42:19 +0200] Report: stringpool-total-bytes=13144
D [12/May/2009:07:43:25 +0200] Report: clients=0
D [12/May/2009:07:43:25 +0200] Report: jobs=17
D [12/May/2009:07:43:25 +0200] Report: jobs-active=1
D [12/May/2009:07:43:25 +0200] Report: printers=2
D [12/May/2009:07:43:25 +0200] Report: printers-implicit=0
D [12/May/2009:07:43:25 +0200] Report: stringpool-string-count=637
D [12/May/2009:07:43:25 +0200] Report: stringpool-alloc-bytes=9456
D [12/May/2009:07:43:25 +0200] Report: stringpool-total-bytes=13144
D [12/May/2009:07:44:31 +0200] Report: clients=0
D [12/May/2009:07:44:31 +0200] Report: jobs=17
D [12/May/2009:07:44:31 +0200] Report: jobs-active=1
D [12/May/2009:07:44:31 +0200] Report: printers=2
D [12/May/2009:07:44:31 +0200] Report: printers-implicit=0
D [12/May/2009:07:44:31 +0200] Report: stringpool-string-count=637
D [12/May/2009:07:44:31 +0200] Report: stringpool-alloc-bytes=9456
D [12/May/2009:07:44:31 +0200] Report: stringpool-total-bytes=13144
D [12/May/2009:07:45:37 +0200] Report: clients=0
D [12/May/2009:07:45:37 +0200] Report: jobs=17
D [12/May/2009:07:45:37 +0200] Report: jobs-active=1
D [12/May/2009:07:45:37 +0200] Report: printers=2
D [12/May/2009:07:45:37 +0200] Report: printers-implicit=0
D [12/May/2009:07:45:37 +0200] Report: stringpool-string-count=637
D [12/May/2009:07:45:37 +0200] Report: stringpool-alloc-bytes=9456
D [12/May/2009:07:45:37 +0200] Report: stringpool-total-bytes=13144
D [12/May/2009:07:46:43 +0200] Report: clients=0
D [12/May/2009:07:46:43 +0200] Report: jobs=17
D [12/May/2009:07:46:43 +0200] Report: jobs-active=1
D [12/May/2009:07:46:43 +0200] Report: printers=2
D [12/May/2009:07:46:43 +0200] Report: printers-implicit=0
D [12/May/2009:07:46:43 +0200] Report: stringpool-string-count=637
D [12/May/2009:07:46:43 +0200] Report: stringpool-alloc-bytes=9456
D [12/May/2009:07:46:43 +0200] Report: stringpool-total-bytes=13144
D [12/May/2009:07:47:49 +0200] Report: clients=0
D [12/May/2009:07:47:49 +0200] Report: jobs=17
D [12/May/2009:07:47:49 +0200] Report: jobs-active=1
D [12/May/2009:07:47:49 +0200] Report: printers=2
D [12/May/2009:07:47:49 +0200] Report: printers-implicit=0
D [12/May/2009:07:47:49 +0200] Report: stringpool-string-count=637
D [12/May/2009:07:47:49 +0200] Report: stringpool-alloc-bytes=9456
D [12/May/2009:07:47:49 +0200] Report: stringpool-total-bytes=13144
D [12/May/2009:07:48:45 +0200] [Job 17] Found: %%PageTrailer
D [12/May/2009:07:48:45 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:48:45 +0200] [Job 17] 
D [12/May/2009:07:48:45 +0200] [Job 17] Found: %%Trailer
D [12/May/2009:07:48:45 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:48:45 +0200] [Job 17] 
D [12/May/2009:07:48:45 +0200] [Job 17] Found: %%Pages: 1
D [12/May/2009:07:48:45 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:48:45 +0200] [Job 17] 
D [12/May/2009:07:48:45 +0200] [Job 17] Found: %%BoundingBox: 18 86 578 778
D [12/May/2009:07:48:45 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:48:45 +0200] [Job 17] 
D [12/May/2009:07:48:45 +0200] [Job 17] Found: %%HiResBoundingBox: 18.000000 86.600000 577.300000 777.800000
D [12/May/2009:07:48:45 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:48:45 +0200] [Job 17] 
D [12/May/2009:07:48:45 +0200] [Job 17] Found: %%EOF
D [12/May/2009:07:48:45 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:48:45 +0200] [Job 17] 
D [12/May/2009:07:48:46 +0200] [Job 17] 
D [12/May/2009:07:48:46 +0200] [Job 17] Closing renderer
D [12/May/2009:07:48:54 +0200] Discarding unused job-progress event...
D [12/May/2009:07:48:54 +0200] Report: clients=0
D [12/May/2009:07:48:54 +0200] Report: jobs=17
D [12/May/2009:07:48:54 +0200] Report: jobs-active=1
D [12/May/2009:07:48:54 +0200] Report: printers=2
D [12/May/2009:07:48:54 +0200] Report: printers-implicit=0
D [12/May/2009:07:48:54 +0200] Report: stringpool-string-count=637
D [12/May/2009:07:48:54 +0200] Report: stringpool-alloc-bytes=9456
D [12/May/2009:07:48:54 +0200] Report: stringpool-total-bytes=13144
E [12/May/2009:07:48:54 +0200] PID 5821 (/usr/lib/cups/backend/socket) stopped with status 1!
D [12/May/2009:07:48:54 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:48:54 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:48:54 +0200] [Job 17] Read 8192 bytes of print data...
E [12/May/2009:07:48:54 +0200] [Job 17] Unable to write print data: Broken pipe
I [12/May/2009:07:48:54 +0200] Saving subscriptions.conf...
D [12/May/2009:07:48:54 +0200] [Job 17] renderer exited with status 0
D [12/May/2009:07:48:54 +0200] [Job 17] kid4 exited with status 0
D [12/May/2009:07:48:54 +0200] [Job 17] kid3 finished
D [12/May/2009:07:48:54 +0200] [Job 17] kid3 exited with status 0
D [12/May/2009:07:48:54 +0200] [Job 17] pdf-to-ps exited with status 0
D [12/May/2009:07:48:54 +0200] [Job 17] 
D [12/May/2009:07:48:54 +0200] [Job 17] Closing foomatic-rip.
D [12/May/2009:07:48:54 +0200] PID 5819 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [12/May/2009:07:48:54 +0200] [Job 17] File 0 is complete.
I [12/May/2009:07:48:54 +0200] [Job 17] Backend returned status 1 (failed)
I [12/May/2009:07:48:54 +0200] Saving subscriptions.conf...
D [12/May/2009:07:48:54 +0200] set_hold_until: hold_until = 1242107634
I [12/May/2009:07:48:54 +0200] Saving subscriptions.conf...
D [12/May/2009:07:48:55 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:07:48:55 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:07:48:55 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:48:55 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:07:48:55 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:07:48:55 +0200] cupsdCloseClient: 11
D [12/May/2009:07:48:55 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:07:48:55 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:07:48:55 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:48:55 +0200] Get-Notifications /
D [12/May/2009:07:48:55 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:07:48:55 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:07:48:55 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:07:48:55 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:07:48:55 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:48:55 +0200] Get-Printer-Attributes ipp://boromir:631/printers/magicolor-2530-DL
D [12/May/2009:07:48:55 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:07:48:55 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:07:48:55 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:48:55 +0200] Get-Job-Attributes ipp://localhost/jobs/17
D [12/May/2009:07:48:55 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:07:48:55 +0200] cupsdCloseClient: 11
D [12/May/2009:07:50:02 +0200] Report: clients=1
D [12/May/2009:07:50:02 +0200] Report: jobs=17
D [12/May/2009:07:50:02 +0200] Report: jobs-active=1
D [12/May/2009:07:50:02 +0200] Report: printers=2
D [12/May/2009:07:50:02 +0200] Report: printers-implicit=0
D [12/May/2009:07:50:02 +0200] Report: stringpool-string-count=729
D [12/May/2009:07:50:02 +0200] Report: stringpool-alloc-bytes=9696
D [12/May/2009:07:50:02 +0200] Report: stringpool-total-bytes=15424
D [12/May/2009:07:51:08 +0200] Report: clients=1
D [12/May/2009:07:51:08 +0200] Report: jobs=17
D [12/May/2009:07:51:08 +0200] Report: jobs-active=1
D [12/May/2009:07:51:08 +0200] Report: printers=2
D [12/May/2009:07:51:08 +0200] Report: printers-implicit=0
D [12/May/2009:07:51:08 +0200] Report: stringpool-string-count=729
D [12/May/2009:07:51:08 +0200] Report: stringpool-alloc-bytes=9696
D [12/May/2009:07:51:08 +0200] Report: stringpool-total-bytes=15424
D [12/May/2009:07:52:14 +0200] Report: clients=1
D [12/May/2009:07:52:14 +0200] Report: jobs=17
D [12/May/2009:07:52:14 +0200] Report: jobs-active=1
D [12/May/2009:07:52:14 +0200] Report: printers=2
D [12/May/2009:07:52:14 +0200] Report: printers-implicit=0
D [12/May/2009:07:52:14 +0200] Report: stringpool-string-count=729
D [12/May/2009:07:52:14 +0200] Report: stringpool-alloc-bytes=9696
D [12/May/2009:07:52:14 +0200] Report: stringpool-total-bytes=15424
D [12/May/2009:07:53:20 +0200] Report: clients=1
D [12/May/2009:07:53:20 +0200] Report: jobs=17
D [12/May/2009:07:53:20 +0200] Report: jobs-active=1
D [12/May/2009:07:53:20 +0200] Report: printers=2
D [12/May/2009:07:53:20 +0200] Report: printers-implicit=0
D [12/May/2009:07:53:20 +0200] Report: stringpool-string-count=729
D [12/May/2009:07:53:20 +0200] Report: stringpool-alloc-bytes=9696
D [12/May/2009:07:53:20 +0200] Report: stringpool-total-bytes=15424
D [12/May/2009:07:53:56 +0200] Closing client 12 after 300 seconds of inactivity...
D [12/May/2009:07:53:56 +0200] cupsdCloseClient: 12
I [12/May/2009:07:54:07 +0200] Saving subscriptions.conf...
D [12/May/2009:07:54:07 +0200] [Job 17] job-sheets=none,none
D [12/May/2009:07:54:07 +0200] [Job 17] banner_page = 0
D [12/May/2009:07:54:07 +0200] [Job 17] argv[0]="magicolor-2530-DL"
D [12/May/2009:07:54:07 +0200] [Job 17] argv[1]="17"
D [12/May/2009:07:54:07 +0200] [Job 17] argv[2]="laurent"
D [12/May/2009:07:54:07 +0200] [Job 17] argv[3]="out.pnm"
D [12/May/2009:07:54:07 +0200] [Job 17] argv[4]="1"
D [12/May/2009:07:54:07 +0200] [Job 17] argv[5]="Resolution=600x600dpi Quality=normal MediaType=plain NupOrient=port ColorMode=ICM PageSize=A4 Nup=1up number-up=1 ICM=km2530-jc Duplex=None InputSlot=Auto job-uuid=urn:uuid:8f662eb9-b7f4-3052-4e04-a43bd7ad6759"
D [12/May/2009:07:54:07 +0200] [Job 17] argv[6]="/var/spool/cups/d00017-001"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[9]="SERVER_ADMIN=root@boromir"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[10]="SOFTWARE=CUPS/1.3.9"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[12]="TZ=Europe/Paris"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[13]="USER=root"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[16]="IPP_PORT=631"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[17]="CHARSET=utf-8"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[18]="LANG=fr_FR.UTF8"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[20]="RIP_MAX_CACHE=8m"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[21]="CONTENT_TYPE=application/pdf"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[22]="DEVICE_URI=socket://192.168.1.117:9100"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[23]="PRINTER=magicolor-2530-DL"
D [12/May/2009:07:54:07 +0200] [Job 17] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL"
I [12/May/2009:07:54:07 +0200] [Job 17] Started filter /usr/lib/cups/filter/pdftopdf (PID 6160)
I [12/May/2009:07:54:07 +0200] [Job 17] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6161)
I [12/May/2009:07:54:07 +0200] [Job 17] Started backend /usr/lib/cups/backend/socket (PID 6162)
I [12/May/2009:07:54:07 +0200] Saving subscriptions.conf...
I [12/May/2009:07:54:07 +0200] Saving subscriptions.conf...
D [12/May/2009:07:54:07 +0200] [Job 17] Connected to 192.168.1.117:9100 (IPv4)...
D [12/May/2009:07:54:07 +0200] [Job 17] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb80d5fc0)
I [12/May/2009:07:54:07 +0200] Saving subscriptions.conf...
D [12/May/2009:07:54:07 +0200] [Job 17] Getting input from file 
D [12/May/2009:07:54:07 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:07:54:07 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:07:54:07 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:54:07 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:07:54:07 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:07:54:07 +0200] cupsdCloseClient: 11
D [12/May/2009:07:54:07 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:07:54:07 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:07:54:07 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:54:07 +0200] Get-Notifications /
D [12/May/2009:07:54:07 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:07:54:07 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:07:54:07 +0200] cupsdCloseClient: 11
D [12/May/2009:07:54:07 +0200] [Job 17] foomatic-rip version 4.0.0.195 running...
D [12/May/2009:07:54:07 +0200] [Job 17] Parsing PPD file ...
D [12/May/2009:07:54:07 +0200] [Job 17] Added option PageSize
D [12/May/2009:07:54:07 +0200] [Job 17] Added option Quality
D [12/May/2009:07:54:07 +0200] [Job 17] Added option PrinterType
D [12/May/2009:07:54:07 +0200] [Job 17] Added option ColorMode
D [12/May/2009:07:54:07 +0200] [Job 17] Added option Resolution
D [12/May/2009:07:54:07 +0200] [Job 17] Added option ImageableArea
D [12/May/2009:07:54:07 +0200] [Job 17] Added option PaperDimension
D [12/May/2009:07:54:07 +0200] [Job 17] Added option InputSlot
D [12/May/2009:07:54:07 +0200] [Job 17] Added option MediaType
D [12/May/2009:07:54:07 +0200] [Job 17] Added option Duplex
D [12/May/2009:07:54:07 +0200] [Job 17] Added option Copies
D [12/May/2009:07:54:07 +0200] [Job 17] Added option ICM
D [12/May/2009:07:54:07 +0200] [Job 17] Added option Nup
D [12/May/2009:07:54:07 +0200] [Job 17] Added option NupOrient
D [12/May/2009:07:54:07 +0200] [Job 17] Added option Font
D [12/May/2009:07:54:07 +0200] [Job 17] 
D [12/May/2009:07:54:07 +0200] [Job 17] Parameter Summary
D [12/May/2009:07:54:07 +0200] [Job 17] -----------------
D [12/May/2009:07:54:07 +0200] [Job 17] 
D [12/May/2009:07:54:07 +0200] [Job 17] Spooler: cups
D [12/May/2009:07:54:07 +0200] [Job 17] Printer: magicolor-2530-DL
D [12/May/2009:07:54:07 +0200] [Job 17] Shell: /bin/bash
D [12/May/2009:07:54:07 +0200] [Job 17] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd
D [12/May/2009:07:54:07 +0200] [Job 17] ATTR file: 
D [12/May/2009:07:54:07 +0200] [Job 17] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended)
D [12/May/2009:07:54:07 +0200] [Job 17] Job title: out.pnm
D [12/May/2009:07:54:07 +0200] [Job 17] File(s) to be printed:
D [12/May/2009:07:54:07 +0200] [Job 17] <STDIN>
D [12/May/2009:07:54:07 +0200] [Job 17] 
D [12/May/2009:07:54:07 +0200] [Job 17] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [12/May/2009:07:54:07 +0200] [Job 17] Printing system options:
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'number-up=1'
D [12/May/2009:07:54:07 +0200] [Job 17] Unknown option number-up=1.
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'job-uuid=urn:uuid:8f662eb9-b7f4-3052-4e04-a43bd7ad6759'
D [12/May/2009:07:54:07 +0200] [Job 17] Unknown option job-uuid=urn:uuid:8f662eb9-b7f4-3052-4e04-a43bd7ad6759.
D [12/May/2009:07:54:07 +0200] [Job 17] Options from the PPD file:
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'Resolution=600x600dpi'
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'Quality=normal'
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'MediaType=plain'
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'NupOrient=port'
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'ColorMode=ICM'
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'PageSize=A4'
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'Nup=1up'
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'ICM=km2530-jc'
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'Duplex=None'
D [12/May/2009:07:54:07 +0200] [Job 17] Pondering option 'InputSlot=Auto'
D [12/May/2009:07:54:07 +0200] [Job 17] 
D [12/May/2009:07:54:07 +0200] [Job 17] ================================================
D [12/May/2009:07:54:07 +0200] [Job 17] 
D [12/May/2009:07:54:07 +0200] [Job 17] File: <STDIN>
D [12/May/2009:07:54:07 +0200] [Job 17] 
D [12/May/2009:07:54:07 +0200] [Job 17] ================================================
D [12/May/2009:07:54:07 +0200] [Job 17] 
D [12/May/2009:07:54:07 +0200] [Job 17] Filetype: PDF
D [12/May/2009:07:54:07 +0200] [Job 17] Driver does not understand PDF input, converting to PostScript
D [12/May/2009:07:54:07 +0200] [Job 17] Starting process "pdf-to-ps" (generation 1)
D [12/May/2009:07:54:17 +0200] PID 6160 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [12/May/2009:07:54:18 +0200] [Job 17] Filetype: PostScript
D [12/May/2009:07:54:18 +0200] [Job 17] Reading PostScript input ...
D [12/May/2009:07:54:18 +0200] [Job 17] --> This document is DSC-conforming!
D [12/May/2009:07:54:18 +0200] [Job 17] 
D [12/May/2009:07:54:18 +0200] [Job 17] -----------
D [12/May/2009:07:54:18 +0200] [Job 17] Found: %%BeginProlog
D [12/May/2009:07:54:18 +0200] [Job 17] Inserting option code into "Prolog" section.
D [12/May/2009:07:54:18 +0200] [Job 17] Found: %%EndProlog
D [12/May/2009:07:54:18 +0200] [Job 17] 
D [12/May/2009:07:54:18 +0200] [Job 17] -----------
D [12/May/2009:07:54:18 +0200] [Job 17] New page: %%Page: 1 1
D [12/May/2009:07:54:18 +0200] [Job 17] "Setup" section is missing, inserting it.
D [12/May/2009:07:54:18 +0200] [Job 17] Inserting PostScript code for CUPS' page accounting
D [12/May/2009:07:54:18 +0200] [Job 17] Inserting option code into "Setup" section.
D [12/May/2009:07:54:18 +0200] [Job 17] No page header or page header not DSC-conforming
D [12/May/2009:07:54:18 +0200] [Job 17] "PageSetup" section is missing, inserting it.
D [12/May/2009:07:54:18 +0200] [Job 17] Inserting option code into "PageSetup" section.
D [12/May/2009:07:54:18 +0200] [Job 17] Stopping search for page header options
D [12/May/2009:07:54:18 +0200] [Job 17] Found: 253 254 r3
D [12/May/2009:07:54:18 +0200] [Job 17] --> Output goes directly to the renderer now.
D [12/May/2009:07:54:18 +0200] [Job 17] 
D [12/May/2009:07:54:18 +0200] [Job 17] 
D [12/May/2009:07:54:18 +0200] [Job 17] Starting renderer with command: "foo2lava-wrapper -z0   -c -C10 -r600x600 -p26 -m0 -s255   -d1 -Gkm2530-jconner-d50.icm "
D [12/May/2009:07:54:18 +0200] [Job 17] Starting process "kid3" (generation 1)
D [12/May/2009:07:54:18 +0200] [Job 17] Starting process "kid4" (generation 2)
D [12/May/2009:07:54:18 +0200] [Job 17] JCL: %-12345X@PJL
D [12/May/2009:07:54:18 +0200] [Job 17] <job data> 
D [12/May/2009:07:54:18 +0200] [Job 17] 
D [12/May/2009:07:54:18 +0200] [Job 17] Starting process "renderer" (generation 2)
D [12/May/2009:07:54:18 +0200] [Job 17] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:07:54:18 +0200] [Job 17] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:07:54:18 +0200] [Job 17] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:07:54:19 +0200] [Job 17] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:07:54:20 +0200] Report: clients=0
D [12/May/2009:07:54:20 +0200] Report: jobs=17
D [12/May/2009:07:54:20 +0200] Report: jobs-active=1
D [12/May/2009:07:54:20 +0200] Report: printers=2
D [12/May/2009:07:54:20 +0200] Report: printers-implicit=0
D [12/May/2009:07:54:20 +0200] Report: stringpool-string-count=812
D [12/May/2009:07:54:20 +0200] Report: stringpool-alloc-bytes=9680
D [12/May/2009:07:54:20 +0200] Report: stringpool-total-bytes=17232
D [12/May/2009:07:55:26 +0200] Report: clients=0
D [12/May/2009:07:55:26 +0200] Report: jobs=17
D [12/May/2009:07:55:26 +0200] Report: jobs-active=1
D [12/May/2009:07:55:26 +0200] Report: printers=2
D [12/May/2009:07:55:26 +0200] Report: printers-implicit=0
D [12/May/2009:07:55:26 +0200] Report: stringpool-string-count=812
D [12/May/2009:07:55:26 +0200] Report: stringpool-alloc-bytes=9680
D [12/May/2009:07:55:26 +0200] Report: stringpool-total-bytes=17232
D [12/May/2009:07:56:32 +0200] Report: clients=0
D [12/May/2009:07:56:32 +0200] Report: jobs=17
D [12/May/2009:07:56:32 +0200] Report: jobs-active=1
D [12/May/2009:07:56:32 +0200] Report: printers=2
D [12/May/2009:07:56:32 +0200] Report: printers-implicit=0
D [12/May/2009:07:56:32 +0200] Report: stringpool-string-count=812
D [12/May/2009:07:56:32 +0200] Report: stringpool-alloc-bytes=9680
D [12/May/2009:07:56:32 +0200] Report: stringpool-total-bytes=17232
D [12/May/2009:07:57:38 +0200] Report: clients=0
D [12/May/2009:07:57:38 +0200] Report: jobs=17
D [12/May/2009:07:57:38 +0200] Report: jobs-active=1
D [12/May/2009:07:57:38 +0200] Report: printers=2
D [12/May/2009:07:57:38 +0200] Report: printers-implicit=0
D [12/May/2009:07:57:38 +0200] Report: stringpool-string-count=812
D [12/May/2009:07:57:38 +0200] Report: stringpool-alloc-bytes=9680
D [12/May/2009:07:57:38 +0200] Report: stringpool-total-bytes=17232
D [12/May/2009:07:58:44 +0200] Report: clients=0
D [12/May/2009:07:58:44 +0200] Report: jobs=17
D [12/May/2009:07:58:44 +0200] Report: jobs-active=1
D [12/May/2009:07:58:44 +0200] Report: printers=2
D [12/May/2009:07:58:44 +0200] Report: printers-implicit=0
D [12/May/2009:07:58:44 +0200] Report: stringpool-string-count=812
D [12/May/2009:07:58:44 +0200] Report: stringpool-alloc-bytes=9680
D [12/May/2009:07:58:44 +0200] Report: stringpool-total-bytes=17232
D [12/May/2009:07:58:52 +0200] [Job 17] Found: %%PageTrailer
D [12/May/2009:07:58:52 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:58:52 +0200] [Job 17] 
D [12/May/2009:07:58:52 +0200] [Job 17] Found: %%Trailer
D [12/May/2009:07:58:52 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:58:52 +0200] [Job 17] 
D [12/May/2009:07:58:52 +0200] [Job 17] Found: %%Pages: 1
D [12/May/2009:07:58:52 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:58:52 +0200] [Job 17] 
D [12/May/2009:07:58:52 +0200] [Job 17] Found: %%BoundingBox: 18 86 578 778
D [12/May/2009:07:58:52 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:58:52 +0200] [Job 17] 
D [12/May/2009:07:58:52 +0200] [Job 17] Found: %%HiResBoundingBox: 18.000000 86.600000 577.300000 777.800000
D [12/May/2009:07:58:52 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:58:52 +0200] [Job 17] 
D [12/May/2009:07:58:52 +0200] [Job 17] Found: %%EOF
D [12/May/2009:07:58:52 +0200] [Job 17] --> Continue DSC parsing now.
D [12/May/2009:07:58:52 +0200] [Job 17] 
D [12/May/2009:07:58:52 +0200] [Job 17] 
D [12/May/2009:07:58:52 +0200] [Job 17] Closing renderer
D [12/May/2009:07:58:57 +0200] Discarding unused job-progress event...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
I [12/May/2009:07:58:57 +0200] Saving subscriptions.conf...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:07:58:57 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:07:58:57 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:58:57 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:07:58:57 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:07:58:57 +0200] cupsdCloseClient: 11
D [12/May/2009:07:58:57 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:07:58:57 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:58:57 +0200] Get-Notifications /
D [12/May/2009:07:58:57 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:07:58:57 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:07:58:57 +0200] cupsdCloseClient: 11
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:57 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] renderer exited with status 0
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] kid4 exited with status 0
D [12/May/2009:07:58:58 +0200] [Job 17] kid3 finished
D [12/May/2009:07:58:58 +0200] [Job 17] kid3 exited with status 0
D [12/May/2009:07:58:58 +0200] [Job 17] pdf-to-ps exited with status 0
D [12/May/2009:07:58:58 +0200] [Job 17] 
D [12/May/2009:07:58:58 +0200] [Job 17] Closing foomatic-rip.
D [12/May/2009:07:58:58 +0200] PID 6161 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 8192 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Read 7268 bytes of print data...
D [12/May/2009:07:58:58 +0200] [Job 17] Wrote 7268 bytes of print data...
I [12/May/2009:07:59:03 +0200] Saving subscriptions.conf...
I [12/May/2009:07:59:03 +0200] Saving subscriptions.conf...
D [12/May/2009:07:59:03 +0200] PID 6162 (/usr/lib/cups/backend/socket) exited with no errors.
D [12/May/2009:07:59:03 +0200] [Job 17] File 0 is complete.
I [12/May/2009:07:59:03 +0200] [Job 17] Completed successfully.
I [12/May/2009:07:59:03 +0200] Saving subscriptions.conf...
I [12/May/2009:07:59:03 +0200] Saving subscriptions.conf...
D [12/May/2009:07:59:03 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:07:59:03 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:07:59:03 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:59:03 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:07:59:03 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:07:59:03 +0200] cupsdCloseClient: 11
D [12/May/2009:07:59:03 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:07:59:03 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:07:59:03 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:07:59:03 +0200] Get-Notifications /
D [12/May/2009:07:59:03 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:07:59:03 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:07:59:04 +0200] cupsdCloseClient: 11
D [12/May/2009:07:59:04 +0200] [Job 17] Unloading...
D [12/May/2009:08:05:15 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:15 +0200] Report: clients=1
D [12/May/2009:08:05:15 +0200] Report: jobs=17
D [12/May/2009:08:05:15 +0200] Report: jobs-active=0
D [12/May/2009:08:05:15 +0200] Report: printers=2
D [12/May/2009:08:05:15 +0200] Report: printers-implicit=0
D [12/May/2009:08:05:15 +0200] Report: stringpool-string-count=856
D [12/May/2009:08:05:15 +0200] Report: stringpool-alloc-bytes=8744
D [12/May/2009:08:05:15 +0200] Report: stringpool-total-bytes=18472
D [12/May/2009:08:05:15 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:15 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:15 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:05:15 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:15 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:15 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:05:15 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:05:15 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:15 +0200] cupsdReadClient: 12 GET /printers/HL-5040-series.ppd HTTP/1.1
D [12/May/2009:08:05:15 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:15 +0200] cupsdCloseClient: 12
D [12/May/2009:08:05:18 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:18 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:18 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:18 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:05:18 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:18 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:18 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:05:18 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:20 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:20 +0200] cupsdReadClient: 11 GET /printers/magicolor-2530-DL.ppd HTTP/1.1
D [12/May/2009:08:05:20 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:20 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:21 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:21 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:21 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:21 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:05:21 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:21 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:21 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:05:21 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:24 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:24 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:24 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:24 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:05:24 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:24 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:24 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:05:24 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:27 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:27 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:05:27 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:27 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:05:27 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:27 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:27 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:05:27 +0200] cupsdCloseClient: 12
D [12/May/2009:08:05:30 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:30 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:05:30 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:30 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:05:30 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:33 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:33 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:05:35 +0200] cupsdCloseClient: 12
D [12/May/2009:08:05:36 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:36 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:05:36 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:37 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:05:37 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:37 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:37 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:05:37 +0200] cupsdCloseClient: 12
D [12/May/2009:08:05:39 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:39 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:39 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:39 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:05:39 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:39 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:39 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:05:40 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:42 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:42 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:42 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:45 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:05:45 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:45 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:45 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:05:45 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:48 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:48 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:05:48 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:49 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:05:49 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:49 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:49 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:05:49 +0200] cupsdCloseClient: 12
D [12/May/2009:08:05:51 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:51 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:51 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:51 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:05:51 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:51 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:51 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:05:51 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:54 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:54 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:05:54 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:54 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:05:54 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:56 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:56 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:05:56 +0200] cupsdCloseClient: 12
D [12/May/2009:08:05:57 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:05:57 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:05:57 +0200] cupsdCloseClient: 11
D [12/May/2009:08:05:57 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:05:57 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:05:57 +0200] CUPS-Get-Printers
D [12/May/2009:08:05:57 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:05:57 +0200] cupsdCloseClient: 12
D [12/May/2009:08:06:00 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:06:00 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:06:00 +0200] cupsdCloseClient: 11
D [12/May/2009:08:06:00 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:06:00 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:06:00 +0200] CUPS-Get-Printers
D [12/May/2009:08:06:00 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:06:00 +0200] cupsdCloseClient: 12
D [12/May/2009:08:06:03 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:06:03 +0200] cupsdCloseClient: 11
D [12/May/2009:08:06:03 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:06:03 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:06:03 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:06:03 +0200] CUPS-Get-Printers
D [12/May/2009:08:06:03 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:06:03 +0200] cupsdCloseClient: 11
D [12/May/2009:08:06:13 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:06:19 +0200] cupsdReadClient: 11 POST /printers/magicolor-2530-DL HTTP/1.1
D [12/May/2009:08:06:19 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:06:24 +0200] Report: clients=1
D [12/May/2009:08:06:24 +0200] Report: jobs=17
D [12/May/2009:08:06:24 +0200] Report: jobs-active=0
D [12/May/2009:08:06:24 +0200] Report: printers=2
D [12/May/2009:08:06:24 +0200] Report: printers-implicit=0
D [12/May/2009:08:06:24 +0200] Report: stringpool-string-count=892
D [12/May/2009:08:06:24 +0200] Report: stringpool-alloc-bytes=9336
D [12/May/2009:08:06:24 +0200] Report: stringpool-total-bytes=18976
D [12/May/2009:08:06:24 +0200] Print-Job ipp://localhost:631/printers/magicolor-2530-DL
D [12/May/2009:08:06:24 +0200] [Job ???] Auto-typing file...
I [12/May/2009:08:06:24 +0200] [Job ???] Request file type is application/pdf.
D [12/May/2009:08:06:24 +0200] add_job: requesting-user-name="laurent"
I [12/May/2009:08:06:24 +0200] [Job 18] Adding start banner page "none".
I [12/May/2009:08:06:24 +0200] Saving subscriptions.conf...
I [12/May/2009:08:06:24 +0200] [Job 18] Adding end banner page "none".
I [12/May/2009:08:06:24 +0200] [Job 18] File of type application/pdf queued by "laurent".
D [12/May/2009:08:06:24 +0200] [Job 18] hold_until=0
I [12/May/2009:08:06:24 +0200] [Job 18] Queued on "magicolor-2530-DL" by "laurent".
I [12/May/2009:08:06:24 +0200] Saving subscriptions.conf...
D [12/May/2009:08:06:24 +0200] [Job 18] job-sheets=none,none
D [12/May/2009:08:06:24 +0200] [Job 18] banner_page = 0
D [12/May/2009:08:06:24 +0200] [Job 18] argv[0]="magicolor-2530-DL"
D [12/May/2009:08:06:24 +0200] [Job 18] argv[1]="18"
D [12/May/2009:08:06:24 +0200] [Job 18] argv[2]="laurent"
D [12/May/2009:08:06:24 +0200] [Job 18] argv[3]="out.pnm"
D [12/May/2009:08:06:24 +0200] [Job 18] argv[4]="1"
D [12/May/2009:08:06:24 +0200] [Job 18] argv[5]="Resolution=600x600dpi Quality=normal MediaType=plain NupOrient=port ColorMode=ICM PageSize=A4 Nup=1up number-up=1 ICM=user1 Duplex=None InputSlot=Auto job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2"
D [12/May/2009:08:06:24 +0200] [Job 18] argv[6]="/var/spool/cups/d00018-001"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[9]="SERVER_ADMIN=root@boromir"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[10]="SOFTWARE=CUPS/1.3.9"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[12]="TZ=Europe/Paris"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[13]="USER=root"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[16]="IPP_PORT=631"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[17]="CHARSET=utf-8"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[18]="LANG=fr_FR.UTF8"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[20]="RIP_MAX_CACHE=8m"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[21]="CONTENT_TYPE=application/pdf"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[22]="DEVICE_URI=socket://192.168.1.117:9100"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[23]="PRINTER=magicolor-2530-DL"
D [12/May/2009:08:06:24 +0200] [Job 18] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL"
I [12/May/2009:08:06:24 +0200] [Job 18] Started filter /usr/lib/cups/filter/pdftopdf (PID 6455)
I [12/May/2009:08:06:24 +0200] [Job 18] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6456)
I [12/May/2009:08:06:24 +0200] [Job 18] Started backend /usr/lib/cups/backend/socket (PID 6457)
I [12/May/2009:08:06:24 +0200] Saving subscriptions.conf...
D [12/May/2009:08:06:24 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:06:24 +0200] [Job 18] Getting input from file 
D [12/May/2009:08:06:24 +0200] [Job 18] foomatic-rip version 4.0.0.195 running...
D [12/May/2009:08:06:24 +0200] [Job 18] Parsing PPD file ...
D [12/May/2009:08:06:24 +0200] [Job 18] Added option PageSize
D [12/May/2009:08:06:24 +0200] [Job 18] Added option Quality
D [12/May/2009:08:06:24 +0200] [Job 18] Added option PrinterType
D [12/May/2009:08:06:24 +0200] [Job 18] Added option ColorMode
D [12/May/2009:08:06:24 +0200] [Job 18] Added option Resolution
D [12/May/2009:08:06:24 +0200] [Job 18] Added option ImageableArea
D [12/May/2009:08:06:24 +0200] [Job 18] Connected to 192.168.1.117:9100 (IPv4)...
D [12/May/2009:08:06:24 +0200] [Job 18] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb7f3cfc0)
I [12/May/2009:08:06:24 +0200] Saving subscriptions.conf...
D [12/May/2009:08:06:24 +0200] [Job 18] Added option PaperDimension
D [12/May/2009:08:06:24 +0200] [Job 18] Added option InputSlot
D [12/May/2009:08:06:24 +0200] [Job 18] Added option MediaType
D [12/May/2009:08:06:24 +0200] [Job 18] Added option Duplex
D [12/May/2009:08:06:24 +0200] [Job 18] Added option Copies
D [12/May/2009:08:06:24 +0200] [Job 18] Added option ICM
D [12/May/2009:08:06:24 +0200] [Job 18] Added option Nup
D [12/May/2009:08:06:24 +0200] [Job 18] Added option NupOrient
D [12/May/2009:08:06:24 +0200] [Job 18] Added option Font
D [12/May/2009:08:06:24 +0200] [Job 18] 
D [12/May/2009:08:06:24 +0200] [Job 18] Parameter Summary
D [12/May/2009:08:06:24 +0200] [Job 18] -----------------
D [12/May/2009:08:06:24 +0200] [Job 18] 
D [12/May/2009:08:06:24 +0200] [Job 18] Spooler: cups
D [12/May/2009:08:06:24 +0200] [Job 18] Printer: magicolor-2530-DL
D [12/May/2009:08:06:24 +0200] [Job 18] Shell: /bin/bash
D [12/May/2009:08:06:24 +0200] [Job 18] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd
D [12/May/2009:08:06:24 +0200] [Job 18] ATTR file: 
D [12/May/2009:08:06:24 +0200] [Job 18] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended)
D [12/May/2009:08:06:24 +0200] [Job 18] Job title: out.pnm
D [12/May/2009:08:06:24 +0200] [Job 18] File(s) to be printed:
D [12/May/2009:08:06:24 +0200] [Job 18] <STDIN>
D [12/May/2009:08:06:24 +0200] [Job 18] 
D [12/May/2009:08:06:24 +0200] [Job 18] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [12/May/2009:08:06:24 +0200] [Job 18] Printing system options:
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'number-up=1'
D [12/May/2009:08:06:24 +0200] [Job 18] Unknown option number-up=1.
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2'
D [12/May/2009:08:06:24 +0200] [Job 18] Unknown option job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2.
D [12/May/2009:08:06:24 +0200] [Job 18] Options from the PPD file:
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'Resolution=600x600dpi'
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'Quality=normal'
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'MediaType=plain'
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'NupOrient=port'
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'ColorMode=ICM'
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'PageSize=A4'
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'Nup=1up'
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'ICM=user1'
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'Duplex=None'
D [12/May/2009:08:06:24 +0200] [Job 18] Pondering option 'InputSlot=Auto'
D [12/May/2009:08:06:24 +0200] [Job 18] 
D [12/May/2009:08:06:24 +0200] [Job 18] ================================================
D [12/May/2009:08:06:24 +0200] [Job 18] 
D [12/May/2009:08:06:24 +0200] [Job 18] File: <STDIN>
D [12/May/2009:08:06:24 +0200] [Job 18] 
D [12/May/2009:08:06:24 +0200] [Job 18] ================================================
D [12/May/2009:08:06:24 +0200] [Job 18] 
D [12/May/2009:08:06:24 +0200] cupsdCloseClient: 11
D [12/May/2009:08:06:24 +0200] [Job 18] Filetype: PDF
D [12/May/2009:08:06:24 +0200] [Job 18] Driver does not understand PDF input, converting to PostScript
D [12/May/2009:08:06:24 +0200] [Job 18] Starting process "pdf-to-ps" (generation 1)
D [12/May/2009:08:06:24 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:06:24 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:06:24 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:06:24 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:08:06:24 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:06:24 +0200] cupsdCloseClient: 11
D [12/May/2009:08:06:24 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:06:24 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:06:24 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:06:24 +0200] Get-Notifications /
D [12/May/2009:08:06:24 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:08:06:24 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:06:24 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:06:24 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:06:24 +0200] Get-Job-Attributes ipp://localhost/jobs/18
D [12/May/2009:08:06:24 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:06:25 +0200] cupsdCloseClient: 11
D [12/May/2009:08:06:36 +0200] PID 6455 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [12/May/2009:08:06:36 +0200] [Job 18] Filetype: PostScript
D [12/May/2009:08:06:36 +0200] [Job 18] Reading PostScript input ...
D [12/May/2009:08:06:36 +0200] [Job 18] --> This document is DSC-conforming!
D [12/May/2009:08:06:36 +0200] [Job 18] 
D [12/May/2009:08:06:36 +0200] [Job 18] -----------
D [12/May/2009:08:06:36 +0200] [Job 18] Found: %%BeginProlog
D [12/May/2009:08:06:36 +0200] [Job 18] Inserting option code into "Prolog" section.
D [12/May/2009:08:06:36 +0200] [Job 18] Found: %%EndProlog
D [12/May/2009:08:06:36 +0200] [Job 18] 
D [12/May/2009:08:06:36 +0200] [Job 18] -----------
D [12/May/2009:08:06:36 +0200] [Job 18] New page: %%Page: 1 1
D [12/May/2009:08:06:36 +0200] [Job 18] "Setup" section is missing, inserting it.
D [12/May/2009:08:06:36 +0200] [Job 18] Inserting PostScript code for CUPS' page accounting
D [12/May/2009:08:06:36 +0200] [Job 18] Inserting option code into "Setup" section.
D [12/May/2009:08:06:36 +0200] [Job 18] No page header or page header not DSC-conforming
D [12/May/2009:08:06:36 +0200] [Job 18] "PageSetup" section is missing, inserting it.
D [12/May/2009:08:06:36 +0200] [Job 18] Inserting option code into "PageSetup" section.
D [12/May/2009:08:06:36 +0200] [Job 18] Stopping search for page header options
D [12/May/2009:08:06:36 +0200] [Job 18] Found: 253 254 r3
D [12/May/2009:08:06:36 +0200] [Job 18] --> Output goes directly to the renderer now.
D [12/May/2009:08:06:36 +0200] [Job 18] 
D [12/May/2009:08:06:37 +0200] [Job 18] 
D [12/May/2009:08:06:37 +0200] [Job 18] Starting renderer with command: "foo2lava-wrapper -z0   -c -C10 -r600x600 -p26 -m0 -s255   -d1 -Guser1.icm "
D [12/May/2009:08:06:37 +0200] [Job 18] Starting process "kid3" (generation 1)
D [12/May/2009:08:06:37 +0200] [Job 18] Starting process "kid4" (generation 2)
D [12/May/2009:08:06:37 +0200] [Job 18] JCL: %-12345X@PJL
D [12/May/2009:08:06:37 +0200] [Job 18] <job data> 
D [12/May/2009:08:06:37 +0200] [Job 18] 
D [12/May/2009:08:06:37 +0200] [Job 18] Starting process "renderer" (generation 2)
D [12/May/2009:08:06:37 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:06:37 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:06:37 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:06:37 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:07:33 +0200] Report: clients=0
D [12/May/2009:08:07:33 +0200] Report: jobs=18
D [12/May/2009:08:07:33 +0200] Report: jobs-active=1
D [12/May/2009:08:07:33 +0200] Report: printers=2
D [12/May/2009:08:07:33 +0200] Report: printers-implicit=0
D [12/May/2009:08:07:33 +0200] Report: stringpool-string-count=1006
D [12/May/2009:08:07:33 +0200] Report: stringpool-alloc-bytes=9864
D [12/May/2009:08:07:33 +0200] Report: stringpool-total-bytes=21416
D [12/May/2009:08:08:39 +0200] Report: clients=0
D [12/May/2009:08:08:39 +0200] Report: jobs=18
D [12/May/2009:08:08:39 +0200] Report: jobs-active=1
D [12/May/2009:08:08:39 +0200] Report: printers=2
D [12/May/2009:08:08:39 +0200] Report: printers-implicit=0
D [12/May/2009:08:08:39 +0200] Report: stringpool-string-count=1006
D [12/May/2009:08:08:39 +0200] Report: stringpool-alloc-bytes=9864
D [12/May/2009:08:08:39 +0200] Report: stringpool-total-bytes=21416
D [12/May/2009:08:09:45 +0200] Report: clients=0
D [12/May/2009:08:09:45 +0200] Report: jobs=18
D [12/May/2009:08:09:45 +0200] Report: jobs-active=1
D [12/May/2009:08:09:45 +0200] Report: printers=2
D [12/May/2009:08:09:45 +0200] Report: printers-implicit=0
D [12/May/2009:08:09:45 +0200] Report: stringpool-string-count=1006
D [12/May/2009:08:09:45 +0200] Report: stringpool-alloc-bytes=9864
D [12/May/2009:08:09:45 +0200] Report: stringpool-total-bytes=21416
D [12/May/2009:08:10:51 +0200] Report: clients=0
D [12/May/2009:08:10:51 +0200] Report: jobs=18
D [12/May/2009:08:10:51 +0200] Report: jobs-active=1
D [12/May/2009:08:10:51 +0200] Report: printers=2
D [12/May/2009:08:10:51 +0200] Report: printers-implicit=0
D [12/May/2009:08:10:51 +0200] Report: stringpool-string-count=1006
D [12/May/2009:08:10:51 +0200] Report: stringpool-alloc-bytes=9864
D [12/May/2009:08:10:51 +0200] Report: stringpool-total-bytes=21416
D [12/May/2009:08:11:31 +0200] [Job 18] Found: %%PageTrailer
D [12/May/2009:08:11:31 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:11:31 +0200] [Job 18] 
D [12/May/2009:08:11:31 +0200] [Job 18] Found: %%Trailer
D [12/May/2009:08:11:31 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:11:31 +0200] [Job 18] 
D [12/May/2009:08:11:31 +0200] [Job 18] Found: %%Pages: 1
D [12/May/2009:08:11:31 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:11:31 +0200] [Job 18] 
D [12/May/2009:08:11:31 +0200] [Job 18] Found: %%BoundingBox: 18 86 578 778
D [12/May/2009:08:11:31 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:11:31 +0200] [Job 18] 
D [12/May/2009:08:11:31 +0200] [Job 18] Found: %%HiResBoundingBox: 18.000000 86.600000 577.300000 777.800000
D [12/May/2009:08:11:31 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:11:31 +0200] [Job 18] 
D [12/May/2009:08:11:31 +0200] [Job 18] Found: %%EOF
D [12/May/2009:08:11:31 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:11:31 +0200] [Job 18] 
D [12/May/2009:08:11:31 +0200] [Job 18] 
D [12/May/2009:08:11:31 +0200] [Job 18] Closing renderer
D [12/May/2009:08:11:41 +0200] Discarding unused job-progress event...
D [12/May/2009:08:11:41 +0200] [Job 18] Read 8192 bytes of print data...
I [12/May/2009:08:11:41 +0200] Saving subscriptions.conf...
I [12/May/2009:08:11:41 +0200] Saving subscriptions.conf...
I [12/May/2009:08:11:41 +0200] Saving subscriptions.conf...
D [12/May/2009:08:11:41 +0200] [Job 18] Wrote 8192 bytes of print data...
D [12/May/2009:08:11:41 +0200] [Job 18] Read 8192 bytes of print data...
E [12/May/2009:08:11:41 +0200] [Job 18] Unable to write print data: Broken pipe
I [12/May/2009:08:11:41 +0200] Saving subscriptions.conf...
E [12/May/2009:08:11:41 +0200] PID 6457 (/usr/lib/cups/backend/socket) stopped with status 1!
D [12/May/2009:08:11:41 +0200] [Job 18] renderer exited with status 0
D [12/May/2009:08:11:41 +0200] [Job 18] kid4 exited with status 0
D [12/May/2009:08:11:41 +0200] [Job 18] kid3 finished
D [12/May/2009:08:11:41 +0200] [Job 18] kid3 exited with status 0
D [12/May/2009:08:11:41 +0200] [Job 18] pdf-to-ps exited with status 0
D [12/May/2009:08:11:41 +0200] [Job 18] 
D [12/May/2009:08:11:41 +0200] [Job 18] Closing foomatic-rip.
D [12/May/2009:08:11:41 +0200] PID 6456 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [12/May/2009:08:11:41 +0200] [Job 18] File 0 is complete.
I [12/May/2009:08:11:41 +0200] [Job 18] Backend returned status 1 (failed)
I [12/May/2009:08:11:41 +0200] Saving subscriptions.conf...
D [12/May/2009:08:11:41 +0200] set_hold_until: hold_until = 1242109001
I [12/May/2009:08:11:41 +0200] Saving subscriptions.conf...
D [12/May/2009:08:11:41 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:11:41 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:11:41 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:11:41 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:08:11:41 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:11:41 +0200] cupsdCloseClient: 11
D [12/May/2009:08:11:41 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:11:41 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:11:41 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:11:41 +0200] Get-Notifications /
D [12/May/2009:08:11:41 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:08:11:41 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:11:41 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:11:41 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:11:41 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:11:41 +0200] Get-Printer-Attributes ipp://boromir:631/printers/magicolor-2530-DL
D [12/May/2009:08:11:41 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:11:41 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:11:41 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:11:41 +0200] Get-Job-Attributes ipp://localhost/jobs/18
D [12/May/2009:08:11:41 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:11:41 +0200] cupsdCloseClient: 11
D [12/May/2009:08:11:53 +0200] Report: clients=1
D [12/May/2009:08:11:53 +0200] Report: jobs=18
D [12/May/2009:08:11:53 +0200] Report: jobs-active=1
D [12/May/2009:08:11:53 +0200] Report: printers=2
D [12/May/2009:08:11:53 +0200] Report: printers-implicit=0
D [12/May/2009:08:11:53 +0200] Report: stringpool-string-count=1119
D [12/May/2009:08:11:53 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:11:53 +0200] Report: stringpool-total-bytes=24016
D [12/May/2009:08:12:59 +0200] Report: clients=1
D [12/May/2009:08:12:59 +0200] Report: jobs=18
D [12/May/2009:08:12:59 +0200] Report: jobs-active=1
D [12/May/2009:08:12:59 +0200] Report: printers=2
D [12/May/2009:08:12:59 +0200] Report: printers-implicit=0
D [12/May/2009:08:12:59 +0200] Report: stringpool-string-count=1119
D [12/May/2009:08:12:59 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:12:59 +0200] Report: stringpool-total-bytes=24016
D [12/May/2009:08:14:05 +0200] Report: clients=1
D [12/May/2009:08:14:05 +0200] Report: jobs=18
D [12/May/2009:08:14:05 +0200] Report: jobs-active=1
D [12/May/2009:08:14:05 +0200] Report: printers=2
D [12/May/2009:08:14:05 +0200] Report: printers-implicit=0
D [12/May/2009:08:14:05 +0200] Report: stringpool-string-count=1119
D [12/May/2009:08:14:05 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:14:05 +0200] Report: stringpool-total-bytes=24016
D [12/May/2009:08:15:11 +0200] Report: clients=1
D [12/May/2009:08:15:11 +0200] Report: jobs=18
D [12/May/2009:08:15:11 +0200] Report: jobs-active=1
D [12/May/2009:08:15:11 +0200] Report: printers=2
D [12/May/2009:08:15:11 +0200] Report: printers-implicit=0
D [12/May/2009:08:15:11 +0200] Report: stringpool-string-count=1119
D [12/May/2009:08:15:11 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:15:11 +0200] Report: stringpool-total-bytes=24016
D [12/May/2009:08:16:17 +0200] Report: clients=1
D [12/May/2009:08:16:17 +0200] Report: jobs=18
D [12/May/2009:08:16:17 +0200] Report: jobs-active=1
D [12/May/2009:08:16:17 +0200] Report: printers=2
D [12/May/2009:08:16:17 +0200] Report: printers-implicit=0
D [12/May/2009:08:16:17 +0200] Report: stringpool-string-count=1119
D [12/May/2009:08:16:17 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:16:17 +0200] Report: stringpool-total-bytes=24016
D [12/May/2009:08:16:42 +0200] Closing client 12 after 300 seconds of inactivity...
D [12/May/2009:08:16:42 +0200] cupsdCloseClient: 12
I [12/May/2009:08:16:53 +0200] Saving subscriptions.conf...
D [12/May/2009:08:16:53 +0200] [Job 18] job-sheets=none,none
D [12/May/2009:08:16:53 +0200] [Job 18] banner_page = 0
D [12/May/2009:08:16:53 +0200] [Job 18] argv[0]="magicolor-2530-DL"
D [12/May/2009:08:16:53 +0200] [Job 18] argv[1]="18"
D [12/May/2009:08:16:53 +0200] [Job 18] argv[2]="laurent"
D [12/May/2009:08:16:53 +0200] [Job 18] argv[3]="out.pnm"
D [12/May/2009:08:16:53 +0200] [Job 18] argv[4]="1"
D [12/May/2009:08:16:53 +0200] [Job 18] argv[5]="Resolution=600x600dpi Quality=normal MediaType=plain NupOrient=port ColorMode=ICM PageSize=A4 Nup=1up number-up=1 ICM=user1 Duplex=None InputSlot=Auto job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2"
D [12/May/2009:08:16:53 +0200] [Job 18] argv[6]="/var/spool/cups/d00018-001"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[9]="SERVER_ADMIN=root@boromir"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[10]="SOFTWARE=CUPS/1.3.9"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[12]="TZ=Europe/Paris"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[13]="USER=root"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[16]="IPP_PORT=631"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[17]="CHARSET=utf-8"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[18]="LANG=fr_FR.UTF8"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[20]="RIP_MAX_CACHE=8m"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[21]="CONTENT_TYPE=application/pdf"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[22]="DEVICE_URI=socket://192.168.1.117:9100"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[23]="PRINTER=magicolor-2530-DL"
D [12/May/2009:08:16:53 +0200] [Job 18] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL"
I [12/May/2009:08:16:53 +0200] [Job 18] Started filter /usr/lib/cups/filter/pdftopdf (PID 6675)
I [12/May/2009:08:16:53 +0200] [Job 18] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6676)
I [12/May/2009:08:16:53 +0200] [Job 18] Started backend /usr/lib/cups/backend/socket (PID 6678)
I [12/May/2009:08:16:53 +0200] Saving subscriptions.conf...
D [12/May/2009:08:16:53 +0200] [Job 18] Getting input from file 
D [12/May/2009:08:16:53 +0200] [Job 18] foomatic-rip version 4.0.0.195 running...
D [12/May/2009:08:16:53 +0200] [Job 18] Parsing PPD file ...
D [12/May/2009:08:16:53 +0200] [Job 18] Added option PageSize
D [12/May/2009:08:16:53 +0200] [Job 18] Added option Quality
D [12/May/2009:08:16:53 +0200] [Job 18] Added option PrinterType
D [12/May/2009:08:16:53 +0200] [Job 18] Added option ColorMode
D [12/May/2009:08:16:53 +0200] [Job 18] Added option Resolution
D [12/May/2009:08:16:53 +0200] [Job 18] Added option ImageableArea
D [12/May/2009:08:16:53 +0200] [Job 18] Added option PaperDimension
D [12/May/2009:08:16:53 +0200] [Job 18] Added option InputSlot
D [12/May/2009:08:16:53 +0200] [Job 18] Added option MediaType
D [12/May/2009:08:16:53 +0200] [Job 18] Added option Duplex
D [12/May/2009:08:16:53 +0200] [Job 18] Added option Copies
D [12/May/2009:08:16:53 +0200] [Job 18] Added option ICM
D [12/May/2009:08:16:53 +0200] [Job 18] Added option Nup
D [12/May/2009:08:16:53 +0200] [Job 18] Added option NupOrient
D [12/May/2009:08:16:53 +0200] [Job 18] Added option Font
D [12/May/2009:08:16:53 +0200] [Job 18] 
D [12/May/2009:08:16:53 +0200] [Job 18] Parameter Summary
D [12/May/2009:08:16:53 +0200] [Job 18] -----------------
D [12/May/2009:08:16:53 +0200] [Job 18] 
D [12/May/2009:08:16:53 +0200] [Job 18] Spooler: cups
D [12/May/2009:08:16:53 +0200] [Job 18] Printer: magicolor-2530-DL
D [12/May/2009:08:16:53 +0200] [Job 18] Shell: /bin/bash
D [12/May/2009:08:16:53 +0200] [Job 18] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd
D [12/May/2009:08:16:53 +0200] [Job 18] ATTR file: 
D [12/May/2009:08:16:53 +0200] [Job 18] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended)
D [12/May/2009:08:16:53 +0200] [Job 18] Job title: out.pnm
D [12/May/2009:08:16:53 +0200] [Job 18] File(s) to be printed:
D [12/May/2009:08:16:53 +0200] [Job 18] <STDIN>
D [12/May/2009:08:16:53 +0200] [Job 18] 
D [12/May/2009:08:16:53 +0200] [Job 18] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [12/May/2009:08:16:53 +0200] [Job 18] Printing system options:
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'number-up=1'
D [12/May/2009:08:16:53 +0200] [Job 18] Unknown option number-up=1.
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2'
D [12/May/2009:08:16:53 +0200] [Job 18] Unknown option job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2.
D [12/May/2009:08:16:53 +0200] [Job 18] Options from the PPD file:
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'Resolution=600x600dpi'
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'Quality=normal'
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'MediaType=plain'
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'NupOrient=port'
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'ColorMode=ICM'
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'PageSize=A4'
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'Nup=1up'
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'ICM=user1'
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'Duplex=None'
D [12/May/2009:08:16:53 +0200] [Job 18] Pondering option 'InputSlot=Auto'
D [12/May/2009:08:16:53 +0200] [Job 18] 
D [12/May/2009:08:16:53 +0200] [Job 18] ================================================
D [12/May/2009:08:16:53 +0200] [Job 18] 
D [12/May/2009:08:16:53 +0200] [Job 18] File: <STDIN>
D [12/May/2009:08:16:53 +0200] [Job 18] 
D [12/May/2009:08:16:53 +0200] [Job 18] ================================================
D [12/May/2009:08:16:53 +0200] [Job 18] 
D [12/May/2009:08:16:53 +0200] [Job 18] Filetype: PDF
D [12/May/2009:08:16:53 +0200] [Job 18] Driver does not understand PDF input, converting to PostScript
D [12/May/2009:08:16:53 +0200] [Job 18] Starting process "pdf-to-ps" (generation 1)
D [12/May/2009:08:16:53 +0200] [Job 18] Connected to 192.168.1.117:9100 (IPv4)...
D [12/May/2009:08:16:53 +0200] [Job 18] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb7fe7fc0)
I [12/May/2009:08:16:53 +0200] Saving subscriptions.conf...
D [12/May/2009:08:16:53 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:16:53 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:16:53 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:16:53 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:08:16:53 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:16:53 +0200] cupsdCloseClient: 11
D [12/May/2009:08:16:53 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:16:53 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:16:53 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:16:53 +0200] Get-Notifications /
D [12/May/2009:08:16:53 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:08:16:53 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:16:53 +0200] cupsdCloseClient: 11
D [12/May/2009:08:17:05 +0200] PID 6675 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [12/May/2009:08:17:06 +0200] [Job 18] Filetype: PostScript
D [12/May/2009:08:17:06 +0200] [Job 18] Reading PostScript input ...
D [12/May/2009:08:17:06 +0200] [Job 18] --> This document is DSC-conforming!
D [12/May/2009:08:17:06 +0200] [Job 18] 
D [12/May/2009:08:17:06 +0200] [Job 18] -----------
D [12/May/2009:08:17:06 +0200] [Job 18] Found: %%BeginProlog
D [12/May/2009:08:17:06 +0200] [Job 18] Inserting option code into "Prolog" section.
D [12/May/2009:08:17:06 +0200] [Job 18] Found: %%EndProlog
D [12/May/2009:08:17:06 +0200] [Job 18] 
D [12/May/2009:08:17:06 +0200] [Job 18] -----------
D [12/May/2009:08:17:06 +0200] [Job 18] New page: %%Page: 1 1
D [12/May/2009:08:17:06 +0200] [Job 18] "Setup" section is missing, inserting it.
D [12/May/2009:08:17:06 +0200] [Job 18] Inserting PostScript code for CUPS' page accounting
D [12/May/2009:08:17:06 +0200] [Job 18] Inserting option code into "Setup" section.
D [12/May/2009:08:17:06 +0200] [Job 18] No page header or page header not DSC-conforming
D [12/May/2009:08:17:06 +0200] [Job 18] "PageSetup" section is missing, inserting it.
D [12/May/2009:08:17:06 +0200] [Job 18] Inserting option code into "PageSetup" section.
D [12/May/2009:08:17:06 +0200] [Job 18] Stopping search for page header options
D [12/May/2009:08:17:06 +0200] [Job 18] Found: 253 254 r3
D [12/May/2009:08:17:06 +0200] [Job 18] --> Output goes directly to the renderer now.
D [12/May/2009:08:17:06 +0200] [Job 18] 
D [12/May/2009:08:17:06 +0200] [Job 18] 
D [12/May/2009:08:17:06 +0200] [Job 18] Starting renderer with command: "foo2lava-wrapper -z0   -c -C10 -r600x600 -p26 -m0 -s255   -d1 -Guser1.icm "
D [12/May/2009:08:17:06 +0200] [Job 18] Starting process "kid3" (generation 1)
D [12/May/2009:08:17:06 +0200] [Job 18] Starting process "kid4" (generation 2)
D [12/May/2009:08:17:06 +0200] [Job 18] JCL: %-12345X@PJL
D [12/May/2009:08:17:06 +0200] [Job 18] <job data> 
D [12/May/2009:08:17:06 +0200] [Job 18] 
D [12/May/2009:08:17:06 +0200] [Job 18] Starting process "renderer" (generation 2)
D [12/May/2009:08:17:06 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:17:06 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:17:06 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:17:07 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:17:19 +0200] Report: clients=0
D [12/May/2009:08:17:19 +0200] Report: jobs=18
D [12/May/2009:08:17:19 +0200] Report: jobs-active=1
D [12/May/2009:08:17:19 +0200] Report: printers=2
D [12/May/2009:08:17:19 +0200] Report: printers-implicit=0
D [12/May/2009:08:17:19 +0200] Report: stringpool-string-count=1183
D [12/May/2009:08:17:19 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:17:19 +0200] Report: stringpool-total-bytes=25384
D [12/May/2009:08:18:25 +0200] Report: clients=0
D [12/May/2009:08:18:25 +0200] Report: jobs=18
D [12/May/2009:08:18:25 +0200] Report: jobs-active=1
D [12/May/2009:08:18:25 +0200] Report: printers=2
D [12/May/2009:08:18:25 +0200] Report: printers-implicit=0
D [12/May/2009:08:18:25 +0200] Report: stringpool-string-count=1183
D [12/May/2009:08:18:25 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:18:25 +0200] Report: stringpool-total-bytes=25384
D [12/May/2009:08:19:31 +0200] Report: clients=0
D [12/May/2009:08:19:31 +0200] Report: jobs=18
D [12/May/2009:08:19:31 +0200] Report: jobs-active=1
D [12/May/2009:08:19:31 +0200] Report: printers=2
D [12/May/2009:08:19:31 +0200] Report: printers-implicit=0
D [12/May/2009:08:19:31 +0200] Report: stringpool-string-count=1183
D [12/May/2009:08:19:31 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:19:31 +0200] Report: stringpool-total-bytes=25384
D [12/May/2009:08:20:37 +0200] Report: clients=0
D [12/May/2009:08:20:37 +0200] Report: jobs=18
D [12/May/2009:08:20:37 +0200] Report: jobs-active=1
D [12/May/2009:08:20:37 +0200] Report: printers=2
D [12/May/2009:08:20:37 +0200] Report: printers-implicit=0
D [12/May/2009:08:20:37 +0200] Report: stringpool-string-count=1183
D [12/May/2009:08:20:37 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:20:37 +0200] Report: stringpool-total-bytes=25384
D [12/May/2009:08:21:43 +0200] Report: clients=0
D [12/May/2009:08:21:43 +0200] Report: jobs=18
D [12/May/2009:08:21:43 +0200] Report: jobs-active=1
D [12/May/2009:08:21:43 +0200] Report: printers=2
D [12/May/2009:08:21:43 +0200] Report: printers-implicit=0
D [12/May/2009:08:21:43 +0200] Report: stringpool-string-count=1183
D [12/May/2009:08:21:43 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:21:43 +0200] Report: stringpool-total-bytes=25384
D [12/May/2009:08:22:26 +0200] [Job 18] Found: %%PageTrailer
D [12/May/2009:08:22:26 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:22:26 +0200] [Job 18] 
D [12/May/2009:08:22:26 +0200] [Job 18] Found: %%Trailer
D [12/May/2009:08:22:26 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:22:26 +0200] [Job 18] 
D [12/May/2009:08:22:26 +0200] [Job 18] Found: %%Pages: 1
D [12/May/2009:08:22:26 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:22:26 +0200] [Job 18] 
D [12/May/2009:08:22:26 +0200] [Job 18] Found: %%BoundingBox: 18 86 578 778
D [12/May/2009:08:22:26 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:22:26 +0200] [Job 18] 
D [12/May/2009:08:22:26 +0200] [Job 18] Found: %%HiResBoundingBox: 18.000000 86.600000 577.300000 777.800000
D [12/May/2009:08:22:26 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:22:26 +0200] [Job 18] 
D [12/May/2009:08:22:26 +0200] [Job 18] Found: %%EOF
D [12/May/2009:08:22:26 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:22:26 +0200] [Job 18] 
D [12/May/2009:08:22:26 +0200] [Job 18] 
D [12/May/2009:08:22:26 +0200] [Job 18] Closing renderer
D [12/May/2009:08:22:36 +0200] Discarding unused job-progress event...
D [12/May/2009:08:22:36 +0200] [Job 18] Read 8192 bytes of print data...
I [12/May/2009:08:22:36 +0200] Saving subscriptions.conf...
I [12/May/2009:08:22:36 +0200] Saving subscriptions.conf...
I [12/May/2009:08:22:36 +0200] Saving subscriptions.conf...
D [12/May/2009:08:22:36 +0200] [Job 18] Wrote 8192 bytes of print data...
D [12/May/2009:08:22:36 +0200] [Job 18] Read 8192 bytes of print data...
E [12/May/2009:08:22:36 +0200] [Job 18] Unable to write print data: Broken pipe
I [12/May/2009:08:22:36 +0200] Saving subscriptions.conf...
E [12/May/2009:08:22:36 +0200] PID 6678 (/usr/lib/cups/backend/socket) stopped with status 1!
D [12/May/2009:08:22:36 +0200] [Job 18] renderer exited with status 0
D [12/May/2009:08:22:36 +0200] [Job 18] kid4 exited with status 0
D [12/May/2009:08:22:36 +0200] [Job 18] kid3 finished
D [12/May/2009:08:22:36 +0200] [Job 18] kid3 exited with status 0
D [12/May/2009:08:22:36 +0200] [Job 18] pdf-to-ps exited with status 0
D [12/May/2009:08:22:36 +0200] [Job 18] 
D [12/May/2009:08:22:36 +0200] [Job 18] Closing foomatic-rip.
D [12/May/2009:08:22:36 +0200] PID 6676 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [12/May/2009:08:22:36 +0200] [Job 18] File 0 is complete.
I [12/May/2009:08:22:36 +0200] [Job 18] Backend returned status 1 (failed)
I [12/May/2009:08:22:36 +0200] Saving subscriptions.conf...
D [12/May/2009:08:22:36 +0200] set_hold_until: hold_until = 1242109656
I [12/May/2009:08:22:36 +0200] Saving subscriptions.conf...
D [12/May/2009:08:22:36 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:22:36 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:22:36 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:22:36 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:08:22:36 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:22:36 +0200] cupsdCloseClient: 11
D [12/May/2009:08:22:36 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:22:36 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:22:36 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:22:36 +0200] Get-Notifications /
D [12/May/2009:08:22:36 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:08:22:36 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:22:36 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:22:36 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:22:36 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:22:36 +0200] Get-Printer-Attributes ipp://boromir:631/printers/magicolor-2530-DL
D [12/May/2009:08:22:36 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:22:36 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:22:36 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:22:36 +0200] Get-Job-Attributes ipp://localhost/jobs/18
D [12/May/2009:08:22:36 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:22:36 +0200] cupsdCloseClient: 11
D [12/May/2009:08:22:48 +0200] Report: clients=1
D [12/May/2009:08:22:48 +0200] Report: jobs=18
D [12/May/2009:08:22:48 +0200] Report: jobs-active=1
D [12/May/2009:08:22:48 +0200] Report: printers=2
D [12/May/2009:08:22:48 +0200] Report: printers-implicit=0
D [12/May/2009:08:22:48 +0200] Report: stringpool-string-count=1296
D [12/May/2009:08:22:48 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:22:48 +0200] Report: stringpool-total-bytes=27992
D [12/May/2009:08:23:54 +0200] Report: clients=1
D [12/May/2009:08:23:54 +0200] Report: jobs=18
D [12/May/2009:08:23:54 +0200] Report: jobs-active=1
D [12/May/2009:08:23:54 +0200] Report: printers=2
D [12/May/2009:08:23:54 +0200] Report: printers-implicit=0
D [12/May/2009:08:23:54 +0200] Report: stringpool-string-count=1296
D [12/May/2009:08:23:54 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:23:54 +0200] Report: stringpool-total-bytes=27992
D [12/May/2009:08:25:00 +0200] Report: clients=1
D [12/May/2009:08:25:00 +0200] Report: jobs=18
D [12/May/2009:08:25:00 +0200] Report: jobs-active=1
D [12/May/2009:08:25:00 +0200] Report: printers=2
D [12/May/2009:08:25:00 +0200] Report: printers-implicit=0
D [12/May/2009:08:25:00 +0200] Report: stringpool-string-count=1296
D [12/May/2009:08:25:00 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:25:00 +0200] Report: stringpool-total-bytes=27992
D [12/May/2009:08:26:06 +0200] Report: clients=1
D [12/May/2009:08:26:06 +0200] Report: jobs=18
D [12/May/2009:08:26:06 +0200] Report: jobs-active=1
D [12/May/2009:08:26:06 +0200] Report: printers=2
D [12/May/2009:08:26:06 +0200] Report: printers-implicit=0
D [12/May/2009:08:26:06 +0200] Report: stringpool-string-count=1296
D [12/May/2009:08:26:06 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:26:06 +0200] Report: stringpool-total-bytes=27992
D [12/May/2009:08:27:12 +0200] Report: clients=1
D [12/May/2009:08:27:12 +0200] Report: jobs=18
D [12/May/2009:08:27:12 +0200] Report: jobs-active=1
D [12/May/2009:08:27:12 +0200] Report: printers=2
D [12/May/2009:08:27:12 +0200] Report: printers-implicit=0
D [12/May/2009:08:27:12 +0200] Report: stringpool-string-count=1296
D [12/May/2009:08:27:12 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:27:12 +0200] Report: stringpool-total-bytes=27992
D [12/May/2009:08:27:37 +0200] Closing client 12 after 300 seconds of inactivity...
D [12/May/2009:08:27:37 +0200] cupsdCloseClient: 12
I [12/May/2009:08:27:48 +0200] Saving subscriptions.conf...
D [12/May/2009:08:27:48 +0200] [Job 18] job-sheets=none,none
D [12/May/2009:08:27:48 +0200] [Job 18] banner_page = 0
D [12/May/2009:08:27:48 +0200] [Job 18] argv[0]="magicolor-2530-DL"
D [12/May/2009:08:27:48 +0200] [Job 18] argv[1]="18"
D [12/May/2009:08:27:48 +0200] [Job 18] argv[2]="laurent"
D [12/May/2009:08:27:48 +0200] [Job 18] argv[3]="out.pnm"
D [12/May/2009:08:27:48 +0200] [Job 18] argv[4]="1"
D [12/May/2009:08:27:48 +0200] [Job 18] argv[5]="Resolution=600x600dpi Quality=normal MediaType=plain NupOrient=port ColorMode=ICM PageSize=A4 Nup=1up number-up=1 ICM=user1 Duplex=None InputSlot=Auto job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2"
D [12/May/2009:08:27:48 +0200] [Job 18] argv[6]="/var/spool/cups/d00018-001"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[9]="SERVER_ADMIN=root@boromir"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[10]="SOFTWARE=CUPS/1.3.9"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[12]="TZ=Europe/Paris"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[13]="USER=root"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[16]="IPP_PORT=631"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[17]="CHARSET=utf-8"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[18]="LANG=fr_FR.UTF8"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[20]="RIP_MAX_CACHE=8m"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[21]="CONTENT_TYPE=application/pdf"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[22]="DEVICE_URI=socket://192.168.1.117:9100"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[23]="PRINTER=magicolor-2530-DL"
D [12/May/2009:08:27:48 +0200] [Job 18] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL"
I [12/May/2009:08:27:48 +0200] [Job 18] Started filter /usr/lib/cups/filter/pdftopdf (PID 6965)
I [12/May/2009:08:27:48 +0200] [Job 18] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6966)
I [12/May/2009:08:27:48 +0200] [Job 18] Started backend /usr/lib/cups/backend/socket (PID 6967)
I [12/May/2009:08:27:48 +0200] Saving subscriptions.conf...
D [12/May/2009:08:27:48 +0200] [Job 18] Getting input from file 
D [12/May/2009:08:27:48 +0200] [Job 18] foomatic-rip version 4.0.0.195 running...
D [12/May/2009:08:27:48 +0200] [Job 18] Parsing PPD file ...
D [12/May/2009:08:27:48 +0200] [Job 18] Added option PageSize
D [12/May/2009:08:27:48 +0200] [Job 18] Added option Quality
D [12/May/2009:08:27:48 +0200] [Job 18] Added option PrinterType
D [12/May/2009:08:27:48 +0200] [Job 18] Added option ColorMode
D [12/May/2009:08:27:48 +0200] [Job 18] Added option Resolution
D [12/May/2009:08:27:48 +0200] [Job 18] Added option ImageableArea
D [12/May/2009:08:27:48 +0200] [Job 18] Added option PaperDimension
D [12/May/2009:08:27:48 +0200] [Job 18] Added option InputSlot
D [12/May/2009:08:27:48 +0200] [Job 18] Added option MediaType
D [12/May/2009:08:27:48 +0200] [Job 18] Added option Duplex
D [12/May/2009:08:27:48 +0200] [Job 18] Added option Copies
D [12/May/2009:08:27:48 +0200] [Job 18] Added option ICM
D [12/May/2009:08:27:48 +0200] [Job 18] Added option Nup
D [12/May/2009:08:27:48 +0200] [Job 18] Added option NupOrient
D [12/May/2009:08:27:48 +0200] [Job 18] Added option Font
D [12/May/2009:08:27:48 +0200] [Job 18] 
D [12/May/2009:08:27:48 +0200] [Job 18] Parameter Summary
D [12/May/2009:08:27:48 +0200] [Job 18] -----------------
D [12/May/2009:08:27:48 +0200] [Job 18] 
D [12/May/2009:08:27:48 +0200] [Job 18] Spooler: cups
D [12/May/2009:08:27:48 +0200] [Job 18] Printer: magicolor-2530-DL
D [12/May/2009:08:27:48 +0200] [Job 18] Shell: /bin/bash
D [12/May/2009:08:27:48 +0200] [Job 18] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd
D [12/May/2009:08:27:48 +0200] [Job 18] ATTR file: 
D [12/May/2009:08:27:48 +0200] [Job 18] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended)
D [12/May/2009:08:27:48 +0200] [Job 18] Job title: out.pnm
D [12/May/2009:08:27:48 +0200] [Job 18] File(s) to be printed:
D [12/May/2009:08:27:48 +0200] [Job 18] <STDIN>
D [12/May/2009:08:27:48 +0200] [Job 18] 
D [12/May/2009:08:27:48 +0200] [Job 18] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [12/May/2009:08:27:48 +0200] [Job 18] Printing system options:
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'number-up=1'
D [12/May/2009:08:27:48 +0200] [Job 18] Unknown option number-up=1.
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2'
D [12/May/2009:08:27:48 +0200] [Job 18] Unknown option job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2.
D [12/May/2009:08:27:48 +0200] [Job 18] Options from the PPD file:
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'Resolution=600x600dpi'
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'Quality=normal'
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'MediaType=plain'
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'NupOrient=port'
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'ColorMode=ICM'
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'PageSize=A4'
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'Nup=1up'
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'ICM=user1'
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'Duplex=None'
D [12/May/2009:08:27:48 +0200] [Job 18] Pondering option 'InputSlot=Auto'
D [12/May/2009:08:27:48 +0200] [Job 18] 
D [12/May/2009:08:27:48 +0200] [Job 18] ================================================
D [12/May/2009:08:27:48 +0200] [Job 18] 
D [12/May/2009:08:27:48 +0200] [Job 18] File: <STDIN>
D [12/May/2009:08:27:48 +0200] [Job 18] 
D [12/May/2009:08:27:48 +0200] [Job 18] ================================================
D [12/May/2009:08:27:48 +0200] [Job 18] 
D [12/May/2009:08:27:48 +0200] [Job 18] Connected to 192.168.1.117:9100 (IPv4)...
D [12/May/2009:08:27:48 +0200] [Job 18] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb7f66fc0)
D [12/May/2009:08:27:48 +0200] [Job 18] Filetype: PDF
D [12/May/2009:08:27:48 +0200] [Job 18] Driver does not understand PDF input, converting to PostScript
D [12/May/2009:08:27:48 +0200] [Job 18] Starting process "pdf-to-ps" (generation 1)
I [12/May/2009:08:27:48 +0200] Saving subscriptions.conf...
D [12/May/2009:08:27:48 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:27:48 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:27:48 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:27:48 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:08:27:48 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:27:48 +0200] cupsdCloseClient: 11
D [12/May/2009:08:27:48 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:27:48 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:27:48 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:27:48 +0200] Get-Notifications /
D [12/May/2009:08:27:48 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:08:27:48 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:27:48 +0200] cupsdCloseClient: 11
D [12/May/2009:08:27:58 +0200] PID 6965 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [12/May/2009:08:27:59 +0200] [Job 18] Filetype: PostScript
D [12/May/2009:08:27:59 +0200] [Job 18] Reading PostScript input ...
D [12/May/2009:08:27:59 +0200] [Job 18] --> This document is DSC-conforming!
D [12/May/2009:08:27:59 +0200] [Job 18] 
D [12/May/2009:08:27:59 +0200] [Job 18] -----------
D [12/May/2009:08:27:59 +0200] [Job 18] Found: %%BeginProlog
D [12/May/2009:08:27:59 +0200] [Job 18] Inserting option code into "Prolog" section.
D [12/May/2009:08:27:59 +0200] [Job 18] Found: %%EndProlog
D [12/May/2009:08:27:59 +0200] [Job 18] 
D [12/May/2009:08:27:59 +0200] [Job 18] -----------
D [12/May/2009:08:27:59 +0200] [Job 18] New page: %%Page: 1 1
D [12/May/2009:08:27:59 +0200] [Job 18] "Setup" section is missing, inserting it.
D [12/May/2009:08:27:59 +0200] [Job 18] Inserting PostScript code for CUPS' page accounting
D [12/May/2009:08:27:59 +0200] [Job 18] Inserting option code into "Setup" section.
D [12/May/2009:08:27:59 +0200] [Job 18] No page header or page header not DSC-conforming
D [12/May/2009:08:27:59 +0200] [Job 18] "PageSetup" section is missing, inserting it.
D [12/May/2009:08:27:59 +0200] [Job 18] Inserting option code into "PageSetup" section.
D [12/May/2009:08:27:59 +0200] [Job 18] Stopping search for page header options
D [12/May/2009:08:27:59 +0200] [Job 18] Found: 253 254 r3
D [12/May/2009:08:27:59 +0200] [Job 18] --> Output goes directly to the renderer now.
D [12/May/2009:08:27:59 +0200] [Job 18] 
D [12/May/2009:08:27:59 +0200] [Job 18] 
D [12/May/2009:08:27:59 +0200] [Job 18] Starting renderer with command: "foo2lava-wrapper -z0   -c -C10 -r600x600 -p26 -m0 -s255   -d1 -Guser1.icm "
D [12/May/2009:08:27:59 +0200] [Job 18] Starting process "kid3" (generation 1)
D [12/May/2009:08:27:59 +0200] [Job 18] Starting process "kid4" (generation 2)
D [12/May/2009:08:27:59 +0200] [Job 18] JCL: %-12345X@PJL
D [12/May/2009:08:27:59 +0200] [Job 18] <job data> 
D [12/May/2009:08:27:59 +0200] [Job 18] 
D [12/May/2009:08:27:59 +0200] [Job 18] Starting process "renderer" (generation 2)
D [12/May/2009:08:28:00 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:28:00 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:28:00 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:28:00 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:28:12 +0200] Report: clients=0
D [12/May/2009:08:28:12 +0200] Report: jobs=18
D [12/May/2009:08:28:12 +0200] Report: jobs-active=1
D [12/May/2009:08:28:12 +0200] Report: printers=2
D [12/May/2009:08:28:12 +0200] Report: printers-implicit=0
D [12/May/2009:08:28:12 +0200] Report: stringpool-string-count=1360
D [12/May/2009:08:28:12 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:28:12 +0200] Report: stringpool-total-bytes=29360
D [12/May/2009:08:29:18 +0200] Report: clients=0
D [12/May/2009:08:29:18 +0200] Report: jobs=18
D [12/May/2009:08:29:18 +0200] Report: jobs-active=1
D [12/May/2009:08:29:18 +0200] Report: printers=2
D [12/May/2009:08:29:18 +0200] Report: printers-implicit=0
D [12/May/2009:08:29:18 +0200] Report: stringpool-string-count=1360
D [12/May/2009:08:29:18 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:29:18 +0200] Report: stringpool-total-bytes=29360
D [12/May/2009:08:30:24 +0200] Report: clients=0
D [12/May/2009:08:30:24 +0200] Report: jobs=18
D [12/May/2009:08:30:24 +0200] Report: jobs-active=1
D [12/May/2009:08:30:24 +0200] Report: printers=2
D [12/May/2009:08:30:24 +0200] Report: printers-implicit=0
D [12/May/2009:08:30:24 +0200] Report: stringpool-string-count=1360
D [12/May/2009:08:30:24 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:30:24 +0200] Report: stringpool-total-bytes=29360
D [12/May/2009:08:31:30 +0200] Report: clients=0
D [12/May/2009:08:31:30 +0200] Report: jobs=18
D [12/May/2009:08:31:30 +0200] Report: jobs-active=1
D [12/May/2009:08:31:30 +0200] Report: printers=2
D [12/May/2009:08:31:30 +0200] Report: printers-implicit=0
D [12/May/2009:08:31:30 +0200] Report: stringpool-string-count=1360
D [12/May/2009:08:31:30 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:31:30 +0200] Report: stringpool-total-bytes=29360
D [12/May/2009:08:32:36 +0200] Report: clients=0
D [12/May/2009:08:32:36 +0200] Report: jobs=18
D [12/May/2009:08:32:36 +0200] Report: jobs-active=1
D [12/May/2009:08:32:36 +0200] Report: printers=2
D [12/May/2009:08:32:36 +0200] Report: printers-implicit=0
D [12/May/2009:08:32:36 +0200] Report: stringpool-string-count=1360
D [12/May/2009:08:32:36 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:32:36 +0200] Report: stringpool-total-bytes=29360
D [12/May/2009:08:33:17 +0200] [Job 18] Found: %%PageTrailer
D [12/May/2009:08:33:17 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:33:17 +0200] [Job 18] 
D [12/May/2009:08:33:17 +0200] [Job 18] Found: %%Trailer
D [12/May/2009:08:33:17 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:33:17 +0200] [Job 18] 
D [12/May/2009:08:33:17 +0200] [Job 18] Found: %%Pages: 1
D [12/May/2009:08:33:17 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:33:17 +0200] [Job 18] 
D [12/May/2009:08:33:17 +0200] [Job 18] Found: %%BoundingBox: 18 86 578 778
D [12/May/2009:08:33:17 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:33:17 +0200] [Job 18] 
D [12/May/2009:08:33:17 +0200] [Job 18] Found: %%HiResBoundingBox: 18.000000 86.600000 577.300000 777.800000
D [12/May/2009:08:33:17 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:33:17 +0200] [Job 18] 
D [12/May/2009:08:33:17 +0200] [Job 18] Found: %%EOF
D [12/May/2009:08:33:17 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:33:17 +0200] [Job 18] 
D [12/May/2009:08:33:18 +0200] [Job 18] 
D [12/May/2009:08:33:18 +0200] [Job 18] Closing renderer
D [12/May/2009:08:33:27 +0200] Discarding unused job-progress event...
D [12/May/2009:08:33:27 +0200] [Job 18] Read 8192 bytes of print data...
I [12/May/2009:08:33:27 +0200] Saving subscriptions.conf...
I [12/May/2009:08:33:27 +0200] Saving subscriptions.conf...
I [12/May/2009:08:33:27 +0200] Saving subscriptions.conf...
D [12/May/2009:08:33:27 +0200] [Job 18] Wrote 8192 bytes of print data...
D [12/May/2009:08:33:27 +0200] [Job 18] Read 8192 bytes of print data...
E [12/May/2009:08:33:27 +0200] [Job 18] Unable to write print data: Broken pipe
I [12/May/2009:08:33:27 +0200] Saving subscriptions.conf...
E [12/May/2009:08:33:27 +0200] PID 6967 (/usr/lib/cups/backend/socket) stopped with status 1!
D [12/May/2009:08:33:27 +0200] [Job 18] renderer exited with status 0
D [12/May/2009:08:33:27 +0200] [Job 18] kid4 exited with status 0
D [12/May/2009:08:33:27 +0200] [Job 18] kid3 finished
D [12/May/2009:08:33:27 +0200] [Job 18] kid3 exited with status 0
D [12/May/2009:08:33:27 +0200] [Job 18] pdf-to-ps exited with status 0
D [12/May/2009:08:33:27 +0200] [Job 18] 
D [12/May/2009:08:33:27 +0200] [Job 18] Closing foomatic-rip.
D [12/May/2009:08:33:27 +0200] PID 6966 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [12/May/2009:08:33:27 +0200] [Job 18] File 0 is complete.
I [12/May/2009:08:33:27 +0200] [Job 18] Backend returned status 1 (failed)
I [12/May/2009:08:33:27 +0200] Saving subscriptions.conf...
D [12/May/2009:08:33:27 +0200] set_hold_until: hold_until = 1242110307
I [12/May/2009:08:33:27 +0200] Saving subscriptions.conf...
D [12/May/2009:08:33:27 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:33:27 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:33:27 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:33:27 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:08:33:27 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:33:27 +0200] cupsdCloseClient: 11
D [12/May/2009:08:33:27 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:33:27 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:33:27 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:33:27 +0200] Get-Notifications /
D [12/May/2009:08:33:27 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:08:33:27 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:33:27 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:33:27 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:33:27 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:33:27 +0200] Get-Printer-Attributes ipp://boromir:631/printers/magicolor-2530-DL
D [12/May/2009:08:33:27 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:33:27 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:33:27 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:33:27 +0200] Get-Job-Attributes ipp://localhost/jobs/18
D [12/May/2009:08:33:27 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:33:27 +0200] cupsdCloseClient: 11
D [12/May/2009:08:33:39 +0200] Report: clients=1
D [12/May/2009:08:33:39 +0200] Report: jobs=18
D [12/May/2009:08:33:39 +0200] Report: jobs-active=1
D [12/May/2009:08:33:39 +0200] Report: printers=2
D [12/May/2009:08:33:39 +0200] Report: printers-implicit=0
D [12/May/2009:08:33:39 +0200] Report: stringpool-string-count=1473
D [12/May/2009:08:33:39 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:33:39 +0200] Report: stringpool-total-bytes=31968
D [12/May/2009:08:34:45 +0200] Report: clients=1
D [12/May/2009:08:34:45 +0200] Report: jobs=18
D [12/May/2009:08:34:45 +0200] Report: jobs-active=1
D [12/May/2009:08:34:45 +0200] Report: printers=2
D [12/May/2009:08:34:45 +0200] Report: printers-implicit=0
D [12/May/2009:08:34:45 +0200] Report: stringpool-string-count=1473
D [12/May/2009:08:34:45 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:34:45 +0200] Report: stringpool-total-bytes=31968
D [12/May/2009:08:35:51 +0200] Report: clients=1
D [12/May/2009:08:35:51 +0200] Report: jobs=18
D [12/May/2009:08:35:51 +0200] Report: jobs-active=1
D [12/May/2009:08:35:51 +0200] Report: printers=2
D [12/May/2009:08:35:51 +0200] Report: printers-implicit=0
D [12/May/2009:08:35:51 +0200] Report: stringpool-string-count=1473
D [12/May/2009:08:35:51 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:35:51 +0200] Report: stringpool-total-bytes=31968
D [12/May/2009:08:36:57 +0200] Report: clients=1
D [12/May/2009:08:36:57 +0200] Report: jobs=18
D [12/May/2009:08:36:57 +0200] Report: jobs-active=1
D [12/May/2009:08:36:57 +0200] Report: printers=2
D [12/May/2009:08:36:57 +0200] Report: printers-implicit=0
D [12/May/2009:08:36:57 +0200] Report: stringpool-string-count=1473
D [12/May/2009:08:36:57 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:36:57 +0200] Report: stringpool-total-bytes=31968
D [12/May/2009:08:38:03 +0200] Report: clients=1
D [12/May/2009:08:38:03 +0200] Report: jobs=18
D [12/May/2009:08:38:03 +0200] Report: jobs-active=1
D [12/May/2009:08:38:03 +0200] Report: printers=2
D [12/May/2009:08:38:03 +0200] Report: printers-implicit=0
D [12/May/2009:08:38:03 +0200] Report: stringpool-string-count=1473
D [12/May/2009:08:38:03 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:38:03 +0200] Report: stringpool-total-bytes=31968
D [12/May/2009:08:38:28 +0200] Closing client 12 after 300 seconds of inactivity...
D [12/May/2009:08:38:28 +0200] cupsdCloseClient: 12
I [12/May/2009:08:38:39 +0200] Saving subscriptions.conf...
D [12/May/2009:08:38:39 +0200] [Job 18] job-sheets=none,none
D [12/May/2009:08:38:39 +0200] [Job 18] banner_page = 0
D [12/May/2009:08:38:39 +0200] [Job 18] argv[0]="magicolor-2530-DL"
D [12/May/2009:08:38:39 +0200] [Job 18] argv[1]="18"
D [12/May/2009:08:38:39 +0200] [Job 18] argv[2]="laurent"
D [12/May/2009:08:38:39 +0200] [Job 18] argv[3]="out.pnm"
D [12/May/2009:08:38:39 +0200] [Job 18] argv[4]="1"
D [12/May/2009:08:38:39 +0200] [Job 18] argv[5]="Resolution=600x600dpi Quality=normal MediaType=plain NupOrient=port ColorMode=ICM PageSize=A4 Nup=1up number-up=1 ICM=user1 Duplex=None InputSlot=Auto job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2"
D [12/May/2009:08:38:39 +0200] [Job 18] argv[6]="/var/spool/cups/d00018-001"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[9]="SERVER_ADMIN=root@boromir"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[10]="SOFTWARE=CUPS/1.3.9"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[12]="TZ=Europe/Paris"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[13]="USER=root"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[16]="IPP_PORT=631"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[17]="CHARSET=utf-8"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[18]="LANG=fr_FR.UTF8"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[20]="RIP_MAX_CACHE=8m"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[21]="CONTENT_TYPE=application/pdf"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[22]="DEVICE_URI=socket://192.168.1.117:9100"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[23]="PRINTER=magicolor-2530-DL"
D [12/May/2009:08:38:39 +0200] [Job 18] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL"
I [12/May/2009:08:38:39 +0200] [Job 18] Started filter /usr/lib/cups/filter/pdftopdf (PID 7188)
I [12/May/2009:08:38:39 +0200] [Job 18] Started filter /usr/lib/cups/filter/foomatic-rip (PID 7189)
I [12/May/2009:08:38:39 +0200] [Job 18] Started backend /usr/lib/cups/backend/socket (PID 7191)
I [12/May/2009:08:38:39 +0200] Saving subscriptions.conf...
D [12/May/2009:08:38:39 +0200] [Job 18] Getting input from file 
D [12/May/2009:08:38:39 +0200] [Job 18] foomatic-rip version 4.0.0.195 running...
D [12/May/2009:08:38:39 +0200] [Job 18] Parsing PPD file ...
D [12/May/2009:08:38:39 +0200] [Job 18] Added option PageSize
D [12/May/2009:08:38:39 +0200] [Job 18] Added option Quality
D [12/May/2009:08:38:39 +0200] [Job 18] Added option PrinterType
D [12/May/2009:08:38:39 +0200] [Job 18] Added option ColorMode
D [12/May/2009:08:38:39 +0200] [Job 18] Added option Resolution
D [12/May/2009:08:38:39 +0200] [Job 18] Added option ImageableArea
D [12/May/2009:08:38:39 +0200] [Job 18] Added option PaperDimension
D [12/May/2009:08:38:39 +0200] [Job 18] Added option InputSlot
D [12/May/2009:08:38:39 +0200] [Job 18] Added option MediaType
D [12/May/2009:08:38:39 +0200] [Job 18] Added option Duplex
D [12/May/2009:08:38:39 +0200] [Job 18] Added option Copies
D [12/May/2009:08:38:39 +0200] [Job 18] Added option ICM
D [12/May/2009:08:38:39 +0200] [Job 18] Added option Nup
D [12/May/2009:08:38:39 +0200] [Job 18] Added option NupOrient
D [12/May/2009:08:38:39 +0200] [Job 18] Added option Font
D [12/May/2009:08:38:39 +0200] [Job 18] 
D [12/May/2009:08:38:39 +0200] [Job 18] Parameter Summary
D [12/May/2009:08:38:39 +0200] [Job 18] -----------------
D [12/May/2009:08:38:39 +0200] [Job 18] 
D [12/May/2009:08:38:39 +0200] [Job 18] Spooler: cups
D [12/May/2009:08:38:39 +0200] [Job 18] Printer: magicolor-2530-DL
D [12/May/2009:08:38:39 +0200] [Job 18] Shell: /bin/bash
D [12/May/2009:08:38:39 +0200] [Job 18] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd
D [12/May/2009:08:38:39 +0200] [Job 18] ATTR file: 
D [12/May/2009:08:38:39 +0200] [Job 18] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended)
D [12/May/2009:08:38:39 +0200] [Job 18] Job title: out.pnm
D [12/May/2009:08:38:39 +0200] [Job 18] File(s) to be printed:
D [12/May/2009:08:38:39 +0200] [Job 18] <STDIN>
D [12/May/2009:08:38:39 +0200] [Job 18] 
D [12/May/2009:08:38:39 +0200] [Job 18] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [12/May/2009:08:38:39 +0200] [Job 18] Printing system options:
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'number-up=1'
D [12/May/2009:08:38:39 +0200] [Job 18] Unknown option number-up=1.
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2'
D [12/May/2009:08:38:39 +0200] [Job 18] Unknown option job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2.
D [12/May/2009:08:38:39 +0200] [Job 18] Options from the PPD file:
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'Resolution=600x600dpi'
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'Quality=normal'
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'MediaType=plain'
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'NupOrient=port'
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'ColorMode=ICM'
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'PageSize=A4'
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'Nup=1up'
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'ICM=user1'
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'Duplex=None'
D [12/May/2009:08:38:39 +0200] [Job 18] Pondering option 'InputSlot=Auto'
D [12/May/2009:08:38:39 +0200] [Job 18] 
D [12/May/2009:08:38:39 +0200] [Job 18] ================================================
D [12/May/2009:08:38:39 +0200] [Job 18] 
D [12/May/2009:08:38:39 +0200] [Job 18] File: <STDIN>
D [12/May/2009:08:38:39 +0200] [Job 18] 
D [12/May/2009:08:38:39 +0200] [Job 18] ================================================
D [12/May/2009:08:38:39 +0200] [Job 18] 
D [12/May/2009:08:38:39 +0200] [Job 18] Filetype: PDF
D [12/May/2009:08:38:39 +0200] [Job 18] Driver does not understand PDF input, converting to PostScript
D [12/May/2009:08:38:39 +0200] [Job 18] Starting process "pdf-to-ps" (generation 1)
D [12/May/2009:08:38:39 +0200] [Job 18] Connected to 192.168.1.117:9100 (IPv4)...
D [12/May/2009:08:38:39 +0200] [Job 18] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb7f1afc0)
I [12/May/2009:08:38:39 +0200] Saving subscriptions.conf...
D [12/May/2009:08:38:40 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:38:40 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:38:40 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:38:40 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:08:38:40 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:38:40 +0200] cupsdCloseClient: 11
D [12/May/2009:08:38:40 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:38:40 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:38:40 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:38:40 +0200] Get-Notifications /
D [12/May/2009:08:38:40 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:08:38:40 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:38:40 +0200] cupsdCloseClient: 11
D [12/May/2009:08:38:50 +0200] PID 7188 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [12/May/2009:08:38:51 +0200] [Job 18] Filetype: PostScript
D [12/May/2009:08:38:51 +0200] [Job 18] Reading PostScript input ...
D [12/May/2009:08:38:51 +0200] [Job 18] --> This document is DSC-conforming!
D [12/May/2009:08:38:51 +0200] [Job 18] 
D [12/May/2009:08:38:51 +0200] [Job 18] -----------
D [12/May/2009:08:38:51 +0200] [Job 18] Found: %%BeginProlog
D [12/May/2009:08:38:51 +0200] [Job 18] Inserting option code into "Prolog" section.
D [12/May/2009:08:38:51 +0200] [Job 18] Found: %%EndProlog
D [12/May/2009:08:38:51 +0200] [Job 18] 
D [12/May/2009:08:38:51 +0200] [Job 18] -----------
D [12/May/2009:08:38:51 +0200] [Job 18] New page: %%Page: 1 1
D [12/May/2009:08:38:51 +0200] [Job 18] "Setup" section is missing, inserting it.
D [12/May/2009:08:38:51 +0200] [Job 18] Inserting PostScript code for CUPS' page accounting
D [12/May/2009:08:38:51 +0200] [Job 18] Inserting option code into "Setup" section.
D [12/May/2009:08:38:51 +0200] [Job 18] No page header or page header not DSC-conforming
D [12/May/2009:08:38:51 +0200] [Job 18] "PageSetup" section is missing, inserting it.
D [12/May/2009:08:38:51 +0200] [Job 18] Inserting option code into "PageSetup" section.
D [12/May/2009:08:38:51 +0200] [Job 18] Stopping search for page header options
D [12/May/2009:08:38:51 +0200] [Job 18] Found: 253 254 r3
D [12/May/2009:08:38:51 +0200] [Job 18] --> Output goes directly to the renderer now.
D [12/May/2009:08:38:51 +0200] [Job 18] 
D [12/May/2009:08:38:51 +0200] [Job 18] 
D [12/May/2009:08:38:51 +0200] [Job 18] Starting renderer with command: "foo2lava-wrapper -z0   -c -C10 -r600x600 -p26 -m0 -s255   -d1 -Guser1.icm "
D [12/May/2009:08:38:51 +0200] [Job 18] Starting process "kid3" (generation 1)
D [12/May/2009:08:38:51 +0200] [Job 18] Starting process "kid4" (generation 2)
D [12/May/2009:08:38:51 +0200] [Job 18] JCL: %-12345X@PJL
D [12/May/2009:08:38:51 +0200] [Job 18] <job data> 
D [12/May/2009:08:38:51 +0200] [Job 18] 
D [12/May/2009:08:38:51 +0200] [Job 18] Starting process "renderer" (generation 2)
D [12/May/2009:08:38:51 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:38:51 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:38:51 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:38:52 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:39:04 +0200] Report: clients=0
D [12/May/2009:08:39:04 +0200] Report: jobs=18
D [12/May/2009:08:39:04 +0200] Report: jobs-active=1
D [12/May/2009:08:39:04 +0200] Report: printers=2
D [12/May/2009:08:39:04 +0200] Report: printers-implicit=0
D [12/May/2009:08:39:04 +0200] Report: stringpool-string-count=1537
D [12/May/2009:08:39:04 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:39:04 +0200] Report: stringpool-total-bytes=33336
D [12/May/2009:08:40:10 +0200] Report: clients=0
D [12/May/2009:08:40:10 +0200] Report: jobs=18
D [12/May/2009:08:40:10 +0200] Report: jobs-active=1
D [12/May/2009:08:40:10 +0200] Report: printers=2
D [12/May/2009:08:40:10 +0200] Report: printers-implicit=0
D [12/May/2009:08:40:10 +0200] Report: stringpool-string-count=1537
D [12/May/2009:08:40:10 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:40:10 +0200] Report: stringpool-total-bytes=33336
D [12/May/2009:08:41:16 +0200] Report: clients=0
D [12/May/2009:08:41:16 +0200] Report: jobs=18
D [12/May/2009:08:41:16 +0200] Report: jobs-active=1
D [12/May/2009:08:41:16 +0200] Report: printers=2
D [12/May/2009:08:41:16 +0200] Report: printers-implicit=0
D [12/May/2009:08:41:16 +0200] Report: stringpool-string-count=1537
D [12/May/2009:08:41:16 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:41:16 +0200] Report: stringpool-total-bytes=33336
D [12/May/2009:08:42:22 +0200] Report: clients=0
D [12/May/2009:08:42:22 +0200] Report: jobs=18
D [12/May/2009:08:42:22 +0200] Report: jobs-active=1
D [12/May/2009:08:42:22 +0200] Report: printers=2
D [12/May/2009:08:42:22 +0200] Report: printers-implicit=0
D [12/May/2009:08:42:22 +0200] Report: stringpool-string-count=1537
D [12/May/2009:08:42:22 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:42:22 +0200] Report: stringpool-total-bytes=33336
D [12/May/2009:08:43:28 +0200] Report: clients=0
D [12/May/2009:08:43:28 +0200] Report: jobs=18
D [12/May/2009:08:43:28 +0200] Report: jobs-active=1
D [12/May/2009:08:43:28 +0200] Report: printers=2
D [12/May/2009:08:43:28 +0200] Report: printers-implicit=0
D [12/May/2009:08:43:28 +0200] Report: stringpool-string-count=1537
D [12/May/2009:08:43:28 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:43:28 +0200] Report: stringpool-total-bytes=33336
D [12/May/2009:08:44:04 +0200] [Job 18] Found: %%PageTrailer
D [12/May/2009:08:44:04 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:44:04 +0200] [Job 18] 
D [12/May/2009:08:44:04 +0200] [Job 18] Found: %%Trailer
D [12/May/2009:08:44:04 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:44:04 +0200] [Job 18] 
D [12/May/2009:08:44:04 +0200] [Job 18] Found: %%Pages: 1
D [12/May/2009:08:44:04 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:44:04 +0200] [Job 18] 
D [12/May/2009:08:44:04 +0200] [Job 18] Found: %%BoundingBox: 18 86 578 778
D [12/May/2009:08:44:04 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:44:04 +0200] [Job 18] 
D [12/May/2009:08:44:04 +0200] [Job 18] Found: %%HiResBoundingBox: 18.000000 86.600000 577.300000 777.800000
D [12/May/2009:08:44:04 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:44:04 +0200] [Job 18] 
D [12/May/2009:08:44:04 +0200] [Job 18] Found: %%EOF
D [12/May/2009:08:44:04 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:44:04 +0200] [Job 18] 
D [12/May/2009:08:44:04 +0200] [Job 18] 
D [12/May/2009:08:44:04 +0200] [Job 18] Closing renderer
D [12/May/2009:08:44:13 +0200] Discarding unused job-progress event...
D [12/May/2009:08:44:13 +0200] [Job 18] Read 8192 bytes of print data...
I [12/May/2009:08:44:13 +0200] Saving subscriptions.conf...
I [12/May/2009:08:44:13 +0200] Saving subscriptions.conf...
I [12/May/2009:08:44:13 +0200] Saving subscriptions.conf...
D [12/May/2009:08:44:13 +0200] [Job 18] Wrote 8192 bytes of print data...
D [12/May/2009:08:44:13 +0200] [Job 18] Read 8192 bytes of print data...
E [12/May/2009:08:44:13 +0200] [Job 18] Unable to write print data: Broken pipe
I [12/May/2009:08:44:13 +0200] Saving subscriptions.conf...
E [12/May/2009:08:44:13 +0200] PID 7191 (/usr/lib/cups/backend/socket) stopped with status 1!
D [12/May/2009:08:44:14 +0200] [Job 18] renderer exited with status 0
D [12/May/2009:08:44:14 +0200] [Job 18] kid4 exited with status 0
D [12/May/2009:08:44:14 +0200] [Job 18] kid3 finished
D [12/May/2009:08:44:14 +0200] [Job 18] kid3 exited with status 0
D [12/May/2009:08:44:14 +0200] [Job 18] pdf-to-ps exited with status 0
D [12/May/2009:08:44:14 +0200] [Job 18] 
D [12/May/2009:08:44:14 +0200] [Job 18] Closing foomatic-rip.
D [12/May/2009:08:44:14 +0200] PID 7189 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [12/May/2009:08:44:14 +0200] [Job 18] File 0 is complete.
I [12/May/2009:08:44:14 +0200] [Job 18] Backend returned status 1 (failed)
I [12/May/2009:08:44:14 +0200] Saving subscriptions.conf...
D [12/May/2009:08:44:14 +0200] set_hold_until: hold_until = 1242110954
I [12/May/2009:08:44:14 +0200] Saving subscriptions.conf...
D [12/May/2009:08:44:14 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:44:14 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:44:14 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:44:14 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:08:44:14 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:44:14 +0200] cupsdCloseClient: 11
D [12/May/2009:08:44:14 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:44:14 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:44:14 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:44:14 +0200] Get-Notifications /
D [12/May/2009:08:44:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:08:44:14 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:44:14 +0200] cupsdAcceptClient: 12 from localhost (Domain)
D [12/May/2009:08:44:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:44:14 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:44:14 +0200] Get-Printer-Attributes ipp://boromir:631/printers/magicolor-2530-DL
D [12/May/2009:08:44:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:44:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1
D [12/May/2009:08:44:14 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:44:14 +0200] Get-Job-Attributes ipp://localhost/jobs/18
D [12/May/2009:08:44:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [12/May/2009:08:44:14 +0200] cupsdCloseClient: 11
D [12/May/2009:08:44:37 +0200] Report: clients=1
D [12/May/2009:08:44:37 +0200] Report: jobs=18
D [12/May/2009:08:44:37 +0200] Report: jobs-active=1
D [12/May/2009:08:44:37 +0200] Report: printers=2
D [12/May/2009:08:44:37 +0200] Report: printers-implicit=0
D [12/May/2009:08:44:37 +0200] Report: stringpool-string-count=1650
D [12/May/2009:08:44:37 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:44:37 +0200] Report: stringpool-total-bytes=35944
D [12/May/2009:08:45:43 +0200] Report: clients=1
D [12/May/2009:08:45:43 +0200] Report: jobs=18
D [12/May/2009:08:45:43 +0200] Report: jobs-active=1
D [12/May/2009:08:45:43 +0200] Report: printers=2
D [12/May/2009:08:45:43 +0200] Report: printers-implicit=0
D [12/May/2009:08:45:43 +0200] Report: stringpool-string-count=1650
D [12/May/2009:08:45:43 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:45:43 +0200] Report: stringpool-total-bytes=35944
D [12/May/2009:08:46:49 +0200] Report: clients=1
D [12/May/2009:08:46:49 +0200] Report: jobs=18
D [12/May/2009:08:46:49 +0200] Report: jobs-active=1
D [12/May/2009:08:46:49 +0200] Report: printers=2
D [12/May/2009:08:46:49 +0200] Report: printers-implicit=0
D [12/May/2009:08:46:49 +0200] Report: stringpool-string-count=1650
D [12/May/2009:08:46:49 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:46:49 +0200] Report: stringpool-total-bytes=35944
D [12/May/2009:08:47:55 +0200] Report: clients=1
D [12/May/2009:08:47:55 +0200] Report: jobs=18
D [12/May/2009:08:47:55 +0200] Report: jobs-active=1
D [12/May/2009:08:47:55 +0200] Report: printers=2
D [12/May/2009:08:47:55 +0200] Report: printers-implicit=0
D [12/May/2009:08:47:55 +0200] Report: stringpool-string-count=1650
D [12/May/2009:08:47:55 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:47:55 +0200] Report: stringpool-total-bytes=35944
D [12/May/2009:08:49:01 +0200] Report: clients=1
D [12/May/2009:08:49:01 +0200] Report: jobs=18
D [12/May/2009:08:49:01 +0200] Report: jobs-active=1
D [12/May/2009:08:49:01 +0200] Report: printers=2
D [12/May/2009:08:49:01 +0200] Report: printers-implicit=0
D [12/May/2009:08:49:01 +0200] Report: stringpool-string-count=1650
D [12/May/2009:08:49:01 +0200] Report: stringpool-alloc-bytes=9792
D [12/May/2009:08:49:01 +0200] Report: stringpool-total-bytes=35944
D [12/May/2009:08:49:15 +0200] Closing client 12 after 300 seconds of inactivity...
D [12/May/2009:08:49:15 +0200] cupsdCloseClient: 12
I [12/May/2009:08:49:26 +0200] Saving subscriptions.conf...
D [12/May/2009:08:49:26 +0200] [Job 18] job-sheets=none,none
D [12/May/2009:08:49:26 +0200] [Job 18] banner_page = 0
D [12/May/2009:08:49:26 +0200] [Job 18] argv[0]="magicolor-2530-DL"
D [12/May/2009:08:49:26 +0200] [Job 18] argv[1]="18"
D [12/May/2009:08:49:26 +0200] [Job 18] argv[2]="laurent"
D [12/May/2009:08:49:26 +0200] [Job 18] argv[3]="out.pnm"
D [12/May/2009:08:49:26 +0200] [Job 18] argv[4]="1"
D [12/May/2009:08:49:26 +0200] [Job 18] argv[5]="Resolution=600x600dpi Quality=normal MediaType=plain NupOrient=port ColorMode=ICM PageSize=A4 Nup=1up number-up=1 ICM=user1 Duplex=None InputSlot=Auto job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2"
D [12/May/2009:08:49:26 +0200] [Job 18] argv[6]="/var/spool/cups/d00018-001"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[9]="SERVER_ADMIN=root@boromir"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[10]="SOFTWARE=CUPS/1.3.9"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[12]="TZ=Europe/Paris"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[13]="USER=root"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[16]="IPP_PORT=631"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[17]="CHARSET=utf-8"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[18]="LANG=fr_FR.UTF8"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[20]="RIP_MAX_CACHE=8m"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[21]="CONTENT_TYPE=application/pdf"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[22]="DEVICE_URI=socket://192.168.1.117:9100"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[23]="PRINTER=magicolor-2530-DL"
D [12/May/2009:08:49:26 +0200] [Job 18] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL"
I [12/May/2009:08:49:26 +0200] [Job 18] Started filter /usr/lib/cups/filter/pdftopdf (PID 7412)
I [12/May/2009:08:49:26 +0200] [Job 18] Started filter /usr/lib/cups/filter/foomatic-rip (PID 7413)
I [12/May/2009:08:49:26 +0200] [Job 18] Started backend /usr/lib/cups/backend/socket (PID 7414)
I [12/May/2009:08:49:26 +0200] Saving subscriptions.conf...
D [12/May/2009:08:49:26 +0200] [Job 18] Connected to 192.168.1.117:9100 (IPv4)...
D [12/May/2009:08:49:26 +0200] [Job 18] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb7ff3fc0)
I [12/May/2009:08:49:26 +0200] Saving subscriptions.conf...
D [12/May/2009:08:49:26 +0200] [Job 18] Getting input from file 
D [12/May/2009:08:49:26 +0200] [Job 18] foomatic-rip version 4.0.0.195 running...
D [12/May/2009:08:49:26 +0200] [Job 18] Parsing PPD file ...
D [12/May/2009:08:49:26 +0200] [Job 18] Added option PageSize
D [12/May/2009:08:49:26 +0200] [Job 18] Added option Quality
D [12/May/2009:08:49:26 +0200] [Job 18] Added option PrinterType
D [12/May/2009:08:49:26 +0200] [Job 18] Added option ColorMode
D [12/May/2009:08:49:26 +0200] [Job 18] Added option Resolution
D [12/May/2009:08:49:26 +0200] [Job 18] Added option ImageableArea
D [12/May/2009:08:49:26 +0200] [Job 18] Added option PaperDimension
D [12/May/2009:08:49:26 +0200] [Job 18] Added option InputSlot
D [12/May/2009:08:49:26 +0200] [Job 18] Added option MediaType
D [12/May/2009:08:49:26 +0200] [Job 18] Added option Duplex
D [12/May/2009:08:49:26 +0200] [Job 18] Added option Copies
D [12/May/2009:08:49:26 +0200] [Job 18] Added option ICM
D [12/May/2009:08:49:26 +0200] [Job 18] Added option Nup
D [12/May/2009:08:49:26 +0200] [Job 18] Added option NupOrient
D [12/May/2009:08:49:26 +0200] [Job 18] Added option Font
D [12/May/2009:08:49:26 +0200] [Job 18] 
D [12/May/2009:08:49:26 +0200] [Job 18] Parameter Summary
D [12/May/2009:08:49:26 +0200] [Job 18] -----------------
D [12/May/2009:08:49:26 +0200] [Job 18] 
D [12/May/2009:08:49:26 +0200] [Job 18] Spooler: cups
D [12/May/2009:08:49:26 +0200] [Job 18] Printer: magicolor-2530-DL
D [12/May/2009:08:49:26 +0200] [Job 18] Shell: /bin/bash
D [12/May/2009:08:49:26 +0200] [Job 18] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd
D [12/May/2009:08:49:26 +0200] [Job 18] ATTR file: 
D [12/May/2009:08:49:26 +0200] [Job 18] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended)
D [12/May/2009:08:49:26 +0200] [Job 18] Job title: out.pnm
D [12/May/2009:08:49:26 +0200] [Job 18] File(s) to be printed:
D [12/May/2009:08:49:26 +0200] [Job 18] <STDIN>
D [12/May/2009:08:49:26 +0200] [Job 18] 
D [12/May/2009:08:49:26 +0200] [Job 18] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [12/May/2009:08:49:26 +0200] [Job 18] Printing system options:
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'number-up=1'
D [12/May/2009:08:49:26 +0200] [Job 18] Unknown option number-up=1.
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2'
D [12/May/2009:08:49:26 +0200] [Job 18] Unknown option job-uuid=urn:uuid:c61ce23c-93e5-3d5f-6466-edc3d8f3d2a2.
D [12/May/2009:08:49:26 +0200] [Job 18] Options from the PPD file:
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'Resolution=600x600dpi'
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'Quality=normal'
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'MediaType=plain'
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'NupOrient=port'
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'ColorMode=ICM'
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'PageSize=A4'
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'Nup=1up'
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'ICM=user1'
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'Duplex=None'
D [12/May/2009:08:49:26 +0200] [Job 18] Pondering option 'InputSlot=Auto'
D [12/May/2009:08:49:26 +0200] [Job 18] 
D [12/May/2009:08:49:26 +0200] [Job 18] ================================================
D [12/May/2009:08:49:26 +0200] [Job 18] 
D [12/May/2009:08:49:26 +0200] [Job 18] File: <STDIN>
D [12/May/2009:08:49:26 +0200] [Job 18] 
D [12/May/2009:08:49:26 +0200] [Job 18] ================================================
D [12/May/2009:08:49:26 +0200] [Job 18] 
D [12/May/2009:08:49:26 +0200] [Job 18] Filetype: PDF
D [12/May/2009:08:49:26 +0200] [Job 18] Driver does not understand PDF input, converting to PostScript
D [12/May/2009:08:49:26 +0200] [Job 18] Starting process "pdf-to-ps" (generation 1)
D [12/May/2009:08:49:26 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:49:26 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:49:26 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:49:26 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:08:49:26 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:49:26 +0200] cupsdCloseClient: 11
D [12/May/2009:08:49:26 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:49:26 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:49:26 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:49:26 +0200] Get-Notifications /
D [12/May/2009:08:49:26 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:08:49:26 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:49:26 +0200] cupsdCloseClient: 11
D [12/May/2009:08:49:36 +0200] PID 7412 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [12/May/2009:08:49:36 +0200] [Job 18] Filetype: PostScript
D [12/May/2009:08:49:36 +0200] [Job 18] Reading PostScript input ...
D [12/May/2009:08:49:36 +0200] [Job 18] --> This document is DSC-conforming!
D [12/May/2009:08:49:36 +0200] [Job 18] 
D [12/May/2009:08:49:36 +0200] [Job 18] -----------
D [12/May/2009:08:49:36 +0200] [Job 18] Found: %%BeginProlog
D [12/May/2009:08:49:36 +0200] [Job 18] Inserting option code into "Prolog" section.
D [12/May/2009:08:49:36 +0200] [Job 18] Found: %%EndProlog
D [12/May/2009:08:49:36 +0200] [Job 18] 
D [12/May/2009:08:49:36 +0200] [Job 18] -----------
D [12/May/2009:08:49:36 +0200] [Job 18] New page: %%Page: 1 1
D [12/May/2009:08:49:36 +0200] [Job 18] "Setup" section is missing, inserting it.
D [12/May/2009:08:49:36 +0200] [Job 18] Inserting PostScript code for CUPS' page accounting
D [12/May/2009:08:49:36 +0200] [Job 18] Inserting option code into "Setup" section.
D [12/May/2009:08:49:36 +0200] [Job 18] No page header or page header not DSC-conforming
D [12/May/2009:08:49:36 +0200] [Job 18] "PageSetup" section is missing, inserting it.
D [12/May/2009:08:49:36 +0200] [Job 18] Inserting option code into "PageSetup" section.
D [12/May/2009:08:49:36 +0200] [Job 18] Stopping search for page header options
D [12/May/2009:08:49:36 +0200] [Job 18] Found: 253 254 r3
D [12/May/2009:08:49:36 +0200] [Job 18] --> Output goes directly to the renderer now.
D [12/May/2009:08:49:36 +0200] [Job 18] 
D [12/May/2009:08:49:37 +0200] [Job 18] 
D [12/May/2009:08:49:37 +0200] [Job 18] Starting renderer with command: "foo2lava-wrapper -z0   -c -C10 -r600x600 -p26 -m0 -s255   -d1 -Guser1.icm "
D [12/May/2009:08:49:37 +0200] [Job 18] Starting process "kid3" (generation 1)
D [12/May/2009:08:49:37 +0200] [Job 18] Starting process "kid4" (generation 2)
D [12/May/2009:08:49:37 +0200] [Job 18] JCL: %-12345X@PJL
D [12/May/2009:08:49:37 +0200] [Job 18] <job data> 
D [12/May/2009:08:49:37 +0200] [Job 18] 
D [12/May/2009:08:49:37 +0200] [Job 18] Starting process "renderer" (generation 2)
D [12/May/2009:08:49:37 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:49:37 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:49:37 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:49:37 +0200] [Job 18] pcache: unable to open '~/.ghostscript/cache/gs_cache'
D [12/May/2009:08:50:11 +0200] Report: clients=0
D [12/May/2009:08:50:11 +0200] Report: jobs=18
D [12/May/2009:08:50:11 +0200] Report: jobs-active=1
D [12/May/2009:08:50:11 +0200] Report: printers=2
D [12/May/2009:08:50:11 +0200] Report: printers-implicit=0
D [12/May/2009:08:50:11 +0200] Report: stringpool-string-count=1714
D [12/May/2009:08:50:11 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:50:11 +0200] Report: stringpool-total-bytes=37312
D [12/May/2009:08:51:17 +0200] Report: clients=0
D [12/May/2009:08:51:17 +0200] Report: jobs=18
D [12/May/2009:08:51:17 +0200] Report: jobs-active=1
D [12/May/2009:08:51:17 +0200] Report: printers=2
D [12/May/2009:08:51:17 +0200] Report: printers-implicit=0
D [12/May/2009:08:51:17 +0200] Report: stringpool-string-count=1714
D [12/May/2009:08:51:17 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:51:17 +0200] Report: stringpool-total-bytes=37312
D [12/May/2009:08:52:23 +0200] Report: clients=0
D [12/May/2009:08:52:23 +0200] Report: jobs=18
D [12/May/2009:08:52:23 +0200] Report: jobs-active=1
D [12/May/2009:08:52:23 +0200] Report: printers=2
D [12/May/2009:08:52:23 +0200] Report: printers-implicit=0
D [12/May/2009:08:52:23 +0200] Report: stringpool-string-count=1714
D [12/May/2009:08:52:23 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:52:23 +0200] Report: stringpool-total-bytes=37312
D [12/May/2009:08:53:29 +0200] Report: clients=0
D [12/May/2009:08:53:29 +0200] Report: jobs=18
D [12/May/2009:08:53:29 +0200] Report: jobs-active=1
D [12/May/2009:08:53:29 +0200] Report: printers=2
D [12/May/2009:08:53:29 +0200] Report: printers-implicit=0
D [12/May/2009:08:53:29 +0200] Report: stringpool-string-count=1714
D [12/May/2009:08:53:29 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:53:29 +0200] Report: stringpool-total-bytes=37312
D [12/May/2009:08:54:35 +0200] Report: clients=0
D [12/May/2009:08:54:35 +0200] Report: jobs=18
D [12/May/2009:08:54:35 +0200] Report: jobs-active=1
D [12/May/2009:08:54:35 +0200] Report: printers=2
D [12/May/2009:08:54:35 +0200] Report: printers-implicit=0
D [12/May/2009:08:54:35 +0200] Report: stringpool-string-count=1714
D [12/May/2009:08:54:35 +0200] Report: stringpool-alloc-bytes=9776
D [12/May/2009:08:54:35 +0200] Report: stringpool-total-bytes=37312
D [12/May/2009:08:54:47 +0200] [Job 18] Found: %%PageTrailer
D [12/May/2009:08:54:47 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:54:47 +0200] [Job 18] 
D [12/May/2009:08:54:47 +0200] [Job 18] Found: %%Trailer
D [12/May/2009:08:54:47 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:54:47 +0200] [Job 18] 
D [12/May/2009:08:54:47 +0200] [Job 18] Found: %%Pages: 1
D [12/May/2009:08:54:47 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:54:47 +0200] [Job 18] 
D [12/May/2009:08:54:47 +0200] [Job 18] Found: %%BoundingBox: 18 86 578 778
D [12/May/2009:08:54:47 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:54:47 +0200] [Job 18] 
D [12/May/2009:08:54:47 +0200] [Job 18] Found: %%HiResBoundingBox: 18.000000 86.600000 577.300000 777.800000
D [12/May/2009:08:54:47 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:54:47 +0200] [Job 18] 
D [12/May/2009:08:54:47 +0200] [Job 18] Found: %%EOF
D [12/May/2009:08:54:47 +0200] [Job 18] --> Continue DSC parsing now.
D [12/May/2009:08:54:47 +0200] [Job 18] 
D [12/May/2009:08:54:47 +0200] [Job 18] 
D [12/May/2009:08:54:47 +0200] [Job 18] Closing renderer
D [12/May/2009:08:54:57 +0200] Discarding unused job-progress event...
D [12/May/2009:08:54:57 +0200] [Job 18] Read 8192 bytes of print data...
I [12/May/2009:08:54:57 +0200] Saving subscriptions.conf...
I [12/May/2009:08:54:57 +0200] Saving subscriptions.conf...
I [12/May/2009:08:54:57 +0200] Saving subscriptions.conf...
D [12/May/2009:08:54:57 +0200] [Job 18] Wrote 8192 bytes of print data...
D [12/May/2009:08:54:57 +0200] [Job 18] Read 8192 bytes of print data...
E [12/May/2009:08:54:57 +0200] [Job 18] Unable to write print data: Broken pipe
I [12/May/2009:08:54:57 +0200] Saving subscriptions.conf...
E [12/May/2009:08:54:57 +0200] PID 7414 (/usr/lib/cups/backend/socket) stopped with status 1!
D [12/May/2009:08:54:57 +0200] [Job 18] renderer exited with status 0
D [12/May/2009:08:54:57 +0200] [Job 18] kid4 exited with status 0
D [12/May/2009:08:54:57 +0200] [Job 18] kid3 finished
D [12/May/2009:08:54:57 +0200] [Job 18] kid3 exited with status 0
D [12/May/2009:08:54:57 +0200] [Job 18] pdf-to-ps exited with status 0
D [12/May/2009:08:54:57 +0200] [Job 18] 
D [12/May/2009:08:54:57 +0200] [Job 18] Closing foomatic-rip.
D [12/May/2009:08:54:57 +0200] PID 7413 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.
D [12/May/2009:08:54:57 +0200] [Job 18] File 0 is complete.
I [12/May/2009:08:54:57 +0200] [Job 18] Backend returned status 1 (failed)
I [12/May/2009:08:54:57 +0200] Saving subscriptions.conf...
E [12/May/2009:08:54:57 +0200] [Job 18] Canceling job since it could not be sent after 5 tries.
I [12/May/2009:08:54:57 +0200] Saving subscriptions.conf...
D [12/May/2009:08:54:57 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:54:57 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:54:57 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:54:57 +0200] Get-Jobs ipp://localhost/printers/
D [12/May/2009:08:54:57 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:54:57 +0200] cupsdCloseClient: 11
D [12/May/2009:08:54:57 +0200] cupsdAcceptClient: 11 from localhost (Domain)
D [12/May/2009:08:54:57 +0200] cupsdReadClient: 11 POST / HTTP/1.1
D [12/May/2009:08:54:57 +0200] cupsdAuthorize: No authentication data provided.
D [12/May/2009:08:54:57 +0200] Get-Notifications /
D [12/May/2009:08:54:57 +0200] cupsdIsAuthorized: requesting-user-name="laurent"
D [12/May/2009:08:54:57 +0200] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [12/May/2009:08:54:57 +0200] cupsdCloseClient: 11
D [12/May/2009:08:54:58 +0200] [Job 18] Unloading...