I [29/Oct/2007:06:39:25 +0100] Listening to :::631 (IPv6)
I [29/Oct/2007:06:39:25 +0100] Listening to 0.0.0.0:631 (IPv4)
I [29/Oct/2007:06:39:25 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
W [29/Oct/2007:06:39:25 +0100] Repairing ownership of "/etc/cups/ppd"
W [29/Oct/2007:06:39:25 +0100] Repairing access permissions of "/etc/cups/ppd"
I [29/Oct/2007:06:39:25 +0100] Using default TempDir of /var/spool/cups/tmp...
W [29/Oct/2007:06:39:25 +0100] Repairing ownership of "/var/spool/cups/tmp"
W [29/Oct/2007:06:39:25 +0100] Repairing access permissions of "/var/spool/cups/tmp"
I [29/Oct/2007:06:39:25 +0100] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [29/Oct/2007:06:39:25 +0100] Configured for up to 100 clients.
I [29/Oct/2007:06:39:25 +0100] Allowing up to 100 client connections per host.
I [29/Oct/2007:06:39:25 +0100] Creating CUPS default administrative policy:
I [29/Oct/2007:06:39:25 +0100] <Policy default>
I [29/Oct/2007:06:39:25 +0100] <Limit Send-Document Send-URI Cancel-Job Hold-Job Release-Job Restart-Job Purge-Jobs Set-Job-Attributes Create-Job-Subscription Renew-Subscription Cancel-Subscription Get-Notifications Reprocess-Job Cancel-Current-Job Suspend-Current-Job Resume-Job CUPS-Move-Job CUPS-Authenticate-Job>
I [29/Oct/2007:06:39:25 +0100] Order Deny,Allow
I [29/Oct/2007:06:39:25 +0100] Require user @OWNER @SYSTEM
I [29/Oct/2007:06:39:25 +0100] </Limit>
I [29/Oct/2007:06:39:25 +0100] <Limit Pause-Printer Resume-Printer Set-Printer-Attributes Enable-Printer Disable-Printer Pause-Printer-After-Current-Job Hold-New-Jobs Release-Held-New-Jobs Deactivate-Printer Activate-Printer Restart-Printer Shutdown-Printer Startup-Printer Promote-Job Schedule-Job-After CUPS-Add-Printer CUPS-Delete-Printer CUPS-Add-Class CUPS-Delete-Class CUPS-Accept-Jobs CUPS-Reject-Jobs CUPS-Set-Default>
I [29/Oct/2007:06:39:25 +0100] Order Deny,Allow
I [29/Oct/2007:06:39:25 +0100] AuthType Basic
I [29/Oct/2007:06:39:25 +0100] Require user @SYSTEM
I [29/Oct/2007:06:39:25 +0100] </Limit>
I [29/Oct/2007:06:39:25 +0100] <Limit All>
I [29/Oct/2007:06:39:25 +0100] Order Deny,Allow
I [29/Oct/2007:06:39:25 +0100] </Limit>
I [29/Oct/2007:06:39:25 +0100] </Policy>
I [29/Oct/2007:06:39:25 +0100] Full reload is required.
I [29/Oct/2007:06:39:25 +0100] Loaded MIME database from '/etc/cups': 34 types, 39 filters...
I [29/Oct/2007:06:39:26 +0100] Full reload complete.
I [29/Oct/2007:06:39:26 +0100] Listening to :::631 on fd 2...
I [29/Oct/2007:06:39:26 +0100] Listening to 0.0.0.0:631 on fd 3...
I [29/Oct/2007:06:39:37 +0100] Started "/usr/lib/cups/daemon/cups-deviced" (pid=4486)
I [29/Oct/2007:06:39:38 +0100] Started "/usr/lib/cups/daemon/cups-deviced" (pid=4501)
I [29/Oct/2007:06:39:42 +0100] Started "/usr/lib/cups/daemon/cups-deviced" (pid=4516)
E [29/Oct/2007:06:43:42 +0100] CUPS-Add-Modify-Printer: Unauthorized
I [29/Oct/2007:06:43:42 +0100] Setting myprinter device-uri to "parallel:/dev/lp0" (was "file:/dev/null".)
I [29/Oct/2007:06:43:42 +0100] Saving printers.conf...
I [29/Oct/2007:06:43:42 +0100] New printer "myprinter" added by "root".
I [29/Oct/2007:06:43:42 +0100] Saving printers.conf...
I [29/Oct/2007:06:43:42 +0100] Printer "myprinter" modified by "root".
I [29/Oct/2007:06:43:42 +0100] Saving printers.conf...
I [29/Oct/2007:06:43:42 +0100] Printer "myprinter" now accepting jobs ("root").
I [29/Oct/2007:06:43:42 +0100] Saving printers.conf...
I [29/Oct/2007:06:43:42 +0100] Printer "myprinter" started by "root".
I [29/Oct/2007:06:43:47 +0100] Adding start banner page "none" to job 1.
I [29/Oct/2007:06:43:47 +0100] Adding end banner page "none" to job 1.
I [29/Oct/2007:06:43:47 +0100] Job 1 queued on "myprinter" by "root".
I [29/Oct/2007:06:43:47 +0100] Started filter /usr/lib/cups/filter/pstops (PID 4752) for job 1.
I [29/Oct/2007:06:43:47 +0100] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4753) for job 1.
I [29/Oct/2007:06:43:47 +0100] Started backend /usr/lib/cups/backend/parallel (PID 4754) for job 1.
E [29/Oct/2007:06:43:50 +0100] PID 4753 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3!
I [29/Oct/2007:06:43:50 +0100] Hint: Try setting the LogLevel to "debug" to find out more.
I [29/Oct/2007:06:44:21 +0100] Scheduler shutting down normally.
I [29/Oct/2007:06:44:21 +0100] Saving remote.cache...
I [29/Oct/2007:06:44:21 +0100] Saving job cache file "/var/cache/cups/job.cache"...
D [29/Oct/2007:06:44:21 +0100] cupsdDenyIP(loc=0x80a1b80(/), address=0:0:0:0, netmask=0:0:0:0)
D [29/Oct/2007:06:44:21 +0100] cupsdDenyIP(loc=0x80a5cf8(/admin), address=0:0:0:0, netmask=0:0:0:0)
I [29/Oct/2007:06:44:21 +0100] Listening to :::631 (IPv6)
I [29/Oct/2007:06:44:21 +0100] Listening to 0.0.0.0:631 (IPv4)
I [29/Oct/2007:06:44:21 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [29/Oct/2007:06:44:21 +0100] Using default TempDir of /var/spool/cups/tmp...
I [29/Oct/2007:06:44:21 +0100] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [29/Oct/2007:06:44:21 +0100] Configured for up to 100 clients.
I [29/Oct/2007:06:44:21 +0100] Allowing up to 100 client connections per host.
I [29/Oct/2007:06:44:21 +0100] Creating CUPS default administrative policy:
I [29/Oct/2007:06:44:21 +0100] <Policy default>
I [29/Oct/2007:06:44:21 +0100] <Limit Send-Document Send-URI Cancel-Job Hold-Job Release-Job Restart-Job Purge-Jobs Set-Job-Attributes Create-Job-Subscription Renew-Subscription Cancel-Subscription Get-Notifications Reprocess-Job Cancel-Current-Job Suspend-Current-Job Resume-Job CUPS-Move-Job CUPS-Authenticate-Job>
I [29/Oct/2007:06:44:21 +0100] Order Deny,Allow
I [29/Oct/2007:06:44:21 +0100] Require user @OWNER @SYSTEM
I [29/Oct/2007:06:44:21 +0100] </Limit>
I [29/Oct/2007:06:44:21 +0100] <Limit Pause-Printer Resume-Printer Set-Printer-Attributes Enable-Printer Disable-Printer Pause-Printer-After-Current-Job Hold-New-Jobs Release-Held-New-Jobs Deactivate-Printer Activate-Printer Restart-Printer Shutdown-Printer Startup-Printer Promote-Job Schedule-Job-After CUPS-Add-Printer CUPS-Delete-Printer CUPS-Add-Class CUPS-Delete-Class CUPS-Accept-Jobs CUPS-Reject-Jobs CUPS-Set-Default>
I [29/Oct/2007:06:44:21 +0100] Order Deny,Allow
I [29/Oct/2007:06:44:21 +0100] AuthType Basic
I [29/Oct/2007:06:44:21 +0100] Require user @SYSTEM
I [29/Oct/2007:06:44:21 +0100] </Limit>
I [29/Oct/2007:06:44:21 +0100] <Limit All>
I [29/Oct/2007:06:44:21 +0100] Order Deny,Allow
I [29/Oct/2007:06:44:21 +0100] </Limit>
I [29/Oct/2007:06:44:21 +0100] </Policy>
I [29/Oct/2007:06:44:21 +0100] Full reload is required.
I [29/Oct/2007:06:44:21 +0100] Loaded MIME database from '/etc/cups': 34 types, 39 filters...
D [29/Oct/2007:06:44:21 +0100] Loading printer myprinter...
I [29/Oct/2007:06:44:21 +0100] Loading job cache file "/var/cache/cups/job.cache"...
D [29/Oct/2007:06:44:21 +0100] Loading job 1 from cache...
D [29/Oct/2007:06:44:21 +0100] Loading attributes for job 1...
I [29/Oct/2007:06:44:21 +0100] Full reload complete.
I [29/Oct/2007:06:44:21 +0100] Listening to :::631 on fd 2...
I [29/Oct/2007:06:44:21 +0100] Listening to 0.0.0.0:631 on fd 3...
D [29/Oct/2007:06:44:22 +0100] cupsdNetIFUpdate: "lo" = localhost...
D [29/Oct/2007:06:44:22 +0100] cupsdNetIFUpdate: "eth0" = 192.168.1.200...
D [29/Oct/2007:06:44:22 +0100] cupsdNetIFUpdate: "lo" = localhost...
D [29/Oct/2007:06:44:22 +0100] cupsdNetIFUpdate: "eth0" = fe80::213:20ff:feb8:c680%eth0...
D [29/Oct/2007:06:44:23 +0100] cupsdAcceptClient: 7 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:23 +0100] cupsdReadClient: 7 POST /printers/ HTTP/1.1
E [29/Oct/2007:06:44:23 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:23 +0100] CUPS-Get-Printers
D [29/Oct/2007:06:44:23 +0100] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:23 +0100] cupsdCloseClient: 7
D [29/Oct/2007:06:44:23 +0100] cupsdAcceptClient: 7 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:23 +0100] cupsdReadClient: 7 POST /classes/ HTTP/1.1
E [29/Oct/2007:06:44:23 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:23 +0100] CUPS-Get-Classes
D [29/Oct/2007:06:44:23 +0100] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:23 +0100] cupsdCloseClient: 7
D [29/Oct/2007:06:44:23 +0100] cupsdAcceptClient: 7 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:23 +0100] cupsdReadClient: 7 POST /printers/ HTTP/1.1
E [29/Oct/2007:06:44:23 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:23 +0100] CUPS-Get-Default
D [29/Oct/2007:06:44:23 +0100] CUPS-Get-Default client-error-not-found: No default printer
D [29/Oct/2007:06:44:23 +0100] cupsdProcessIPPRequest: 7 status_code=406 (client-error-not-found)
D [29/Oct/2007:06:44:23 +0100] cupsdCloseClient: 7
D [29/Oct/2007:06:44:23 +0100] cupsdAcceptClient: 7 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:23 +0100] cupsdReadClient: 7 POST /printers/ HTTP/1.1
E [29/Oct/2007:06:44:23 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:23 +0100] Get-Printer-Attributes ipp://knoppix:631/printers/myprinter
D [29/Oct/2007:06:44:23 +0100] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:23 +0100] cupsdCloseClient: 7
D [29/Oct/2007:06:44:23 +0100] cupsdAcceptClient: 7 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:23 +0100] cupsdReadClient: 7 POST / HTTP/1.1
D [29/Oct/2007:06:44:23 +0100] cupsdAuthorize: No authentication data provided.
D [29/Oct/2007:06:44:23 +0100] Get-Printer-Attributes ipp://localhost/printers/myprinter
D [29/Oct/2007:06:44:23 +0100] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:23 +0100] cupsdReadClient: 7 GET /printers/myprinter.ppd HTTP/1.1
D [29/Oct/2007:06:44:23 +0100] cupsdAuthorize: No authentication data provided.
D [29/Oct/2007:06:44:23 +0100] write_file: 7 file=9
D [29/Oct/2007:06:44:23 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:23 +0100] cupsdReadClient: 9 POST / HTTP/1.1
E [29/Oct/2007:06:44:23 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:23 +0100] Get-Jobs ipp://knoppix:631/printers/myprinter
D [29/Oct/2007:06:44:23 +0100] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:23 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:28 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:28 +0100] cupsdReadClient: 9 POST /printers/ HTTP/1.1
E [29/Oct/2007:06:44:28 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:28 +0100] CUPS-Get-Printers
D [29/Oct/2007:06:44:28 +0100] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:28 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:28 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:28 +0100] cupsdReadClient: 9 POST /classes/ HTTP/1.1
E [29/Oct/2007:06:44:28 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:28 +0100] CUPS-Get-Classes
D [29/Oct/2007:06:44:28 +0100] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:28 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:28 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:28 +0100] cupsdReadClient: 9 POST /printers/ HTTP/1.1
E [29/Oct/2007:06:44:28 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:28 +0100] CUPS-Get-Default
D [29/Oct/2007:06:44:28 +0100] CUPS-Get-Default client-error-not-found: No default printer
D [29/Oct/2007:06:44:28 +0100] cupsdProcessIPPRequest: 9 status_code=406 (client-error-not-found)
D [29/Oct/2007:06:44:28 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:28 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:28 +0100] cupsdReadClient: 9 POST /printers/ HTTP/1.1
E [29/Oct/2007:06:44:28 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:28 +0100] Get-Printer-Attributes ipp://knoppix:631/printers/myprinter
D [29/Oct/2007:06:44:28 +0100] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:28 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:28 +0100] cupsdReadClient: 7 POST / HTTP/1.1
D [29/Oct/2007:06:44:28 +0100] cupsdAuthorize: No authentication data provided.
D [29/Oct/2007:06:44:28 +0100] Get-Printer-Attributes ipp://localhost/printers/myprinter
D [29/Oct/2007:06:44:28 +0100] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:28 +0100] cupsdReadClient: 7 GET /printers/myprinter.ppd HTTP/1.1
D [29/Oct/2007:06:44:28 +0100] cupsdAuthorize: No authentication data provided.
D [29/Oct/2007:06:44:28 +0100] write_file: 7 file=9
D [29/Oct/2007:06:44:28 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:28 +0100] cupsdReadClient: 9 POST / HTTP/1.1
E [29/Oct/2007:06:44:28 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:28 +0100] Get-Jobs ipp://knoppix:631/printers/myprinter
D [29/Oct/2007:06:44:28 +0100] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:28 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:32 +0100] cupsdReadClient: 7 POST / HTTP/1.1
D [29/Oct/2007:06:44:32 +0100] cupsdAuthorize: No authentication data provided.
D [29/Oct/2007:06:44:32 +0100] Get-Printer-Attributes ipp://localhost/printers/myprinter
D [29/Oct/2007:06:44:32 +0100] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:32 +0100] cupsdReadClient: 7 GET /printers/myprinter.ppd HTTP/1.1
D [29/Oct/2007:06:44:32 +0100] cupsdAuthorize: No authentication data provided.
D [29/Oct/2007:06:44:32 +0100] write_file: 7 file=9
D [29/Oct/2007:06:44:32 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:32 +0100] cupsdReadClient: 9 POST /printers/myprinter HTTP/1.1
D [29/Oct/2007:06:44:32 +0100] cupsdAuthorize: No authentication data provided.
D [29/Oct/2007:06:44:32 +0100] Print-Job ipp://localhost/printers/myprinter
D [29/Oct/2007:06:44:32 +0100] print_job: auto-typing file...
D [29/Oct/2007:06:44:32 +0100] print_job: request file type is application/postscript.
D [29/Oct/2007:06:44:32 +0100] add_job: requesting-user-name="root"
D [29/Oct/2007:06:44:32 +0100] Adding default job-sheets values "none,none"...
I [29/Oct/2007:06:44:32 +0100] Adding start banner page "none" to job 2.
D [29/Oct/2007:06:44:32 +0100] Discarding unused job-created event...
I [29/Oct/2007:06:44:32 +0100] Adding end banner page "none" to job 2.
I [29/Oct/2007:06:44:32 +0100] Job 2 queued on "myprinter" by "root".
D [29/Oct/2007:06:44:32 +0100] Job 2 hold_until = 0
D [29/Oct/2007:06:44:32 +0100] Discarding unused printer-state-changed event...
D [29/Oct/2007:06:44:32 +0100] job-sheets=none,none
D [29/Oct/2007:06:44:32 +0100] banner_page = 0
D [29/Oct/2007:06:44:32 +0100] [Job 2] argv[0]="myprinter"
D [29/Oct/2007:06:44:32 +0100] [Job 2] argv[1]="2"
D [29/Oct/2007:06:44:32 +0100] [Job 2] argv[2]="root"
D [29/Oct/2007:06:44:32 +0100] [Job 2] argv[3]="KDE Print Test"
D [29/Oct/2007:06:44:32 +0100] [Job 2] argv[4]="1"
D [29/Oct/2007:06:44:32 +0100] [Job 2] argv[5]="multiple-document-handling=separate-documents-uncollated-copies orientation-requested=3 job-uuid=urn:uuid:ce5e2e54-1542-33a0-6076-fb4ab1d24e20"
D [29/Oct/2007:06:44:32 +0100] [Job 2] argv[6]="/var/spool/cups/d00002-001"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[9]="SERVER_ADMIN=root@Knoppix"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[10]="SOFTWARE=CUPS/1.2.7"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[12]="TZ=Europe/Paris"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[13]="USER=root"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[14]="CUPS_SERVER=localhost"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[16]="IPP_PORT=631"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[17]="CHARSET=utf-8"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[18]="LANG=ko_KR"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[19]="PPD=/etc/cups/ppd/myprinter.ppd"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[20]="RIP_MAX_CACHE=8m"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[21]="CONTENT_TYPE=application/postscript"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[22]="DEVICE_URI=parallel:/dev/lp0"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[23]="PRINTER=myprinter"
D [29/Oct/2007:06:44:32 +0100] [Job 2] envp[24]="FINAL_CONTENT_TYPE=printer/myprinter"
I [29/Oct/2007:06:44:32 +0100] Started filter /usr/lib/cups/filter/pstops (PID 4794) for job 2.
I [29/Oct/2007:06:44:32 +0100] Started filter /usr/lib/cups/filter/foomatic-rip (PID 4795) for job 2.
I [29/Oct/2007:06:44:32 +0100] Started backend /usr/lib/cups/backend/parallel (PID 4796) for job 2.
D [29/Oct/2007:06:44:32 +0100] Discarding unused job-state event...
D [29/Oct/2007:06:44:32 +0100] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:32 +0100] [Job 2] Page = 612x792; 18,36 to 594,783
D [29/Oct/2007:06:44:32 +0100] [Job 2] slow_collate=0, slow_duplex=0, slow_order=0
D [29/Oct/2007:06:44:32 +0100] [Job 2] Before copy_comments - %!PS-Adobe-3.0
D [29/Oct/2007:06:44:32 +0100] [Job 2] %!PS-Adobe-3.0
D [29/Oct/2007:06:44:32 +0100] [Job 2] %%BoundingBox: 0 0 612 792
D [29/Oct/2007:06:44:32 +0100] [Job 2] %%Pages: 1
D [29/Oct/2007:06:44:32 +0100] [Job 2] %%LanguageLevel: 1
D [29/Oct/2007:06:44:32 +0100] [Job 2] %%DocumentData: Clean7Bit
D [29/Oct/2007:06:44:32 +0100] [Job 2] %%DocumentSuppliedResources: procset testprint/1.0
D [29/Oct/2007:06:44:32 +0100] [Job 2] %%DocumentNeededResources: font Helvetica Helvetica-Bold Times-Roman
D [29/Oct/2007:06:44:32 +0100] [Job 2] %%Creator: Michael Sweet, Easy Software Products
D [29/Oct/2007:06:44:32 +0100] [Job 2] %%CreationDate: May 11, 1999
D [29/Oct/2007:06:44:32 +0100] [Job 2] %%Title: Test Page
D [29/Oct/2007:06:44:32 +0100] [Job 2] %%EndComments
D [29/Oct/2007:06:44:32 +0100] [Job 2] Before copy_prolog - %%BeginProlog
D [29/Oct/2007:06:44:32 +0100] [Job 2] Before copy_setup - %%Page: 1 1
D [29/Oct/2007:06:44:32 +0100] [Job 2] Before page loop - %%Page: 1 1
D [29/Oct/2007:06:44:32 +0100] [Job 2] Copying page 1...
D [29/Oct/2007:06:44:32 +0100] [Job 2] pagew = 576.0, pagel = 747.0
D [29/Oct/2007:06:44:32 +0100] [Job 2] bboxw = 612, bboxl = 792
D [29/Oct/2007:06:44:32 +0100] [Job 2] PageLeft = 18.0, PageRight = 594.0
D [29/Oct/2007:06:44:32 +0100] [Job 2] PageTop = 783.0, PageBottom = 36.0
D [29/Oct/2007:06:44:32 +0100] [Job 2] PageWidth = 612.0, PageLength = 792.0
D [29/Oct/2007:06:44:32 +0100] [Job 2] Wrote 1 pages...
D [29/Oct/2007:06:44:32 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:32 +0100] PID 4794 (/usr/lib/cups/filter/pstops) exited with no errors.
D [29/Oct/2007:06:44:32 +0100] [Job 2] backendRunLoop(print_fd=0, device_fd=4, use_bc=0)
D [29/Oct/2007:06:44:32 +0100] Discarding unused printer-state-changed event...
D [29/Oct/2007:06:44:32 +0100] [Job 2] foomatic-rip version $Revision: 3.43.2.15 $ running...
D [29/Oct/2007:06:44:32 +0100] [Job 2] Parsing PPD file ...
D [29/Oct/2007:06:44:32 +0100] [Job 2] *cupsFilter: "application/vnd.cups-postscript 0 foomatic-rip"
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option ColorSpace
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option Resolution
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option PageSize
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option PageRegion
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option Model
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option PrintoutMode
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option ImageableArea
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option PaperDimension
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option InputSlot
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option Duplex
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option Quality
D [29/Oct/2007:06:44:32 +0100] [Job 2] Added option Font
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] Parameter Summary
D [29/Oct/2007:06:44:32 +0100] [Job 2] -----------------
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] Spooler: cups
D [29/Oct/2007:06:44:32 +0100] [Job 2] Printer: myprinter
D [29/Oct/2007:06:44:32 +0100] [Job 2] PPD file: /etc/cups/ppd/myprinter.ppd
D [29/Oct/2007:06:44:32 +0100] [Job 2] Printer model: HP DeskJet 1125C Foomatic/hpijs (recommended)
D [29/Oct/2007:06:44:32 +0100] [Job 2] Job title: KDE Print Test
D [29/Oct/2007:06:44:32 +0100] [Job 2] File(s) to be printed: 
D [29/Oct/2007:06:44:32 +0100] [Job 2] <STDIN>
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] GhostScript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [29/Oct/2007:06:44:32 +0100] [Job 2] Pondering option 'multiple-document-handling=separate-documents-uncollated-copies'
D [29/Oct/2007:06:44:32 +0100] [Job 2] Unknown option multiple-document-handling=separate-documents-uncollated-copies.
D [29/Oct/2007:06:44:32 +0100] [Job 2] Pondering option 'orientation-requested=3'
D [29/Oct/2007:06:44:32 +0100] [Job 2] Unknown option orientation-requested=3.
D [29/Oct/2007:06:44:32 +0100] [Job 2] Pondering option 'job-uuid=urn:uuid:ce5e2e54-1542-33a0-6076-fb4ab1d24e20'
D [29/Oct/2007:06:44:32 +0100] [Job 2] Unknown option job-uuid=urn:uuid:ce5e2e54-1542-33a0-6076-fb4ab1d24e20.
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] ================================================
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] File: <STDIN>
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] ================================================
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] Reading PostScript input ...
D [29/Oct/2007:06:44:32 +0100] [Job 2] --> This document is DSC-conforming!
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] -----------
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%BeginProlog
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%EndProlog
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] -----------
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%BeginSetup
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%BeginFeature: *PrintoutMode Normal
D [29/Oct/2007:06:44:32 +0100] [Job 2] Option: PrintoutMode=Normal --> Setting option
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %% FoomaticRIPOptionSetting: PrintoutMode=Normal
D [29/Oct/2007:06:44:32 +0100] [Job 2] Option: PrintoutMode=Normal --> Setting option
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%BeginFeature: *InputSlot Default
D [29/Oct/2007:06:44:32 +0100] [Job 2] Option: InputSlot=Default --> Setting option
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %% FoomaticRIPOptionSetting: InputSlot=Default
D [29/Oct/2007:06:44:32 +0100] [Job 2] Option: InputSlot=Default --> Setting option
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%BeginFeature: *Quality FromPrintoutMode
D [29/Oct/2007:06:44:32 +0100] [Job 2] Option: Quality=FromPrintoutMode --> Setting option
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %% FoomaticRIPOptionSetting: Quality=@PrintoutMode
D [29/Oct/2007:06:44:32 +0100] [Job 2] Option: Quality=FromPrintoutMode --> Setting option
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%BeginFeature: *PageRegion Letter
D [29/Oct/2007:06:44:32 +0100] [Job 2] Option: PageRegion=Letter --> Option will be set by PostScript interpreter
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
D [29/Oct/2007:06:44:32 +0100] [Job 2] Option: PageSize=Letter --> Setting option
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%BeginFeature: *Duplex None
D [29/Oct/2007:06:44:32 +0100] [Job 2] Option: Duplex=None --> Setting option
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %% FoomaticRIPOptionSetting: Duplex=None
D [29/Oct/2007:06:44:32 +0100] [Job 2] Option: Duplex=None --> Setting option
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%EndSetup
D [29/Oct/2007:06:44:32 +0100] [Job 2] Inserting PostScript code for CUPS' page accounting
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] -----------
D [29/Oct/2007:06:44:32 +0100] [Job 2] New page:  1 1
D [29/Oct/2007:06:44:32 +0100] [Job 2] Inserting option code into "PageSetup" section.
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%BeginPageSetup
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found: %%EndPageSetup
D [29/Oct/2007:06:44:32 +0100] [Job 2] End of page header
D [29/Oct/2007:06:44:32 +0100] [Job 2] Stopping search for page header options
D [29/Oct/2007:06:44:32 +0100] [Job 2] Found:
D [29/Oct/2007:06:44:32 +0100] [Job 2] (x) show				% "x"
D [29/Oct/2007:06:44:32 +0100] [Job 2] --> Output goes directly to the renderer now.
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] Starting renderer
D [29/Oct/2007:06:44:32 +0100] [Job 2] JCL: <job data> 
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] renderer PID kid4=4799
D [29/Oct/2007:06:44:32 +0100] [Job 2] renderer command: gs -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="DESKJET 1125" -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -dDuplex=false -r300 -sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=- -
D [29/Oct/2007:06:44:32 +0100] [Job 2] 
D [29/Oct/2007:06:44:32 +0100] [Job 2] Closing renderer
D [29/Oct/2007:06:44:32 +0100] [Job 2] foomatic-gswrapper: gs '-dBATCH' '-dPARANOIDSAFER' '-dQUIET' '-dNOPAUSE' '-sDEVICE=ijs' '-sIjsServer=hpijs' '-sDeviceManufacturer=HEWLETT-PACKARD' '-sDeviceModel=DESKJET 1125' '-dDEVICEWIDTHPOINTS=612' '-dDEVICEHEIGHTPOINTS=792' '-dDuplex=false' '-r300' '-sIjsParams=Quality:Quality=0,Quality:ColorMode=2,Quality:MediaType=0,Quality:PenSet=2,PS:MediaPosition=7' '-dIjsUseOutputFD' '-sOutputFile=/dev/fd/3' '/dev/fd/0' 3>&1 1>&2
D [29/Oct/2007:06:44:32 +0100] [Job 2] ESP Ghostscript 815.01: **** Could not open temporary file /var/spool/cups/tmp/gs_nu3QB9
D [29/Oct/2007:06:44:32 +0100] [Job 2] ESP Ghostscript 815.01: Could not open the scratch file /var/spool/cups/tmp/gs_nu3QB9.
D [29/Oct/2007:06:44:32 +0100] [Job 2] **** Unable to open the initial device, quitting.
D [29/Oct/2007:06:44:32 +0100] [Job 2] renderer return value: 1
D [29/Oct/2007:06:44:32 +0100] [Job 2] renderer received signal: 1
D [29/Oct/2007:06:44:32 +0100] [Job 2] KID3 exited with status 3
D [29/Oct/2007:06:44:32 +0100] [Job 2] Renderer exit stat: 3
D [29/Oct/2007:06:44:32 +0100] [Job 2] Process dying with "Possible error on renderer command line or PostScript error. Check options.", exit stat: 3
D [29/Oct/2007:06:44:32 +0100] [Job 2] error: À߸øµÈ Ž»ö (29)
D [29/Oct/2007:06:44:32 +0100] [Job 2] Possible error on renderer command line or PostScript error. Check options.
D [29/Oct/2007:06:44:32 +0100] [Job 2] tail process done writing data to STDOUT
D [29/Oct/2007:06:44:32 +0100] [Job 2] KID4 finished
D [29/Oct/2007:06:44:32 +0100] [Job 2] Renderer process finished
D [29/Oct/2007:06:44:32 +0100] [Job 2] Killing process 4798 (KID3)
D [29/Oct/2007:06:44:32 +0100] [Job 2] Process dying with "Error closing renderer", exit stat: 3
D [29/Oct/2007:06:44:32 +0100] [Job 2] error: À߸øµÈ ÆÄÀÏ ±â¼úÀÚ (9)
D [29/Oct/2007:06:44:32 +0100] [Job 2] Error closing renderer
D [29/Oct/2007:06:44:32 +0100] PID 4796 (/usr/lib/cups/backend/parallel) exited with no errors.
E [29/Oct/2007:06:44:32 +0100] PID 4795 (/usr/lib/cups/filter/foomatic-rip) stopped with status 3!
D [29/Oct/2007:06:44:32 +0100] [Job 2] File 0 is complete.
D [29/Oct/2007:06:44:32 +0100] Discarding unused printer-state-changed event...
D [29/Oct/2007:06:44:32 +0100] Discarding unused job-stopped event...
D [29/Oct/2007:06:44:33 +0100] Unloading job 2...
D [29/Oct/2007:06:44:33 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:33 +0100] cupsdReadClient: 9 POST /printers/ HTTP/1.1
E [29/Oct/2007:06:44:33 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:33 +0100] CUPS-Get-Printers
D [29/Oct/2007:06:44:33 +0100] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:33 +0100] cupsdAcceptClient: 11 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:33 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:33 +0100] cupsdReadClient: 11 POST /classes/ HTTP/1.1
E [29/Oct/2007:06:44:33 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:33 +0100] CUPS-Get-Classes
D [29/Oct/2007:06:44:33 +0100] cupsdProcessIPPRequest: 11 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:33 +0100] cupsdCloseClient: 11
D [29/Oct/2007:06:44:33 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:33 +0100] cupsdReadClient: 9 POST /printers/ HTTP/1.1
E [29/Oct/2007:06:44:33 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:33 +0100] CUPS-Get-Default
D [29/Oct/2007:06:44:33 +0100] CUPS-Get-Default client-error-not-found: No default printer
D [29/Oct/2007:06:44:33 +0100] cupsdProcessIPPRequest: 9 status_code=406 (client-error-not-found)
D [29/Oct/2007:06:44:33 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:33 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:33 +0100] cupsdReadClient: 9 POST /printers/ HTTP/1.1
E [29/Oct/2007:06:44:33 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:33 +0100] Get-Printer-Attributes ipp://knoppix:631/printers/myprinter
D [29/Oct/2007:06:44:33 +0100] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:33 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:33 +0100] cupsdReadClient: 7 POST / HTTP/1.1
D [29/Oct/2007:06:44:33 +0100] cupsdAuthorize: No authentication data provided.
D [29/Oct/2007:06:44:33 +0100] Get-Printer-Attributes ipp://localhost/printers/myprinter
D [29/Oct/2007:06:44:33 +0100] cupsdProcessIPPRequest: 7 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:33 +0100] cupsdReadClient: 7 GET /printers/myprinter.ppd HTTP/1.1
D [29/Oct/2007:06:44:33 +0100] cupsdAuthorize: No authentication data provided.
D [29/Oct/2007:06:44:33 +0100] write_file: 7 file=9
D [29/Oct/2007:06:44:33 +0100] cupsdAcceptClient: 9 from localhost:631 (IPv4)
D [29/Oct/2007:06:44:33 +0100] cupsdReadClient: 9 POST / HTTP/1.1
E [29/Oct/2007:06:44:33 +0100] cupsdAuthorize: Local authentication certificate not found!
D [29/Oct/2007:06:44:33 +0100] Get-Jobs ipp://knoppix:631/printers/myprinter
D [29/Oct/2007:06:44:33 +0100] Loading attributes for job 2...
D [29/Oct/2007:06:44:33 +0100] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [29/Oct/2007:06:44:33 +0100] cupsdCloseClient: 9
D [29/Oct/2007:06:44:35 +0100] cupsdCloseClient: 7
D [29/Oct/2007:06:45:35 +0100] Unloading job 1...
D [29/Oct/2007:06:45:35 +0100] Unloading job 2...
D [29/Oct/2007:06:45:35 +0100] cupsdNetIFUpdate: "lo" = localhost...
D [29/Oct/2007:06:45:35 +0100] cupsdNetIFUpdate: "eth0" = 192.168.1.200...
D [29/Oct/2007:06:45:35 +0100] cupsdNetIFUpdate: "lo" = localhost...
D [29/Oct/2007:06:45:35 +0100] cupsdNetIFUpdate: "eth0" = fe80::213:20ff:feb8:c680%eth0...
D [29/Oct/2007:06:46:37 +0100] cupsdNetIFUpdate: "lo" = localhost...
D [29/Oct/2007:06:46:37 +0100] cupsdNetIFUpdate: "eth0" = 192.168.1.200...
D [29/Oct/2007:06:46:37 +0100] cupsdNetIFUpdate: "lo" = localhost...
D [29/Oct/2007:06:46:37 +0100] cupsdNetIFUpdate: "eth0" = fe80::213:20ff:feb8:c680%eth0...