E [10/May/2009:11:02:39 +0200] PID 3879 (/usr/lib/cups/backend/socket) stopped with status 1! E [10/May/2009:11:02:39 +0200] [Job 4] Unable to write print data: Broken pipe I [10/May/2009:11:05:31 +0200] Listening to ::1:631 (IPv6) I [10/May/2009:11:05:31 +0200] Listening to 127.0.0.1:631 (IPv4) I [10/May/2009:11:05:31 +0200] Listening to /var/run/cups/cups.sock (Domain) I [10/May/2009:11:05:31 +0200] Loaded configuration file "/etc/cups/cupsd.conf" I [10/May/2009:11:05:31 +0200] Using default TempDir of /var/spool/cups/tmp... I [10/May/2009:11:05:31 +0200] Configured for up to 100 clients. I [10/May/2009:11:05:31 +0200] Allowing up to 100 client connections per host. I [10/May/2009:11:05:31 +0200] Using policy "default" as the default! I [10/May/2009:11:05:31 +0200] Partial reload complete. I [10/May/2009:11:05:31 +0200] Listening to ::1:631 on fd 2... I [10/May/2009:11:05:31 +0200] Listening to 127.0.0.1:631 on fd 4... I [10/May/2009:11:05:31 +0200] Listening to /var/run/cups/cups.sock on fd 5... I [10/May/2009:11:05:31 +0200] Resuming new connection processing... D [10/May/2009:11:05:31 +0200] Discarding unused server-restarted event... D [10/May/2009:11:05:32 +0200] cupsdAcceptClient: 8 from localhost (Domain) D [10/May/2009:11:05:32 +0200] Report: clients=1 D [10/May/2009:11:05:32 +0200] Report: jobs=4 D [10/May/2009:11:05:32 +0200] Report: jobs-active=0 D [10/May/2009:11:05:32 +0200] Report: printers=2 D [10/May/2009:11:05:32 +0200] Report: printers-implicit=0 D [10/May/2009:11:05:32 +0200] Report: stringpool-string-count=1143 D [10/May/2009:11:05:32 +0200] Report: stringpool-alloc-bytes=8856 D [10/May/2009:11:05:32 +0200] Report: stringpool-total-bytes=24560 D [10/May/2009:11:05:35 +0200] cupsdReadClient: 8 GET /admin/log/error_log HTTP/1.1 D [10/May/2009:11:05:35 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:35 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:05:35 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:35 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:05:35 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:05:35 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:05:35 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:35 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:05:35 +0200] [Job 1] Loading attributes... D [10/May/2009:11:05:35 +0200] [Job 2] Loading attributes... D [10/May/2009:11:05:35 +0200] [Job 3] Loading attributes... D [10/May/2009:11:05:35 +0200] [Job 4] Loading attributes... D [10/May/2009:11:05:35 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:05:35 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:05:35 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:35 +0200] Create-Printer-Subscription / D [10/May/2009:11:05:35 +0200] cupsdCreateSubscription(con=0xb961f1b8(8), uri="/") D [10/May/2009:11:05:35 +0200] pullmethod="ippget" D [10/May/2009:11:05:35 +0200] notify-lease-duration=86400 D [10/May/2009:11:05:35 +0200] notify-time-interval=0 D [10/May/2009:11:05:35 +0200] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)") D [10/May/2009:11:05:35 +0200] Added subscription 4 for server I [10/May/2009:11:05:35 +0200] Saving subscriptions.conf... D [10/May/2009:11:05:35 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:05:36 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:05:36 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:36 +0200] Get-Notifications / D [10/May/2009:11:05:36 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:05:36 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:05:40 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:11:05:40 +0200] cupsdReadClient: 9 POST /printers/magicolor-2530-DL HTTP/1.1 D [10/May/2009:11:05:40 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:40 +0200] Print-Job ipp://localhost/printers/magicolor-2530-DL D [10/May/2009:11:05:40 +0200] [Job ???] Auto-typing file... I [10/May/2009:11:05:40 +0200] [Job ???] Request file type is application/postscript. D [10/May/2009:11:05:40 +0200] add_job: requesting-user-name="laurent" D [10/May/2009:11:05:40 +0200] Adding default job-sheets values "none,none"... I [10/May/2009:11:05:40 +0200] [Job 5] Adding start banner page "none". I [10/May/2009:11:05:40 +0200] Saving subscriptions.conf... I [10/May/2009:11:05:40 +0200] [Job 5] Adding end banner page "none". I [10/May/2009:11:05:40 +0200] [Job 5] File of type application/postscript queued by "laurent". D [10/May/2009:11:05:40 +0200] [Job 5] hold_until=0 I [10/May/2009:11:05:40 +0200] [Job 5] Queued on "magicolor-2530-DL" by "laurent". I [10/May/2009:11:05:40 +0200] Saving subscriptions.conf... D [10/May/2009:11:05:40 +0200] [Job 5] job-sheets=none,none D [10/May/2009:11:05:40 +0200] [Job 5] banner_page = 0 D [10/May/2009:11:05:40 +0200] [Job 5] argv[0]="magicolor-2530-DL" D [10/May/2009:11:05:40 +0200] [Job 5] argv[1]="5" D [10/May/2009:11:05:40 +0200] [Job 5] argv[2]="laurent" D [10/May/2009:11:05:40 +0200] [Job 5] argv[3]="Test Page" D [10/May/2009:11:05:40 +0200] [Job 5] argv[4]="1" D [10/May/2009:11:05:40 +0200] [Job 5] argv[5]="job-uuid=urn:uuid:1c322066-0d12-3622-6980-434901cc6c76" D [10/May/2009:11:05:40 +0200] [Job 5] argv[6]="/var/spool/cups/d00005-001" D [10/May/2009:11:05:40 +0200] [Job 5] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [10/May/2009:11:05:40 +0200] [Job 5] envp[1]="CUPS_DATADIR=/usr/share/cups" D [10/May/2009:11:05:40 +0200] [Job 5] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [10/May/2009:11:05:40 +0200] [Job 5] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [10/May/2009:11:05:40 +0200] [Job 5] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [10/May/2009:11:05:40 +0200] [Job 5] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [10/May/2009:11:05:40 +0200] [Job 5] envp[6]="CUPS_SERVERROOT=/etc/cups" D [10/May/2009:11:05:40 +0200] [Job 5] envp[7]="CUPS_STATEDIR=/var/run/cups" D [10/May/2009:11:05:40 +0200] [Job 5] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [10/May/2009:11:05:40 +0200] [Job 5] envp[9]="SERVER_ADMIN=root@boromir" D [10/May/2009:11:05:40 +0200] [Job 5] envp[10]="SOFTWARE=CUPS/1.3.9" D [10/May/2009:11:05:40 +0200] [Job 5] envp[11]="TMPDIR=/var/spool/cups/tmp" D [10/May/2009:11:05:40 +0200] [Job 5] envp[12]="TZ=Europe/Paris" D [10/May/2009:11:05:40 +0200] [Job 5] envp[13]="USER=root" D [10/May/2009:11:05:40 +0200] [Job 5] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [10/May/2009:11:05:40 +0200] [Job 5] envp[15]="CUPS_ENCRYPTION=IfRequested" D [10/May/2009:11:05:40 +0200] [Job 5] envp[16]="IPP_PORT=631" D [10/May/2009:11:05:40 +0200] [Job 5] envp[17]="CHARSET=utf-8" D [10/May/2009:11:05:40 +0200] [Job 5] envp[18]="LANG=fr_FR.UTF8" D [10/May/2009:11:05:40 +0200] [Job 5] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd" D [10/May/2009:11:05:40 +0200] [Job 5] envp[20]="RIP_MAX_CACHE=8m" D [10/May/2009:11:05:40 +0200] [Job 5] envp[21]="CONTENT_TYPE=application/postscript" D [10/May/2009:11:05:40 +0200] [Job 5] envp[22]="DEVICE_URI=socket://192.168.1.117:9100" D [10/May/2009:11:05:40 +0200] [Job 5] envp[23]="PRINTER=magicolor-2530-DL" D [10/May/2009:11:05:40 +0200] [Job 5] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL" I [10/May/2009:11:05:40 +0200] [Job 5] Started filter /usr/lib/cups/filter/pstopdf (PID 4464) I [10/May/2009:11:05:40 +0200] [Job 5] Started filter /usr/lib/cups/filter/pdftopdf (PID 4465) I [10/May/2009:11:05:40 +0200] [Job 5] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4466) I [10/May/2009:11:05:40 +0200] [Job 5] Started backend /usr/lib/cups/backend/socket (PID 4468) I [10/May/2009:11:05:40 +0200] Saving subscriptions.conf... D [10/May/2009:11:05:40 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:11:05:40 +0200] [Job 5] pstopdf 6 args: 5 laurent Test Page 1 job-uuid=urn:uuid:1c322066-0d12-3622-6980-434901cc6c76 /var/spool/cups/d00005-001 D [10/May/2009:11:05:40 +0200] [Job 5] PPD: /etc/cups/ppd/magicolor-2530-DL.ppd D [10/May/2009:11:05:40 +0200] [Job 5] Connected to 192.168.1.117:9100 (IPv4)... D [10/May/2009:11:05:40 +0200] [Job 5] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb8098fc0) I [10/May/2009:11:05:40 +0200] Saving subscriptions.conf... D [10/May/2009:11:05:40 +0200] [Job 5] Getting input from file D [10/May/2009:11:05:40 +0200] [Job 5] foomatic-rip version 4.0.0.195 running... D [10/May/2009:11:05:40 +0200] [Job 5] Parsing PPD file ... D [10/May/2009:11:05:40 +0200] [Job 5] Added option PageSize D [10/May/2009:11:05:40 +0200] [Job 5] Added option Quality D [10/May/2009:11:05:40 +0200] [Job 5] Added option PrinterType D [10/May/2009:11:05:40 +0200] [Job 5] Added option ColorMode D [10/May/2009:11:05:40 +0200] [Job 5] Added option Resolution D [10/May/2009:11:05:40 +0200] [Job 5] Added option ImageableArea D [10/May/2009:11:05:40 +0200] [Job 5] Added option PaperDimension D [10/May/2009:11:05:40 +0200] [Job 5] Added option InputSlot D [10/May/2009:11:05:40 +0200] [Job 5] Added option MediaType D [10/May/2009:11:05:40 +0200] [Job 5] Added option Duplex D [10/May/2009:11:05:40 +0200] [Job 5] Added option Copies D [10/May/2009:11:05:40 +0200] [Job 5] Added option ICM D [10/May/2009:11:05:40 +0200] [Job 5] Added option Nup D [10/May/2009:11:05:40 +0200] [Job 5] Added option NupOrient D [10/May/2009:11:05:40 +0200] [Job 5] Added option Font D [10/May/2009:11:05:40 +0200] [Job 5] D [10/May/2009:11:05:40 +0200] [Job 5] Parameter Summary D [10/May/2009:11:05:40 +0200] [Job 5] ----------------- D [10/May/2009:11:05:40 +0200] [Job 5] D [10/May/2009:11:05:40 +0200] [Job 5] Spooler: cups D [10/May/2009:11:05:40 +0200] [Job 5] Printer: magicolor-2530-DL D [10/May/2009:11:05:40 +0200] [Job 5] Shell: /bin/bash D [10/May/2009:11:05:40 +0200] [Job 5] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd D [10/May/2009:11:05:40 +0200] [Job 5] ATTR file: D [10/May/2009:11:05:40 +0200] [Job 5] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended) D [10/May/2009:11:05:40 +0200] [Job 5] Job title: Test Page D [10/May/2009:11:05:40 +0200] [Job 5] File(s) to be printed: D [10/May/2009:11:05:40 +0200] [Job 5] D [10/May/2009:11:05:40 +0200] [Job 5] D [10/May/2009:11:05:40 +0200] [Job 5] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [10/May/2009:11:05:40 +0200] [Job 5] Printing system options: D [10/May/2009:11:05:40 +0200] [Job 5] Pondering option 'job-uuid=urn:uuid:1c322066-0d12-3622-6980-434901cc6c76' D [10/May/2009:11:05:40 +0200] [Job 5] Unknown option job-uuid=urn:uuid:1c322066-0d12-3622-6980-434901cc6c76. D [10/May/2009:11:05:40 +0200] [Job 5] Options from the PPD file: D [10/May/2009:11:05:40 +0200] [Job 5] D [10/May/2009:11:05:40 +0200] [Job 5] ================================================ D [10/May/2009:11:05:40 +0200] [Job 5] D [10/May/2009:11:05:40 +0200] [Job 5] File: D [10/May/2009:11:05:40 +0200] [Job 5] D [10/May/2009:11:05:40 +0200] [Job 5] ================================================ D [10/May/2009:11:05:40 +0200] [Job 5] D [10/May/2009:11:05:40 +0200] [Job 5] Resolution: 600x600 D [10/May/2009:11:05:40 +0200] [Job 5] Page size: A4 D [10/May/2009:11:05:40 +0200] [Job 5] Width: 595, height: 842, absolute margins: 11.34, 11.34, 583.66, 830.66 D [10/May/2009:11:05:40 +0200] [Job 5] Relative margins: 11.34, 11.34, 11.34, 11.34 D [10/May/2009:11:05:40 +0200] [Job 5] PPD options: -r600x600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 D [10/May/2009:11:05:40 +0200] [Job 5] PostScript to be injected: D [10/May/2009:11:05:40 +0200] [Job 5] Running cat | /usr/bin/ps2pdf13 -dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode -dDoNumCopies -dPDFSETTINGS=/printer -r600x600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 - - D [10/May/2009:11:05:40 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:11:05:40 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:11:05:40 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:40 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:05:40 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:11:05:40 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:11:05:40 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:11:05:40 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:40 +0200] Get-Notifications / D [10/May/2009:11:05:40 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:05:40 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:11:05:40 +0200] cupsdCloseClient: 13 D [10/May/2009:11:05:40 +0200] cupsdCloseClient: 12 D [10/May/2009:11:05:40 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:11:05:40 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:11:05:40 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:40 +0200] Get-Notifications / D [10/May/2009:11:05:40 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:05:40 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:11:05:40 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:11:05:40 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:40 +0200] Get-Job-Attributes ipp://localhost/jobs/5 D [10/May/2009:11:05:40 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:11:05:40 +0200] cupsdCloseClient: 12 D [10/May/2009:11:05:40 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:05:40 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:40 +0200] Get-Notifications / D [10/May/2009:11:05:40 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:05:40 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:05:41 +0200] [Job 5] GPL Ghostscript 8.64: Set UseCIEColor for UseDeviceIndependentColor to work properly. D [10/May/2009:11:05:41 +0200] PID 4464 (/usr/lib/cups/filter/pstopdf) exited with no errors. D [10/May/2009:11:05:41 +0200] [Job 5] Filetype: PDF D [10/May/2009:11:05:41 +0200] [Job 5] Driver does not understand PDF input, converting to PostScript D [10/May/2009:11:05:41 +0200] [Job 5] Starting process "pdf-to-ps" (generation 1) D [10/May/2009:11:05:42 +0200] PID 4465 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [10/May/2009:11:05:42 +0200] [Job 5] Filetype: PostScript D [10/May/2009:11:05:42 +0200] [Job 5] Reading PostScript input ... D [10/May/2009:11:05:42 +0200] [Job 5] --> This document is DSC-conforming! D [10/May/2009:11:05:42 +0200] [Job 5] D [10/May/2009:11:05:42 +0200] [Job 5] ----------- D [10/May/2009:11:05:42 +0200] [Job 5] Found: %%BeginProlog D [10/May/2009:11:05:42 +0200] [Job 5] Inserting option code into "Prolog" section. D [10/May/2009:11:05:42 +0200] [Job 5] Found: %%EndProlog D [10/May/2009:11:05:42 +0200] [Job 5] D [10/May/2009:11:05:42 +0200] [Job 5] ----------- D [10/May/2009:11:05:42 +0200] [Job 5] New page: %%Page: 1 1 D [10/May/2009:11:05:42 +0200] [Job 5] "Setup" section is missing, inserting it. D [10/May/2009:11:05:42 +0200] [Job 5] Inserting PostScript code for CUPS' page accounting D [10/May/2009:11:05:42 +0200] [Job 5] Inserting option code into "Setup" section. D [10/May/2009:11:05:42 +0200] [Job 5] No page header or page header not DSC-conforming D [10/May/2009:11:05:42 +0200] [Job 5] "PageSetup" section is missing, inserting it. D [10/May/2009:11:05:42 +0200] [Job 5] Inserting option code into "PageSetup" section. D [10/May/2009:11:05:42 +0200] [Job 5] Stopping search for page header options D [10/May/2009:11:05:42 +0200] [Job 5] Found: 0 6750 60 0 S D [10/May/2009:11:05:42 +0200] [Job 5] --> Output goes directly to the renderer now. D [10/May/2009:11:05:42 +0200] [Job 5] D [10/May/2009:11:05:42 +0200] [Job 5] D [10/May/2009:11:05:42 +0200] [Job 5] Starting renderer with command: "foo2lava-wrapper -z0 -c -C2 -r600x600 -p26 -m0 -s255 -d1 -Gnone.icm " D [10/May/2009:11:05:42 +0200] [Job 5] Starting process "kid3" (generation 1) D [10/May/2009:11:05:42 +0200] [Job 5] Starting process "kid4" (generation 2) D [10/May/2009:11:05:42 +0200] [Job 5] JCL: %-12345X@PJL D [10/May/2009:11:05:42 +0200] [Job 5] D [10/May/2009:11:05:42 +0200] [Job 5] D [10/May/2009:11:05:42 +0200] [Job 5] Starting process "renderer" (generation 2) D [10/May/2009:11:05:43 +0200] [Job 5] Found: %%PageTrailer D [10/May/2009:11:05:43 +0200] [Job 5] --> Continue DSC parsing now. D [10/May/2009:11:05:43 +0200] [Job 5] D [10/May/2009:11:05:43 +0200] [Job 5] Found: %%Trailer D [10/May/2009:11:05:43 +0200] [Job 5] --> Continue DSC parsing now. D [10/May/2009:11:05:43 +0200] [Job 5] D [10/May/2009:11:05:43 +0200] [Job 5] Found: %%Pages: 1 D [10/May/2009:11:05:43 +0200] [Job 5] --> Continue DSC parsing now. D [10/May/2009:11:05:43 +0200] [Job 5] D [10/May/2009:11:05:43 +0200] [Job 5] Found: %%BoundingBox: 0 0 595 842 D [10/May/2009:11:05:43 +0200] [Job 5] --> Continue DSC parsing now. D [10/May/2009:11:05:43 +0200] [Job 5] D [10/May/2009:11:05:43 +0200] [Job 5] Found: %%HiResBoundingBox: 0.000000 0.000000 595.000000 842.000000 D [10/May/2009:11:05:43 +0200] [Job 5] --> Continue DSC parsing now. D [10/May/2009:11:05:43 +0200] [Job 5] D [10/May/2009:11:05:43 +0200] [Job 5] Found: %%EOF D [10/May/2009:11:05:43 +0200] [Job 5] --> Continue DSC parsing now. D [10/May/2009:11:05:43 +0200] [Job 5] D [10/May/2009:11:05:43 +0200] [Job 5] D [10/May/2009:11:05:43 +0200] [Job 5] Closing renderer D [10/May/2009:11:05:43 +0200] [Job 5] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:11:05:43 +0200] [Job 5] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:11:05:43 +0200] [Job 5] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:11:05:44 +0200] [Job 5] pcache: unable to open '~/.ghostscript/cache/gs_cache' I [10/May/2009:11:05:48 +0200] Saving subscriptions.conf... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... I [10/May/2009:11:05:48 +0200] Saving subscriptions.conf... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] renderer exited with status 0 D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] kid4 exited with status 0 D [10/May/2009:11:05:48 +0200] [Job 5] kid3 finished D [10/May/2009:11:05:48 +0200] [Job 5] kid3 exited with status 0 D [10/May/2009:11:05:48 +0200] [Job 5] pdf-to-ps exited with status 0 D [10/May/2009:11:05:48 +0200] [Job 5] D [10/May/2009:11:05:48 +0200] [Job 5] Closing foomatic-rip. D [10/May/2009:11:05:48 +0200] PID 4466 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 8192 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Read 2520 bytes of print data... D [10/May/2009:11:05:48 +0200] [Job 5] Wrote 2520 bytes of print data... D [10/May/2009:11:05:48 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:11:05:48 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:11:05:48 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:48 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:05:48 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:11:05:48 +0200] cupsdCloseClient: 13 D [10/May/2009:11:05:48 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:11:05:48 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:11:05:48 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:48 +0200] Get-Notifications / D [10/May/2009:11:05:48 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:05:48 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:11:05:48 +0200] cupsdCloseClient: 13 D [10/May/2009:11:05:48 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:11:05:48 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:11:05:48 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:48 +0200] Get-Notifications / D [10/May/2009:11:05:48 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:05:48 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:11:05:48 +0200] cupsdCloseClient: 13 D [10/May/2009:11:05:48 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:05:48 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:48 +0200] Get-Notifications / D [10/May/2009:11:05:48 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:05:48 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) I [10/May/2009:11:05:53 +0200] Saving subscriptions.conf... I [10/May/2009:11:05:53 +0200] Saving subscriptions.conf... D [10/May/2009:11:05:53 +0200] PID 4468 (/usr/lib/cups/backend/socket) exited with no errors. D [10/May/2009:11:05:53 +0200] [Job 5] File 0 is complete. I [10/May/2009:11:05:53 +0200] [Job 5] Completed successfully. I [10/May/2009:11:05:53 +0200] Saving subscriptions.conf... I [10/May/2009:11:05:53 +0200] Saving subscriptions.conf... D [10/May/2009:11:05:53 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:11:05:53 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:11:05:53 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:11:05:53 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:53 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:05:53 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:11:05:53 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:11:05:53 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:53 +0200] Get-Notifications / D [10/May/2009:11:05:53 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:05:53 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:11:05:53 +0200] cupsdCloseClient: 14 D [10/May/2009:11:05:53 +0200] cupsdCloseClient: 13 D [10/May/2009:11:05:53 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:11:05:53 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:11:05:53 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:53 +0200] Get-Notifications / D [10/May/2009:11:05:53 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:05:53 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:11:05:53 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:05:53 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:05:53 +0200] Get-Notifications / D [10/May/2009:11:05:53 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:05:53 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:05:54 +0200] cupsdCloseClient: 13 D [10/May/2009:11:05:54 +0200] [Job 5] Unloading... D [10/May/2009:11:06:19 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:06:19 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:06:19 +0200] Get-Job-Attributes ipp://localhost/jobs/5 D [10/May/2009:11:06:19 +0200] [Job 5] Loading attributes... D [10/May/2009:11:06:19 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:06:19 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:06:19 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:06:19 +0200] Cancel-Subscription / D [10/May/2009:11:06:19 +0200] cupsdIsAuthorized: requesting-user-name="laurent" I [10/May/2009:11:06:19 +0200] Saving subscriptions.conf... D [10/May/2009:11:06:19 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:06:19 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:11:06:19 +0200] cupsdReadClient: 13 GET /admin/log/error_log HTTP/1.1 D [10/May/2009:11:06:19 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:06:19 +0200] cupsdReadClient: 13 PUT /admin/conf/cupsd.conf HTTP/1.1 D [10/May/2009:11:06:19 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:06:19 +0200] cupsdIsAuthorized: username="" D [10/May/2009:11:06:19 +0200] cupsdSendError: 13 code=401 (Unauthorized) D [10/May/2009:11:06:19 +0200] cupsdSendHeader: WWW-Authenticate: Basic realm="CUPS" D [10/May/2009:11:06:19 +0200] cupsdCloseClient: 13 D [10/May/2009:11:06:19 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:11:06:19 +0200] cupsdReadClient: 13 PUT /admin/conf/cupsd.conf HTTP/1.1 D [10/May/2009:11:06:19 +0200] cupsdAuthorize: Authorized as root using Local D [10/May/2009:11:06:19 +0200] cupsdIsAuthorized: username="root" I [10/May/2009:11:06:19 +0200] Installing config file "/etc/cups/cupsd.conf"... D [10/May/2009:11:06:19 +0200] cupsdSendError: 13 code=201 (Created) D [10/May/2009:11:06:19 +0200] cupsdCloseClient: 8 D [10/May/2009:11:06:19 +0200] cupsdCloseClient: 9 D [10/May/2009:11:06:19 +0200] cupsdCloseClient: 13 D [10/May/2009:11:06:19 +0200] Saving remote.cache... I [10/May/2009:11:06:19 +0200] Listening to ::1:631 (IPv6) I [10/May/2009:11:06:19 +0200] Listening to 127.0.0.1:631 (IPv4) I [10/May/2009:11:06:19 +0200] Listening to /var/run/cups/cups.sock (Domain) I [10/May/2009:11:06:19 +0200] Loaded configuration file "/etc/cups/cupsd.conf" I [10/May/2009:11:06:19 +0200] Using default TempDir of /var/spool/cups/tmp... I [10/May/2009:11:06:19 +0200] Configured for up to 100 clients. I [10/May/2009:11:06:19 +0200] Allowing up to 100 client connections per host. I [10/May/2009:11:06:19 +0200] Using policy "default" as the default! I [10/May/2009:11:06:19 +0200] Partial reload complete. I [10/May/2009:11:06:19 +0200] Listening to ::1:631 on fd 2... I [10/May/2009:11:06:19 +0200] Listening to 127.0.0.1:631 on fd 4... I [10/May/2009:11:06:19 +0200] Listening to /var/run/cups/cups.sock on fd 5... I [10/May/2009:11:06:19 +0200] Resuming new connection processing... I [10/May/2009:11:06:39 +0200] Installing config file "/etc/cups/cupsd.conf"... I [10/May/2009:11:06:39 +0200] Listening to ::1:631 (IPv6) I [10/May/2009:11:06:39 +0200] Listening to 127.0.0.1:631 (IPv4) I [10/May/2009:11:06:39 +0200] Listening to /var/run/cups/cups.sock (Domain) I [10/May/2009:11:06:39 +0200] Loaded configuration file "/etc/cups/cupsd.conf" I [10/May/2009:11:06:39 +0200] Using default TempDir of /var/spool/cups/tmp... I [10/May/2009:11:06:39 +0200] Configured for up to 100 clients. I [10/May/2009:11:06:39 +0200] Allowing up to 100 client connections per host. I [10/May/2009:11:06:39 +0200] Using policy "default" as the default! I [10/May/2009:11:06:39 +0200] Partial reload complete. I [10/May/2009:11:06:39 +0200] Listening to ::1:631 on fd 2... I [10/May/2009:11:06:39 +0200] Listening to 127.0.0.1:631 on fd 4... I [10/May/2009:11:06:39 +0200] Listening to /var/run/cups/cups.sock on fd 5... I [10/May/2009:11:06:39 +0200] Resuming new connection processing... I [10/May/2009:11:07:15 +0200] [Job ???] Request file type is application/pdf. I [10/May/2009:11:07:15 +0200] [Job 6] Adding start banner page "none". I [10/May/2009:11:07:15 +0200] Saving subscriptions.conf... I [10/May/2009:11:07:15 +0200] [Job 6] Adding end banner page "none". I [10/May/2009:11:07:15 +0200] [Job 6] File of type application/pdf queued by "laurent". I [10/May/2009:11:07:15 +0200] [Job 6] Queued on "magicolor-2530-DL" by "laurent". I [10/May/2009:11:07:15 +0200] Saving subscriptions.conf... I [10/May/2009:11:07:16 +0200] [Job 6] Started filter /usr/lib/cups/filter/pdftopdf (PID 4527) I [10/May/2009:11:07:16 +0200] [Job 6] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4528) I [10/May/2009:11:07:16 +0200] [Job 6] Started backend /usr/lib/cups/backend/socket (PID 4529) I [10/May/2009:11:07:16 +0200] Saving subscriptions.conf... I [10/May/2009:11:07:16 +0200] Saving subscriptions.conf... I [10/May/2009:11:07:16 +0200] Saving subscriptions.conf... I [10/May/2009:11:18:50 +0200] Saving subscriptions.conf... I [10/May/2009:11:18:50 +0200] Saving subscriptions.conf... I [10/May/2009:11:18:50 +0200] Saving subscriptions.conf... E [10/May/2009:11:18:50 +0200] [Job 6] Unable to write print data: Broken pipe I [10/May/2009:11:18:50 +0200] Saving subscriptions.conf... E [10/May/2009:11:18:50 +0200] PID 4529 (/usr/lib/cups/backend/socket) stopped with status 1! I [10/May/2009:11:18:50 +0200] Hint: Try setting the LogLevel to "debug" to find out more. I [10/May/2009:11:18:50 +0200] [Job 6] Backend returned status 1 (failed) I [10/May/2009:11:18:50 +0200] Saving subscriptions.conf... I [10/May/2009:11:18:50 +0200] Saving subscriptions.conf... I [10/May/2009:11:23:45 +0200] Installing config file "/etc/cups/cupsd.conf"... I [10/May/2009:11:23:45 +0200] Listening to ::1:631 (IPv6) I [10/May/2009:11:23:45 +0200] Listening to 127.0.0.1:631 (IPv4) I [10/May/2009:11:23:45 +0200] Listening to /var/run/cups/cups.sock (Domain) I [10/May/2009:11:23:45 +0200] Loaded configuration file "/etc/cups/cupsd.conf" I [10/May/2009:11:23:45 +0200] Using default TempDir of /var/spool/cups/tmp... I [10/May/2009:11:23:45 +0200] Configured for up to 100 clients. I [10/May/2009:11:23:45 +0200] Allowing up to 100 client connections per host. I [10/May/2009:11:23:45 +0200] Using policy "default" as the default! I [10/May/2009:11:23:45 +0200] Partial reload complete. I [10/May/2009:11:23:45 +0200] Listening to ::1:631 on fd 2... I [10/May/2009:11:23:45 +0200] Listening to 127.0.0.1:631 on fd 4... I [10/May/2009:11:23:45 +0200] Listening to /var/run/cups/cups.sock on fd 5... I [10/May/2009:11:23:45 +0200] Resuming new connection processing... I [10/May/2009:11:23:59 +0200] Saving subscriptions.conf... I [10/May/2009:11:23:59 +0200] [Job 6] Started filter /usr/lib/cups/filter/pdftopdf (PID 4961) I [10/May/2009:11:23:59 +0200] [Job 6] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4962) I [10/May/2009:11:23:59 +0200] [Job 6] Started backend /usr/lib/cups/backend/socket (PID 4963) I [10/May/2009:11:23:59 +0200] Saving subscriptions.conf... I [10/May/2009:11:23:59 +0200] Saving subscriptions.conf... I [10/May/2009:11:24:01 +0200] Saving subscriptions.conf... I [10/May/2009:11:24:01 +0200] Saving subscriptions.conf... I [10/May/2009:11:24:01 +0200] [Job 6] Canceled by "laurent". I [10/May/2009:11:24:35 +0200] [Job ???] Request file type is application/pdf. I [10/May/2009:11:24:35 +0200] [Job 7] Adding start banner page "none". I [10/May/2009:11:24:35 +0200] Saving subscriptions.conf... I [10/May/2009:11:24:35 +0200] [Job 7] Adding end banner page "none". I [10/May/2009:11:24:35 +0200] [Job 7] File of type application/pdf queued by "laurent". I [10/May/2009:11:24:35 +0200] [Job 7] Queued on "magicolor-2530-DL" by "laurent". I [10/May/2009:11:24:35 +0200] Saving subscriptions.conf... I [10/May/2009:11:24:35 +0200] [Job 7] Started filter /usr/lib/cups/filter/pdftopdf (PID 4965) I [10/May/2009:11:24:35 +0200] [Job 7] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4966) I [10/May/2009:11:24:35 +0200] [Job 7] Started backend /usr/lib/cups/backend/socket (PID 4967) I [10/May/2009:11:24:35 +0200] Saving subscriptions.conf... I [10/May/2009:11:24:35 +0200] Saving subscriptions.conf... I [10/May/2009:11:24:35 +0200] Saving subscriptions.conf... I [10/May/2009:11:34:38 +0200] Saving subscriptions.conf... E [10/May/2009:11:34:44 +0200] [Job 7] Unable to write print data: Broken pipe I [10/May/2009:11:34:44 +0200] Saving subscriptions.conf... E [10/May/2009:11:34:44 +0200] PID 4967 (/usr/lib/cups/backend/socket) stopped with status 1! I [10/May/2009:11:34:44 +0200] Hint: Try setting the LogLevel to "debug" to find out more. I [10/May/2009:11:34:44 +0200] [Job 7] Backend returned status 1 (failed) I [10/May/2009:11:34:44 +0200] Saving subscriptions.conf... I [10/May/2009:11:34:44 +0200] Saving subscriptions.conf... I [10/May/2009:11:39:45 +0200] Saving subscriptions.conf... I [10/May/2009:11:39:45 +0200] [Job 7] Started filter /usr/lib/cups/filter/pdftopdf (PID 5612) I [10/May/2009:11:39:45 +0200] [Job 7] Started filter /usr/lib/cups/filter/foomatic-rip (PID 5613) I [10/May/2009:11:39:45 +0200] [Job 7] Started backend /usr/lib/cups/backend/socket (PID 5614) I [10/May/2009:11:39:45 +0200] Saving subscriptions.conf... I [10/May/2009:11:39:45 +0200] Saving subscriptions.conf... E [10/May/2009:11:50:41 +0200] [Job 7] Unable to write print data: Broken pipe I [10/May/2009:11:50:41 +0200] Saving subscriptions.conf... E [10/May/2009:11:50:41 +0200] PID 5614 (/usr/lib/cups/backend/socket) stopped with status 1! I [10/May/2009:11:50:41 +0200] Hint: Try setting the LogLevel to "debug" to find out more. I [10/May/2009:11:50:41 +0200] [Job 7] Backend returned status 1 (failed) I [10/May/2009:11:50:41 +0200] Saving subscriptions.conf... I [10/May/2009:11:50:41 +0200] Saving subscriptions.conf... I [10/May/2009:11:52:49 +0200] Saving subscriptions.conf... I [10/May/2009:11:52:57 +0200] Started "/usr/lib/cups/daemon/cups-deviced" (pid=6376) I [10/May/2009:11:53:14 +0200] Installing config file "/etc/cups/cupsd.conf"... I [10/May/2009:11:53:14 +0200] Listening to ::1:631 (IPv6) I [10/May/2009:11:53:14 +0200] Listening to 127.0.0.1:631 (IPv4) I [10/May/2009:11:53:14 +0200] Listening to /var/run/cups/cups.sock (Domain) I [10/May/2009:11:53:14 +0200] Loaded configuration file "/etc/cups/cupsd.conf" I [10/May/2009:11:53:14 +0200] Using default TempDir of /var/spool/cups/tmp... I [10/May/2009:11:53:14 +0200] Configured for up to 100 clients. I [10/May/2009:11:53:14 +0200] Allowing up to 100 client connections per host. I [10/May/2009:11:53:14 +0200] Using policy "default" as the default! I [10/May/2009:11:53:14 +0200] Partial reload complete. I [10/May/2009:11:53:14 +0200] Listening to ::1:631 on fd 2... I [10/May/2009:11:53:14 +0200] Listening to 127.0.0.1:631 on fd 4... I [10/May/2009:11:53:14 +0200] Listening to /var/run/cups/cups.sock on fd 5... I [10/May/2009:11:53:14 +0200] Resuming new connection processing... D [10/May/2009:11:53:14 +0200] Discarding unused server-restarted event... D [10/May/2009:11:53:15 +0200] cupsdAcceptClient: 8 from localhost (Domain) D [10/May/2009:11:53:15 +0200] Report: clients=1 D [10/May/2009:11:53:15 +0200] Report: jobs=7 D [10/May/2009:11:53:15 +0200] Report: jobs-active=1 D [10/May/2009:11:53:15 +0200] Report: printers=2 D [10/May/2009:11:53:15 +0200] Report: printers-implicit=0 D [10/May/2009:11:53:15 +0200] Report: stringpool-string-count=2015 D [10/May/2009:11:53:15 +0200] Report: stringpool-alloc-bytes=9992 D [10/May/2009:11:53:15 +0200] Report: stringpool-total-bytes=43400 D [10/May/2009:11:53:17 +0200] cupsdReadClient: 8 GET /admin/log/error_log HTTP/1.1 D [10/May/2009:11:53:17 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:17 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:53:17 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:17 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:53:17 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:53:17 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:53:17 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:17 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:53:17 +0200] [Job 1] Loading attributes... D [10/May/2009:11:53:17 +0200] [Job 2] Loading attributes... D [10/May/2009:11:53:17 +0200] [Job 3] Loading attributes... D [10/May/2009:11:53:17 +0200] [Job 4] Loading attributes... D [10/May/2009:11:53:17 +0200] [Job 5] Loading attributes... D [10/May/2009:11:53:17 +0200] [Job 6] Loading attributes... D [10/May/2009:11:53:17 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:53:17 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:53:17 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:17 +0200] Create-Printer-Subscription / D [10/May/2009:11:53:17 +0200] cupsdCreateSubscription(con=0xb963d288(8), uri="/") D [10/May/2009:11:53:17 +0200] pullmethod="ippget" D [10/May/2009:11:53:17 +0200] notify-lease-duration=86400 D [10/May/2009:11:53:17 +0200] notify-time-interval=0 D [10/May/2009:11:53:17 +0200] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)") D [10/May/2009:11:53:17 +0200] Added subscription 6 for server I [10/May/2009:11:53:17 +0200] Saving subscriptions.conf... D [10/May/2009:11:53:17 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:53:18 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:53:18 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:18 +0200] Get-Notifications / D [10/May/2009:11:53:18 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:18 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:53:24 +0200] cupsdReadClient: 8 POST /jobs/ HTTP/1.1 D [10/May/2009:11:53:24 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:24 +0200] Cancel-Job ipp://localhost/jobs/7 D [10/May/2009:11:53:24 +0200] cupsdIsAuthorized: requesting-user-name="laurent" I [10/May/2009:11:53:24 +0200] Saving subscriptions.conf... I [10/May/2009:11:53:24 +0200] [Job 7] Canceled by "laurent". D [10/May/2009:11:53:24 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:53:24 +0200] [Job 7] Unloading... D [10/May/2009:11:53:31 +0200] cupsdReadClient: 8 POST /jobs/ HTTP/1.1 D [10/May/2009:11:53:31 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:31 +0200] Cancel-Job ipp://localhost/jobs/7 D [10/May/2009:11:53:31 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:31 +0200] Cancel-Job client-error-not-possible: Job #7 is already canceled - can't cancel. D [10/May/2009:11:53:31 +0200] cupsdProcessIPPRequest: 8 status_code=404 (client-error-not-possible) D [10/May/2009:11:53:33 +0200] cupsdReadClient: 8 POST /jobs/ HTTP/1.1 D [10/May/2009:11:53:33 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:33 +0200] Cancel-Job ipp://localhost/jobs/7 D [10/May/2009:11:53:33 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:33 +0200] Cancel-Job client-error-not-possible: Job #7 is already canceled - can't cancel. D [10/May/2009:11:53:33 +0200] cupsdProcessIPPRequest: 8 status_code=404 (client-error-not-possible) D [10/May/2009:11:53:45 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:11:53:45 +0200] cupsdReadClient: 9 POST /jobs/ HTTP/1.1 D [10/May/2009:11:53:45 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:45 +0200] Cancel-Job ipp://localhost/jobs/7 D [10/May/2009:11:53:45 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:45 +0200] Cancel-Job client-error-not-possible: Job #7 is already canceled - can't cancel. D [10/May/2009:11:53:45 +0200] cupsdProcessIPPRequest: 9 status_code=404 (client-error-not-possible) D [10/May/2009:11:53:45 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:11:53:45 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:11:53:45 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:45 +0200] Get-Notifications / D [10/May/2009:11:53:45 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:45 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:11:53:45 +0200] cupsdCloseClient: 12 D [10/May/2009:11:53:50 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:11:53:50 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:11:53:50 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:50 +0200] Get-Notifications / D [10/May/2009:11:53:50 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:50 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:11:53:50 +0200] cupsdCloseClient: 12 D [10/May/2009:11:53:50 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:11:53:50 +0200] cupsdReadClient: 12 POST /printers/magicolor-2530-DL HTTP/1.1 D [10/May/2009:11:53:50 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:50 +0200] Print-Job ipp://localhost/printers/magicolor-2530-DL D [10/May/2009:11:53:50 +0200] [Job ???] Auto-typing file... I [10/May/2009:11:53:50 +0200] [Job ???] Request file type is application/postscript. D [10/May/2009:11:53:50 +0200] add_job: requesting-user-name="laurent" D [10/May/2009:11:53:50 +0200] Adding default job-sheets values "none,none"... I [10/May/2009:11:53:50 +0200] [Job 8] Adding start banner page "none". I [10/May/2009:11:53:50 +0200] Saving subscriptions.conf... I [10/May/2009:11:53:50 +0200] [Job 8] Adding end banner page "none". I [10/May/2009:11:53:50 +0200] [Job 8] File of type application/postscript queued by "laurent". D [10/May/2009:11:53:50 +0200] [Job 8] hold_until=0 I [10/May/2009:11:53:50 +0200] [Job 8] Queued on "magicolor-2530-DL" by "laurent". I [10/May/2009:11:53:50 +0200] Saving subscriptions.conf... D [10/May/2009:11:53:50 +0200] [Job 8] job-sheets=none,none D [10/May/2009:11:53:50 +0200] [Job 8] banner_page = 0 D [10/May/2009:11:53:50 +0200] [Job 8] argv[0]="magicolor-2530-DL" D [10/May/2009:11:53:50 +0200] [Job 8] argv[1]="8" D [10/May/2009:11:53:50 +0200] [Job 8] argv[2]="laurent" D [10/May/2009:11:53:50 +0200] [Job 8] argv[3]="Test Page" D [10/May/2009:11:53:50 +0200] [Job 8] argv[4]="1" D [10/May/2009:11:53:50 +0200] [Job 8] argv[5]="job-uuid=urn:uuid:1b582b7b-630e-35ba-526e-bcf25b313aad" D [10/May/2009:11:53:50 +0200] [Job 8] argv[6]="/var/spool/cups/d00008-001" D [10/May/2009:11:53:50 +0200] [Job 8] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [10/May/2009:11:53:50 +0200] [Job 8] envp[1]="CUPS_DATADIR=/usr/share/cups" D [10/May/2009:11:53:50 +0200] [Job 8] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [10/May/2009:11:53:50 +0200] [Job 8] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [10/May/2009:11:53:50 +0200] [Job 8] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [10/May/2009:11:53:50 +0200] [Job 8] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [10/May/2009:11:53:50 +0200] [Job 8] envp[6]="CUPS_SERVERROOT=/etc/cups" D [10/May/2009:11:53:50 +0200] [Job 8] envp[7]="CUPS_STATEDIR=/var/run/cups" D [10/May/2009:11:53:50 +0200] [Job 8] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [10/May/2009:11:53:50 +0200] [Job 8] envp[9]="SERVER_ADMIN=root@boromir" D [10/May/2009:11:53:50 +0200] [Job 8] envp[10]="SOFTWARE=CUPS/1.3.9" D [10/May/2009:11:53:50 +0200] [Job 8] envp[11]="TMPDIR=/var/spool/cups/tmp" D [10/May/2009:11:53:50 +0200] [Job 8] envp[12]="TZ=Europe/Paris" D [10/May/2009:11:53:50 +0200] [Job 8] envp[13]="USER=root" D [10/May/2009:11:53:50 +0200] [Job 8] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [10/May/2009:11:53:50 +0200] [Job 8] envp[15]="CUPS_ENCRYPTION=IfRequested" D [10/May/2009:11:53:50 +0200] [Job 8] envp[16]="IPP_PORT=631" D [10/May/2009:11:53:50 +0200] [Job 8] envp[17]="CHARSET=utf-8" D [10/May/2009:11:53:50 +0200] [Job 8] envp[18]="LANG=fr_FR.UTF8" D [10/May/2009:11:53:50 +0200] [Job 8] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd" D [10/May/2009:11:53:50 +0200] [Job 8] envp[20]="RIP_MAX_CACHE=8m" D [10/May/2009:11:53:50 +0200] [Job 8] envp[21]="CONTENT_TYPE=application/postscript" D [10/May/2009:11:53:50 +0200] [Job 8] envp[22]="DEVICE_URI=socket://192.168.1.117:9100" D [10/May/2009:11:53:50 +0200] [Job 8] envp[23]="PRINTER=magicolor-2530-DL" D [10/May/2009:11:53:50 +0200] [Job 8] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL" I [10/May/2009:11:53:50 +0200] [Job 8] Started filter /usr/lib/cups/filter/pstopdf (PID 6412) I [10/May/2009:11:53:50 +0200] [Job 8] Started filter /usr/lib/cups/filter/pdftopdf (PID 6414) I [10/May/2009:11:53:50 +0200] [Job 8] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6415) I [10/May/2009:11:53:50 +0200] [Job 8] Started backend /usr/lib/cups/backend/socket (PID 6416) I [10/May/2009:11:53:50 +0200] Saving subscriptions.conf... D [10/May/2009:11:53:50 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:11:53:50 +0200] [Job 8] pstopdf 6 args: 8 laurent Test Page 1 job-uuid=urn:uuid:1b582b7b-630e-35ba-526e-bcf25b313aad /var/spool/cups/d00008-001 D [10/May/2009:11:53:50 +0200] [Job 8] PPD: /etc/cups/ppd/magicolor-2530-DL.ppd D [10/May/2009:11:53:50 +0200] [Job 8] Connected to 192.168.1.117:9100 (IPv4)... D [10/May/2009:11:53:50 +0200] [Job 8] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb8035fc0) I [10/May/2009:11:53:50 +0200] Saving subscriptions.conf... D [10/May/2009:11:53:50 +0200] [Job 8] Resolution: 600x600 D [10/May/2009:11:53:50 +0200] [Job 8] Page size: A4 D [10/May/2009:11:53:50 +0200] [Job 8] Width: 595, height: 842, absolute margins: 11.34, 11.34, 583.66, 830.66 D [10/May/2009:11:53:50 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:11:53:50 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:11:53:50 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:50 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:53:50 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:11:53:50 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:11:53:50 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:11:53:50 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:50 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:53:50 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:11:53:50 +0200] cupsdCloseClient: 14 D [10/May/2009:11:53:50 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:11:53:50 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:11:53:50 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:50 +0200] Get-Notifications / D [10/May/2009:11:53:50 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:50 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:11:53:50 +0200] cupsdCloseClient: 14 D [10/May/2009:11:53:50 +0200] cupsdCloseClient: 13 D [10/May/2009:11:53:50 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:11:53:50 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:11:53:50 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:50 +0200] Get-Notifications / D [10/May/2009:11:53:50 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:50 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:11:53:50 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:11:53:50 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:50 +0200] Get-Job-Attributes ipp://localhost/jobs/8 D [10/May/2009:11:53:50 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:11:53:50 +0200] cupsdCloseClient: 13 D [10/May/2009:11:53:50 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:53:50 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:50 +0200] Get-Notifications / D [10/May/2009:11:53:50 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:50 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:53:50 +0200] [Job 8] Relative margins: 11.34, 11.34, 11.34, 11.34 D [10/May/2009:11:53:50 +0200] [Job 8] PPD options: -r600x600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 D [10/May/2009:11:53:50 +0200] [Job 8] PostScript to be injected: D [10/May/2009:11:53:50 +0200] [Job 8] Getting input from file D [10/May/2009:11:53:50 +0200] [Job 8] foomatic-rip version 4.0.0.195 running... D [10/May/2009:11:53:50 +0200] [Job 8] Parsing PPD file ... D [10/May/2009:11:53:50 +0200] [Job 8] Added option PageSize D [10/May/2009:11:53:50 +0200] [Job 8] Added option Quality D [10/May/2009:11:53:50 +0200] [Job 8] Added option PrinterType D [10/May/2009:11:53:51 +0200] [Job 8] Added option ColorMode D [10/May/2009:11:53:51 +0200] [Job 8] Added option Resolution D [10/May/2009:11:53:51 +0200] [Job 8] Added option ImageableArea D [10/May/2009:11:53:51 +0200] [Job 8] Added option PaperDimension D [10/May/2009:11:53:51 +0200] [Job 8] Added option InputSlot D [10/May/2009:11:53:51 +0200] [Job 8] Added option MediaType D [10/May/2009:11:53:51 +0200] [Job 8] Added option Duplex D [10/May/2009:11:53:51 +0200] [Job 8] Added option Copies D [10/May/2009:11:53:51 +0200] [Job 8] Added option ICM D [10/May/2009:11:53:51 +0200] [Job 8] Added option Nup D [10/May/2009:11:53:51 +0200] [Job 8] Added option NupOrient D [10/May/2009:11:53:51 +0200] [Job 8] Added option Font D [10/May/2009:11:53:51 +0200] [Job 8] D [10/May/2009:11:53:51 +0200] [Job 8] Parameter Summary D [10/May/2009:11:53:51 +0200] [Job 8] ----------------- D [10/May/2009:11:53:51 +0200] [Job 8] D [10/May/2009:11:53:51 +0200] [Job 8] Spooler: cups D [10/May/2009:11:53:51 +0200] [Job 8] Printer: magicolor-2530-DL D [10/May/2009:11:53:51 +0200] [Job 8] Shell: /bin/bash D [10/May/2009:11:53:51 +0200] [Job 8] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd D [10/May/2009:11:53:51 +0200] [Job 8] ATTR file: D [10/May/2009:11:53:51 +0200] [Job 8] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended) D [10/May/2009:11:53:51 +0200] [Job 8] Job title: Test Page D [10/May/2009:11:53:51 +0200] [Job 8] File(s) to be printed: D [10/May/2009:11:53:51 +0200] [Job 8] D [10/May/2009:11:53:51 +0200] [Job 8] D [10/May/2009:11:53:51 +0200] [Job 8] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [10/May/2009:11:53:51 +0200] [Job 8] Printing system options: D [10/May/2009:11:53:51 +0200] [Job 8] Pondering option 'job-uuid=urn:uuid:1b582b7b-630e-35ba-526e-bcf25b313aad' D [10/May/2009:11:53:51 +0200] [Job 8] Unknown option job-uuid=urn:uuid:1b582b7b-630e-35ba-526e-bcf25b313aad. D [10/May/2009:11:53:51 +0200] [Job 8] Options from the PPD file: D [10/May/2009:11:53:51 +0200] [Job 8] D [10/May/2009:11:53:51 +0200] [Job 8] ================================================ D [10/May/2009:11:53:51 +0200] [Job 8] D [10/May/2009:11:53:51 +0200] [Job 8] File: D [10/May/2009:11:53:51 +0200] [Job 8] D [10/May/2009:11:53:51 +0200] [Job 8] ================================================ D [10/May/2009:11:53:51 +0200] [Job 8] D [10/May/2009:11:53:51 +0200] [Job 8] Running cat | /usr/bin/ps2pdf13 -dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode -dDoNumCopies -dPDFSETTINGS=/printer -r600x600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 - - D [10/May/2009:11:53:51 +0200] [Job 8] GPL Ghostscript 8.64: Set UseCIEColor for UseDeviceIndependentColor to work properly. D [10/May/2009:11:53:52 +0200] [Job 8] Filetype: PDF D [10/May/2009:11:53:52 +0200] [Job 8] Driver does not understand PDF input, converting to PostScript D [10/May/2009:11:53:52 +0200] [Job 8] Starting process "pdf-to-ps" (generation 1) D [10/May/2009:11:53:52 +0200] PID 6412 (/usr/lib/cups/filter/pstopdf) exited with no errors. D [10/May/2009:11:53:52 +0200] PID 6414 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [10/May/2009:11:53:52 +0200] [Job 8] Filetype: PostScript D [10/May/2009:11:53:52 +0200] [Job 8] Reading PostScript input ... D [10/May/2009:11:53:52 +0200] [Job 8] --> This document is DSC-conforming! D [10/May/2009:11:53:52 +0200] [Job 8] D [10/May/2009:11:53:52 +0200] [Job 8] ----------- D [10/May/2009:11:53:52 +0200] [Job 8] Found: %%BeginProlog D [10/May/2009:11:53:52 +0200] [Job 8] Inserting option code into "Prolog" section. D [10/May/2009:11:53:52 +0200] [Job 8] Found: %%EndProlog D [10/May/2009:11:53:52 +0200] [Job 8] D [10/May/2009:11:53:52 +0200] [Job 8] ----------- D [10/May/2009:11:53:52 +0200] [Job 8] New page: %%Page: 1 1 D [10/May/2009:11:53:52 +0200] [Job 8] "Setup" section is missing, inserting it. D [10/May/2009:11:53:52 +0200] [Job 8] Inserting PostScript code for CUPS' page accounting D [10/May/2009:11:53:52 +0200] [Job 8] Inserting option code into "Setup" section. D [10/May/2009:11:53:52 +0200] [Job 8] No page header or page header not DSC-conforming D [10/May/2009:11:53:52 +0200] [Job 8] "PageSetup" section is missing, inserting it. D [10/May/2009:11:53:52 +0200] [Job 8] Inserting option code into "PageSetup" section. D [10/May/2009:11:53:52 +0200] [Job 8] Stopping search for page header options D [10/May/2009:11:53:52 +0200] [Job 8] Found: 0 6750 60 0 S D [10/May/2009:11:53:52 +0200] [Job 8] --> Output goes directly to the renderer now. D [10/May/2009:11:53:52 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] Starting renderer with command: "foo2lava-wrapper -z0 -c -C2 -r600x600 -p26 -m0 -s255 -d1 -Gnone.icm " D [10/May/2009:11:53:53 +0200] [Job 8] Starting process "kid3" (generation 1) D [10/May/2009:11:53:53 +0200] [Job 8] Starting process "kid4" (generation 2) D [10/May/2009:11:53:53 +0200] [Job 8] JCL: %-12345X@PJL D [10/May/2009:11:53:53 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] Starting process "renderer" (generation 2) D [10/May/2009:11:53:53 +0200] [Job 8] Found: %%PageTrailer D [10/May/2009:11:53:53 +0200] [Job 8] --> Continue DSC parsing now. D [10/May/2009:11:53:53 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] Found: %%Trailer D [10/May/2009:11:53:53 +0200] [Job 8] --> Continue DSC parsing now. D [10/May/2009:11:53:53 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] Found: %%Pages: 1 D [10/May/2009:11:53:53 +0200] [Job 8] --> Continue DSC parsing now. D [10/May/2009:11:53:53 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] Found: %%BoundingBox: 0 0 595 842 D [10/May/2009:11:53:53 +0200] [Job 8] --> Continue DSC parsing now. D [10/May/2009:11:53:53 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] Found: %%HiResBoundingBox: 0.000000 0.000000 595.000000 842.000000 D [10/May/2009:11:53:53 +0200] [Job 8] --> Continue DSC parsing now. D [10/May/2009:11:53:53 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] Found: %%EOF D [10/May/2009:11:53:53 +0200] [Job 8] --> Continue DSC parsing now. D [10/May/2009:11:53:53 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] D [10/May/2009:11:53:53 +0200] [Job 8] Closing renderer D [10/May/2009:11:53:53 +0200] [Job 8] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:11:53:54 +0200] [Job 8] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:11:53:54 +0200] [Job 8] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:11:53:54 +0200] [Job 8] pcache: unable to open '~/.ghostscript/cache/gs_cache' I [10/May/2009:11:53:57 +0200] Saving subscriptions.conf... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... I [10/May/2009:11:53:57 +0200] Saving subscriptions.conf... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] renderer exited with status 0 D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] kid4 exited with status 0 D [10/May/2009:11:53:57 +0200] [Job 8] kid3 finished D [10/May/2009:11:53:57 +0200] [Job 8] kid3 exited with status 0 D [10/May/2009:11:53:57 +0200] [Job 8] pdf-to-ps exited with status 0 D [10/May/2009:11:53:57 +0200] [Job 8] D [10/May/2009:11:53:57 +0200] [Job 8] Closing foomatic-rip. D [10/May/2009:11:53:57 +0200] PID 6415 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 8192 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Read 2520 bytes of print data... D [10/May/2009:11:53:57 +0200] [Job 8] Wrote 2520 bytes of print data... D [10/May/2009:11:53:57 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:11:53:57 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:11:53:57 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:57 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:53:57 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:11:53:57 +0200] cupsdAcceptClient: 15 from localhost (Domain) D [10/May/2009:11:53:57 +0200] cupsdAcceptClient: 16 from localhost (Domain) D [10/May/2009:11:53:57 +0200] cupsdReadClient: 15 POST / HTTP/1.1 D [10/May/2009:11:53:57 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:57 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:53:57 +0200] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok) D [10/May/2009:11:53:57 +0200] cupsdAcceptClient: 18 from localhost (Domain) D [10/May/2009:11:53:57 +0200] cupsdReadClient: 16 POST / HTTP/1.1 D [10/May/2009:11:53:57 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:57 +0200] Get-Notifications / D [10/May/2009:11:53:57 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:57 +0200] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok) D [10/May/2009:11:53:57 +0200] cupsdCloseClient: 14 D [10/May/2009:11:53:57 +0200] cupsdReadClient: 18 POST / HTTP/1.1 D [10/May/2009:11:53:57 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:57 +0200] Get-Notifications / D [10/May/2009:11:53:57 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:57 +0200] cupsdProcessIPPRequest: 18 status_code=0 (successful-ok) D [10/May/2009:11:53:57 +0200] cupsdCloseClient: 15 D [10/May/2009:11:53:57 +0200] cupsdCloseClient: 16 D [10/May/2009:11:53:57 +0200] cupsdCloseClient: 18 D [10/May/2009:11:53:57 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:53:57 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:53:57 +0200] Get-Notifications / D [10/May/2009:11:53:57 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:53:57 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) I [10/May/2009:11:54:02 +0200] Saving subscriptions.conf... I [10/May/2009:11:54:02 +0200] Saving subscriptions.conf... D [10/May/2009:11:54:02 +0200] PID 6416 (/usr/lib/cups/backend/socket) exited with no errors. D [10/May/2009:11:54:02 +0200] [Job 8] File 0 is complete. I [10/May/2009:11:54:02 +0200] [Job 8] Completed successfully. I [10/May/2009:11:54:02 +0200] Saving subscriptions.conf... I [10/May/2009:11:54:02 +0200] Saving subscriptions.conf... D [10/May/2009:11:54:03 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:11:54:03 +0200] [Job 8] Unloading... D [10/May/2009:11:54:03 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:11:54:03 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:54:03 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:54:03 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:11:54:03 +0200] cupsdCloseClient: 14 D [10/May/2009:11:54:03 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:11:54:03 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:11:54:03 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:54:03 +0200] Get-Notifications / D [10/May/2009:11:54:03 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:54:03 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:11:54:03 +0200] cupsdCloseClient: 14 D [10/May/2009:11:54:03 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:11:54:03 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:11:54:03 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:54:03 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:11:54:03 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:11:54:03 +0200] cupsdCloseClient: 14 D [10/May/2009:11:54:03 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:11:54:03 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:11:54:03 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:54:03 +0200] Get-Notifications / D [10/May/2009:11:54:03 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:54:03 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:11:54:03 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:54:03 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:54:03 +0200] Get-Notifications / D [10/May/2009:11:54:03 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:11:54:03 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:54:03 +0200] cupsdCloseClient: 14 D [10/May/2009:11:54:37 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:54:37 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:54:37 +0200] Get-Job-Attributes ipp://localhost/jobs/8 D [10/May/2009:11:54:37 +0200] [Job 8] Loading attributes... D [10/May/2009:11:54:37 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:54:37 +0200] [Job 1] Unloading... D [10/May/2009:11:54:37 +0200] [Job 2] Unloading... D [10/May/2009:11:54:37 +0200] [Job 3] Unloading... D [10/May/2009:11:54:37 +0200] [Job 4] Unloading... D [10/May/2009:11:54:37 +0200] [Job 5] Unloading... D [10/May/2009:11:54:37 +0200] [Job 6] Unloading... D [10/May/2009:11:54:37 +0200] Report: clients=3 D [10/May/2009:11:54:37 +0200] Report: jobs=8 D [10/May/2009:11:54:37 +0200] Report: jobs-active=0 D [10/May/2009:11:54:37 +0200] Report: printers=2 D [10/May/2009:11:54:37 +0200] Report: printers-implicit=0 D [10/May/2009:11:54:37 +0200] Report: stringpool-string-count=2494 D [10/May/2009:11:54:37 +0200] Report: stringpool-alloc-bytes=9944 D [10/May/2009:11:54:37 +0200] Report: stringpool-total-bytes=53840 D [10/May/2009:11:54:37 +0200] cupsdReadClient: 8 POST / HTTP/1.1 D [10/May/2009:11:54:37 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:54:37 +0200] Cancel-Subscription / D [10/May/2009:11:54:37 +0200] cupsdIsAuthorized: requesting-user-name="laurent" I [10/May/2009:11:54:37 +0200] Saving subscriptions.conf... D [10/May/2009:11:54:37 +0200] cupsdProcessIPPRequest: 8 status_code=0 (successful-ok) D [10/May/2009:11:54:37 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:11:54:37 +0200] cupsdCloseClient: 12 D [10/May/2009:11:54:37 +0200] cupsdReadClient: 14 GET /admin/log/error_log HTTP/1.1 D [10/May/2009:11:54:37 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:54:37 +0200] cupsdReadClient: 14 PUT /admin/conf/cupsd.conf HTTP/1.1 D [10/May/2009:11:54:37 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:11:54:37 +0200] cupsdIsAuthorized: username="" D [10/May/2009:11:54:37 +0200] cupsdSendError: 14 code=401 (Unauthorized) D [10/May/2009:11:54:37 +0200] cupsdSendHeader: WWW-Authenticate: Basic realm="CUPS" D [10/May/2009:11:54:37 +0200] cupsdCloseClient: 14 D [10/May/2009:11:54:37 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:11:54:37 +0200] cupsdReadClient: 12 PUT /admin/conf/cupsd.conf HTTP/1.1 D [10/May/2009:11:54:37 +0200] cupsdAuthorize: Authorized as root using Local D [10/May/2009:11:54:37 +0200] cupsdIsAuthorized: username="root" I [10/May/2009:11:54:37 +0200] Installing config file "/etc/cups/cupsd.conf"... D [10/May/2009:11:54:37 +0200] cupsdSendError: 12 code=201 (Created) D [10/May/2009:11:54:37 +0200] cupsdCloseClient: 8 D [10/May/2009:11:54:37 +0200] cupsdCloseClient: 9 D [10/May/2009:11:54:37 +0200] cupsdCloseClient: 12 D [10/May/2009:11:54:37 +0200] Saving remote.cache... I [10/May/2009:11:54:37 +0200] Listening to ::1:631 (IPv6) I [10/May/2009:11:54:37 +0200] Listening to 127.0.0.1:631 (IPv4) I [10/May/2009:11:54:37 +0200] Listening to /var/run/cups/cups.sock (Domain) I [10/May/2009:11:54:37 +0200] Loaded configuration file "/etc/cups/cupsd.conf" I [10/May/2009:11:54:37 +0200] Using default TempDir of /var/spool/cups/tmp... I [10/May/2009:11:54:37 +0200] Configured for up to 100 clients. I [10/May/2009:11:54:37 +0200] Allowing up to 100 client connections per host. I [10/May/2009:11:54:37 +0200] Using policy "default" as the default! I [10/May/2009:11:54:37 +0200] Partial reload complete. I [10/May/2009:11:54:37 +0200] Listening to ::1:631 on fd 2... I [10/May/2009:11:54:37 +0200] Listening to 127.0.0.1:631 on fd 4... I [10/May/2009:11:54:37 +0200] Listening to /var/run/cups/cups.sock on fd 5... I [10/May/2009:11:54:37 +0200] Resuming new connection processing... I [10/May/2009:11:54:50 +0200] Installing config file "/etc/cups/cupsd.conf"... I [10/May/2009:11:54:50 +0200] Listening to ::1:631 (IPv6) I [10/May/2009:11:54:50 +0200] Listening to 127.0.0.1:631 (IPv4) I [10/May/2009:11:54:50 +0200] Listening to /var/run/cups/cups.sock (Domain) I [10/May/2009:11:54:50 +0200] Loaded configuration file "/etc/cups/cupsd.conf" I [10/May/2009:11:54:50 +0200] Using default TempDir of /var/spool/cups/tmp... I [10/May/2009:11:54:50 +0200] Configured for up to 100 clients. I [10/May/2009:11:54:50 +0200] Allowing up to 100 client connections per host. I [10/May/2009:11:54:50 +0200] Using policy "default" as the default! I [10/May/2009:11:54:50 +0200] Partial reload complete. I [10/May/2009:11:54:50 +0200] Listening to ::1:631 on fd 2... I [10/May/2009:11:54:50 +0200] Listening to 127.0.0.1:631 on fd 4... I [10/May/2009:11:54:50 +0200] Listening to /var/run/cups/cups.sock on fd 5... I [10/May/2009:11:54:50 +0200] Resuming new connection processing... I [10/May/2009:12:00:48 +0200] [Job ???] Request file type is application/postscript. I [10/May/2009:12:00:48 +0200] [Job 9] Adding start banner page "none". I [10/May/2009:12:00:48 +0200] Saving subscriptions.conf... I [10/May/2009:12:00:48 +0200] [Job 9] Adding end banner page "none". I [10/May/2009:12:00:48 +0200] [Job 9] File of type application/postscript queued by "laurent". I [10/May/2009:12:00:48 +0200] [Job 9] Queued on "magicolor-2530-DL" by "laurent". I [10/May/2009:12:00:48 +0200] Saving subscriptions.conf... I [10/May/2009:12:00:48 +0200] [Job 9] Started filter /usr/lib/cups/filter/pstopdf (PID 6967) I [10/May/2009:12:00:48 +0200] [Job 9] Started filter /usr/lib/cups/filter/pdftopdf (PID 6969) I [10/May/2009:12:00:48 +0200] [Job 9] Started filter /usr/lib/cups/filter/foomatic-rip (PID 6970) I [10/May/2009:12:00:48 +0200] [Job 9] Started backend /usr/lib/cups/backend/socket (PID 6972) I [10/May/2009:12:00:48 +0200] Saving subscriptions.conf... I [10/May/2009:12:00:48 +0200] Saving subscriptions.conf... I [10/May/2009:12:00:53 +0200] Saving subscriptions.conf... I [10/May/2009:12:00:58 +0200] Saving subscriptions.conf... I [10/May/2009:12:00:58 +0200] Saving subscriptions.conf... I [10/May/2009:12:00:58 +0200] [Job 9] Completed successfully. I [10/May/2009:12:00:58 +0200] Saving subscriptions.conf... I [10/May/2009:12:00:58 +0200] Saving subscriptions.conf... I [10/May/2009:12:14:57 +0200] Saving subscriptions.conf... I [10/May/2009:12:15:29 +0200] Saving subscriptions.conf... I [10/May/2009:12:16:03 +0200] Saving subscriptions.conf... I [10/May/2009:15:13:59 +0200] Saving subscriptions.conf... I [10/May/2009:15:14:07 +0200] Installing config file "/etc/cups/cupsd.conf"... I [10/May/2009:15:14:07 +0200] Listening to ::1:631 (IPv6) I [10/May/2009:15:14:07 +0200] Listening to 127.0.0.1:631 (IPv4) I [10/May/2009:15:14:07 +0200] Listening to /var/run/cups/cups.sock (Domain) I [10/May/2009:15:14:07 +0200] Loaded configuration file "/etc/cups/cupsd.conf" I [10/May/2009:15:14:08 +0200] Using default TempDir of /var/spool/cups/tmp... I [10/May/2009:15:14:08 +0200] Configured for up to 100 clients. I [10/May/2009:15:14:08 +0200] Allowing up to 100 client connections per host. I [10/May/2009:15:14:08 +0200] Using policy "default" as the default! I [10/May/2009:15:14:08 +0200] Partial reload complete. I [10/May/2009:15:14:08 +0200] Listening to ::1:631 on fd 2... I [10/May/2009:15:14:08 +0200] Listening to 127.0.0.1:631 on fd 4... I [10/May/2009:15:14:08 +0200] Listening to /var/run/cups/cups.sock on fd 5... I [10/May/2009:15:14:08 +0200] Resuming new connection processing... D [10/May/2009:15:14:08 +0200] Discarding unused server-restarted event... D [10/May/2009:15:14:09 +0200] Report: clients=0 D [10/May/2009:15:14:09 +0200] Report: jobs=9 D [10/May/2009:15:14:09 +0200] Report: jobs-active=0 D [10/May/2009:15:14:09 +0200] Report: printers=2 D [10/May/2009:15:14:09 +0200] Report: printers-implicit=0 D [10/May/2009:15:14:09 +0200] Report: stringpool-string-count=2363 D [10/May/2009:15:14:09 +0200] Report: stringpool-alloc-bytes=9064 D [10/May/2009:15:14:09 +0200] Report: stringpool-total-bytes=51152 D [10/May/2009:15:14:09 +0200] cupsdAcceptClient: 8 from localhost (Domain) D [10/May/2009:15:14:42 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:42 +0200] cupsdCloseClient: 9 D [10/May/2009:15:14:42 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:43 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:14:43 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:14:43 +0200] CUPS-Get-Printers D [10/May/2009:15:14:43 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:14:43 +0200] cupsdCloseClient: 9 D [10/May/2009:15:14:43 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:43 +0200] cupsdReadClient: 9 GET /printers/HL-5040-series.ppd HTTP/1.1 D [10/May/2009:15:14:43 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:14:43 +0200] cupsdCloseClient: 9 D [10/May/2009:15:14:45 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:45 +0200] cupsdCloseClient: 9 D [10/May/2009:15:14:45 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:45 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:14:45 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:14:45 +0200] CUPS-Get-Printers D [10/May/2009:15:14:45 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:14:45 +0200] cupsdCloseClient: 9 D [10/May/2009:15:14:45 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:45 +0200] cupsdReadClient: 9 GET /printers/magicolor-2530-DL.ppd HTTP/1.1 D [10/May/2009:15:14:45 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:14:46 +0200] cupsdCloseClient: 9 D [10/May/2009:15:14:48 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:48 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:14:48 +0200] cupsdCloseClient: 9 D [10/May/2009:15:14:48 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:14:48 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:14:48 +0200] CUPS-Get-Printers D [10/May/2009:15:14:48 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:14:48 +0200] cupsdCloseClient: 12 D [10/May/2009:15:14:51 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:51 +0200] cupsdCloseClient: 9 D [10/May/2009:15:14:51 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:51 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:14:51 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:14:51 +0200] CUPS-Get-Printers D [10/May/2009:15:14:51 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:14:51 +0200] cupsdCloseClient: 9 D [10/May/2009:15:14:54 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:54 +0200] cupsdCloseClient: 9 D [10/May/2009:15:14:54 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:14:54 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:14:54 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:14:54 +0200] CUPS-Get-Printers D [10/May/2009:15:14:54 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:14:55 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:01 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:15:01 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:01 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:01 +0200] Get-Notifications / D [10/May/2009:15:15:01 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:15:01 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:01 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:05 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:15:06 +0200] cupsdReadClient: 9 POST /printers/magicolor-2530-DL HTTP/1.1 D [10/May/2009:15:15:06 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:06 +0200] Print-Job ipp://localhost:631/printers/magicolor-2530-DL D [10/May/2009:15:15:06 +0200] [Job ???] Auto-typing file... I [10/May/2009:15:15:06 +0200] [Job ???] Request file type is application/pdf. D [10/May/2009:15:15:06 +0200] add_job: requesting-user-name="laurent" I [10/May/2009:15:15:06 +0200] [Job 10] Adding start banner page "none". I [10/May/2009:15:15:06 +0200] Saving subscriptions.conf... I [10/May/2009:15:15:06 +0200] [Job 10] Adding end banner page "none". I [10/May/2009:15:15:06 +0200] [Job 10] File of type application/pdf queued by "laurent". D [10/May/2009:15:15:06 +0200] [Job 10] hold_until=0 I [10/May/2009:15:15:06 +0200] [Job 10] Queued on "magicolor-2530-DL" by "laurent". I [10/May/2009:15:15:06 +0200] Saving subscriptions.conf... D [10/May/2009:15:15:06 +0200] [Job 10] job-sheets=none,none D [10/May/2009:15:15:06 +0200] [Job 10] banner_page = 0 D [10/May/2009:15:15:06 +0200] [Job 10] argv[0]="magicolor-2530-DL" D [10/May/2009:15:15:06 +0200] [Job 10] argv[1]="10" D [10/May/2009:15:15:06 +0200] [Job 10] argv[2]="laurent" D [10/May/2009:15:15:06 +0200] [Job 10] argv[3]="out.pnm" D [10/May/2009:15:15:06 +0200] [Job 10] argv[4]="1" D [10/May/2009:15:15:06 +0200] [Job 10] argv[5]="Resolution=600x600dpi Quality=normal MediaType=plain NupOrient=port ColorMode=PhotosAndText PageSize=A4 Nup=1up number-up=1 ICM=none Duplex=None InputSlot=Auto job-uuid=urn:uuid:38e68a18-4ada-32ef-5687-e038b743aae9" D [10/May/2009:15:15:06 +0200] [Job 10] argv[6]="/var/spool/cups/d00010-001" D [10/May/2009:15:15:06 +0200] [Job 10] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [10/May/2009:15:15:06 +0200] [Job 10] envp[1]="CUPS_DATADIR=/usr/share/cups" D [10/May/2009:15:15:06 +0200] [Job 10] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [10/May/2009:15:15:06 +0200] [Job 10] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [10/May/2009:15:15:06 +0200] [Job 10] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [10/May/2009:15:15:06 +0200] [Job 10] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [10/May/2009:15:15:06 +0200] [Job 10] envp[6]="CUPS_SERVERROOT=/etc/cups" D [10/May/2009:15:15:06 +0200] [Job 10] envp[7]="CUPS_STATEDIR=/var/run/cups" D [10/May/2009:15:15:06 +0200] [Job 10] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [10/May/2009:15:15:06 +0200] [Job 10] envp[9]="SERVER_ADMIN=root@boromir" D [10/May/2009:15:15:06 +0200] [Job 10] envp[10]="SOFTWARE=CUPS/1.3.9" D [10/May/2009:15:15:06 +0200] [Job 10] envp[11]="TMPDIR=/var/spool/cups/tmp" D [10/May/2009:15:15:06 +0200] [Job 10] envp[12]="TZ=Europe/Paris" D [10/May/2009:15:15:06 +0200] [Job 10] envp[13]="USER=root" D [10/May/2009:15:15:06 +0200] [Job 10] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [10/May/2009:15:15:06 +0200] [Job 10] envp[15]="CUPS_ENCRYPTION=IfRequested" D [10/May/2009:15:15:06 +0200] [Job 10] envp[16]="IPP_PORT=631" D [10/May/2009:15:15:06 +0200] [Job 10] envp[17]="CHARSET=utf-8" D [10/May/2009:15:15:06 +0200] [Job 10] envp[18]="LANG=fr_FR.UTF8" D [10/May/2009:15:15:06 +0200] [Job 10] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd" D [10/May/2009:15:15:06 +0200] [Job 10] envp[20]="RIP_MAX_CACHE=8m" D [10/May/2009:15:15:06 +0200] [Job 10] envp[21]="CONTENT_TYPE=application/pdf" D [10/May/2009:15:15:06 +0200] [Job 10] envp[22]="DEVICE_URI=socket://192.168.1.117:9100" D [10/May/2009:15:15:06 +0200] [Job 10] envp[23]="PRINTER=magicolor-2530-DL" D [10/May/2009:15:15:06 +0200] [Job 10] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL" I [10/May/2009:15:15:06 +0200] [Job 10] Started filter /usr/lib/cups/filter/pdftopdf (PID 13798) I [10/May/2009:15:15:06 +0200] [Job 10] Started filter /usr/lib/cups/filter/foomatic-rip (PID 13799) I [10/May/2009:15:15:06 +0200] [Job 10] Started backend /usr/lib/cups/backend/socket (PID 13800) I [10/May/2009:15:15:06 +0200] Saving subscriptions.conf... D [10/May/2009:15:15:06 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:06 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:07 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:15:07 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:07 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:07 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:15:07 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:07 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:15:07 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:15:07 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:07 +0200] Get-Notifications / D [10/May/2009:15:15:07 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:15:07 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:15:07 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:07 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:15:07 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:07 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:07 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:15:07 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:07 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:07 +0200] cupsdCloseClient: 12 I [10/May/2009:15:15:07 +0200] Saving subscriptions.conf... D [10/May/2009:15:15:07 +0200] [Job 10] Connected to 192.168.1.117:9100 (IPv4)... D [10/May/2009:15:15:07 +0200] [Job 10] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb7f71fc0) I [10/May/2009:15:15:07 +0200] Saving subscriptions.conf... D [10/May/2009:15:15:08 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:15:08 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:08 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:08 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:15:08 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:08 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:15:08 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:15:08 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:08 +0200] Get-Notifications / D [10/May/2009:15:15:08 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:15:08 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:15:08 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:08 +0200] cupsdCloseClient: 12 D [10/May/2009:15:15:08 +0200] [Job 10] Getting input from file D [10/May/2009:15:15:08 +0200] [Job 10] foomatic-rip version 4.0.0.195 running... D [10/May/2009:15:15:08 +0200] [Job 10] Parsing PPD file ... D [10/May/2009:15:15:08 +0200] [Job 10] Added option PageSize D [10/May/2009:15:15:08 +0200] [Job 10] Added option Quality D [10/May/2009:15:15:08 +0200] [Job 10] Added option PrinterType D [10/May/2009:15:15:08 +0200] [Job 10] Added option ColorMode D [10/May/2009:15:15:08 +0200] [Job 10] Added option Resolution D [10/May/2009:15:15:08 +0200] [Job 10] Added option ImageableArea D [10/May/2009:15:15:08 +0200] [Job 10] Added option PaperDimension D [10/May/2009:15:15:08 +0200] [Job 10] Added option InputSlot D [10/May/2009:15:15:08 +0200] [Job 10] Added option MediaType D [10/May/2009:15:15:08 +0200] [Job 10] Added option Duplex D [10/May/2009:15:15:08 +0200] [Job 10] Added option Copies D [10/May/2009:15:15:08 +0200] [Job 10] Added option ICM D [10/May/2009:15:15:08 +0200] [Job 10] Added option Nup D [10/May/2009:15:15:08 +0200] [Job 10] Added option NupOrient D [10/May/2009:15:15:08 +0200] [Job 10] Added option Font D [10/May/2009:15:15:08 +0200] [Job 10] D [10/May/2009:15:15:08 +0200] [Job 10] Parameter Summary D [10/May/2009:15:15:08 +0200] [Job 10] ----------------- D [10/May/2009:15:15:08 +0200] [Job 10] D [10/May/2009:15:15:08 +0200] [Job 10] Spooler: cups D [10/May/2009:15:15:08 +0200] [Job 10] Printer: magicolor-2530-DL D [10/May/2009:15:15:08 +0200] [Job 10] Shell: /bin/bash D [10/May/2009:15:15:08 +0200] [Job 10] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd D [10/May/2009:15:15:08 +0200] [Job 10] ATTR file: D [10/May/2009:15:15:08 +0200] [Job 10] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended) D [10/May/2009:15:15:08 +0200] [Job 10] Job title: out.pnm D [10/May/2009:15:15:08 +0200] [Job 10] File(s) to be printed: D [10/May/2009:15:15:08 +0200] [Job 10] D [10/May/2009:15:15:08 +0200] [Job 10] D [10/May/2009:15:15:08 +0200] [Job 10] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [10/May/2009:15:15:08 +0200] [Job 10] Printing system options: D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'number-up=1' D [10/May/2009:15:15:08 +0200] [Job 10] Unknown option number-up=1. D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'job-uuid=urn:uuid:38e68a18-4ada-32ef-5687-e038b743aae9' D [10/May/2009:15:15:08 +0200] [Job 10] Unknown option job-uuid=urn:uuid:38e68a18-4ada-32ef-5687-e038b743aae9. D [10/May/2009:15:15:08 +0200] [Job 10] Options from the PPD file: D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'Resolution=600x600dpi' D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'Quality=normal' D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'MediaType=plain' D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'NupOrient=port' D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'ColorMode=PhotosAndText' D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'PageSize=A4' D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'Nup=1up' D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'ICM=none' D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'Duplex=None' D [10/May/2009:15:15:08 +0200] [Job 10] Pondering option 'InputSlot=Auto' D [10/May/2009:15:15:08 +0200] [Job 10] D [10/May/2009:15:15:08 +0200] [Job 10] ================================================ D [10/May/2009:15:15:08 +0200] [Job 10] D [10/May/2009:15:15:08 +0200] [Job 10] File: D [10/May/2009:15:15:08 +0200] [Job 10] D [10/May/2009:15:15:08 +0200] [Job 10] ================================================ D [10/May/2009:15:15:08 +0200] [Job 10] D [10/May/2009:15:15:09 +0200] [Job 10] Filetype: PDF D [10/May/2009:15:15:09 +0200] [Job 10] Driver does not understand PDF input, converting to PostScript D [10/May/2009:15:15:09 +0200] [Job 10] Starting process "pdf-to-ps" (generation 1) D [10/May/2009:15:15:09 +0200] Report: clients=1 D [10/May/2009:15:15:09 +0200] Report: jobs=10 D [10/May/2009:15:15:09 +0200] Report: jobs-active=1 D [10/May/2009:15:15:09 +0200] Report: printers=2 D [10/May/2009:15:15:09 +0200] Report: printers-implicit=0 D [10/May/2009:15:15:09 +0200] Report: stringpool-string-count=2589 D [10/May/2009:15:15:09 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:15:09 +0200] Report: stringpool-total-bytes=55864 D [10/May/2009:15:15:14 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:15:14 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:14 +0200] Create-Printer-Subscription / D [10/May/2009:15:15:14 +0200] cupsdCreateSubscription(con=0xb9649a90(9), uri="/") D [10/May/2009:15:15:14 +0200] pullmethod="ippget" D [10/May/2009:15:15:14 +0200] notify-lease-duration=86400 D [10/May/2009:15:15:14 +0200] notify-time-interval=0 D [10/May/2009:15:15:14 +0200] cupsdAddSubscription(mask=798f, dest=(nil)(), job=(nil)(0), uri="(null)") D [10/May/2009:15:15:14 +0200] Added subscription 9 for server I [10/May/2009:15:15:14 +0200] Saving subscriptions.conf... D [10/May/2009:15:15:14 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:14 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:14 +0200] CUPS-Get-Printers D [10/May/2009:15:15:14 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:14 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:14 +0200] CUPS-Get-Printers D [10/May/2009:15:15:14 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:14 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:14 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:15:14 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:14 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:15:14 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:14 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:15:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:15:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:14 +0200] Get-Printer-Attributes ipp://boromir:631/printers/magicolor-2530-DL D [10/May/2009:15:15:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:15:14 +0200] cupsdCloseClient: 12 D [10/May/2009:15:15:14 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:14 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:15:14 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:14 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:15:14 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:14 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:14 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:15:14 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:14 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:15:14 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:14 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:14 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:15:14 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:15:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:15:14 +0200] Get-Notifications / D [10/May/2009:15:15:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:15:14 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:15:14 +0200] cupsdCloseClient: 9 D [10/May/2009:15:15:30 +0200] PID 13798 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [10/May/2009:15:15:31 +0200] [Job 10] Filetype: PostScript D [10/May/2009:15:15:31 +0200] [Job 10] Reading PostScript input ... D [10/May/2009:15:15:31 +0200] [Job 10] --> This document is DSC-conforming! D [10/May/2009:15:15:31 +0200] [Job 10] D [10/May/2009:15:15:31 +0200] [Job 10] ----------- D [10/May/2009:15:15:31 +0200] [Job 10] Found: %%BeginProlog D [10/May/2009:15:15:31 +0200] [Job 10] Inserting option code into "Prolog" section. D [10/May/2009:15:15:31 +0200] [Job 10] Found: %%EndProlog D [10/May/2009:15:15:31 +0200] [Job 10] D [10/May/2009:15:15:31 +0200] [Job 10] ----------- D [10/May/2009:15:15:31 +0200] [Job 10] New page: %%Page: 1 1 D [10/May/2009:15:15:31 +0200] [Job 10] "Setup" section is missing, inserting it. D [10/May/2009:15:15:31 +0200] [Job 10] Inserting PostScript code for CUPS' page accounting D [10/May/2009:15:15:31 +0200] [Job 10] Inserting option code into "Setup" section. D [10/May/2009:15:15:31 +0200] [Job 10] No page header or page header not DSC-conforming D [10/May/2009:15:15:31 +0200] [Job 10] "PageSetup" section is missing, inserting it. D [10/May/2009:15:15:31 +0200] [Job 10] Inserting option code into "PageSetup" section. D [10/May/2009:15:15:31 +0200] [Job 10] Stopping search for page header options D [10/May/2009:15:15:31 +0200] [Job 10] Found: 253 254 r3 D [10/May/2009:15:15:31 +0200] [Job 10] --> Output goes directly to the renderer now. D [10/May/2009:15:15:31 +0200] [Job 10] D [10/May/2009:15:15:32 +0200] [Job 10] D [10/May/2009:15:15:32 +0200] [Job 10] Starting renderer with command: "foo2lava-wrapper -z0 -c -C2 -r600x600 -p26 -m0 -s255 -d1 -Gnone.icm " D [10/May/2009:15:15:32 +0200] [Job 10] Starting process "kid3" (generation 1) D [10/May/2009:15:15:32 +0200] [Job 10] Starting process "kid4" (generation 2) D [10/May/2009:15:15:32 +0200] [Job 10] JCL: %-12345X@PJL D [10/May/2009:15:15:32 +0200] [Job 10] D [10/May/2009:15:15:32 +0200] [Job 10] D [10/May/2009:15:15:32 +0200] [Job 10] Starting process "renderer" (generation 2) D [10/May/2009:15:15:34 +0200] [Job 10] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:15:34 +0200] [Job 10] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:15:34 +0200] [Job 10] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:15:34 +0200] [Job 10] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:16:19 +0200] Report: clients=1 D [10/May/2009:15:16:19 +0200] Report: jobs=10 D [10/May/2009:15:16:19 +0200] Report: jobs-active=1 D [10/May/2009:15:16:19 +0200] Report: printers=2 D [10/May/2009:15:16:19 +0200] Report: printers-implicit=0 D [10/May/2009:15:16:19 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:16:19 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:16:19 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:17:25 +0200] Report: clients=1 D [10/May/2009:15:17:25 +0200] Report: jobs=10 D [10/May/2009:15:17:25 +0200] Report: jobs-active=1 D [10/May/2009:15:17:25 +0200] Report: printers=2 D [10/May/2009:15:17:25 +0200] Report: printers-implicit=0 D [10/May/2009:15:17:25 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:17:25 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:17:25 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:18:31 +0200] Report: clients=1 D [10/May/2009:15:18:31 +0200] Report: jobs=10 D [10/May/2009:15:18:31 +0200] Report: jobs-active=1 D [10/May/2009:15:18:31 +0200] Report: printers=2 D [10/May/2009:15:18:31 +0200] Report: printers-implicit=0 D [10/May/2009:15:18:31 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:18:31 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:18:31 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:19:10 +0200] Closing client 8 after 300 seconds of inactivity... D [10/May/2009:15:19:10 +0200] cupsdCloseClient: 8 D [10/May/2009:15:19:32 +0200] Report: clients=0 D [10/May/2009:15:19:32 +0200] Report: jobs=10 D [10/May/2009:15:19:32 +0200] Report: jobs-active=1 D [10/May/2009:15:19:32 +0200] Report: printers=2 D [10/May/2009:15:19:32 +0200] Report: printers-implicit=0 D [10/May/2009:15:19:32 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:19:32 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:19:32 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:20:38 +0200] Report: clients=0 D [10/May/2009:15:20:38 +0200] Report: jobs=10 D [10/May/2009:15:20:38 +0200] Report: jobs-active=1 D [10/May/2009:15:20:38 +0200] Report: printers=2 D [10/May/2009:15:20:38 +0200] Report: printers-implicit=0 D [10/May/2009:15:20:38 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:20:38 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:20:38 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:21:44 +0200] Report: clients=0 D [10/May/2009:15:21:44 +0200] Report: jobs=10 D [10/May/2009:15:21:44 +0200] Report: jobs-active=1 D [10/May/2009:15:21:44 +0200] Report: printers=2 D [10/May/2009:15:21:44 +0200] Report: printers-implicit=0 D [10/May/2009:15:21:44 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:21:44 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:21:44 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:22:50 +0200] Report: clients=0 D [10/May/2009:15:22:50 +0200] Report: jobs=10 D [10/May/2009:15:22:50 +0200] Report: jobs-active=1 D [10/May/2009:15:22:50 +0200] Report: printers=2 D [10/May/2009:15:22:50 +0200] Report: printers-implicit=0 D [10/May/2009:15:22:50 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:22:50 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:22:50 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:23:56 +0200] Report: clients=0 D [10/May/2009:15:23:56 +0200] Report: jobs=10 D [10/May/2009:15:23:56 +0200] Report: jobs-active=1 D [10/May/2009:15:23:56 +0200] Report: printers=2 D [10/May/2009:15:23:56 +0200] Report: printers-implicit=0 D [10/May/2009:15:23:56 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:23:56 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:23:56 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:25:02 +0200] Report: clients=0 D [10/May/2009:15:25:02 +0200] Report: jobs=10 D [10/May/2009:15:25:02 +0200] Report: jobs-active=1 D [10/May/2009:15:25:02 +0200] Report: printers=2 D [10/May/2009:15:25:02 +0200] Report: printers-implicit=0 D [10/May/2009:15:25:02 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:25:02 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:25:02 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:26:08 +0200] Report: clients=0 D [10/May/2009:15:26:08 +0200] Report: jobs=10 D [10/May/2009:15:26:08 +0200] Report: jobs-active=1 D [10/May/2009:15:26:08 +0200] Report: printers=2 D [10/May/2009:15:26:08 +0200] Report: printers-implicit=0 D [10/May/2009:15:26:08 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:26:08 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:26:08 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:27:14 +0200] Report: clients=0 D [10/May/2009:15:27:14 +0200] Report: jobs=10 D [10/May/2009:15:27:14 +0200] Report: jobs-active=1 D [10/May/2009:15:27:14 +0200] Report: printers=2 D [10/May/2009:15:27:14 +0200] Report: printers-implicit=0 D [10/May/2009:15:27:14 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:27:14 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:27:14 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:28:20 +0200] Report: clients=0 D [10/May/2009:15:28:20 +0200] Report: jobs=10 D [10/May/2009:15:28:20 +0200] Report: jobs-active=1 D [10/May/2009:15:28:20 +0200] Report: printers=2 D [10/May/2009:15:28:20 +0200] Report: printers-implicit=0 D [10/May/2009:15:28:20 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:28:20 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:28:20 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:29:19 +0200] [Job 10] Found: %%PageTrailer D [10/May/2009:15:29:19 +0200] [Job 10] --> Continue DSC parsing now. D [10/May/2009:15:29:19 +0200] [Job 10] D [10/May/2009:15:29:19 +0200] [Job 10] Found: %%Trailer D [10/May/2009:15:29:19 +0200] [Job 10] --> Continue DSC parsing now. D [10/May/2009:15:29:19 +0200] [Job 10] D [10/May/2009:15:29:19 +0200] [Job 10] Found: %%Pages: 1 D [10/May/2009:15:29:19 +0200] [Job 10] --> Continue DSC parsing now. D [10/May/2009:15:29:19 +0200] [Job 10] D [10/May/2009:15:29:19 +0200] [Job 10] Found: %%BoundingBox: 18 86 578 778 D [10/May/2009:15:29:19 +0200] [Job 10] --> Continue DSC parsing now. D [10/May/2009:15:29:19 +0200] [Job 10] D [10/May/2009:15:29:19 +0200] [Job 10] Found: %%HiResBoundingBox: 18.000000 86.600000 577.300000 777.800000 D [10/May/2009:15:29:19 +0200] [Job 10] --> Continue DSC parsing now. D [10/May/2009:15:29:19 +0200] [Job 10] D [10/May/2009:15:29:19 +0200] [Job 10] Found: %%EOF D [10/May/2009:15:29:19 +0200] [Job 10] --> Continue DSC parsing now. D [10/May/2009:15:29:19 +0200] [Job 10] D [10/May/2009:15:29:20 +0200] [Job 10] D [10/May/2009:15:29:20 +0200] [Job 10] Closing renderer D [10/May/2009:15:29:20 +0200] Report: clients=0 D [10/May/2009:15:29:20 +0200] Report: jobs=10 D [10/May/2009:15:29:20 +0200] Report: jobs-active=1 D [10/May/2009:15:29:20 +0200] Report: printers=2 D [10/May/2009:15:29:20 +0200] Report: printers-implicit=0 D [10/May/2009:15:29:20 +0200] Report: stringpool-string-count=2590 D [10/May/2009:15:29:20 +0200] Report: stringpool-alloc-bytes=10168 D [10/May/2009:15:29:20 +0200] Report: stringpool-total-bytes=55872 D [10/May/2009:15:29:29 +0200] Discarding unused job-progress event... E [10/May/2009:15:29:29 +0200] PID 13800 (/usr/lib/cups/backend/socket) stopped with status 1! D [10/May/2009:15:29:29 +0200] [Job 10] Read 8192 bytes of print data... D [10/May/2009:15:29:29 +0200] [Job 10] Wrote 8192 bytes of print data... D [10/May/2009:15:29:29 +0200] [Job 10] Read 8192 bytes of print data... E [10/May/2009:15:29:29 +0200] [Job 10] Unable to write print data: Broken pipe I [10/May/2009:15:29:29 +0200] Saving subscriptions.conf... D [10/May/2009:15:29:29 +0200] [Job 10] renderer exited with status 0 D [10/May/2009:15:29:29 +0200] [Job 10] kid4 exited with status 0 D [10/May/2009:15:29:29 +0200] [Job 10] kid3 finished D [10/May/2009:15:29:29 +0200] [Job 10] kid3 exited with status 0 D [10/May/2009:15:29:29 +0200] [Job 10] pdf-to-ps exited with status 0 D [10/May/2009:15:29:29 +0200] [Job 10] D [10/May/2009:15:29:29 +0200] [Job 10] Closing foomatic-rip. D [10/May/2009:15:29:29 +0200] PID 13799 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [10/May/2009:15:29:29 +0200] [Job 10] File 0 is complete. I [10/May/2009:15:29:29 +0200] [Job 10] Backend returned status 1 (failed) I [10/May/2009:15:29:29 +0200] Saving subscriptions.conf... D [10/May/2009:15:29:29 +0200] set_hold_until: hold_until = 1241962469 I [10/May/2009:15:29:29 +0200] Saving subscriptions.conf... D [10/May/2009:15:29:30 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:29:30 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:29:30 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:29:30 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:29:30 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:29:30 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:29:30 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:15:29:30 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:29:30 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:29:30 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:29:30 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:29:30 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:15:29:30 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:29:30 +0200] Get-Notifications / D [10/May/2009:15:29:30 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:29:30 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:15:29:30 +0200] cupsdCloseClient: 9 D [10/May/2009:15:29:30 +0200] cupsdCloseClient: 12 D [10/May/2009:15:29:30 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:29:30 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:29:30 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:29:30 +0200] Get-Notifications / D [10/May/2009:15:29:30 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:29:30 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:29:30 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:29:30 +0200] cupsdCloseClient: 13 D [10/May/2009:15:29:30 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:29:30 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:29:30 +0200] Get-Printer-Attributes ipp://boromir:631/printers/magicolor-2530-DL D [10/May/2009:15:29:30 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:29:30 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:29:30 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:29:30 +0200] Get-Job-Attributes ipp://localhost/jobs/10 D [10/May/2009:15:29:30 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:29:30 +0200] cupsdCloseClient: 9 D [10/May/2009:15:30:26 +0200] Report: clients=1 D [10/May/2009:15:30:26 +0200] Report: jobs=10 D [10/May/2009:15:30:26 +0200] Report: jobs-active=1 D [10/May/2009:15:30:26 +0200] Report: printers=2 D [10/May/2009:15:30:26 +0200] Report: printers-implicit=0 D [10/May/2009:15:30:26 +0200] Report: stringpool-string-count=2740 D [10/May/2009:15:30:26 +0200] Report: stringpool-alloc-bytes=10152 D [10/May/2009:15:30:26 +0200] Report: stringpool-total-bytes=59360 D [10/May/2009:15:31:32 +0200] Report: clients=1 D [10/May/2009:15:31:32 +0200] Report: jobs=10 D [10/May/2009:15:31:32 +0200] Report: jobs-active=1 D [10/May/2009:15:31:32 +0200] Report: printers=2 D [10/May/2009:15:31:32 +0200] Report: printers-implicit=0 D [10/May/2009:15:31:32 +0200] Report: stringpool-string-count=2740 D [10/May/2009:15:31:32 +0200] Report: stringpool-alloc-bytes=10152 D [10/May/2009:15:31:32 +0200] Report: stringpool-total-bytes=59360 D [10/May/2009:15:32:38 +0200] Report: clients=1 D [10/May/2009:15:32:38 +0200] Report: jobs=10 D [10/May/2009:15:32:38 +0200] Report: jobs-active=1 D [10/May/2009:15:32:38 +0200] Report: printers=2 D [10/May/2009:15:32:38 +0200] Report: printers-implicit=0 D [10/May/2009:15:32:38 +0200] Report: stringpool-string-count=2740 D [10/May/2009:15:32:38 +0200] Report: stringpool-alloc-bytes=10152 D [10/May/2009:15:32:38 +0200] Report: stringpool-total-bytes=59360 D [10/May/2009:15:33:44 +0200] Report: clients=1 D [10/May/2009:15:33:44 +0200] Report: jobs=10 D [10/May/2009:15:33:44 +0200] Report: jobs-active=1 D [10/May/2009:15:33:44 +0200] Report: printers=2 D [10/May/2009:15:33:44 +0200] Report: printers-implicit=0 D [10/May/2009:15:33:44 +0200] Report: stringpool-string-count=2740 D [10/May/2009:15:33:44 +0200] Report: stringpool-alloc-bytes=10152 D [10/May/2009:15:33:44 +0200] Report: stringpool-total-bytes=59360 D [10/May/2009:15:34:31 +0200] Closing client 12 after 300 seconds of inactivity... D [10/May/2009:15:34:31 +0200] cupsdCloseClient: 12 I [10/May/2009:15:34:42 +0200] Saving subscriptions.conf... D [10/May/2009:15:34:42 +0200] [Job 10] job-sheets=none,none D [10/May/2009:15:34:42 +0200] [Job 10] banner_page = 0 D [10/May/2009:15:34:42 +0200] [Job 10] argv[0]="magicolor-2530-DL" D [10/May/2009:15:34:42 +0200] [Job 10] argv[1]="10" D [10/May/2009:15:34:42 +0200] [Job 10] argv[2]="laurent" D [10/May/2009:15:34:42 +0200] [Job 10] argv[3]="out.pnm" D [10/May/2009:15:34:42 +0200] [Job 10] argv[4]="1" D [10/May/2009:15:34:42 +0200] [Job 10] argv[5]="Resolution=600x600dpi Quality=normal MediaType=plain NupOrient=port ColorMode=PhotosAndText PageSize=A4 Nup=1up number-up=1 ICM=none Duplex=None InputSlot=Auto job-uuid=urn:uuid:38e68a18-4ada-32ef-5687-e038b743aae9" D [10/May/2009:15:34:42 +0200] [Job 10] argv[6]="/var/spool/cups/d00010-001" D [10/May/2009:15:34:42 +0200] [Job 10] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [10/May/2009:15:34:42 +0200] [Job 10] envp[1]="CUPS_DATADIR=/usr/share/cups" D [10/May/2009:15:34:42 +0200] [Job 10] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [10/May/2009:15:34:42 +0200] [Job 10] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [10/May/2009:15:34:42 +0200] [Job 10] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [10/May/2009:15:34:42 +0200] [Job 10] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [10/May/2009:15:34:42 +0200] [Job 10] envp[6]="CUPS_SERVERROOT=/etc/cups" D [10/May/2009:15:34:42 +0200] [Job 10] envp[7]="CUPS_STATEDIR=/var/run/cups" D [10/May/2009:15:34:42 +0200] [Job 10] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [10/May/2009:15:34:42 +0200] [Job 10] envp[9]="SERVER_ADMIN=root@boromir" D [10/May/2009:15:34:42 +0200] [Job 10] envp[10]="SOFTWARE=CUPS/1.3.9" D [10/May/2009:15:34:42 +0200] [Job 10] envp[11]="TMPDIR=/var/spool/cups/tmp" D [10/May/2009:15:34:42 +0200] [Job 10] envp[12]="TZ=Europe/Paris" D [10/May/2009:15:34:42 +0200] [Job 10] envp[13]="USER=root" D [10/May/2009:15:34:42 +0200] [Job 10] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [10/May/2009:15:34:42 +0200] [Job 10] envp[15]="CUPS_ENCRYPTION=IfRequested" D [10/May/2009:15:34:42 +0200] [Job 10] envp[16]="IPP_PORT=631" D [10/May/2009:15:34:42 +0200] [Job 10] envp[17]="CHARSET=utf-8" D [10/May/2009:15:34:42 +0200] [Job 10] envp[18]="LANG=fr_FR.UTF8" D [10/May/2009:15:34:42 +0200] [Job 10] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd" D [10/May/2009:15:34:42 +0200] [Job 10] envp[20]="RIP_MAX_CACHE=8m" D [10/May/2009:15:34:42 +0200] [Job 10] envp[21]="CONTENT_TYPE=application/pdf" D [10/May/2009:15:34:42 +0200] [Job 10] envp[22]="DEVICE_URI=socket://192.168.1.117:9100" D [10/May/2009:15:34:42 +0200] [Job 10] envp[23]="PRINTER=magicolor-2530-DL" D [10/May/2009:15:34:42 +0200] [Job 10] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL" I [10/May/2009:15:34:42 +0200] [Job 10] Started filter /usr/lib/cups/filter/pdftopdf (PID 14597) I [10/May/2009:15:34:42 +0200] [Job 10] Started filter /usr/lib/cups/filter/foomatic-rip (PID 14598) I [10/May/2009:15:34:42 +0200] [Job 10] Started backend /usr/lib/cups/backend/socket (PID 14599) I [10/May/2009:15:34:42 +0200] Saving subscriptions.conf... D [10/May/2009:15:34:42 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:34:42 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:34:42 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:34:42 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:34:42 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:34:42 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:34:42 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:34:42 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:34:42 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:34:42 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:34:42 +0200] cupsdCloseClient: 12 D [10/May/2009:15:34:42 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:34:42 +0200] cupsdCloseClient: 9 D [10/May/2009:15:34:42 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:34:42 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:34:42 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:34:42 +0200] Get-Notifications / D [10/May/2009:15:34:42 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:34:42 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:34:42 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:34:42 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:34:42 +0200] Get-Notifications / D [10/May/2009:15:34:42 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:34:42 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:34:42 +0200] cupsdCloseClient: 9 D [10/May/2009:15:34:42 +0200] cupsdCloseClient: 12 I [10/May/2009:15:34:42 +0200] Saving subscriptions.conf... D [10/May/2009:15:34:42 +0200] [Job 10] Connected to 192.168.1.117:9100 (IPv4)... D [10/May/2009:15:34:42 +0200] [Job 10] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb7ffbfc0) I [10/May/2009:15:34:42 +0200] Saving subscriptions.conf... D [10/May/2009:15:34:43 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:34:43 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:34:43 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:34:43 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:34:43 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:34:43 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:34:43 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:34:43 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:34:43 +0200] Get-Notifications / D [10/May/2009:15:34:43 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:34:43 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:34:43 +0200] cupsdCloseClient: 12 D [10/May/2009:15:34:43 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:34:43 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:34:43 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:34:43 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:34:43 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:34:43 +0200] cupsdCloseClient: 12 D [10/May/2009:15:34:43 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:34:43 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:34:43 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:34:43 +0200] Get-Notifications / D [10/May/2009:15:34:43 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:34:43 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:34:43 +0200] cupsdCloseClient: 12 D [10/May/2009:15:34:43 +0200] cupsdCloseClient: 9 D [10/May/2009:15:34:43 +0200] [Job 10] Getting input from file D [10/May/2009:15:34:43 +0200] [Job 10] foomatic-rip version 4.0.0.195 running... D [10/May/2009:15:34:43 +0200] [Job 10] Parsing PPD file ... D [10/May/2009:15:34:43 +0200] [Job 10] Added option PageSize D [10/May/2009:15:34:43 +0200] [Job 10] Added option Quality D [10/May/2009:15:34:43 +0200] [Job 10] Added option PrinterType D [10/May/2009:15:34:43 +0200] [Job 10] Added option ColorMode D [10/May/2009:15:34:43 +0200] [Job 10] Added option Resolution D [10/May/2009:15:34:43 +0200] [Job 10] Added option ImageableArea D [10/May/2009:15:34:43 +0200] [Job 10] Added option PaperDimension D [10/May/2009:15:34:43 +0200] [Job 10] Added option InputSlot D [10/May/2009:15:34:43 +0200] [Job 10] Added option MediaType D [10/May/2009:15:34:43 +0200] [Job 10] Added option Duplex D [10/May/2009:15:34:43 +0200] [Job 10] Added option Copies D [10/May/2009:15:34:43 +0200] [Job 10] Added option ICM D [10/May/2009:15:34:43 +0200] [Job 10] Added option Nup D [10/May/2009:15:34:43 +0200] [Job 10] Added option NupOrient D [10/May/2009:15:34:43 +0200] [Job 10] Added option Font D [10/May/2009:15:34:43 +0200] [Job 10] D [10/May/2009:15:34:43 +0200] [Job 10] Parameter Summary D [10/May/2009:15:34:43 +0200] [Job 10] ----------------- D [10/May/2009:15:34:43 +0200] [Job 10] D [10/May/2009:15:34:43 +0200] [Job 10] Spooler: cups D [10/May/2009:15:34:43 +0200] [Job 10] Printer: magicolor-2530-DL D [10/May/2009:15:34:43 +0200] [Job 10] Shell: /bin/bash D [10/May/2009:15:34:43 +0200] [Job 10] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd D [10/May/2009:15:34:43 +0200] [Job 10] ATTR file: D [10/May/2009:15:34:43 +0200] [Job 10] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended) D [10/May/2009:15:34:43 +0200] [Job 10] Job title: out.pnm D [10/May/2009:15:34:43 +0200] [Job 10] File(s) to be printed: D [10/May/2009:15:34:43 +0200] [Job 10] D [10/May/2009:15:34:43 +0200] [Job 10] D [10/May/2009:15:34:43 +0200] [Job 10] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [10/May/2009:15:34:43 +0200] [Job 10] Printing system options: D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'number-up=1' D [10/May/2009:15:34:43 +0200] [Job 10] Unknown option number-up=1. D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'job-uuid=urn:uuid:38e68a18-4ada-32ef-5687-e038b743aae9' D [10/May/2009:15:34:43 +0200] [Job 10] Unknown option job-uuid=urn:uuid:38e68a18-4ada-32ef-5687-e038b743aae9. D [10/May/2009:15:34:43 +0200] [Job 10] Options from the PPD file: D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'Resolution=600x600dpi' D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'Quality=normal' D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'MediaType=plain' D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'NupOrient=port' D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'ColorMode=PhotosAndText' D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'PageSize=A4' D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'Nup=1up' D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'ICM=none' D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'Duplex=None' D [10/May/2009:15:34:43 +0200] [Job 10] Pondering option 'InputSlot=Auto' D [10/May/2009:15:34:43 +0200] [Job 10] D [10/May/2009:15:34:43 +0200] [Job 10] ================================================ D [10/May/2009:15:34:43 +0200] [Job 10] D [10/May/2009:15:34:43 +0200] [Job 10] File: D [10/May/2009:15:34:43 +0200] [Job 10] D [10/May/2009:15:34:43 +0200] [Job 10] ================================================ D [10/May/2009:15:34:43 +0200] [Job 10] D [10/May/2009:15:34:43 +0200] [Job 10] Filetype: PDF D [10/May/2009:15:34:43 +0200] [Job 10] Driver does not understand PDF input, converting to PostScript D [10/May/2009:15:34:43 +0200] [Job 10] Starting process "pdf-to-ps" (generation 1) D [10/May/2009:15:34:44 +0200] Report: clients=0 D [10/May/2009:15:34:44 +0200] Report: jobs=10 D [10/May/2009:15:34:44 +0200] Report: jobs-active=1 D [10/May/2009:15:34:44 +0200] Report: printers=2 D [10/May/2009:15:34:44 +0200] Report: printers-implicit=0 D [10/May/2009:15:34:44 +0200] Report: stringpool-string-count=2963 D [10/May/2009:15:34:44 +0200] Report: stringpool-alloc-bytes=10128 D [10/May/2009:15:34:44 +0200] Report: stringpool-total-bytes=64320 D [10/May/2009:15:34:58 +0200] PID 14597 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [10/May/2009:15:34:59 +0200] [Job 10] Filetype: PostScript D [10/May/2009:15:34:59 +0200] [Job 10] Reading PostScript input ... D [10/May/2009:15:34:59 +0200] [Job 10] --> This document is DSC-conforming! D [10/May/2009:15:34:59 +0200] [Job 10] D [10/May/2009:15:34:59 +0200] [Job 10] ----------- D [10/May/2009:15:34:59 +0200] [Job 10] Found: %%BeginProlog D [10/May/2009:15:34:59 +0200] [Job 10] Inserting option code into "Prolog" section. D [10/May/2009:15:34:59 +0200] [Job 10] Found: %%EndProlog D [10/May/2009:15:34:59 +0200] [Job 10] D [10/May/2009:15:34:59 +0200] [Job 10] ----------- D [10/May/2009:15:34:59 +0200] [Job 10] New page: %%Page: 1 1 D [10/May/2009:15:34:59 +0200] [Job 10] "Setup" section is missing, inserting it. D [10/May/2009:15:34:59 +0200] [Job 10] Inserting PostScript code for CUPS' page accounting D [10/May/2009:15:34:59 +0200] [Job 10] Inserting option code into "Setup" section. D [10/May/2009:15:34:59 +0200] [Job 10] No page header or page header not DSC-conforming D [10/May/2009:15:34:59 +0200] [Job 10] "PageSetup" section is missing, inserting it. D [10/May/2009:15:34:59 +0200] [Job 10] Inserting option code into "PageSetup" section. D [10/May/2009:15:34:59 +0200] [Job 10] Stopping search for page header options D [10/May/2009:15:34:59 +0200] [Job 10] Found: 253 254 r3 D [10/May/2009:15:34:59 +0200] [Job 10] --> Output goes directly to the renderer now. D [10/May/2009:15:34:59 +0200] [Job 10] D [10/May/2009:15:35:00 +0200] [Job 10] D [10/May/2009:15:35:00 +0200] [Job 10] Starting renderer with command: "foo2lava-wrapper -z0 -c -C2 -r600x600 -p26 -m0 -s255 -d1 -Gnone.icm " D [10/May/2009:15:35:00 +0200] [Job 10] Starting process "kid3" (generation 1) D [10/May/2009:15:35:00 +0200] [Job 10] Starting process "kid4" (generation 2) D [10/May/2009:15:35:00 +0200] [Job 10] JCL: %-12345X@PJL D [10/May/2009:15:35:00 +0200] [Job 10] D [10/May/2009:15:35:00 +0200] [Job 10] D [10/May/2009:15:35:00 +0200] [Job 10] Starting process "renderer" (generation 2) D [10/May/2009:15:35:01 +0200] [Job 10] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:35:02 +0200] [Job 10] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:35:02 +0200] [Job 10] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:35:02 +0200] [Job 10] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:35:47 +0200] Report: clients=0 D [10/May/2009:15:35:47 +0200] Report: jobs=10 D [10/May/2009:15:35:47 +0200] Report: jobs-active=1 D [10/May/2009:15:35:47 +0200] Report: printers=2 D [10/May/2009:15:35:47 +0200] Report: printers-implicit=0 D [10/May/2009:15:35:47 +0200] Report: stringpool-string-count=2963 D [10/May/2009:15:35:47 +0200] Report: stringpool-alloc-bytes=10128 D [10/May/2009:15:35:47 +0200] Report: stringpool-total-bytes=64320 D [10/May/2009:15:36:53 +0200] Report: clients=0 D [10/May/2009:15:36:53 +0200] Report: jobs=10 D [10/May/2009:15:36:53 +0200] Report: jobs-active=1 D [10/May/2009:15:36:53 +0200] Report: printers=2 D [10/May/2009:15:36:53 +0200] Report: printers-implicit=0 D [10/May/2009:15:36:53 +0200] Report: stringpool-string-count=2963 D [10/May/2009:15:36:53 +0200] Report: stringpool-alloc-bytes=10128 D [10/May/2009:15:36:53 +0200] Report: stringpool-total-bytes=64320 D [10/May/2009:15:37:59 +0200] Report: clients=0 D [10/May/2009:15:37:59 +0200] Report: jobs=10 D [10/May/2009:15:37:59 +0200] Report: jobs-active=1 D [10/May/2009:15:37:59 +0200] Report: printers=2 D [10/May/2009:15:37:59 +0200] Report: printers-implicit=0 D [10/May/2009:15:37:59 +0200] Report: stringpool-string-count=2963 D [10/May/2009:15:37:59 +0200] Report: stringpool-alloc-bytes=10128 D [10/May/2009:15:37:59 +0200] Report: stringpool-total-bytes=64320 D [10/May/2009:15:38:18 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:38:18 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:38:18 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:18 +0200] Get-Printer-Attributes ipp://localhost/printers/magicolor-2530-DL D [10/May/2009:15:38:18 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:38:18 +0200] cupsdReadClient: 9 POST / HTTP/1.1 D [10/May/2009:15:38:18 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:18 +0200] Get-Printer-Attributes ipp://localhost/printers/magicolor-2530-DL D [10/May/2009:15:38:18 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:38:18 +0200] cupsdReadClient: 9 GET /printers/magicolor-2530-DL.ppd HTTP/1.1 D [10/May/2009:15:38:18 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:51 +0200] cupsdAcceptClient: 12 from localhost (Domain) D [10/May/2009:15:38:51 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:15:38:51 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:15:38:51 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:15:38:51 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:51 +0200] CUPS-Get-Printers D [10/May/2009:15:38:51 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:15:38:51 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:15:38:51 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:51 +0200] Get-Printer-Attributes ipp://localhost/printers/magicolor-2530-DL D [10/May/2009:15:38:51 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:15:38:51 +0200] cupsdAcceptClient: 16 from localhost (Domain) D [10/May/2009:15:38:52 +0200] cupsdReadClient: 16 POST / HTTP/1.1 D [10/May/2009:15:38:52 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:52 +0200] CUPS-Get-Printers D [10/May/2009:15:38:52 +0200] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok) D [10/May/2009:15:38:52 +0200] cupsdReadClient: 16 POST / HTTP/1.1 D [10/May/2009:15:38:52 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:52 +0200] CUPS-Get-Classes D [10/May/2009:15:38:52 +0200] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok) D [10/May/2009:15:38:52 +0200] cupsdReadClient: 16 POST / HTTP/1.1 D [10/May/2009:15:38:52 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:52 +0200] CUPS-Get-Default D [10/May/2009:15:38:52 +0200] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok) D [10/May/2009:15:38:52 +0200] cupsdReadClient: 16 POST / HTTP/1.1 D [10/May/2009:15:38:52 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:52 +0200] CUPS-Get-Printers D [10/May/2009:15:38:52 +0200] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok) D [10/May/2009:15:38:54 +0200] cupsdAcceptClient: 17 from localhost (Domain) D [10/May/2009:15:38:54 +0200] cupsdReadClient: 17 POST / HTTP/1.1 D [10/May/2009:15:38:54 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:54 +0200] CUPS-Get-Printers D [10/May/2009:15:38:54 +0200] cupsdProcessIPPRequest: 17 status_code=0 (successful-ok) D [10/May/2009:15:38:55 +0200] cupsdCloseClient: 17 D [10/May/2009:15:38:55 +0200] cupsdAcceptClient: 17 from localhost (Domain) D [10/May/2009:15:38:55 +0200] cupsdReadClient: 17 POST / HTTP/1.1 D [10/May/2009:15:38:55 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:55 +0200] Get-Printer-Attributes ipp://localhost/printers/magicolor-2530-DL D [10/May/2009:15:38:55 +0200] cupsdProcessIPPRequest: 17 status_code=0 (successful-ok) D [10/May/2009:15:38:55 +0200] cupsdReadClient: 17 GET /printers/magicolor-2530-DL.ppd HTTP/1.1 D [10/May/2009:15:38:55 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:55 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:38:55 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:55 +0200] CUPS-Get-Devices D [10/May/2009:15:38:55 +0200] [CGI] /usr/lib/cups/daemon/cups-deviced started - PID = 14635 I [10/May/2009:15:38:55 +0200] Started "/usr/lib/cups/daemon/cups-deviced" (pid=14635) D [10/May/2009:15:38:55 +0200] cupsdSendCommand: 12 file=18 D [10/May/2009:15:38:55 +0200] [cups-deviced] Added device "lpd"... D [10/May/2009:15:38:55 +0200] [cups-deviced] Added device "hp"... D [10/May/2009:15:38:56 +0200] [cups-deviced] Added device "usb://Brother/HL-5040%20series"... D [10/May/2009:15:38:57 +0200] [cups-deviced] Added device "socket://192.168.1.117"... D [10/May/2009:15:38:57 +0200] [cups-deviced] Added device "socket"... D [10/May/2009:15:38:59 +0200] cupsdAcceptClient: 19 from localhost (Domain) D [10/May/2009:15:38:59 +0200] Report: clients=7 D [10/May/2009:15:38:59 +0200] Report: jobs=10 D [10/May/2009:15:38:59 +0200] Report: jobs-active=1 D [10/May/2009:15:38:59 +0200] Report: printers=2 D [10/May/2009:15:38:59 +0200] Report: printers-implicit=0 D [10/May/2009:15:38:59 +0200] Report: stringpool-string-count=2967 D [10/May/2009:15:38:59 +0200] Report: stringpool-alloc-bytes=10128 D [10/May/2009:15:38:59 +0200] Report: stringpool-total-bytes=64392 D [10/May/2009:15:38:59 +0200] cupsdReadClient: 19 POST / HTTP/1.1 D [10/May/2009:15:38:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:59 +0200] CUPS-Get-Printers D [10/May/2009:15:38:59 +0200] cupsdProcessIPPRequest: 19 status_code=0 (successful-ok) D [10/May/2009:15:38:59 +0200] cupsdReadClient: 19 POST / HTTP/1.1 D [10/May/2009:15:38:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:59 +0200] CUPS-Get-Classes D [10/May/2009:15:38:59 +0200] cupsdProcessIPPRequest: 19 status_code=0 (successful-ok) D [10/May/2009:15:38:59 +0200] cupsdReadClient: 19 POST / HTTP/1.1 D [10/May/2009:15:38:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:59 +0200] CUPS-Get-Default D [10/May/2009:15:38:59 +0200] cupsdProcessIPPRequest: 19 status_code=0 (successful-ok) D [10/May/2009:15:38:59 +0200] cupsdReadClient: 19 POST / HTTP/1.1 D [10/May/2009:15:38:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:59 +0200] CUPS-Get-Printers D [10/May/2009:15:38:59 +0200] cupsdProcessIPPRequest: 19 status_code=0 (successful-ok) D [10/May/2009:15:38:59 +0200] cupsdReadClient: 19 POST / HTTP/1.1 D [10/May/2009:15:38:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:59 +0200] Get-Jobs ipp://localhost/printers/magicolor-2530-DL D [10/May/2009:15:38:59 +0200] cupsdProcessIPPRequest: 19 status_code=0 (successful-ok) D [10/May/2009:15:38:59 +0200] cupsdReadClient: 19 POST / HTTP/1.1 D [10/May/2009:15:38:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:38:59 +0200] CUPS-Get-Printers D [10/May/2009:15:38:59 +0200] cupsdProcessIPPRequest: 19 status_code=0 (successful-ok) D [10/May/2009:15:38:59 +0200] cupsdCloseClient: 19 D [10/May/2009:15:39:01 +0200] [cups-deviced] Added device "hpfax"... D [10/May/2009:15:39:01 +0200] [cups-deviced] Added device "scsi"... D [10/May/2009:15:39:01 +0200] [cups-deviced] Added device "ipp"... D [10/May/2009:15:39:01 +0200] [cups-deviced] Added device "parallel:/dev/lp0"... D [10/May/2009:15:39:01 +0200] [cups-deviced] Added device "http"... D [10/May/2009:15:39:02 +0200] [cups-deviced] Added device "smb"... D [10/May/2009:15:39:02 +0200] [cups-deviced] Added device "beh"... D [10/May/2009:15:39:02 +0200] [cups-deviced] Added device "serial:/dev/ttyS0?baud=115200"... D [10/May/2009:15:39:02 +0200] [cups-deviced] Added device "serial:/dev/ttyS1?baud=115200"... D [10/May/2009:15:39:02 +0200] [cups-deviced] Added device "hal:///org/freedesktop/Hal/devices/usb_device_4f9_16_J3J445586_if0_printer_noserial"... D [10/May/2009:15:39:02 +0200] PID 14635 (/usr/lib/cups/daemon/cups-deviced) exited with no errors. D [10/May/2009:15:39:02 +0200] cupsdAcceptClient: 18 from localhost (Domain) D [10/May/2009:15:39:02 +0200] cupsdReadClient: 18 POST / HTTP/1.1 D [10/May/2009:15:39:02 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:02 +0200] Get-Printer-Attributes ipp://localhost/printers/magicolor-2530-DL D [10/May/2009:15:39:02 +0200] cupsdProcessIPPRequest: 18 status_code=0 (successful-ok) D [10/May/2009:15:39:08 +0200] cupsdAcceptClient: 19 from localhost (Domain) D [10/May/2009:15:39:08 +0200] cupsdCloseClient: 19 D [10/May/2009:15:39:09 +0200] cupsdReadClient: 12 GET /admin/log/error_log HTTP/1.1 D [10/May/2009:15:39:09 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:09 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:39:09 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:09 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:39:09 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:39:09 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:39:09 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:09 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:39:09 +0200] [Job 1] Loading attributes... D [10/May/2009:15:39:09 +0200] [Job 2] Loading attributes... D [10/May/2009:15:39:09 +0200] [Job 3] Loading attributes... D [10/May/2009:15:39:09 +0200] [Job 4] Loading attributes... D [10/May/2009:15:39:09 +0200] [Job 5] Loading attributes... D [10/May/2009:15:39:09 +0200] [Job 6] Loading attributes... D [10/May/2009:15:39:09 +0200] [Job 7] Loading attributes... D [10/May/2009:15:39:09 +0200] [Job 8] Loading attributes... D [10/May/2009:15:39:09 +0200] [Job 9] Loading attributes... D [10/May/2009:15:39:09 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:39:09 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:39:09 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:09 +0200] Create-Printer-Subscription / D [10/May/2009:15:39:09 +0200] cupsdCreateSubscription(con=0xb964baf0(12), uri="/") D [10/May/2009:15:39:09 +0200] pullmethod="ippget" D [10/May/2009:15:39:09 +0200] notify-lease-duration=86400 D [10/May/2009:15:39:09 +0200] notify-time-interval=0 D [10/May/2009:15:39:09 +0200] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)") D [10/May/2009:15:39:09 +0200] Added subscription 10 for server I [10/May/2009:15:39:09 +0200] Saving subscriptions.conf... D [10/May/2009:15:39:09 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:39:10 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:39:10 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:10 +0200] Get-Notifications / D [10/May/2009:15:39:10 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:39:10 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:39:13 +0200] cupsdAcceptClient: 19 from localhost (Domain) D [10/May/2009:15:39:13 +0200] cupsdReadClient: 19 POST /printers/magicolor-2530-DL HTTP/1.1 D [10/May/2009:15:39:13 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:13 +0200] Print-Job ipp://localhost/printers/magicolor-2530-DL D [10/May/2009:15:39:13 +0200] [Job ???] Auto-typing file... I [10/May/2009:15:39:13 +0200] [Job ???] Request file type is application/postscript. D [10/May/2009:15:39:13 +0200] add_job: requesting-user-name="laurent" D [10/May/2009:15:39:13 +0200] Adding default job-sheets values "none,none"... I [10/May/2009:15:39:13 +0200] [Job 11] Adding start banner page "none". I [10/May/2009:15:39:13 +0200] Saving subscriptions.conf... I [10/May/2009:15:39:13 +0200] [Job 11] Adding end banner page "none". I [10/May/2009:15:39:13 +0200] [Job 11] File of type application/postscript queued by "laurent". D [10/May/2009:15:39:13 +0200] [Job 11] hold_until=0 I [10/May/2009:15:39:13 +0200] [Job 11] Queued on "magicolor-2530-DL" by "laurent". D [10/May/2009:15:39:13 +0200] cupsdProcessIPPRequest: 19 status_code=0 (successful-ok) D [10/May/2009:15:39:14 +0200] cupsdAcceptClient: 20 from localhost (Domain) D [10/May/2009:15:39:14 +0200] cupsdReadClient: 20 POST / HTTP/1.1 D [10/May/2009:15:39:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:14 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:39:14 +0200] cupsdProcessIPPRequest: 20 status_code=0 (successful-ok) D [10/May/2009:15:39:14 +0200] cupsdCloseClient: 20 D [10/May/2009:15:39:14 +0200] cupsdAcceptClient: 20 from localhost (Domain) D [10/May/2009:15:39:14 +0200] cupsdReadClient: 20 POST / HTTP/1.1 D [10/May/2009:15:39:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:14 +0200] Get-Notifications / D [10/May/2009:15:39:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:39:14 +0200] cupsdProcessIPPRequest: 20 status_code=0 (successful-ok) D [10/May/2009:15:39:14 +0200] cupsdCloseClient: 20 D [10/May/2009:15:39:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:39:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:14 +0200] Get-Notifications / D [10/May/2009:15:39:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:39:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:39:14 +0200] cupsdAcceptClient: 20 from localhost (Domain) D [10/May/2009:15:39:14 +0200] cupsdReadClient: 20 POST / HTTP/1.1 D [10/May/2009:15:39:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:14 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:39:14 +0200] cupsdProcessIPPRequest: 20 status_code=0 (successful-ok) D [10/May/2009:15:39:14 +0200] cupsdCloseClient: 20 D [10/May/2009:15:39:14 +0200] cupsdAcceptClient: 20 from localhost (Domain) D [10/May/2009:15:39:14 +0200] cupsdReadClient: 20 POST / HTTP/1.1 D [10/May/2009:15:39:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:14 +0200] Get-Notifications / D [10/May/2009:15:39:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:39:14 +0200] cupsdProcessIPPRequest: 20 status_code=0 (successful-ok) D [10/May/2009:15:39:14 +0200] cupsdReadClient: 20 POST / HTTP/1.1 D [10/May/2009:15:39:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:39:14 +0200] Get-Job-Attributes ipp://localhost/jobs/11 D [10/May/2009:15:39:14 +0200] cupsdProcessIPPRequest: 20 status_code=0 (successful-ok) D [10/May/2009:15:39:14 +0200] cupsdCloseClient: 20 D [10/May/2009:15:39:59 +0200] Report: clients=8 D [10/May/2009:15:39:59 +0200] Report: jobs=11 D [10/May/2009:15:39:59 +0200] Report: jobs-active=2 D [10/May/2009:15:39:59 +0200] Report: printers=2 D [10/May/2009:15:39:59 +0200] Report: printers-implicit=0 D [10/May/2009:15:39:59 +0200] Report: stringpool-string-count=3524 D [10/May/2009:15:39:59 +0200] Report: stringpool-alloc-bytes=10984 D [10/May/2009:15:39:59 +0200] Report: stringpool-total-bytes=74800 D [10/May/2009:15:40:10 +0200] [Job 1] Unloading... D [10/May/2009:15:40:10 +0200] [Job 2] Unloading... D [10/May/2009:15:40:10 +0200] [Job 3] Unloading... D [10/May/2009:15:40:10 +0200] [Job 4] Unloading... D [10/May/2009:15:40:10 +0200] [Job 5] Unloading... D [10/May/2009:15:40:10 +0200] [Job 6] Unloading... D [10/May/2009:15:40:10 +0200] [Job 7] Unloading... D [10/May/2009:15:40:10 +0200] [Job 8] Unloading... D [10/May/2009:15:40:10 +0200] [Job 9] Unloading... D [10/May/2009:15:40:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:40:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:40:14 +0200] Get-Notifications / D [10/May/2009:15:40:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:40:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:40:59 +0200] Report: clients=8 D [10/May/2009:15:40:59 +0200] Report: jobs=11 D [10/May/2009:15:40:59 +0200] Report: jobs-active=2 D [10/May/2009:15:40:59 +0200] Report: printers=2 D [10/May/2009:15:40:59 +0200] Report: printers-implicit=0 D [10/May/2009:15:40:59 +0200] Report: stringpool-string-count=3077 D [10/May/2009:15:40:59 +0200] Report: stringpool-alloc-bytes=10240 D [10/May/2009:15:40:59 +0200] Report: stringpool-total-bytes=66592 D [10/May/2009:15:41:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:41:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:41:14 +0200] Get-Notifications / D [10/May/2009:15:41:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:41:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:41:59 +0200] Report: clients=8 D [10/May/2009:15:41:59 +0200] Report: jobs=11 D [10/May/2009:15:41:59 +0200] Report: jobs-active=2 D [10/May/2009:15:41:59 +0200] Report: printers=2 D [10/May/2009:15:41:59 +0200] Report: printers-implicit=0 D [10/May/2009:15:41:59 +0200] Report: stringpool-string-count=3077 D [10/May/2009:15:41:59 +0200] Report: stringpool-alloc-bytes=10240 D [10/May/2009:15:41:59 +0200] Report: stringpool-total-bytes=66592 D [10/May/2009:15:42:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:42:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:42:14 +0200] Get-Notifications / D [10/May/2009:15:42:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:42:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:42:59 +0200] Report: clients=8 D [10/May/2009:15:42:59 +0200] Report: jobs=11 D [10/May/2009:15:42:59 +0200] Report: jobs-active=2 D [10/May/2009:15:42:59 +0200] Report: printers=2 D [10/May/2009:15:42:59 +0200] Report: printers-implicit=0 D [10/May/2009:15:42:59 +0200] Report: stringpool-string-count=3077 D [10/May/2009:15:42:59 +0200] Report: stringpool-alloc-bytes=10240 D [10/May/2009:15:42:59 +0200] Report: stringpool-total-bytes=66592 D [10/May/2009:15:43:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:43:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:43:14 +0200] Get-Notifications / D [10/May/2009:15:43:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:43:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:43:20 +0200] Closing client 9 after 300 seconds of inactivity... D [10/May/2009:15:43:20 +0200] cupsdCloseClient: 9 D [10/May/2009:15:43:52 +0200] Closing client 13 after 300 seconds of inactivity... D [10/May/2009:15:43:52 +0200] cupsdCloseClient: 13 D [10/May/2009:15:43:52 +0200] Closing client 14 after 300 seconds of inactivity... D [10/May/2009:15:43:52 +0200] cupsdCloseClient: 14 D [10/May/2009:15:43:53 +0200] Closing client 16 after 300 seconds of inactivity... D [10/May/2009:15:43:53 +0200] cupsdCloseClient: 16 D [10/May/2009:15:43:56 +0200] Closing client 17 after 300 seconds of inactivity... D [10/May/2009:15:43:56 +0200] cupsdCloseClient: 17 D [10/May/2009:15:44:03 +0200] Closing client 18 after 300 seconds of inactivity... D [10/May/2009:15:44:03 +0200] cupsdCloseClient: 18 D [10/May/2009:15:44:03 +0200] Report: clients=2 D [10/May/2009:15:44:03 +0200] Report: jobs=11 D [10/May/2009:15:44:03 +0200] Report: jobs-active=2 D [10/May/2009:15:44:03 +0200] Report: printers=2 D [10/May/2009:15:44:03 +0200] Report: printers-implicit=0 D [10/May/2009:15:44:03 +0200] Report: stringpool-string-count=3077 D [10/May/2009:15:44:03 +0200] Report: stringpool-alloc-bytes=10240 D [10/May/2009:15:44:03 +0200] Report: stringpool-total-bytes=66592 D [10/May/2009:15:44:14 +0200] Closing client 19 after 300 seconds of inactivity... D [10/May/2009:15:44:14 +0200] cupsdCloseClient: 19 D [10/May/2009:15:44:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:44:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:44:14 +0200] Get-Notifications / D [10/May/2009:15:44:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:44:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:45:10 +0200] Report: clients=1 D [10/May/2009:15:45:10 +0200] Report: jobs=11 D [10/May/2009:15:45:10 +0200] Report: jobs-active=2 D [10/May/2009:15:45:10 +0200] Report: printers=2 D [10/May/2009:15:45:10 +0200] Report: printers-implicit=0 D [10/May/2009:15:45:10 +0200] Report: stringpool-string-count=3077 D [10/May/2009:15:45:10 +0200] Report: stringpool-alloc-bytes=10240 D [10/May/2009:15:45:10 +0200] Report: stringpool-total-bytes=66592 D [10/May/2009:15:45:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:45:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:45:14 +0200] Get-Notifications / D [10/May/2009:15:45:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:45:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:46:10 +0200] Report: clients=1 D [10/May/2009:15:46:10 +0200] Report: jobs=11 D [10/May/2009:15:46:10 +0200] Report: jobs-active=2 D [10/May/2009:15:46:10 +0200] Report: printers=2 D [10/May/2009:15:46:10 +0200] Report: printers-implicit=0 D [10/May/2009:15:46:10 +0200] Report: stringpool-string-count=3077 D [10/May/2009:15:46:10 +0200] Report: stringpool-alloc-bytes=10240 D [10/May/2009:15:46:10 +0200] Report: stringpool-total-bytes=66592 D [10/May/2009:15:46:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:46:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:46:14 +0200] Get-Notifications / D [10/May/2009:15:46:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:46:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:47:10 +0200] Report: clients=1 D [10/May/2009:15:47:10 +0200] Report: jobs=11 D [10/May/2009:15:47:10 +0200] Report: jobs-active=2 D [10/May/2009:15:47:10 +0200] Report: printers=2 D [10/May/2009:15:47:10 +0200] Report: printers-implicit=0 D [10/May/2009:15:47:10 +0200] Report: stringpool-string-count=3077 D [10/May/2009:15:47:10 +0200] Report: stringpool-alloc-bytes=10240 D [10/May/2009:15:47:10 +0200] Report: stringpool-total-bytes=66592 D [10/May/2009:15:47:14 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:47:14 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:14 +0200] Get-Notifications / D [10/May/2009:15:47:14 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:47:14 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:47:34 +0200] cupsdAcceptClient: 9 from localhost (Domain) D [10/May/2009:15:47:34 +0200] cupsdReadClient: 9 POST /jobs/ HTTP/1.1 D [10/May/2009:15:47:34 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:34 +0200] Cancel-Job ipp://localhost/jobs/10 D [10/May/2009:15:47:34 +0200] cupsdIsAuthorized: requesting-user-name="laurent" I [10/May/2009:15:47:34 +0200] Saving subscriptions.conf... I [10/May/2009:15:47:34 +0200] Saving subscriptions.conf... I [10/May/2009:15:47:34 +0200] Saving subscriptions.conf... D [10/May/2009:15:47:34 +0200] [Job 11] job-sheets=none,none D [10/May/2009:15:47:34 +0200] [Job 11] banner_page = 0 D [10/May/2009:15:47:34 +0200] [Job 11] argv[0]="magicolor-2530-DL" D [10/May/2009:15:47:34 +0200] [Job 11] argv[1]="11" D [10/May/2009:15:47:34 +0200] [Job 11] argv[2]="laurent" D [10/May/2009:15:47:34 +0200] [Job 11] argv[3]="Test Page" D [10/May/2009:15:47:34 +0200] [Job 11] argv[4]="1" D [10/May/2009:15:47:34 +0200] [Job 11] argv[5]="job-uuid=urn:uuid:5ccab7f2-b8be-31ca-40a7-78692a2822ad" D [10/May/2009:15:47:34 +0200] [Job 11] argv[6]="/var/spool/cups/d00011-001" D [10/May/2009:15:47:34 +0200] [Job 11] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [10/May/2009:15:47:34 +0200] [Job 11] envp[1]="CUPS_DATADIR=/usr/share/cups" D [10/May/2009:15:47:34 +0200] [Job 11] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root" D [10/May/2009:15:47:34 +0200] [Job 11] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [10/May/2009:15:47:34 +0200] [Job 11] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [10/May/2009:15:47:34 +0200] [Job 11] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [10/May/2009:15:47:34 +0200] [Job 11] envp[6]="CUPS_SERVERROOT=/etc/cups" D [10/May/2009:15:47:34 +0200] [Job 11] envp[7]="CUPS_STATEDIR=/var/run/cups" D [10/May/2009:15:47:34 +0200] [Job 11] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [10/May/2009:15:47:34 +0200] [Job 11] envp[9]="SERVER_ADMIN=root@boromir" D [10/May/2009:15:47:34 +0200] [Job 11] envp[10]="SOFTWARE=CUPS/1.3.9" D [10/May/2009:15:47:34 +0200] [Job 11] envp[11]="TMPDIR=/var/spool/cups/tmp" D [10/May/2009:15:47:34 +0200] [Job 11] envp[12]="TZ=Europe/Paris" D [10/May/2009:15:47:34 +0200] [Job 11] envp[13]="USER=root" D [10/May/2009:15:47:34 +0200] [Job 11] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock" D [10/May/2009:15:47:34 +0200] [Job 11] envp[15]="CUPS_ENCRYPTION=IfRequested" D [10/May/2009:15:47:34 +0200] [Job 11] envp[16]="IPP_PORT=631" D [10/May/2009:15:47:34 +0200] [Job 11] envp[17]="CHARSET=utf-8" D [10/May/2009:15:47:34 +0200] [Job 11] envp[18]="LANG=fr_FR.UTF8" D [10/May/2009:15:47:34 +0200] [Job 11] envp[19]="PPD=/etc/cups/ppd/magicolor-2530-DL.ppd" D [10/May/2009:15:47:34 +0200] [Job 11] envp[20]="RIP_MAX_CACHE=8m" D [10/May/2009:15:47:34 +0200] [Job 11] envp[21]="CONTENT_TYPE=application/postscript" D [10/May/2009:15:47:34 +0200] [Job 11] envp[22]="DEVICE_URI=socket://192.168.1.117:9100" D [10/May/2009:15:47:34 +0200] [Job 11] envp[23]="PRINTER=magicolor-2530-DL" D [10/May/2009:15:47:34 +0200] [Job 11] envp[24]="FINAL_CONTENT_TYPE=printer/magicolor-2530-DL" I [10/May/2009:15:47:34 +0200] [Job 11] Started filter /usr/lib/cups/filter/pstopdf (PID 14954) I [10/May/2009:15:47:34 +0200] [Job 11] Started filter /usr/lib/cups/filter/pdftopdf (PID 14956) I [10/May/2009:15:47:34 +0200] [Job 11] Started filter /usr/lib/cups/filter/foomatic-rip (PID 14957) I [10/May/2009:15:47:34 +0200] [Job 11] Started backend /usr/lib/cups/backend/socket (PID 14958) I [10/May/2009:15:47:34 +0200] Saving subscriptions.conf... I [10/May/2009:15:47:34 +0200] [Job 10] Canceled by "laurent". D [10/May/2009:15:47:34 +0200] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok) D [10/May/2009:15:47:34 +0200] [Job 11] pstopdf 6 args: 11 laurent Test Page 1 job-uuid=urn:uuid:5ccab7f2-b8be-31ca-40a7-78692a2822ad /var/spool/cups/d00011-001 D [10/May/2009:15:47:34 +0200] [Job 11] PPD: /etc/cups/ppd/magicolor-2530-DL.ppd I [10/May/2009:15:47:34 +0200] Saving subscriptions.conf... D [10/May/2009:15:47:34 +0200] [Job 11] Connected to 192.168.1.117:9100 (IPv4)... D [10/May/2009:15:47:34 +0200] [Job 11] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0xb80f2fc0) I [10/May/2009:15:47:34 +0200] Saving subscriptions.conf... D [10/May/2009:15:47:34 +0200] [Job 11] Resolution: 600x600 D [10/May/2009:15:47:34 +0200] [Job 11] Page size: A4 D [10/May/2009:15:47:34 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:15:47:34 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:15:47:34 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:34 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:47:34 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:15:47:34 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:15:47:34 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:15:47:34 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:34 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:47:34 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:15:47:34 +0200] cupsdCloseClient: 14 D [10/May/2009:15:47:34 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:15:47:34 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:15:47:34 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:34 +0200] Get-Notifications / D [10/May/2009:15:47:34 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:47:34 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:15:47:34 +0200] cupsdAcceptClient: 15 from localhost (Domain) D [10/May/2009:15:47:34 +0200] cupsdCloseClient: 13 D [10/May/2009:15:47:34 +0200] cupsdReadClient: 15 POST / HTTP/1.1 D [10/May/2009:15:47:34 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:34 +0200] Get-Notifications / D [10/May/2009:15:47:34 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:47:34 +0200] cupsdProcessIPPRequest: 15 status_code=0 (successful-ok) D [10/May/2009:15:47:34 +0200] cupsdCloseClient: 15 D [10/May/2009:15:47:34 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:47:34 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:34 +0200] Get-Notifications / D [10/May/2009:15:47:34 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:47:34 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:47:34 +0200] [Job 11] Getting input from file D [10/May/2009:15:47:34 +0200] [Job 11] foomatic-rip version 4.0.0.195 running... D [10/May/2009:15:47:34 +0200] [Job 11] Parsing PPD file ... D [10/May/2009:15:47:34 +0200] [Job 11] Added option PageSize D [10/May/2009:15:47:34 +0200] [Job 11] Added option Quality D [10/May/2009:15:47:34 +0200] [Job 11] Added option PrinterType D [10/May/2009:15:47:34 +0200] [Job 11] Added option ColorMode D [10/May/2009:15:47:34 +0200] [Job 11] Added option Resolution D [10/May/2009:15:47:34 +0200] [Job 11] Added option ImageableArea D [10/May/2009:15:47:34 +0200] [Job 11] Added option PaperDimension D [10/May/2009:15:47:34 +0200] [Job 11] Added option InputSlot D [10/May/2009:15:47:34 +0200] [Job 11] Added option MediaType D [10/May/2009:15:47:34 +0200] [Job 11] Added option Duplex D [10/May/2009:15:47:34 +0200] [Job 11] Added option Copies D [10/May/2009:15:47:34 +0200] [Job 11] Added option ICM D [10/May/2009:15:47:34 +0200] [Job 11] Added option Nup D [10/May/2009:15:47:34 +0200] [Job 11] Added option NupOrient D [10/May/2009:15:47:34 +0200] [Job 11] Added option Font D [10/May/2009:15:47:34 +0200] [Job 11] D [10/May/2009:15:47:34 +0200] [Job 11] Parameter Summary D [10/May/2009:15:47:34 +0200] [Job 11] ----------------- D [10/May/2009:15:47:34 +0200] [Job 11] D [10/May/2009:15:47:34 +0200] [Job 11] Spooler: cups D [10/May/2009:15:47:34 +0200] [Job 11] Printer: magicolor-2530-DL D [10/May/2009:15:47:34 +0200] [Job 11] Shell: /bin/bash D [10/May/2009:15:47:34 +0200] [Job 11] PPD file: /etc/cups/ppd/magicolor-2530-DL.ppd D [10/May/2009:15:47:34 +0200] [Job 11] ATTR file: D [10/May/2009:15:47:34 +0200] [Job 11] Printer model: KONICA MINOLTA magicolor 2530 DL Foomatic/foo2lava (recommended) D [10/May/2009:15:47:34 +0200] [Job 11] Job title: Test Page D [10/May/2009:15:47:34 +0200] [Job 11] File(s) to be printed: D [10/May/2009:15:47:34 +0200] [Job 11] D [10/May/2009:15:47:34 +0200] [Job 11] D [10/May/2009:15:47:34 +0200] [Job 11] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [10/May/2009:15:47:34 +0200] [Job 11] Printing system options: D [10/May/2009:15:47:34 +0200] [Job 11] Pondering option 'job-uuid=urn:uuid:5ccab7f2-b8be-31ca-40a7-78692a2822ad' D [10/May/2009:15:47:34 +0200] [Job 11] Unknown option job-uuid=urn:uuid:5ccab7f2-b8be-31ca-40a7-78692a2822ad. D [10/May/2009:15:47:34 +0200] [Job 11] Options from the PPD file: D [10/May/2009:15:47:34 +0200] [Job 11] D [10/May/2009:15:47:34 +0200] [Job 11] ================================================ D [10/May/2009:15:47:34 +0200] [Job 11] D [10/May/2009:15:47:34 +0200] [Job 11] File: D [10/May/2009:15:47:34 +0200] [Job 11] D [10/May/2009:15:47:34 +0200] [Job 11] ================================================ D [10/May/2009:15:47:34 +0200] [Job 11] D [10/May/2009:15:47:34 +0200] cupsdCloseClient: 14 D [10/May/2009:15:47:34 +0200] [Job 11] Width: 595, height: 842, absolute margins: 11.34, 11.34, 583.66, 830.66 D [10/May/2009:15:47:35 +0200] [Job 11] Relative margins: 11.34, 11.34, 11.34, 11.34 D [10/May/2009:15:47:35 +0200] [Job 10] Unloading... D [10/May/2009:15:47:35 +0200] [Job 11] PPD options: -r600x600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 D [10/May/2009:15:47:35 +0200] [Job 11] PostScript to be injected: D [10/May/2009:15:47:35 +0200] [Job 11] Running cat | /usr/bin/ps2pdf13 -dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode -dDoNumCopies -dPDFSETTINGS=/printer -r600x600 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 - - D [10/May/2009:15:47:36 +0200] [Job 11] GPL Ghostscript 8.64: Set UseCIEColor for UseDeviceIndependentColor to work properly. D [10/May/2009:15:47:37 +0200] [Job 11] Filetype: PDF D [10/May/2009:15:47:37 +0200] [Job 11] Driver does not understand PDF input, converting to PostScript D [10/May/2009:15:47:37 +0200] [Job 11] Starting process "pdf-to-ps" (generation 1) D [10/May/2009:15:47:37 +0200] PID 14954 (/usr/lib/cups/filter/pstopdf) exited with no errors. D [10/May/2009:15:47:38 +0200] PID 14956 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [10/May/2009:15:47:38 +0200] [Job 11] Filetype: PostScript D [10/May/2009:15:47:38 +0200] [Job 11] Reading PostScript input ... D [10/May/2009:15:47:38 +0200] [Job 11] --> This document is DSC-conforming! D [10/May/2009:15:47:38 +0200] [Job 11] D [10/May/2009:15:47:38 +0200] [Job 11] ----------- D [10/May/2009:15:47:38 +0200] [Job 11] Found: %%BeginProlog D [10/May/2009:15:47:38 +0200] [Job 11] Inserting option code into "Prolog" section. D [10/May/2009:15:47:38 +0200] [Job 11] Found: %%EndProlog D [10/May/2009:15:47:38 +0200] [Job 11] D [10/May/2009:15:47:38 +0200] [Job 11] ----------- D [10/May/2009:15:47:38 +0200] [Job 11] New page: %%Page: 1 1 D [10/May/2009:15:47:38 +0200] [Job 11] "Setup" section is missing, inserting it. D [10/May/2009:15:47:38 +0200] [Job 11] Inserting PostScript code for CUPS' page accounting D [10/May/2009:15:47:38 +0200] [Job 11] Inserting option code into "Setup" section. D [10/May/2009:15:47:38 +0200] [Job 11] No page header or page header not DSC-conforming D [10/May/2009:15:47:38 +0200] [Job 11] "PageSetup" section is missing, inserting it. D [10/May/2009:15:47:38 +0200] [Job 11] Inserting option code into "PageSetup" section. D [10/May/2009:15:47:38 +0200] [Job 11] Stopping search for page header options D [10/May/2009:15:47:38 +0200] [Job 11] Found: 0 6750 60 0 S D [10/May/2009:15:47:38 +0200] [Job 11] --> Output goes directly to the renderer now. D [10/May/2009:15:47:38 +0200] [Job 11] D [10/May/2009:15:47:39 +0200] [Job 11] D [10/May/2009:15:47:39 +0200] [Job 11] Starting renderer with command: "foo2lava-wrapper -z0 -c -C2 -r600x600 -p26 -m0 -s255 -d1 -Gnone.icm " D [10/May/2009:15:47:39 +0200] [Job 11] Starting process "kid3" (generation 1) D [10/May/2009:15:47:39 +0200] [Job 11] Starting process "kid4" (generation 2) D [10/May/2009:15:47:39 +0200] [Job 11] JCL: %-12345X@PJL D [10/May/2009:15:47:39 +0200] [Job 11] D [10/May/2009:15:47:39 +0200] [Job 11] D [10/May/2009:15:47:39 +0200] [Job 11] Starting process "renderer" (generation 2) D [10/May/2009:15:47:40 +0200] [Job 11] Found: %%PageTrailer D [10/May/2009:15:47:40 +0200] [Job 11] --> Continue DSC parsing now. D [10/May/2009:15:47:40 +0200] [Job 11] D [10/May/2009:15:47:40 +0200] [Job 11] Found: %%Trailer D [10/May/2009:15:47:40 +0200] [Job 11] --> Continue DSC parsing now. D [10/May/2009:15:47:40 +0200] [Job 11] D [10/May/2009:15:47:40 +0200] [Job 11] Found: %%Pages: 1 D [10/May/2009:15:47:40 +0200] [Job 11] --> Continue DSC parsing now. D [10/May/2009:15:47:40 +0200] [Job 11] D [10/May/2009:15:47:40 +0200] [Job 11] Found: %%BoundingBox: 0 0 595 842 D [10/May/2009:15:47:40 +0200] [Job 11] --> Continue DSC parsing now. D [10/May/2009:15:47:40 +0200] [Job 11] D [10/May/2009:15:47:40 +0200] [Job 11] Found: %%HiResBoundingBox: 0.000000 0.000000 595.000000 842.000000 D [10/May/2009:15:47:40 +0200] [Job 11] --> Continue DSC parsing now. D [10/May/2009:15:47:40 +0200] [Job 11] D [10/May/2009:15:47:40 +0200] [Job 11] Found: %%EOF D [10/May/2009:15:47:40 +0200] [Job 11] --> Continue DSC parsing now. D [10/May/2009:15:47:40 +0200] [Job 11] D [10/May/2009:15:47:40 +0200] [Job 11] D [10/May/2009:15:47:40 +0200] [Job 11] Closing renderer D [10/May/2009:15:47:41 +0200] [Job 11] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:47:41 +0200] [Job 11] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:47:41 +0200] [Job 11] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:47:42 +0200] [Job 11] pcache: unable to open '~/.ghostscript/cache/gs_cache' D [10/May/2009:15:47:50 +0200] PID 14598 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [10/May/2009:15:47:50 +0200] PID 14599 (/usr/lib/cups/backend/socket) exited with no errors. I [10/May/2009:15:47:53 +0200] Saving subscriptions.conf... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... I [10/May/2009:15:47:53 +0200] Saving subscriptions.conf... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] renderer exited with status 0 D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] kid4 exited with status 0 D [10/May/2009:15:47:53 +0200] [Job 11] kid3 finished D [10/May/2009:15:47:53 +0200] [Job 11] kid3 exited with status 0 D [10/May/2009:15:47:53 +0200] [Job 11] pdf-to-ps exited with status 0 D [10/May/2009:15:47:53 +0200] [Job 11] D [10/May/2009:15:47:53 +0200] [Job 11] Closing foomatic-rip. D [10/May/2009:15:47:53 +0200] PID 14957 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:53 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Read 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Wrote 8192 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Read 2520 bytes of print data... D [10/May/2009:15:47:54 +0200] [Job 11] Wrote 2520 bytes of print data... D [10/May/2009:15:47:54 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:15:47:54 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:15:47:54 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:15:47:54 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:54 +0200] Get-Notifications / D [10/May/2009:15:47:54 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:47:54 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:15:47:54 +0200] cupsdCloseClient: 14 D [10/May/2009:15:47:54 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:15:47:54 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:15:47:54 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:54 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:47:54 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:15:47:54 +0200] cupsdCloseClient: 14 D [10/May/2009:15:47:54 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:15:47:54 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:15:47:54 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:54 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:47:54 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:15:47:54 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:15:47:54 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:54 +0200] Get-Notifications / D [10/May/2009:15:47:54 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:47:54 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:15:47:54 +0200] cupsdCloseClient: 13 D [10/May/2009:15:47:54 +0200] cupsdCloseClient: 14 D [10/May/2009:15:47:54 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:47:54 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:54 +0200] Get-Notifications / D [10/May/2009:15:47:54 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:47:54 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) I [10/May/2009:15:47:59 +0200] Saving subscriptions.conf... I [10/May/2009:15:47:59 +0200] Saving subscriptions.conf... D [10/May/2009:15:47:59 +0200] PID 14958 (/usr/lib/cups/backend/socket) exited with no errors. D [10/May/2009:15:47:59 +0200] [Job 11] File 0 is complete. I [10/May/2009:15:47:59 +0200] [Job 11] Completed successfully. I [10/May/2009:15:47:59 +0200] Saving subscriptions.conf... I [10/May/2009:15:47:59 +0200] Saving subscriptions.conf... D [10/May/2009:15:47:59 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:15:47:59 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:15:47:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:59 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:47:59 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:15:47:59 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:15:47:59 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:15:47:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:59 +0200] Get-Notifications / D [10/May/2009:15:47:59 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:47:59 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:15:47:59 +0200] cupsdCloseClient: 14 D [10/May/2009:15:47:59 +0200] cupsdAcceptClient: 14 from localhost (Domain) D [10/May/2009:15:47:59 +0200] cupsdCloseClient: 13 D [10/May/2009:15:47:59 +0200] cupsdReadClient: 14 POST / HTTP/1.1 D [10/May/2009:15:47:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:59 +0200] Get-Jobs ipp://localhost/printers/ D [10/May/2009:15:47:59 +0200] cupsdProcessIPPRequest: 14 status_code=0 (successful-ok) D [10/May/2009:15:47:59 +0200] cupsdCloseClient: 14 D [10/May/2009:15:47:59 +0200] cupsdAcceptClient: 13 from localhost (Domain) D [10/May/2009:15:47:59 +0200] cupsdReadClient: 13 POST / HTTP/1.1 D [10/May/2009:15:47:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:59 +0200] Get-Notifications / D [10/May/2009:15:47:59 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:47:59 +0200] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok) D [10/May/2009:15:47:59 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:47:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:47:59 +0200] Get-Notifications / D [10/May/2009:15:47:59 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:47:59 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:48:00 +0200] [Job 11] Unloading... D [10/May/2009:15:48:02 +0200] cupsdCloseClient: 13 D [10/May/2009:15:48:59 +0200] cupsdReadClient: 12 POST / HTTP/1.1 D [10/May/2009:15:48:59 +0200] cupsdAuthorize: No authentication data provided. D [10/May/2009:15:48:59 +0200] Get-Notifications / D [10/May/2009:15:48:59 +0200] cupsdIsAuthorized: requesting-user-name="laurent" D [10/May/2009:15:48:59 +0200] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok) D [10/May/2009:15:48:59 +0200] Report: clients=2 D [10/May/2009:15:48:59 +0200] Report: jobs=11 D [10/May/2009:15:48:59 +0200] Report: jobs-active=0 D [10/May/2009:15:48:59 +0200] Report: printers=2 D [10/May/2009:15:48:59 +0200] Report: printers-implicit=0 D [10/May/2009:15:48:59 +0200] Report: stringpool-string-count=3571 D [10/May/2009:15:48:59 +0200] Report: stringpool-alloc-bytes=9360 D [10/May/2009:15:48:59 +0200] Report: stringpool-total-bytes=77864