Idle - "/usr/local/libexec/cups/filter/pstoraster failed"

[Dino Vliet]

Dear freebsd-cups maintainer,
my samsung ml-1610 printer which I installed with the ppd driver from the splix driver gives me the following status after I print a file:

Idle - "/usr/local/libexec/cups/filter/pstoraster failed"

What could be cuasing this? What can I do to solve this and make cups print multiple files continuously? The error_log file is below:


cat /var/log/cups/error_log

E [29/Jan/2012:20:01:44 +0100] Unknown directive DefaultCharset on line 81.
E [29/Jan/2012:20:01:44 +0100] File or directory for "ServerCertificate /usr/local/etc/cups/ssl/server.crt" on line 142 does not exist.
E [29/Jan/2012:20:01:44 +0100] File or directory for "ServerKey /usr/local/etc/cups/ssl/server.key" on line 153 does not exist.
E [29/Jan/2012:20:01:44 +0100] Unknown User "lp" on line 519, ignoring.
W [29/Jan/2012:20:01:44 +0100] No limit for Validate-Job defined in policy default and no suitable template found.
W [29/Jan/2012:20:01:44 +0100] No limit for Cancel-Jobs defined in policy default - using Pause-Printer's policy.
W [29/Jan/2012:20:01:44 +0100] No limit for Cancel-My-Jobs defined in policy default - using Send-Document's policy.
W [29/Jan/2012:20:01:44 +0100] No limit for Close-Job defined in policy default - using Send-Document's policy.
W [29/Jan/2012:20:01:44 +0100] No limit for CUPS-Get-Document defined in policy default - using Send-Document's policy.
W [29/Jan/2012:20:01:44 +0100] No JobPrivateAccess defined in policy default - using defaults.
W [29/Jan/2012:20:01:44 +0100] No JobPrivateValues defined in policy default - using defaults.
W [29/Jan/2012:20:01:44 +0100] No SubscriptionPrivateAccess defined in policy default - using defaults.
W [29/Jan/2012:20:01:44 +0100] No SubscriptionPrivateValues defined in policy default - using defaults.
I [29/Jan/2012:20:01:44 +0100] Remote access is disabled.
I [29/Jan/2012:20:01:44 +0100] Loaded configuration file "/usr/local/etc/cups/cupsd.conf"
I [29/Jan/2012:20:01:44 +0100] Configured for up to 100 clients.
I [29/Jan/2012:20:01:44 +0100] Allowing up to 100 client connections per host.
I [29/Jan/2012:20:01:44 +0100] Using policy "default" as the default.
I [29/Jan/2012:20:01:44 +0100] Full reload is required.
I [29/Jan/2012:20:01:44 +0100] Loaded MIME database from "/usr/local/share/cups/mime" and "/usr/local/etc/cups": 35 types, 41 filters...
I [29/Jan/2012:20:01:44 +0100] Full reload complete.
I [29/Jan/2012:20:01:44 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
I [29/Jan/2012:20:01:44 +0100] Cleaning out old files in "/var/db/cups"...
I [29/Jan/2012:20:01:44 +0100] Listening to [v1.::1]:631 on fd 7...
I [29/Jan/2012:20:01:44 +0100] Listening to 127.0.0.1:631 on fd 8...
I [29/Jan/2012:20:01:44 +0100] Listening to /var/run/cups.sock:631 on fd 9...
I [29/Jan/2012:20:01:44 +0100] Resuming new connection processing...
I [29/Jan/2012:20:18:55 +0100] Scheduler shutting down normally.
X [29/Jan/2012:20:18:55 +0100] kevent() returned Bad file descriptor
I [29/Jan/2012:20:18:55 +0100] Saving job.cache...
E [29/Jan/2012:20:18:56 +0100] Unknown directive DefaultCharset on line 81.
E [29/Jan/2012:20:18:56 +0100] File or directory for "ServerCertificate /usr/local/etc/cups/ssl/server.crt" on line 142 does not exist.
E [29/Jan/2012:20:18:56 +0100] File or directory for "ServerKey /usr/local/etc/cups/ssl/server.key" on line 153 does not exist.
E [29/Jan/2012:20:18:56 +0100] Unknown User "lp" on line 519, ignoring.
W [29/Jan/2012:20:18:56 +0100] No limit for Validate-Job defined in policy default and no suitable template found.
W [29/Jan/2012:20:18:56 +0100] No limit for Cancel-Jobs defined in policy default - using Pause-Printer's policy.
W [29/Jan/2012:20:18:56 +0100] No limit for Cancel-My-Jobs defined in policy default - using Send-Document's policy.
W [29/Jan/2012:20:18:56 +0100] No limit for Close-Job defined in policy default - using Send-Document's policy.
W [29/Jan/2012:20:18:56 +0100] No limit for CUPS-Get-Document defined in policy default - using Send-Document's policy.
W [29/Jan/2012:20:18:56 +0100] No JobPrivateAccess defined in policy default - using defaults.
W [29/Jan/2012:20:18:56 +0100] No JobPrivateValues defined in policy default - using defaults.
W [29/Jan/2012:20:18:56 +0100] No SubscriptionPrivateAccess defined in policy default - using defaults.
W [29/Jan/2012:20:18:56 +0100] No SubscriptionPrivateValues defined in policy default - using defaults.
I [29/Jan/2012:20:18:56 +0100] Remote access is disabled.
I [29/Jan/2012:20:18:56 +0100] Loaded configuration file "/usr/local/etc/cups/cupsd.conf"
I [29/Jan/2012:20:18:56 +0100] Configured for up to 100 clients.
I [29/Jan/2012:20:18:56 +0100] Allowing up to 100 client connections per host.
I [29/Jan/2012:20:18:56 +0100] Using policy "default" as the default.
I [29/Jan/2012:20:18:56 +0100] Full reload is required.
I [29/Jan/2012:20:18:56 +0100] Loaded MIME database from "/usr/local/share/cups/mime" and "/usr/local/etc/cups": 35 types, 41 filters...
I [29/Jan/2012:20:18:56 +0100] Loading NextJobId from job cache file "/var/db/cups/job.cache"...
I [29/Jan/2012:20:18:56 +0100] Full reload complete.
I [29/Jan/2012:20:18:56 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
I [29/Jan/2012:20:18:56 +0100] Cleaning out old files in "/var/db/cups"...
I [29/Jan/2012:20:18:56 +0100] Listening to [v1.::1]:631 on fd 7...
I [29/Jan/2012:20:18:56 +0100] Listening to 127.0.0.1:631 on fd 8...
I [29/Jan/2012:20:18:56 +0100] Listening to /var/run/cups.sock:631 on fd 9...
I [29/Jan/2012:20:18:56 +0100] Resuming new connection processing...
I [29/Jan/2012:20:23:21 +0100] Started "/usr/local/libexec/cups/daemon/cups-deviced" (pid=36796)
I [29/Jan/2012:20:23:26 +0100] Started "/usr/local/libexec/cups/daemon/cups-deviced" (pid=36814)
I [29/Jan/2012:20:23:28 +0100] Started "/usr/local/libexec/cups/daemon/cups-driverd" (pid=36822)
W [29/Jan/2012:20:23:28 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Brother/BR5070DN_GPL.ppd.gz!
W [29/Jan/2012:20:23:29 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp830c.ppd.gz!
W [29/Jan/2012:20:23:29 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp850c.ppd.gz!
W [29/Jan/2012:20:23:29 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp880c.ppd.gz!
W [29/Jan/2012:20:23:29 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp9100.ppd.gz!
W [29/Jan/2012:20:23:29 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp920c.ppd.gz!
W [29/Jan/2012:20:23:29 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp950c.ppd.gz!
W [29/Jan/2012:20:23:29 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp960s.ppd.gz!
W [29/Jan/2012:20:23:29 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp980c.ppd.gz!
W [29/Jan/2012:20:23:34 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/KONICA_MINOLTA/KOC451JX.ppd.gz!
E [29/Jan/2012:20:23:34 +0100] [cups-driverd] Bad driver information file "/usr/local/share/cups/model/foomatic/Kyocera/ReadMe.htm"!
E [29/Jan/2012:20:23:38 +0100] [cups-driverd] Bad driver information file "/usr/local/share/cups/model/foomatic/Kyocera/kyocera_ppd_8.2.txt"!
I [29/Jan/2012:20:23:48 +0100] Saving subscriptions.conf...
E [29/Jan/2012:20:23:50 +0100] [cups-driverd] Skipping "/usr/local/share/cups/model/foomatic-db-ppds": loop detected!
I [29/Jan/2012:20:23:50 +0100] [cups-driverd] Wrote "/var/db/cups/ppds.dat", 2055 PPDs...
I [29/Jan/2012:20:24:12 +0100] Started "/usr/local/libexec/cups/daemon/cups-driverd" (pid=36872)
I [29/Jan/2012:20:24:17 +0100] Setting Samsung-ML-1650 device-uri to "usb:/dev/ulpt0" (was "file:///dev/null".)
I [29/Jan/2012:20:24:17 +0100] New printer "Samsung-ML-1650" added by "anonymous".
I [29/Jan/2012:20:24:17 +0100] Printer "Samsung-ML-1650" started by "anonymous".
I [29/Jan/2012:20:24:17 +0100] Printer "Samsung-ML-1650" now accepting jobs ("anonymous").
I [29/Jan/2012:20:24:17 +0100] Default destination set to "Samsung-ML-1650" by "anonymous".
I [29/Jan/2012:20:24:17 +0100] Printer "Samsung-ML-1650" modified by "anonymous".
I [29/Jan/2012:20:24:17 +0100] Printer "Samsung-ML-1650" modified by "anonymous".
I [29/Jan/2012:20:24:20 +0100] [Job ???] Request file type is application/vnd.cups-banner.
I [29/Jan/2012:20:24:48 +0100] Saving printers.conf...
I [29/Jan/2012:20:24:48 +0100] Saving classes.conf...
I [29/Jan/2012:20:24:48 +0100] Generating printcap /etc/printcap...
I [29/Jan/2012:20:24:48 +0100] Saving job.cache...
I [29/Jan/2012:20:24:48 +0100] Saving subscriptions.conf...
I [29/Jan/2012:20:25:24 +0100] Saving subscriptions.conf...
I [29/Jan/2012:20:27:16 +0100] Scheduler shutting down normally.
X [29/Jan/2012:20:27:16 +0100] kevent() returned Bad file descriptor
I [29/Jan/2012:20:27:16 +0100] Saving job.cache...
E [29/Jan/2012:20:29:40 +0100] Unknown directive DefaultCharset on line 81.
E [29/Jan/2012:20:29:40 +0100] File or directory for "ServerCertificate /usr/local/etc/cups/ssl/server.crt" on line 142 does not exist.
E [29/Jan/2012:20:29:40 +0100] File or directory for "ServerKey /usr/local/etc/cups/ssl/server.key" on line 153 does not exist.
E [29/Jan/2012:20:29:40 +0100] Unknown User "lp" on line 519, ignoring.
W [29/Jan/2012:20:29:41 +0100] No limit for Validate-Job defined in policy default and no suitable template found.
W [29/Jan/2012:20:29:41 +0100] No limit for Cancel-Jobs defined in policy default - using Pause-Printer's policy.
W [29/Jan/2012:20:29:41 +0100] No limit for Cancel-My-Jobs defined in policy default - using Send-Document's policy.
W [29/Jan/2012:20:29:41 +0100] No limit for Close-Job defined in policy default - using Send-Document's policy.
W [29/Jan/2012:20:29:41 +0100] No limit for CUPS-Get-Document defined in policy default - using Send-Document's policy.
W [29/Jan/2012:20:29:41 +0100] No JobPrivateAccess defined in policy default - using defaults.
W [29/Jan/2012:20:29:41 +0100] No JobPrivateValues defined in policy default - using defaults.
W [29/Jan/2012:20:29:41 +0100] No SubscriptionPrivateAccess defined in policy default - using defaults.
W [29/Jan/2012:20:29:41 +0100] No SubscriptionPrivateValues defined in policy default - using defaults.
I [29/Jan/2012:20:29:41 +0100] Remote access is disabled.
I [29/Jan/2012:20:29:41 +0100] Loaded configuration file "/usr/local/etc/cups/cupsd.conf"
I [29/Jan/2012:20:29:41 +0100] Configured for up to 100 clients.
I [29/Jan/2012:20:29:41 +0100] Allowing up to 100 client connections per host.
I [29/Jan/2012:20:29:41 +0100] Using policy "default" as the default.
I [29/Jan/2012:20:29:41 +0100] Full reload is required.
I [29/Jan/2012:20:29:41 +0100] Loaded MIME database from "/usr/local/share/cups/mime" and "/usr/local/etc/cups": 35 types, 41 filters...
I [29/Jan/2012:20:29:41 +0100] Loading job cache file "/var/db/cups/job.cache"...
I [29/Jan/2012:20:29:41 +0100] Full reload complete.
I [29/Jan/2012:20:29:41 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
I [29/Jan/2012:20:29:41 +0100] Cleaning out old files in "/var/db/cups"...
I [29/Jan/2012:20:29:41 +0100] Listening to [v1.::1]:631 on fd 7...
I [29/Jan/2012:20:29:41 +0100] Listening to 127.0.0.1:631 on fd 8...
I [29/Jan/2012:20:29:41 +0100] Listening to /var/run/cups.sock:631 on fd 9...
I [29/Jan/2012:20:29:41 +0100] Resuming new connection processing...
I [29/Jan/2012:21:30:33 +0100] Scheduler shutting down normally.
X [29/Jan/2012:21:30:33 +0100] kevent() returned Bad file descriptor
I [29/Jan/2012:21:30:33 +0100] Saving job.cache...
E [29/Jan/2012:21:32:28 +0100] Unknown directive DefaultCharset on line 81.
E [29/Jan/2012:21:32:28 +0100] File or directory for "ServerCertificate /usr/local/etc/cups/ssl/server.crt" on line 142 does not exist.
E [29/Jan/2012:21:32:28 +0100] File or directory for "ServerKey /usr/local/etc/cups/ssl/server.key" on line 153 does not exist.
E [29/Jan/2012:21:32:28 +0100] Unknown User "lp" on line 519, ignoring.
W [29/Jan/2012:21:32:28 +0100] No limit for Validate-Job defined in policy default and no suitable template found.
W [29/Jan/2012:21:32:28 +0100] No limit for Cancel-Jobs defined in policy default - using Pause-Printer's policy.
W [29/Jan/2012:21:32:28 +0100] No limit for Cancel-My-Jobs defined in policy default - using Send-Document's policy.
W [29/Jan/2012:21:32:28 +0100] No limit for Close-Job defined in policy default - using Send-Document's policy.
W [29/Jan/2012:21:32:28 +0100] No limit for CUPS-Get-Document defined in policy default - using Send-Document's policy.
W [29/Jan/2012:21:32:28 +0100] No JobPrivateAccess defined in policy default - using defaults.
W [29/Jan/2012:21:32:28 +0100] No JobPrivateValues defined in policy default - using defaults.
W [29/Jan/2012:21:32:28 +0100] No SubscriptionPrivateAccess defined in policy default - using defaults.
W [29/Jan/2012:21:32:28 +0100] No SubscriptionPrivateValues defined in policy default - using defaults.
I [29/Jan/2012:21:32:28 +0100] Remote access is disabled.
I [29/Jan/2012:21:32:28 +0100] Loaded configuration file "/usr/local/etc/cups/cupsd.conf"
I [29/Jan/2012:21:32:28 +0100] Configured for up to 100 clients.
I [29/Jan/2012:21:32:28 +0100] Allowing up to 100 client connections per host.
I [29/Jan/2012:21:32:28 +0100] Using policy "default" as the default.
I [29/Jan/2012:21:32:28 +0100] Full reload is required.
I [29/Jan/2012:21:32:28 +0100] Loaded MIME database from "/usr/local/share/cups/mime" and "/usr/local/etc/cups": 35 types, 41 filters...
I [29/Jan/2012:21:32:28 +0100] Loading job cache file "/var/db/cups/job.cache"...
I [29/Jan/2012:21:32:28 +0100] Full reload complete.
I [29/Jan/2012:21:32:28 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
I [29/Jan/2012:21:32:28 +0100] Cleaning out old files in "/var/db/cups"...
I [29/Jan/2012:21:32:28 +0100] Listening to [v1.::1]:631 on fd 7...
I [29/Jan/2012:21:32:28 +0100] Listening to 127.0.0.1:631 on fd 8...
I [29/Jan/2012:21:32:28 +0100] Listening to /var/run/cups.sock:631 on fd 9...
I [29/Jan/2012:21:32:28 +0100] Resuming new connection processing...
I [29/Jan/2012:23:30:46 +0100] Scheduler shutting down normally.
X [29/Jan/2012:23:30:46 +0100] kevent() returned Bad file descriptor
I [29/Jan/2012:23:30:46 +0100] Saving job.cache...
E [30/Jan/2012:19:51:50 +0100] Unknown directive DefaultCharset on line 81.
E [30/Jan/2012:19:51:50 +0100] File or directory for "ServerCertificate /usr/local/etc/cups/ssl/server.crt" on line 142 does not exist.
E [30/Jan/2012:19:51:50 +0100] File or directory for "ServerKey /usr/local/etc/cups/ssl/server.key" on line 153 does not exist.
E [30/Jan/2012:19:51:50 +0100] Unknown User "lp" on line 519, ignoring.
W [30/Jan/2012:19:51:50 +0100] No limit for Validate-Job defined in policy default and no suitable template found.
W [30/Jan/2012:19:51:50 +0100] No limit for Cancel-Jobs defined in policy default - using Pause-Printer's policy.
W [30/Jan/2012:19:51:50 +0100] No limit for Cancel-My-Jobs defined in policy default - using Send-Document's policy.
W [30/Jan/2012:19:51:50 +0100] No limit for Close-Job defined in policy default - using Send-Document's policy.
W [30/Jan/2012:19:51:50 +0100] No limit for CUPS-Get-Document defined in policy default - using Send-Document's policy.
W [30/Jan/2012:19:51:50 +0100] No JobPrivateAccess defined in policy default - using defaults.
W [30/Jan/2012:19:51:50 +0100] No JobPrivateValues defined in policy default - using defaults.
W [30/Jan/2012:19:51:50 +0100] No SubscriptionPrivateAccess defined in policy default - using defaults.
W [30/Jan/2012:19:51:50 +0100] No SubscriptionPrivateValues defined in policy default - using defaults.
I [30/Jan/2012:19:51:50 +0100] Remote access is disabled.
I [30/Jan/2012:19:51:50 +0100] Loaded configuration file "/usr/local/etc/cups/cupsd.conf"
I [30/Jan/2012:19:51:50 +0100] Configured for up to 100 clients.
I [30/Jan/2012:19:51:50 +0100] Allowing up to 100 client connections per host.
I [30/Jan/2012:19:51:50 +0100] Using policy "default" as the default.
I [30/Jan/2012:19:51:50 +0100] Full reload is required.
I [30/Jan/2012:19:51:51 +0100] Loaded MIME database from "/usr/local/share/cups/mime" and "/usr/local/etc/cups": 35 types, 41 filters...
I [30/Jan/2012:19:51:51 +0100] Loading job cache file "/var/db/cups/job.cache"...
I [30/Jan/2012:19:51:51 +0100] Full reload complete.
I [30/Jan/2012:19:51:51 +0100] Cleaning out old files in "/var/spool/cups/tmp"...
I [30/Jan/2012:19:51:51 +0100] Cleaning out old files in "/var/db/cups"...
I [30/Jan/2012:19:51:51 +0100] Listening to [v1.::1]:631 on fd 7...
I [30/Jan/2012:19:51:51 +0100] Listening to 127.0.0.1:631 on fd 8...
I [30/Jan/2012:19:51:51 +0100] Listening to /var/run/cups.sock:631 on fd 9...
I [30/Jan/2012:19:51:51 +0100] Resuming new connection processing...
I [30/Jan/2012:21:38:15 +0100] Printer "Samsung-ML-1650" deleted by "anonymous".
I [30/Jan/2012:21:38:29 +0100] Started "/usr/local/libexec/cups/daemon/cups-deviced" (pid=50997)
I [30/Jan/2012:21:38:33 +0100] Started "/usr/local/libexec/cups/daemon/cups-deviced" (pid=51009)
I [30/Jan/2012:21:38:35 +0100] Started "/usr/local/libexec/cups/daemon/cups-driverd" (pid=51017)
I [30/Jan/2012:21:38:35 +0100] [cups-driverd] Read "/var/db/cups/ppds.dat", 2055 PPDs...
W [30/Jan/2012:21:38:35 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Brother/BR5070DN_GPL.ppd.gz!
W [30/Jan/2012:21:38:35 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp830c.ppd.gz!
W [30/Jan/2012:21:38:35 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp850c.ppd.gz!
W [30/Jan/2012:21:38:35 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp880c.ppd.gz!
W [30/Jan/2012:21:38:35 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp9100.ppd.gz!
W [30/Jan/2012:21:38:35 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp920c.ppd.gz!
W [30/Jan/2012:21:38:35 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp950c.ppd.gz!
W [30/Jan/2012:21:38:35 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp960s.ppd.gz!
W [30/Jan/2012:21:38:35 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp980c.ppd.gz!
W [30/Jan/2012:21:38:35 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/KONICA_MINOLTA/KOC451JX.ppd.gz!
E [30/Jan/2012:21:38:35 +0100] [cups-driverd] Bad driver information file "/usr/local/share/cups/model/foomatic/Kyocera/ReadMe.htm"!
E [30/Jan/2012:21:38:36 +0100] [cups-driverd] Bad driver information file "/usr/local/share/cups/model/foomatic/Kyocera/kyocera_ppd_8.2.txt"!
E [30/Jan/2012:21:38:36 +0100] [cups-driverd] Skipping "/usr/local/share/cups/model/foomatic-db-ppds": loop detected!
I [30/Jan/2012:21:38:36 +0100] [cups-driverd] Wrote "/var/db/cups/ppds.dat", 2055 PPDs...
I [30/Jan/2012:21:38:41 +0100] Saving printers.conf...
I [30/Jan/2012:21:38:41 +0100] Generating printcap /etc/printcap...
I [30/Jan/2012:21:38:41 +0100] Saving subscriptions.conf...
I [30/Jan/2012:21:39:18 +0100] Setting Samsung-ML-1610 device-uri to "usb:/dev/unlpt0" (was "file:///dev/null".)
I [30/Jan/2012:21:39:19 +0100] New printer "Samsung-ML-1610" added by "anonymous".
I [30/Jan/2012:21:39:19 +0100] Printer "Samsung-ML-1610" started by "anonymous".
I [30/Jan/2012:21:39:19 +0100] Printer "Samsung-ML-1610" now accepting jobs ("anonymous").
I [30/Jan/2012:21:39:19 +0100] Default destination set to "Samsung-ML-1610" by "anonymous".
I [30/Jan/2012:21:39:19 +0100] Printer "Samsung-ML-1610" modified by "anonymous".
I [30/Jan/2012:21:39:19 +0100] Printer "Samsung-ML-1610" modified by "anonymous".
I [30/Jan/2012:21:39:21 +0100] [Job ???] Request file type is application/vnd.cups-banner.
E [30/Jan/2012:21:39:21 +0100] [Job 2] SpliX Cannot get input slot information.
I [30/Jan/2012:21:39:48 +0100] Saving printers.conf...
I [30/Jan/2012:21:39:48 +0100] Saving classes.conf...
I [30/Jan/2012:21:39:48 +0100] Generating printcap /etc/printcap...
I [30/Jan/2012:21:39:48 +0100] Saving job.cache...
I [30/Jan/2012:21:39:48 +0100] Saving subscriptions.conf...
I [30/Jan/2012:21:39:48 +0100] Printer "Samsung-ML-1610" modified by "anonymous".
I [30/Jan/2012:21:40:05 +0100] Printer "Samsung-ML-1610" modified by "anonymous".
I [30/Jan/2012:21:40:18 +0100] Saving printers.conf...
I [30/Jan/2012:21:40:18 +0100] Generating printcap /etc/printcap...
I [30/Jan/2012:21:40:18 +0100] Saving subscriptions.conf...
I [30/Jan/2012:21:40:27 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=51469)
I [30/Jan/2012:21:40:30 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=51476)
I [30/Jan/2012:21:40:34 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=51483)
I [30/Jan/2012:21:40:41 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=51490)
I [30/Jan/2012:21:40:48 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=51497)
I [30/Jan/2012:21:40:48 +0100] Printer "Samsung-ML-1610" modified by "anonymous".
I [30/Jan/2012:21:40:51 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=51504)
I [30/Jan/2012:21:40:55 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=51511)
I [30/Jan/2012:21:40:55 +0100] Default destination set to "Samsung-ML-1610" by "anonymous".
I [30/Jan/2012:21:40:57 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=51512)
I [30/Jan/2012:21:41:00 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=51519)
I [30/Jan/2012:21:41:05 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=51526)
I [30/Jan/2012:21:41:07 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=51527)
I [30/Jan/2012:21:41:11 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=51534)
I [30/Jan/2012:21:41:15 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=51541)
I [30/Jan/2012:21:41:19 +0100] Saving printers.conf...
I [30/Jan/2012:21:41:19 +0100] Saving classes.conf...
I [30/Jan/2012:21:41:19 +0100] Generating printcap /etc/printcap...
I [30/Jan/2012:21:42:18 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=51689)
I [30/Jan/2012:21:42:19 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=51723)
I [30/Jan/2012:21:42:23 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=51724)
I [30/Jan/2012:21:42:26 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=51731)
I [30/Jan/2012:21:44:09 +0100] [Job ???] Request file type is application/pdf.
I [30/Jan/2012:21:44:21 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=52027)
I [30/Jan/2012:21:44:22 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=52032)
I [30/Jan/2012:21:44:29 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=52198)
I [30/Jan/2012:21:44:40 +0100] Saving job.cache...
I [30/Jan/2012:21:45:06 +0100] Hint: Try setting the LogLevel to "debug" to find out more.
E [30/Jan/2012:21:45:06 +0100] [Job 3] Job stopped due to filter errors; please consult the error_log file for details.
D [30/Jan/2012:21:45:06 +0100] [Job 3] The following messages were recorded from 21:44:14 to 21:45:06
D [30/Jan/2012:21:45:06 +0100] [Job 3] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups_get_matrix(0x8041b7058, 0x7fffffffbee0)
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups->header.Duplex = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups->page = 1
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsPPD = 0x8042f2180
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsPPD->flip_duplex = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] width = 2460, height = 3175
D [30/Jan/2012:21:45:06 +0100] [Job 3] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups_get_matrix(0x8041b7058, 0x7fffffffbee0)
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups->header.Duplex = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups->page = 1
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsPPD = 0x8042f2180
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsPPD->flip_duplex = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] width = 2460, height = 3175
D [30/Jan/2012:21:45:06 +0100] [Job 3] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups_get_matrix(0x8041b7058, 0x7fffffffbee0)
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups->header.Duplex = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups->page = 1
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsPPD = 0x8042f2180
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsPPD->flip_duplex = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] width = 2460, height = 3175
D [30/Jan/2012:21:45:06 +0100] [Job 3] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups_get_matrix(0x8041b7058, 0x7fffffffc220)
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups->header.Duplex = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups->page = 1
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsPPD = 0x8042f2180
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsPPD->flip_duplex = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] width = 2460, height = 3175
D [30/Jan/2012:21:45:06 +0100] [Job 3] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] Finishing.
D [30/Jan/2012:21:45:06 +0100] [Job 3] -dict-
D [30/Jan/2012:21:45:06 +0100] [Job 3] -dict-
D [30/Jan/2012:21:45:06 +0100] [Job 3] -dict-
D [30/Jan/2012:21:45:06 +0100] [Job 3] -dict-
D [30/Jan/2012:21:45:06 +0100] [Job 3] -mark-
D [30/Jan/2012:21:45:06 +0100] [Job 3] -dict-
D [30/Jan/2012:21:45:06 +0100] [Job 3] false
D [30/Jan/2012:21:45:06 +0100] [Job 3] 792
D [30/Jan/2012:21:45:06 +0100] [Job 3] 612
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0.0
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0.0
D [30/Jan/2012:21:45:06 +0100] [Job 3] 792
D [30/Jan/2012:21:45:06 +0100] [Job 3] 612
D [30/Jan/2012:21:45:06 +0100] [Job 3] -dict-
D [30/Jan/2012:21:45:06 +0100] [Job 3] 5
D [30/Jan/2012:21:45:06 +0100] [Job 3] Copying page 2...
D [30/Jan/2012:21:45:06 +0100] [Job 3] pagew = 590.5, pagel = 762.0
D [30/Jan/2012:21:45:06 +0100] [Job 3] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [30/Jan/2012:21:45:06 +0100] [Job 3] PageLeft = 10.8, PageRight = 601.2
D [30/Jan/2012:21:45:06 +0100] [Job 3] PageTop = 777.0, PageBottom = 15.0
D [30/Jan/2012:21:45:06 +0100] [Job 3] PageWidth = 612.0, PageLength = 792.0
D [30/Jan/2012:21:45:06 +0100] [Job 3] PID 51909 (gs) exited with no errors.
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 2 pages...
D [30/Jan/2012:21:45:06 +0100] [Job 3] PID 51910 (pstops) exited with no errors.
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups_get_params(0x8041b7058, 0x7fffffffc210)
D [30/Jan/2012:21:45:06 +0100] [Job 3] before gdev_prn_get_params()
D [30/Jan/2012:21:45:06 +0100] [Job 3] after gdev_prn_get_params()
D [30/Jan/2012:21:45:06 +0100] [Job 3] Leaving cups_get_params()
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups_print_pages(0x8041b7058, 0x802a88d70, 1)
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsBitsPerPixel = 1, cupsWidth = 2460, cupsBytesPerLine = 308, srcbytes = 308
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Next requested page : 1 (# pages into memory=0/30)
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsWidth = 2460, cupsHeight = 3175, cupsBytesPerLine = 308
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Document width=2464 height=3175
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Page width=2552 (319) height=3174
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Margin width in bytes=6 height=63
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Clipping X=0 Y=0
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Line size=308, Plane size=1052700, bytes to copy=307
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups_print_chunked - flip = 0, height = 3175
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Page 1 (2460×3175 on 2552×3300) has been successfully loaded into memory
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Page 1 has been compressed and is ready for rendering
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 348 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] STATE: -media-empty-warning
D [30/Jan/2012:21:45:06 +0100] [Job 3] STATE: -offline-report
D [30/Jan/2012:21:45:06 +0100] [Job 3] Printer is now connected.
D [30/Jan/2012:21:45:06 +0100] [Job 3] Set job-printer-state-message to "Printer is now connected.", current level=INFO
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 348 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 17 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 17 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 11 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 11 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 4 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Processing page 2...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Set job-printer-state-message to "Processing page 2...", current level=INFO
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups_get_matrix(0x8041b7058, 0x7fffffffc220)
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups->header.Duplex = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups->page = 2
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsPPD = 0x8042f2180
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsPPD->flip_duplex = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] width = 2460, height = 3175
D [30/Jan/2012:21:45:06 +0100] [Job 3] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:21:45:06 +0100] [Job 3] Putting.
D [30/Jan/2012:21:45:06 +0100] [Job 3] [612.0 792.0]
D [30/Jan/2012:21:45:06 +0100] [Job 3] /.MediaSize
D [30/Jan/2012:21:45:06 +0100] [Job 3] false
D [30/Jan/2012:21:45:06 +0100] [Job 3] /Separations
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] /
D [30/Jan/2012:21:45:06 +0100] [Job 3] EBUG: Setting cupsRowCount to 0...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Setting cupsRowFeed to 0...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Setting cupsRowStep to 0...
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups_set_color_info(0x8041b7058)
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsEncodeLUT[0] = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsEncodeLUT[65535] = 1
D [30/Jan/2012:21:45:06 +0100] [Job 3] num_components = 1, depth = 1
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsColorSpace = 3, cupsColorOrder = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsBitsPerPixel = 1, cupsBitsPerColor = 1
D [30/Jan/2012:21:45:06 +0100] [Job 3] max_gray = 1, dither_grays = 2
D [30/Jan/2012:21:45:06 +0100] [Job 3] max_color = 0, dither_colors = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] Result of putting.
D [30/Jan/2012:21:45:06 +0100] [Job 3] /undefined
D [30/Jan/2012:21:45:06 +0100] [Job 3] /
D [30/Jan/2012:21:45:06 +0100] [Job 3] late
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0.0
D [30/Jan/2012:21:45:06 +0100] [Job 3] /
D [30/Jan/2012:21:45:06 +0100] [Job 3] 1
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsColorSpace = 3, cupsColorOrder = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] cupsBitsPerPixel = 1, cupsBitsPerColor = 1
D [30/Jan/2012:21:45:06 +0100] [Job 3] max_gray = 1, dither_grays = 2
D [30/Jan/2012:21:45:06 +0100] [Job 3] max_color = 0, dither_colors = 0
D [30/Jan/2012:21:45:06 +0100] [Job 3] Result of putting.
D [30/Jan/2012:21:45:06 +0100] [Job 3] /undefined
D [30/Jan/2012:21:45:06 +0100] [Job 3] /
D [30/Jan/2012:21:45:06 +0100] [Job 3] ---- 0x0402 0x00565350
D [30/Jan/2012:21:45:06 +0100] [Job 3] Execution stack at 0x8040963f8:
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de3e8: 0x0f oper --F---e-- 0x0000 0x00458630 = %interp_exit
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de3f8: 0x0d name --S---e-- 0x03f6 0x8040a1818 = .runexec2
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de408: 0x03 file --G-rx--- 0x0001 0x804435250
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de418: 0x05 mpry --G-rxe-- 0x0006 0x8040a7840
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de428: 0x0e null --F---e-- 0x0003 0x00465160
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de438: 0x0e null --F------ 0x0402 0x00565350
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de448: 0x0b int  --F------ 0x0000 0x00000002 = 2
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de458: 0x0f oper --F---e-- 0x0000 0x00465100 = %stopped_push
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de468: 0x03 file --G-rxe-- 0x0001 0x804435250
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de478: 0x05 mpry --G-rxe-- 0x0002 0x8040aa3d8
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de488: 0x06 spry --G-rxe-- 0x0002 0x8040aa37a
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de498: 0x04 arry --L-rxe-- 0x0002 0x8045c9f08
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de4a8: 0x04 arry --L-rxe-- 0x0019 0x8045c9ce8
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de4b8: 0x06 spry --G-rxe-- 0x0001 0x80423650e
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de4c8: 0x05 mpry --G-rxe-- 0x0004 0x8042364b2
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040de4d8: 0x05 mpry --DEBUG: Wrote 4 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] G-rxe-- 0x0039 0x8040aab6a
D [30/Jan/2012:21:45:06 +0100] [Job 3] Dictionary stack at 0x804096360:
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040f1d28: 0x02 dict --Gwrx--- 0x0000 0x80408c058
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040f1d38: 0x02 dict --Gwrx--- 0x0000 0x8041fe058
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040f1d48: 0x02 dict --Lwrx--- 0x0000 0x804091eb0
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040f1d58: 0x02 dict --Gwrx--- 0x0000 0x80408c058
D [30/Jan/2012:21:45:06 +0100] [Job 3] 0x8040f1d68: 0x02 dict --Lwrx--- 0x0000 0x804093a78
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] cups_close(0x8041b7058)
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX No more pages
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Compression thread: work done. See ya
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Compression thread: work done. See ya
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] PAGE: 1 1
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Next requested page : 2 (# pages into memory=0/30)
D [30/Jan/2012:21:45:06 +0100] [Job 3] SpliX Cache controller unloaded. See ya
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 8192 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Read 6525 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] Wrote 6525 bytes of print data...
D [30/Jan/2012:21:45:06 +0100] [Job 3] End of messages
D [30/Jan/2012:21:45:06 +0100] [Job 3] printer-state=3(idle)
D [30/Jan/2012:21:45:06 +0100] [Job 3] printer-state-message="/usr/local/libexec/cups/filter/pstoraster failed"
D [30/Jan/2012:21:45:06 +0100] [Job 3] printer-state-reasons=none
I [30/Jan/2012:21:45:37 +0100] Saving job.cache...
E [30/Jan/2012:21:50:07 +0100] [Job 3] Stopping unresponsive job!
I [30/Jan/2012:21:51:01 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=53046)
I [30/Jan/2012:21:54:56 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=56732)
I [30/Jan/2012:21:55:01 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=56815)
I [30/Jan/2012:21:55:01 +0100] [Job 3] Canceled by "guest".
I [30/Jan/2012:21:55:03 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=56844)
I [30/Jan/2012:21:55:10 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=57003)
I [30/Jan/2012:21:55:13 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57060)
I [30/Jan/2012:21:55:14 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57064)
I [30/Jan/2012:21:55:22 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57173)
I [30/Jan/2012:21:55:22 +0100] All jobs on "Samsung-ML-1610" were purged by "anonymous".
I [30/Jan/2012:21:55:23 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57195)
I [30/Jan/2012:21:55:26 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57220)
I [30/Jan/2012:21:55:28 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=57239)
I [30/Jan/2012:21:55:30 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=57249)
I [30/Jan/2012:21:55:31 +0100] Saving job.cache...
I [30/Jan/2012:21:55:32 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=57262)
I [30/Jan/2012:21:55:33 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=57272)
I [30/Jan/2012:21:55:34 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57288)
I [30/Jan/2012:21:55:35 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57295)
I [30/Jan/2012:21:55:40 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57347)
I [30/Jan/2012:21:55:40 +0100] Printer "Samsung-ML-1610" stopped by "anonymous".
I [30/Jan/2012:21:55:44 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57381)
I [30/Jan/2012:21:55:45 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57392)
I [30/Jan/2012:21:55:48 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57427)
I [30/Jan/2012:21:55:48 +0100] Printer "Samsung-ML-1610" started by "anonymous".
I [30/Jan/2012:21:55:50 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57492)
I [30/Jan/2012:21:55:52 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57512)
I [30/Jan/2012:21:55:57 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=57619)
I [30/Jan/2012:21:55:57 +0100] Default destination set to "Samsung-ML-1610" by "anonymous".
I [30/Jan/2012:21:55:59 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57638)
I [30/Jan/2012:21:56:01 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=57647)
I [30/Jan/2012:21:56:09 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=57787)
I [30/Jan/2012:21:56:09 +0100] Started "/usr/local/libexec/cups/daemon/cups-deviced" (pid=57788)
I [30/Jan/2012:21:56:10 +0100] Saving printers.conf...
I [30/Jan/2012:21:56:10 +0100] Saving classes.conf...
I [30/Jan/2012:21:56:10 +0100] Generating printcap /etc/printcap...
I [30/Jan/2012:21:57:44 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=58565)
I [30/Jan/2012:21:57:48 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=58566)
I [30/Jan/2012:21:57:48 +0100] Started "/usr/local/libexec/cups/daemon/cups-driverd" (pid=58567)
I [30/Jan/2012:21:57:48 +0100] [cups-driverd] Read "/var/db/cups/ppds.dat", 2055 PPDs...
W [30/Jan/2012:21:57:48 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Brother/BR5070DN_GPL.ppd.gz!
W [30/Jan/2012:21:57:48 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp830c.ppd.gz!
W [30/Jan/2012:21:57:48 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp850c.ppd.gz!
W [30/Jan/2012:21:57:48 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp880c.ppd.gz!
W [30/Jan/2012:21:57:48 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp9100.ppd.gz!
W [30/Jan/2012:21:57:48 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp920c.ppd.gz!
W [30/Jan/2012:21:57:48 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp950c.ppd.gz!
W [30/Jan/2012:21:57:48 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp960s.ppd.gz!
W [30/Jan/2012:21:57:48 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp980c.ppd.gz!
W [30/Jan/2012:21:57:48 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/KONICA_MINOLTA/KOC451JX.ppd.gz!
E [30/Jan/2012:21:57:48 +0100] [cups-driverd] Bad driver information file "/usr/local/share/cups/model/foomatic/Kyocera/ReadMe.htm"!
E [30/Jan/2012:21:57:48 +0100] [cups-driverd] Bad driver information file "/usr/local/share/cups/model/foomatic/Kyocera/kyocera_ppd_8.2.txt"!
E [30/Jan/2012:21:57:48 +0100] [cups-driverd] Skipping "/usr/local/share/cups/model/foomatic-db-ppds": loop detected!
I [30/Jan/2012:21:57:48 +0100] [cups-driverd] Wrote "/var/db/cups/ppds.dat", 2055 PPDs...
I [30/Jan/2012:21:57:57 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=58581)
I [30/Jan/2012:21:57:57 +0100] Setting Samsung-ML-1610 device-uri to "usb:/dev/unlpt0" (was "usb:/dev/unlpt0".)
I [30/Jan/2012:21:57:57 +0100] Setting Samsung-ML-1610 printer-is-shared to 1 (was 1.)
I [30/Jan/2012:21:57:57 +0100] Setting Samsung-ML-1610 printer-state to 3 (was 3.)
I [30/Jan/2012:21:57:57 +0100] Printer "Samsung-ML-1610" modified by "anonymous".
I [30/Jan/2012:21:57:59 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=58588)
I [30/Jan/2012:21:58:04 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=58595)
I [30/Jan/2012:21:58:09 +0100] Started "/usr/local/libexec/cups/cgi-bin/jobs.cgi" (pid=58602)
I [30/Jan/2012:21:58:11 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=58603)
I [30/Jan/2012:21:58:28 +0100] Saving printers.conf...
I [30/Jan/2012:21:58:28 +0100] Generating printcap /etc/printcap...
I [30/Jan/2012:22:04:36 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=59428)
I [30/Jan/2012:22:04:38 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=59429)
I [30/Jan/2012:22:04:40 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=59430)
I [30/Jan/2012:22:04:44 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=59437)
I [30/Jan/2012:22:04:44 +0100] Started "/usr/local/libexec/cups/daemon/cups-deviced" (pid=59438)
I [30/Jan/2012:22:04:51 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=59463)
I [30/Jan/2012:22:04:53 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=59464)
I [30/Jan/2012:22:04:53 +0100] Started "/usr/local/libexec/cups/daemon/cups-driverd" (pid=59465)
I [30/Jan/2012:22:04:53 +0100] [cups-driverd] Read "/var/db/cups/ppds.dat", 2055 PPDs...
W [30/Jan/2012:22:04:53 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Brother/BR5070DN_GPL.ppd.gz!
W [30/Jan/2012:22:04:53 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp830c.ppd.gz!
W [30/Jan/2012:22:04:53 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp850c.ppd.gz!
W [30/Jan/2012:22:04:53 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp880c.ppd.gz!
W [30/Jan/2012:22:04:53 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp9100.ppd.gz!
W [30/Jan/2012:22:04:53 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp920c.ppd.gz!
W [30/Jan/2012:22:04:53 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp950c.ppd.gz!
W [30/Jan/2012:22:04:53 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp960s.ppd.gz!
W [30/Jan/2012:22:04:53 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/Epson/eplp980c.ppd.gz!
W [30/Jan/2012:22:04:53 +0100] [CGI] Missing NickName and ModelName in /usr/local/share/cups/model/foomatic/KONICA_MINOLTA/KOC451JX.ppd.gz!
E [30/Jan/2012:22:04:53 +0100] [cups-driverd] Bad driver information file "/usr/local/share/cups/model/foomatic/Kyocera/ReadMe.htm"!
E [30/Jan/2012:22:04:53 +0100] [cups-driverd] Bad driver information file "/usr/local/share/cups/model/foomatic/Kyocera/kyocera_ppd_8.2.txt"!
E [30/Jan/2012:22:04:53 +0100] [cups-driverd] Skipping "/usr/local/share/cups/model/foomatic-db-ppds": loop detected!
I [30/Jan/2012:22:04:53 +0100] [cups-driverd] Wrote "/var/db/cups/ppds.dat", 2055 PPDs...
I [30/Jan/2012:22:04:55 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=59467)
I [30/Jan/2012:22:04:55 +0100] Setting Samsung-ML-1610 device-uri to "usb:/dev/ulpt0" (was "usb:/dev/unlpt0".)
I [30/Jan/2012:22:04:55 +0100] Setting Samsung-ML-1610 printer-is-shared to 1 (was 1.)
I [30/Jan/2012:22:04:55 +0100] Setting Samsung-ML-1610 printer-state to 3 (was 3.)
I [30/Jan/2012:22:04:55 +0100] Printer "Samsung-ML-1610" modified by "anonymous".
I [30/Jan/2012:22:05:00 +0100] Started "/usr/local/libexec/cups/cgi-bin/admin.cgi" (pid=59474)
I [30/Jan/2012:22:05:00 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=59475)
I [30/Jan/2012:22:05:09 +0100] [Job ???] Request file type is application/pdf.
I [30/Jan/2012:22:05:16 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=59815)
I [30/Jan/2012:22:05:24 +0100] Hint: Try setting the LogLevel to "debug" to find out more.
E [30/Jan/2012:22:05:24 +0100] [Job 4] Job stopped due to filter errors; please consult the error_log file for details.
D [30/Jan/2012:22:05:24 +0100] [Job 4] The following messages were recorded from 22:05:22 to 22:05:24
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->page = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD = 0x8042f2180
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD->flip_duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] width = 2460, height = 3175
D [30/Jan/2012:22:05:24 +0100] [Job 4] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_get_params(0x8041b7058, 0x7fffffffc210)
D [30/Jan/2012:22:05:24 +0100] [Job 4] before gdev_prn_get_params()
D [30/Jan/2012:22:05:24 +0100] [Job 4] after gdev_prn_get_params()
D [30/Jan/2012:22:05:24 +0100] [Job 4] Leaving cups_get_params()
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_get_matrix(0x8041b7058, 0x7fffffffc280)
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->header.Duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->page = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD = 0x8042f2180
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD->flip_duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] width = 2460, height = 3175
D [30/Jan/2012:22:05:24 +0100] [Job 4] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_get_params(0x8041b7058, 0x7fffffffc210)
D [30/Jan/2012:22:05:24 +0100] [Job 4] before gdev_prn_get_params()
D [30/Jan/2012:22:05:24 +0100] [Job 4] after gdev_prn_get_params()
D [30/Jan/2012:22:05:24 +0100] [Job 4] Leaving cups_get_params()
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_get_matrix(0x8041b7058, 0x7fffffffbee0)
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->header.Duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->page = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD = 0x8042f2180
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD->flip_duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] width = 2460, height = 3175
D [30/Jan/2012:22:05:24 +0100] [Job 4] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_get_matrix(0x8041b7058, 0x7fffffffbee0)
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->header.Duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->page = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD = 0x8042f2180
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD->flip_duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] width = 2460, height = 3175
D [30/Jan/2012:22:05:24 +0100] [Job 4] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_get_matrix(0x8041b7058, 0x7fffffffbee0)
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->header.Duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->page = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD = 0x8042f2180
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD->flip_duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] width = 2460, height = 3175
D [30/Jan/2012:22:05:24 +0100] [Job 4] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_get_matrix(0x8041b7058, 0x7fffffffbee0)
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->header.Duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->page = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD = 0x8042f2180
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD->flip_duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] width = 2460, height = 3175
D [30/Jan/2012:22:05:24 +0100] [Job 4] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_get_matrix(0x8041b7058, 0x7fffffffc220)
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->header.Duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->page = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD = 0x8042f2180
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD->flip_duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] width = 2460, height = 3175
D [30/Jan/2012:22:05:24 +0100] [Job 4] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] Finishing.
D [30/Jan/2012:22:05:24 +0100] [Job 4] -dict-
D [30/Jan/2012:22:05:24 +0100] [Job 4] -dict-
D [30/Jan/2012:22:05:24 +0100] [Job 4] -dict-
D [30/Jan/2012:22:05:24 +0100] [Job 4] -dict-
D [30/Jan/2012:22:05:24 +0100] [Job 4] -mark-
D [30/Jan/2012:22:05:24 +0100] [Job 4] -dict-
D [30/Jan/2012:22:05:24 +0100] [Job 4] false
D [30/Jan/2012:22:05:24 +0100] [Job 4] 792
D [30/Jan/2012:22:05:24 +0100] [Job 4] 612
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0.0
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0.0
D [30/Jan/2012:22:05:24 +0100] [Job 4] 792
D [30/Jan/2012:22:05:24 +0100] [Job 4] 612
D [30/Jan/2012:22:05:24 +0100] [Job 4] -dict-
D [30/Jan/2012:22:05:24 +0100] [Job 4] 5
D [30/Jan/2012:22:05:24 +0100] [Job 4] Wrote 1 pages...
D [30/Jan/2012:22:05:24 +0100] [Job 4] PID 59645 (pstops) exited with no errors.
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_get_params(0x8041b7058, 0x7fffffffc210)
D [30/Jan/2012:22:05:24 +0100] [Job 4] before gdev_prn_get_params()
D [30/Jan/2012:22:05:24 +0100] [Job 4] after gdev_prn_get_params()
D [30/Jan/2012:22:05:24 +0100] [Job 4] Leaving cups_get_params()
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_print_pages(0x8041b7058, 0x802a88d70, 1)
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsBitsPerPixel = 1, cupsWidth = 2460, cupsBytesPerLine = 308, srcbytes = 308
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Next requested page : 1 (# pages into memory=0/30)
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsWidth = 2460, cupsHeight = 3175, cupsBytesPerLine = 308
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Document width=2464 height=3175
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Page width=2552 (319) height=3174
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Margin width in bytes=6 height=63
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Clipping X=0 Y=0
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Line size=308, Plane size=1052700, bytes to copy=307
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_print_chunked - flip = 0, height = 3175
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Page 1 (2460×3175 on 2552×3300) has been successfully loaded into memory
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Page 1 has been compressed and is ready for rendering
D [30/Jan/2012:22:05:24 +0100] [Job 4] Read 329 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] STATE: -media-empty-warning
D [30/Jan/2012:22:05:24 +0100] [Job 4] STATE: -offline-report
D [30/Jan/2012:22:05:24 +0100] [Job 4] Printer is now connected.
D [30/Jan/2012:22:05:24 +0100] [Job 4] Set job-printer-state-message to "Printer is now connected.", current level=INFO
D [30/Jan/2012:22:05:24 +0100] [Job 4] Wrote 329 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Read 17 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Wrote 17 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Read 11 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Wrote 11 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Read 4 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] PAGE: 1 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Next requested page : 2 (# pages into memory=0/30)
D [30/Jan/2012:22:05:24 +0100] [Job 4] Processing page 2...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Set job-printer-state-message to "Processing page 2...", current level=INFO
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_get_matrix(0x8041b7058, 0x7fffffffc220)
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->header.Duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups->page = 2
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD = 0x8042f2180
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsPPD->flip_duplex = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] width = 2460, height = 3175
D [30/Jan/2012:22:05:24 +0100] [Job 4] PageSize = [ 612 792 ], HWResolution = [ 300 300 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] HWMargins = [ 10.750 15.000 10.750 15.000 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] matrix = [ 4.167 0.000 0.000 -4.167 -44.792 3237.500 ]
D [30/Jan/2012:22:05:24 +0100] [Job 4] Putting.
D [30/Jan/2012:22:05:24 +0100] [Job 4] [612.0 792.0]
D [30/Jan/2012:22:05:24 +0100] [Job 4] /.MediaSize
D [30/Jan/2012:22:05:24 +0100] [Job 4] false
D [30/Jan/2012:22:05:24 +0100] [Job 4] /Separations
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] /
D [30/Jan/2012:22:05:24 +0100] [Job 4] EBUG: Setting cupsRowCount to 0...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Setting cupsRowFeed to 0...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Setting cupsRowStep to 0...
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_set_color_info(0x8041b7058)
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsEncodeLUT[0] = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsEncodeLUT[65535] = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] num_components = 1, depth = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsColorSpace = 3, cupsColorOrder = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsBitsPerPixel = 1, cupsBitsPerColor = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] max_gray = 1, dither_grays = 2
D [30/Jan/2012:22:05:24 +0100] [Job 4] max_color = 0, dither_colors = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] Result of putting.
D [30/Jan/2012:22:05:24 +0100] [Job 4] /undefined
D [30/Jan/2012:22:05:24 +0100] [Job 4] /
D [30/Jan/2012:22:05:24 +0100] [Job 4] late
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0.0
D [30/Jan/2012:22:05:24 +0100] [Job 4] /
D [30/Jan/2012:22:05:24 +0100] [Job 4] 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsColorSpace = 3, cupsColorOrder = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] cupsBitsPerPixel = 1, cupsBitsPerColor = 1
D [30/Jan/2012:22:05:24 +0100] [Job 4] max_gray = 1, dither_grays = 2
D [30/Jan/2012:22:05:24 +0100] [Job 4] max_color = 0, dither_colors = 0
D [30/Jan/2012:22:05:24 +0100] [Job 4] Result of putting.
D [30/Jan/2012:22:05:24 +0100] [Job 4] /undefined
D [30/Jan/2012:22:05:24 +0100] [Job 4] /
D [30/Jan/2012:22:05:24 +0100] [Job 4] ---- 0x0402 0x00565350
D [30/Jan/2012:22:05:24 +0100] [Job 4] Execution stack at 0x8040963f8:
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de3e8: 0x0f oper --F---e-- 0x0000 0x00458630 = %interp_exit
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de3f8: 0x0d name --S---e-- 0x03f6 0x8040a1818 = .runexec2
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de408: 0x03 file --G-rx--- 0x0001 0x804435250
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de418: 0x05 mpry --G-rxe-- 0x0006 0x8040a7840
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de428: 0x0e null --F---e-- 0x0003 0x00465160
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de438: 0x0e null --F------ 0x0402 0x00565350
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de448: 0x0b int  --F------ 0x0000 0x00000002 = 2
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de458: 0x0f oper --F---e-- 0x0000 0x00465100 = %stopped_push
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de468: 0x03 file --G-rxe-- 0x0001 0x804435250
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de478: 0x05 mpry --G-rxe-- 0x0002 0x8040aa3d8
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de488: 0x06 spry --G-rxe-- 0x0002 0x8040aa37a
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de498: 0x04 arry --L-rxe-- 0x0002 0x8045c9f08
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de4a8: 0x04 arry --L-rxe-- 0x0019 0x8045c9ce8
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de4b8: 0x06 spry --G-rxe-- 0x0001 0x80423650e
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de4c8: 0x05 mpry --G-rxe-- 0x0004 0x8042364b2
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040de4d8: 0x05 mpry --G-rxe-- 0x0039 0x8040aab6a
D [30/Jan/2012:22:05:24 +0100] [Job 4] Dictionary stack at 0x804096360:
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040f1d28: 0x02 dict --Gwrx--- 0x0000 0x80408c058
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040f1d38: 0x02 dict --Gwrx--- 0x0000 0x8041fe058
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040f1d48: 0x02 dict --Lwrx--- 0x0000 0x804091eb0
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040f1d58: 0x02 dict --Gwrx--- 0x0000 0x80408c058
D [30/Jan/2012:22:05:24 +0100] [Job 4] 0x8040f1d68: 0x02 dict --Lwrx--- 0x0000 0x804093a78
D [30/Jan/2012:22:05:24 +0100] [Job 4] cups_close(0x8041b7058)
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX No more pages
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Compression thread: work done. See ya
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Compression thread: work done. See ya
D [30/Jan/2012:22:05:24 +0100] [Job 4] SpliX Cache controller unloaded. See ya
D [30/Jan/2012:22:05:24 +0100] [Job 4] PID 59644 (gs) exited with no errors.
D [30/Jan/2012:22:05:24 +0100] [Job 4] Wrote 4 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Read 8192 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Wrote 8192 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Read 8192 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Wrote 8192 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Read 8192 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Wrote 8192 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Read 8192 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Wrote 8192 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Read 2576 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] Wrote 2576 bytes of print data...
D [30/Jan/2012:22:05:24 +0100] [Job 4] End of messages
D [30/Jan/2012:22:05:24 +0100] [Job 4] printer-state=3(idle)
D [30/Jan/2012:22:05:24 +0100] [Job 4] printer-state-message="/usr/local/libexec/cups/filter/pstoraster failed"
D [30/Jan/2012:22:05:24 +0100] [Job 4] printer-state-reasons=none
I [30/Jan/2012:22:05:25 +0100] Saving printers.conf...
I [30/Jan/2012:22:05:25 +0100] Generating printcap /etc/printcap...
I [30/Jan/2012:22:05:25 +0100] Saving job.cache...
I [30/Jan/2012:22:06:05 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=59883)
I [30/Jan/2012:22:06:13 +0100] Started "/usr/local/libexec/cups/cgi-bin/printers.cgi" (pid=59896)