[SOLVED] I do not know why the error of cups. [ (hp) crashed on signal 9! ]

TeaYoung · 10-23-2014, 08:26 AM

Hi

English incorrectly. Please understand

I'm croos the compile to use the printer.

I was under the croos compile the package in order to use the printer.

1. cups1.5.4
2. hplip
3. system-config-printer-udev
4. python2.7.4
5. python-cups
6. python-curl
7. python-dbus ...

I know it is connected to a printer, ppd file is generated in the / etc / cups / ppd folder.

However, I do not generate a ppd file cups that are compiled.

I cups, shows an error message similar to the following.

Quote:

/var/cups/log/error_log

[cups-deviced] PID 1868 (hp) crashed on signal 9!
[cups-deviced] PID 1869 (hpfax) stopped with status 1!

Quote:

/var/cups/log/access_log

localhost - root [23/Oct/2014:12:06:03 +0000] "POST / HTTP/1.1" 401 244 CUPS-Get-Devices Successful-ok
localhost - - [23/Oct/2014:12:06:03 +0000] "POST / HTTP/1.1" 200 1023 CUPS-Get-Devices -

One month did not resolve the issue.

What's going wrong?

cusp...? hplip...? or... system-config-printer-udev ??

Please help how should solve.

I am using the hp officejet pro k8600.

business_kid · 10-24-2014, 04:21 AM

I would look suspiciously at the ppd and the dependencies of cups, e.g. ghostscript. /a good place for a list is here
http://www.linuxfromscratch.org/blfs.../pst/cups.html

It would also help if we knew what way yopu cross compiled, and for what system (e.g. x86_c4 compiles for arm-32 or whatever)

TeaYoung · 10-24-2014, 08:31 AM

Quote:

Originally Posted by business_kid

I would look suspiciously at the ppd and the dependencies of cups, e.g. ghostscript. /a good place for a list is here
http://www.linuxfromscratch.org/blfs.../pst/cups.html

It would also help if we knew what way yopu cross compiled, and for what system (e.g. x86_c4 compiles for arm-32 or whatever)

I saw your answer.

I cross-compiled in virtual box.

I'm a cross-compiled using Scratchbox.

The CPU is used exynos4412.

I am cross-compiling to arm.

I was set up by the cross-compile ghostscript. But was not well.

I found that you can be in the debug log level to cupsd. ( /etc/cupsd/cupsd.conf )

Here my cuspd debug log is as follows:

Quote:

/var/log/cups/error_log ( /etc/cups/cupsd.conf -> LogLevel debug )

I [24/Oct/2014:02:23:50 +0000] Saving job.cache...
I [24/Oct/2014:02:24:03 +0000] Remote access is disabled.
D [24/Oct/2014:02:24:03 +0000] Added auto ServerAlias kwon
I [24/Oct/2014:02:24:03 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
D [24/Oct/2014:02:24:03 +0000] Repairing ownership of "/var/run/cups"
D [24/Oct/2014:02:24:03 +0000] Repairing ownership of "/var/run/cups/certs"
D [24/Oct/2014:02:24:03 +0000] Repairing access permissions of "/var/run/cups/certs"
I [24/Oct/2014:02:24:03 +0000] Using default TempDir of /var/spool/cups/tmp...
I [24/Oct/2014:02:24:03 +0000] Configured for up to 100 clients.
I [24/Oct/2014:02:24:03 +0000] Allowing up to 100 client connections per host.
I [24/Oct/2014:02:24:03 +0000] Using policy "default" as the default.
I [24/Oct/2014:02:24:03 +0000] Full reload is required.
I [24/Oct/2014:02:24:03 +0000] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 35 types, 44 filters...
I [24/Oct/2014:02:24:03 +0000] Loading job cache file "/var/cache/cups/job.cache"...
I [24/Oct/2014:02:24:03 +0000] Full reload complete.
D [24/Oct/2014:02:24:03 +0000] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [24/Oct/2014:02:24:03 +0000] Cleaning out old files in "/var/spool/cups/tmp"...
D [24/Oct/2014:02:24:03 +0000] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [24/Oct/2014:02:24:03 +0000] Cleaning out old files in "/var/cache/cups"...
I [24/Oct/2014:02:24:03 +0000] Listening to [v1.::1]:631 on fd 7...
I [24/Oct/2014:02:24:03 +0000] Listening to 127.0.0.1:631 on fd 8...
I [24/Oct/2014:02:24:03 +0000] Listening to /var/run/cups/cups.sock:631 on fd 9...
I [24/Oct/2014:02:24:03 +0000] Resuming new connection processing...
D [24/Oct/2014:02:24:03 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [24/Oct/2014:02:24:03 +0000] Discarding unused server-started event...
D [24/Oct/2014:02:24:04 +0000] Report: clients=0
D [24/Oct/2014:02:24:04 +0000] Report: jobs=0
D [24/Oct/2014:02:24:04 +0000] Report: jobs-active=0
D [24/Oct/2014:02:24:04 +0000] Report: printers=0
D [24/Oct/2014:02:24:04 +0000] Report: printers-implicit=0
D [24/Oct/2014:02:24:04 +0000] Report: stringpool-string-count=291
D [24/Oct/2014:02:24:04 +0000] Report: stringpool-alloc-bytes=4624
D [24/Oct/2014:02:24:04 +0000] Report: stringpool-total-bytes=4784
D [24/Oct/2014:02:24:41 +0000] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Oct/2014:02:24:41 +0000] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Oct/2014:02:24:41 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [24/Oct/2014:02:24:41 +0000] cupsdAuthorize: No authentication data provided.
D [24/Oct/2014:02:24:41 +0000] cupsdReadClient: 13 1.1 CUPS-Get-Devices 1
D [24/Oct/2014:02:24:41 +0000] CUPS-Get-Devices
D [24/Oct/2014:02:24:41 +0000] cupsdIsAuthorized: username=""
D [24/Oct/2014:02:24:41 +0000] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
D [24/Oct/2014:02:24:41 +0000] cupsdSendHeader: 13 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [24/Oct/2014:02:24:41 +0000] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Oct/2014:02:24:41 +0000] cupsdCloseClient: 13
D [24/Oct/2014:02:24:41 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [24/Oct/2014:02:24:41 +0000] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Oct/2014:02:24:41 +0000] cupsdAcceptClient: 14 from localhost (Domain)
D [24/Oct/2014:02:24:41 +0000] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Oct/2014:02:24:41 +0000] cupsdCloseClient: 13
D [24/Oct/2014:02:24:41 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [24/Oct/2014:02:24:41 +0000] cupsdReadClient: 14 POST / HTTP/1.1
D [24/Oct/2014:02:24:41 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [24/Oct/2014:02:24:41 +0000] cupsdAuthorize: Authorized as root using PeerCred
D [24/Oct/2014:02:24:41 +0000] cupsdReadClient: 14 1.1 CUPS-Get-Devices 1
D [24/Oct/2014:02:24:41 +0000] CUPS-Get-Devices
D [24/Oct/2014:02:24:41 +0000] cupsdIsAuthorized: username="root"
D [24/Oct/2014:02:24:41 +0000] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-deviced"
D [24/Oct/2014:02:24:41 +0000] [CGI] argv[1] = "1"
D [24/Oct/2014:02:24:41 +0000] [CGI] argv[2] = "0"
D [24/Oct/2014:02:24:41 +0000] [CGI] argv[3] = "2"
D [24/Oct/2014:02:24:41 +0000] [CGI] argv[4] = "7"
D [24/Oct/2014:02:24:41 +0000] [CGI] argv[5] = "requested-attributes=all exclude-schemes='beh','cups-pdf','bluetooth','dnssd','http','https','ipp','lpd','ncp','parallel','scsi','smb','snmp','socket'"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@kwon"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.4"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[13] = "USER=root"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[18] = "AUTH_TYPE=PeerCred"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[19] = "LANG=en_US.UTF8"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[20] = "REDIRECT_STATUS=1"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[21] = "GATEWAY_INTERFACE=CGI/1.1"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[22] = "SERVER_NAME=localhost"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[23] = "SERVER_PORT=631"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[24] = "REMOTE_ADDR=localhost"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[25] = "REMOTE_HOST=localhost"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[26] = "SCRIPT_NAME=/"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[27] = "SCRIPT_FILENAME=/usr/share/doc/cups/"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[28] = "REMOTE_USER=root"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[29] = "SERVER_PROTOCOL=HTTP/1.1"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[30] = "HTTP_USER_AGENT=CUPS/1.5.4"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[31] = "REQUEST_METHOD=POST"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[32] = "CONTENT_LENGTH=244"
D [24/Oct/2014:02:24:41 +0000] [CGI] envp[33] = "CONTENT_TYPE=application/ipp"
D [24/Oct/2014:02:24:41 +0000] [CGI] Started /usr/lib/cups/daemon/cups-deviced (PID 1887)
I [24/Oct/2014:02:24:41 +0000] Started "/usr/lib/cups/daemon/cups-deviced" (pid=1887)
D [24/Oct/2014:02:24:41 +0000] cupsdSendCommand: 14 file=13
D [24/Oct/2014:02:24:41 +0000] [cups-deviced] Started backend /usr/lib/cups/backend/serial (PID 1888)
D [24/Oct/2014:02:24:41 +0000] [cups-deviced] Started backend /usr/lib/cups/backend/usb (PID 1889)
D [24/Oct/2014:02:24:41 +0000] [cups-deviced] Started backend /usr/lib/cups/backend/hp (PID 1890)
D [24/Oct/2014:02:24:41 +0000] [cups-deviced] Started backend /usr/lib/cups/backend/ipps (PID 1891)
D [24/Oct/2014:02:24:41 +0000] [cups-deviced] Started backend /usr/lib/cups/backend/hpfax (PID 1892)
E [24/Oct/2014:02:24:41 +0000] [cups-deviced] PID 1890 (hp) crashed on signal 9!
D [24/Oct/2014:02:24:41 +0000] [CGI] Flushed attributes...
D [24/Oct/2014:02:24:41 +0000] [cups-deviced] Found device "usb://HP/Officejet%20Pro%20K8600?serial=TH28V22060"...
D [24/Oct/2014:02:24:41 +0000] Script header: Content-Type: application/ipp
D [24/Oct/2014:02:24:41 +0000] Script header:
D [24/Oct/2014:02:24:41 +0000] [cups-deviced] PID 1889 (usb) exited with no errors.
D [24/Oct/2014:02:24:41 +0000] [cups-deviced] PID 1888 (serial) exited with no errors.
D [24/Oct/2014:02:24:41 +0000] [CGI] Flushed attributes...
D [24/Oct/2014:02:24:41 +0000] [cups-deviced] Found device "ipps"...
D [24/Oct/2014:02:24:41 +0000] [cups-deviced] PID 1891 (ipps) exited with no errors.
D [24/Oct/2014:02:24:42 +0000] [CGI] Traceback (most recent call last):
D [24/Oct/2014:02:24:42 +0000] [CGI] File "/usr/lib/cups/backend/hpfax", line 86, in <module>
D [24/Oct/2014:02:24:42 +0000] [CGI] bug("Error importing HPLIP modules: %s\n" % (pid, e))
D [24/Oct/2014:02:24:42 +0000] [CGI] TypeError: not all arguments converted during string formatting
E [24/Oct/2014:02:24:42 +0000] [cups-deviced] PID 1892 (hpfax) stopped with status 1!
D [24/Oct/2014:02:24:42 +0000] PID 1887 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
D [24/Oct/2014:02:24:42 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [24/Oct/2014:02:24:42 +0000] cupsdReadClient: 14 WAITING Closing on EOF
D [24/Oct/2014:02:24:42 +0000] cupsdCloseClient: 14
D [24/Oct/2014:02:24:42 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [24/Oct/2014:02:24:42 +0000] cupsdAcceptClient: 13 from localhost (Domain)
D [24/Oct/2014:02:24:42 +0000] cupsdReadClient: 13 POST / HTTP/1.1
D [24/Oct/2014:02:24:42 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [24/Oct/2014:02:24:42 +0000] cupsdAuthorize: No authentication data provided.
D [24/Oct/2014:02:24:42 +0000] cupsdReadClient: 13 1.1 CUPS-Get-Printers 1
D [24/Oct/2014:02:24:42 +0000] CUPS-Get-Printers
D [24/Oct/2014:02:24:42 +0000] CUPS-Get-Printers client-error-not-found: No destinations added.
D [24/Oct/2014:02:24:42 +0000] Returning IPP client-error-not-found for CUPS-Get-Printers (no URI) from localhost
D [24/Oct/2014:02:24:42 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [24/Oct/2014:02:24:42 +0000] cupsdReadClient: 13 WAITING Closing on EOF
D [24/Oct/2014:02:24:42 +0000] cupsdCloseClient: 13
D [24/Oct/2014:02:24:42 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"

What's the problem?

Thank you very much for your kind response.

Sorry Clumsy English. ^^;;

business_kid · 10-25-2014, 03:54 AM

cups looks decidedly sick. I gather your permissions are all over the place. Have you a group lp? I would imagine it will need to be recompiled.

Bear in mind also that many hp printers need a foo2zjs or foo2xqx wrapper. Why cross compile when you are working in a VM? Why not compile in the VM for the VM

TeaYoung · 10-27-2014, 01:10 AM

Quote:

Originally Posted by business_kid

cups looks decidedly sick. I gather your permissions are all over the place. Have you a group lp? I would imagine it will need to be recompiled.

Bear in mind also that many hp printers need a foo2zjs or foo2xqx wrapper. Why cross compile when you are working in a VM? Why not compile in the VM for the VM

HI

I have the lp group.

Quote:

root@test:~# cat /etc/passwd | grep lp
lp:x:7:7:lp:/var/spool/lpd:/bin/sh

do a cross-compile hplip again hpfax issue has been resolved.

Quote:

E [24/Oct/2014:02:24:42 +0000] [cups-deviced] PID 1892 (hpfax) stopped with status 1!

Add letting the /etc/init/dbus.conf hp problem has also been fixed.

Quote:

E [24/Oct/2014:02:24:41 +0000] [cups-deviced] PID 1890 (hp) crashed on signal 9!

Now / etc / ppd file may look for cups / ppd!

Quote:

root@test:~# ls -al /etc/cups/ppd/
total 36
drwxr-xr-x 2 root lp 4096 Oct 27 05:28 .
drwxr-xr-x 5 root lp 4096 Oct 27 05:27 ..
-rw-r--r-- 1 root root 26845 Oct 27 05:28 Officejet-Pro-K8600.ppd
root@test:~#

I print a file to the test. But did not print. (# lp /root/aaa )

Below cups log.

Quote:

/var/log/cups/access_log

localhost - - [27/Oct/2014:05:33:11 +0000] "POST / HTTP/1.1" 401 244 CUPS-Get-Devices successful-ok
localhost - root [27/Oct/2014:05:33:11 +0000] "POST / HTTP/1.1" 200 1164 CUPS-Get-Devices -
localhost - - [27/Oct/2014:05:33:12 +0000] "POST / HTTP/1.1" 200 921816 CUPS-Get-PPDs -
localhost - - [27/Oct/2014:05:33:14 +0000] "POST /admin/ HTTP/1.1" 401 349 CUPS-Add-Modify-Printer successful-ok
localhost - root [27/Oct/2014:05:33:14 +0000] "POST /admin/ HTTP/1.1" 200 349 CUPS-Add-Modify-Printer successful-ok
localhost - root [27/Oct/2014:05:33:15 +0000] "POST /admin/ HTTP/1.1" 200 135 Resume-Printer successful-ok
localhost - root [27/Oct/2014:05:33:15 +0000] "POST /admin/ HTTP/1.1" 200 135 CUPS-Accept-Jobs successful-ok
localhost - root [27/Oct/2014:05:33:15 +0000] "POST /admin/ HTTP/1.1" 200 135 CUPS-Set-Default successful-ok
localhost - - [27/Oct/2014:05:34:36 +0000] "POST /printers/Officejet-Pro-K8600 HTTP/1.1" 200 309 Create-Job successful-ok
localhost - - [27/Oct/2014:05:34:36 +0000] "POST /printers/Officejet-Pro-K8600 HTTP/1.1" 200 3854 Send-Document successful-ok

Quote:

/var/log/cups/error_log

D [27/Oct/2014:05:33:12 +0000] cupsdReadClient: 12 1.1 CUPS-Get-PPDs 1
D [27/Oct/2014:05:33:12 +0000] CUPS-Get-PPDs
D [27/Oct/2014:05:33:12 +0000] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-driverd"
D [27/Oct/2014:05:33:12 +0000] [CGI] argv[1] = "list"
D [27/Oct/2014:05:33:12 +0000] [CGI] argv[2] = "1"
D [27/Oct/2014:05:33:12 +0000] [CGI] argv[3] = "0"
D [27/Oct/2014:05:33:12 +0000] [CGI] argv[4] = "requested-attributes=all"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/doc/cups"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[10] = "SERVER_ADMIN=root@test"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.4"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[13] = "USER=root"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[14] = "CUPS_MAX_MESSAGE=2047"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[15] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[16] = "CUPS_ENCRYPTION=IfRequested"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[17] = "IPP_PORT=631"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[18] = "LANG=en_US.UTF8"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[21] = "SERVER_NAME=localhost"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[22] = "SERVER_PORT=631"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[23] = "REMOTE_ADDR=localhost"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[25] = "SCRIPT_NAME=/"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/doc/cups/"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[27] = "SERVER_PROTOCOL=HTTP/1.1"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[28] = "HTTP_USER_AGENT=CUPS/1.5.4"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[29] = "REQUEST_METHOD=POST"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[30] = "CONTENT_LENGTH=75"
D [27/Oct/2014:05:33:12 +0000] [CGI] envp[31] = "CONTENT_TYPE=application/ipp"
D [27/Oct/2014:05:33:12 +0000] [CGI] Started /usr/lib/cups/daemon/cups-driverd (PID 1894)
I [27/Oct/2014:05:33:12 +0000] Started "/usr/lib/cups/daemon/cups-driverd" (pid=1894)
D [27/Oct/2014:05:33:12 +0000] cupsdSendCommand: 12 file=13
I [27/Oct/2014:05:33:13 +0000] [cups-driverd] Read "/var/cache/cups/ppds.dat", 2026 PPDs...
I [27/Oct/2014:05:33:13 +0000] [cups-driverd] Read "/var/cache/cups/ppds.dat", 2026 PPDs...
D [27/Oct/2014:05:33:13 +0000] [cups-driverd] Loading "/usr/share/cups/model"...
D [27/Oct/2014:05:33:13 +0000] [cups-driverd] Loading "/usr/share/cups/drv"...
D [27/Oct/2014:05:33:13 +0000] [cups-driverd] Loading "/usr/share/cups/drv/hp"...
D [27/Oct/2014:05:33:13 +0000] [cups-driverd] Loading "/usr/local/share/ppd"...
D [27/Oct/2014:05:33:13 +0000] [cups-driverd] Loading "/usr/local/share/ppd/HP"...
D [27/Oct/2014:05:33:13 +0000] [cups-driverd] Loading "/usr/share/ppd"...
D [27/Oct/2014:05:33:13 +0000] [cups-driverd] Loading "/usr/share/ppd/HP"...
D [27/Oct/2014:05:33:13 +0000] [cups-driverd] ChangedPPD=0
I [27/Oct/2014:05:33:13 +0000] [cups-driverd] No new or changed PPDs...
I [27/Oct/2014:05:33:13 +0000] [cups-driverd] No new or changed PPDs...
D [27/Oct/2014:05:33:13 +0000] Script header: Content-Type: application/ipp
D [27/Oct/2014:05:33:13 +0000] Script header:
D [27/Oct/2014:05:33:13 +0000] PID 1894 (/usr/lib/cups/daemon/cups-driverd) exited with no errors.
D [27/Oct/2014:05:33:13 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Oct/2014:05:33:14 +0000] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Oct/2014:05:33:14 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Oct/2014:05:33:14 +0000] cupsdAuthorize: No authentication data provided.
D [27/Oct/2014:05:33:14 +0000] cupsdReadClient: 12 1.1 CUPS-Get-Printers 1
D [27/Oct/2014:05:33:14 +0000] CUPS-Get-Printers
D [27/Oct/2014:05:33:14 +0000] CUPS-Get-Printers client-error-not-found: No destinations added.
D [27/Oct/2014:05:33:14 +0000] Returning IPP client-error-not-found for CUPS-Get-Printers (no URI) from localhost
D [27/Oct/2014:05:33:14 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Oct/2014:05:33:14 +0000] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Oct/2014:05:33:14 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Oct/2014:05:33:14 +0000] cupsdAuthorize: No authentication data provided.
D [27/Oct/2014:05:33:14 +0000] cupsdReadClient: 12 1.1 CUPS-Get-Classes 1
D [27/Oct/2014:05:33:14 +0000] CUPS-Get-Classes
D [27/Oct/2014:05:33:14 +0000] CUPS-Get-Classes client-error-not-found: No destinations added.
D [27/Oct/2014:05:33:14 +0000] Returning IPP client-error-not-found for CUPS-Get-Classes (no URI) from localhost
D [27/Oct/2014:05:33:14 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Oct/2014:05:33:14 +0000] cupsdReadClient: 12 POST /admin/ HTTP/1.1
D [27/Oct/2014:05:33:14 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Oct/2014:05:33:14 +0000] cupsdAuthorize: No authentication data provided.
D [27/Oct/2014:05:33:14 +0000] cupsdReadClient: 12 1.1 CUPS-Add-Modify-Printer 1
D [27/Oct/2014:05:33:14 +0000] CUPS-Add-Modify-Printer ipp://localhost/printers/Officejet-Pro-K8600
D [27/Oct/2014:05:33:14 +0000] cupsdIsAuthorized: username=""
D [27/Oct/2014:05:33:14 +0000] Returning HTTP Unauthorized for CUPS-Add-Modify-Printer (ipp://localhost/printers/Officejet-Pro-K8600) from localhost
D [27/Oct/2014:05:33:14 +0000] cupsdSendHeader: 12 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [27/Oct/2014:05:33:14 +0000] cupsdReadClient: 12 WAITING Closing on EOF
D [27/Oct/2014:05:33:14 +0000] cupsdCloseClient: 12
D [27/Oct/2014:05:33:14 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Oct/2014:05:33:14 +0000] cupsdAcceptClient: 12 from localhost (Domain)
D [27/Oct/2014:05:33:14 +0000] cupsdAcceptClient: 13 from localhost (Domain)
D [27/Oct/2014:05:33:14 +0000] cupsdReadClient: 12 WAITING Closing on EOF
D [27/Oct/2014:05:33:14 +0000] cupsdCloseClient: 12
D [27/Oct/2014:05:33:14 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Oct/2014:05:33:14 +0000] cupsdReadClient: 13 POST /admin/ HTTP/1.1
D [27/Oct/2014:05:33:14 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Oct/2014:05:33:14 +0000] cupsdAuthorize: Authorized as root using PeerCred
D [27/Oct/2014:05:33:14 +0000] cupsdReadClient: 13 1.1 CUPS-Add-Modify-Printer 1
D [27/Oct/2014:05:33:14 +0000] CUPS-Add-Modify-Printer ipp://localhost/printers/Officejet-Pro-K8600
D [27/Oct/2014:05:33:14 +0000] cupsdIsAuthorized: username="root"
I [27/Oct/2014:05:33:14 +0000] Setting Officejet-Pro-K8600 device-uri to "usb://HP/Officejet%20Pro%20K8600?serial=TH28V22060" (was "file:///dev/null".)
D [27/Oct/2014:05:33:14 +0000] Discarding unused printer-state-changed event...
D [27/Oct/2014:05:33:14 +0000] copy_model: Running "cups-driverd cat lsb/usr/HP/hp-officejet_pro_k8600.ppd.gz"...
D [27/Oct/2014:05:33:14 +0000] Copied PPD file successfully
D [27/Oct/2014:05:33:14 +0000] cupsdMarkDirty(P-----)
D [27/Oct/2014:05:33:14 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/Oct/2014:05:33:14 +0000] cupsdMarkDirty(P-----)
D [27/Oct/2014:05:33:14 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:33:14 +0000] load_ppd: Loading /etc/cups/ppd/Officejet-Pro-K8600.ppd...
D [27/Oct/2014:05:33:14 +0000] load_ppd: Saving /var/cache/cups/Officejet-Pro-K8600.data...
D [27/Oct/2014:05:33:15 +0000] cupsdRegisterPrinter(p=0xb6f754c0(Officejet-Pro-K8600))
D [27/Oct/2014:05:33:15 +0000] cupsdMarkDirty(P-----)
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:33:15 +0000] cupsdMarkDirty(---p--)
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:33:15 +0000] Discarding unused printer-added event...
I [27/Oct/2014:05:33:15 +0000] New printer "Officejet-Pro-K8600" added by "root".
D [27/Oct/2014:05:33:15 +0000] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost/printers/Officejet-Pro-K8600) from localhost
D [27/Oct/2014:05:33:15 +0000] PID 1896 (/usr/lib/cups/daemon/cups-driverd) exited with no errors.
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:33:15 +0000] cupsdReadClient: 13 POST /admin/ HTTP/1.1
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [27/Oct/2014:05:33:15 +0000] cupsdAuthorize: Authorized as root using PeerCred
D [27/Oct/2014:05:33:15 +0000] cupsdReadClient: 13 1.1 Resume-Printer 1
D [27/Oct/2014:05:33:15 +0000] Resume-Printer ipp://localhost/printers/Officejet-Pro-K8600
D [27/Oct/2014:05:33:15 +0000] cupsdIsAuthorized: username="root"
D [27/Oct/2014:05:33:15 +0000] Discarding unused printer-state-changed event...
D [27/Oct/2014:05:33:15 +0000] cupsdRegisterPrinter(p=0xb6f754c0(Officejet-Pro-K8600))
D [27/Oct/2014:05:33:15 +0000] cupsdMarkDirty(P-----)
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [27/Oct/2014:05:33:15 +0000] Printer "Officejet-Pro-K8600" started by "root".
D [27/Oct/2014:05:33:15 +0000] Returning IPP successful-ok for Resume-Printer (ipp://localhost/printers/Officejet-Pro-K8600) from localhost
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:33:15 +0000] cupsdReadClient: 13 POST /admin/ HTTP/1.1
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [27/Oct/2014:05:33:15 +0000] cupsdAuthorize: Authorized as root using PeerCred
D [27/Oct/2014:05:33:15 +0000] cupsdReadClient: 13 1.1 CUPS-Accept-Jobs 1
D [27/Oct/2014:05:33:15 +0000] CUPS-Accept-Jobs ipp://localhost/printers/Officejet-Pro-K8600
D [27/Oct/2014:05:33:15 +0000] cupsdIsAuthorized: username="root"
D [27/Oct/2014:05:33:15 +0000] Discarding unused printer-state-changed event...
D [27/Oct/2014:05:33:15 +0000] cupsdMarkDirty(P-----)
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [27/Oct/2014:05:33:15 +0000] Printer "Officejet-Pro-K8600" now accepting jobs ("root").
D [27/Oct/2014:05:33:15 +0000] Returning IPP successful-ok for CUPS-Accept-Jobs (ipp://localhost/printers/Officejet-Pro-K8600) from localhost
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:33:15 +0000] cupsdReadClient: 13 POST / HTTP/1.1
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [27/Oct/2014:05:33:15 +0000] cupsdAuthorize: Authorized as root using PeerCred
D [27/Oct/2014:05:33:15 +0000] cupsdReadClient: 13 1.1 CUPS-Get-Default 1
D [27/Oct/2014:05:33:15 +0000] CUPS-Get-Default
D [27/Oct/2014:05:33:15 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [27/Oct/2014:05:33:15 +0000] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:33:15 +0000] cupsdReadClient: 13 POST /admin/ HTTP/1.1
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [27/Oct/2014:05:33:15 +0000] cupsdAuthorize: Authorized as root using PeerCred
D [27/Oct/2014:05:33:15 +0000] cupsdReadClient: 13 1.1 CUPS-Set-Default 1
D [27/Oct/2014:05:33:15 +0000] CUPS-Set-Default ipp://localhost/printers/Officejet-Pro-K8600
D [27/Oct/2014:05:33:15 +0000] cupsdIsAuthorized: username="root"
D [27/Oct/2014:05:33:15 +0000] Discarding unused printer-state-changed event...
D [27/Oct/2014:05:33:15 +0000] cupsdMarkDirty(PCRp--)
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [27/Oct/2014:05:33:15 +0000] Default destination set to "Officejet-Pro-K8600" by "root".
D [27/Oct/2014:05:33:15 +0000] Returning IPP successful-ok for CUPS-Set-Default (ipp://localhost/printers/Officejet-Pro-K8600) from localhost
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:33:15 +0000] cupsdReadClient: 13 WAITING Closing on EOF
D [27/Oct/2014:05:33:15 +0000] cupsdCloseClient: 13
D [27/Oct/2014:05:33:15 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/Oct/2014:05:33:41 +0000] cupsdAcceptClient: 13 from localhost (Domain)
D [27/Oct/2014:05:33:41 +0000] cupsdReadClient: 13 POST / HTTP/1.1
D [27/Oct/2014:05:33:41 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [27/Oct/2014:05:33:41 +0000] cupsdAuthorize: No authentication data provided.
D [27/Oct/2014:05:33:41 +0000] cupsdReadClient: 13 1.1 Get-Jobs 1
D [27/Oct/2014:05:33:41 +0000] Get-Jobs ipp://localhost/
D [27/Oct/2014:05:33:41 +0000] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
D [27/Oct/2014:05:33:41 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:33:41 +0000] cupsdReadClient: 13 WAITING Closing on EOF
D [27/Oct/2014:05:33:41 +0000] cupsdCloseClient: 13
D [27/Oct/2014:05:33:41 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [27/Oct/2014:05:33:44 +0000] cupsdAcceptClient: 13 from localhost (Domain)
I [27/Oct/2014:05:33:44 +0000] Saving printers.conf...
I [27/Oct/2014:05:33:44 +0000] Saving classes.conf...
D [27/Oct/2014:05:33:44 +0000] Saving remote.cache...
I [27/Oct/2014:05:33:44 +0000] Generating printcap /etc/printcap...
D [27/Oct/2014:05:33:44 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/Oct/2014:05:33:44 +0000] cupsdReadClient: 13 POST / HTTP/1.1
D [27/Oct/2014:05:33:44 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Oct/2014:05:33:44 +0000] cupsdAuthorize: No authentication data provided.
D [27/Oct/2014:05:33:44 +0000] cupsdReadClient: 13 1.1 Get-Jobs 1
D [27/Oct/2014:05:33:44 +0000] Get-Jobs ipp://localhost/
D [27/Oct/2014:05:33:44 +0000] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
D [27/Oct/2014:05:33:44 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Oct/2014:05:33:44 +0000] cupsdReadClient: 13 WAITING Closing on EOF
D [27/Oct/2014:05:33:44 +0000] cupsdCloseClient: 13
D [27/Oct/2014:05:33:44 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Oct/2014:05:34:36 +0000] cupsdAcceptClient: 13 from localhost (Domain)
D [27/Oct/2014:05:34:36 +0000] Report: clients=1
D [27/Oct/2014:05:34:36 +0000] Report: jobs=0
D [27/Oct/2014:05:34:36 +0000] Report: jobs-active=0
D [27/Oct/2014:05:34:36 +0000] Report: printers=1
D [27/Oct/2014:05:34:36 +0000] Report: printers-implicit=0
D [27/Oct/2014:05:34:36 +0000] Report: stringpool-string-count=20892
D [27/Oct/2014:05:34:36 +0000] Report: stringpool-alloc-bytes=10408
D [27/Oct/2014:05:34:36 +0000] Report: stringpool-total-bytes=385528
D [27/Oct/2014:05:34:36 +0000] cupsdReadClient: 13 POST / HTTP/1.1
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Oct/2014:05:34:36 +0000] cupsdAuthorize: No authentication data provided.
D [27/Oct/2014:05:34:36 +0000] cupsdReadClient: 13 1.1 Get-Printer-Attributes 1
D [27/Oct/2014:05:34:36 +0000] Get-Printer-Attributes ipp://localhost:631/printers/Officejet-Pro-K8600
D [27/Oct/2014:05:34:36 +0000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/Officejet-Pro-K8600) from localhost
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Oct/2014:05:34:36 +0000] cupsdReadClient: 13 POST /printers/Officejet-Pro-K8600 HTTP/1.1
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Oct/2014:05:34:36 +0000] cupsdAuthorize: No authentication data provided.
D [27/Oct/2014:05:34:36 +0000] cupsdReadClient: 13 1.1 Create-Job 1
D [27/Oct/2014:05:34:36 +0000] Create-Job ipp://localhost:631/printers/Officejet-Pro-K8600
D [27/Oct/2014:05:34:36 +0000] cupsdMarkDirty(----J-)
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/Oct/2014:05:34:36 +0000] add_job: requesting-user-name="root"
I [27/Oct/2014:05:34:36 +0000] [Job 15] Adding start banner page "none".
D [27/Oct/2014:05:34:36 +0000] Discarding unused job-created event...
I [27/Oct/2014:05:34:36 +0000] [Job 15] Queued on "Officejet-Pro-K8600" by "root".
D [27/Oct/2014:05:34:36 +0000] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/Officejet-Pro-K8600) from localhost
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:34:36 +0000] cupsdReadClient: 13 POST /printers/Officejet-Pro-K8600 HTTP/1.1
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [27/Oct/2014:05:34:36 +0000] cupsdAuthorize: No authentication data provided.
D [27/Oct/2014:05:34:36 +0000] cupsdReadClient: 13 1.1 Send-Document 1
D [27/Oct/2014:05:34:36 +0000] Send-Document ipp://localhost:631/printers/Officejet-Pro-K8600
D [27/Oct/2014:05:34:36 +0000] cupsdIsAuthorized: requesting-user-name="root"
D [27/Oct/2014:05:34:36 +0000] [Job 15] Auto-typing file...
D [27/Oct/2014:05:34:36 +0000] [Job 15] Request file type is text/plain.
D [27/Oct/2014:05:34:36 +0000] cupsdMarkDirty(----J-)
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [27/Oct/2014:05:34:36 +0000] [Job 15] File of type text/plain queued by "root".
I [27/Oct/2014:05:34:36 +0000] [Job 15] Adding end banner page "none".
D [27/Oct/2014:05:34:36 +0000] cupsdMarkDirty(----J-)
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:34:36 +0000] cupsdMarkDirty(----J-)
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:34:36 +0000] Discarding unused printer-state-changed event...
D [27/Oct/2014:05:34:36 +0000] [Job 15] job-sheets=none,none
D [27/Oct/2014:05:34:36 +0000] [Job 15] argv[0]="Officejet-Pro-K8600"
D [27/Oct/2014:05:34:36 +0000] [Job 15] argv[1]="15"
D [27/Oct/2014:05:34:36 +0000] [Job 15] argv[2]="root"
D [27/Oct/2014:05:34:36 +0000] [Job 15] argv[3]="aaa"
D [27/Oct/2014:05:34:36 +0000] [Job 15] argv[4]="1"
D [27/Oct/2014:05:34:36 +0000] [Job 15] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:d479c86e-d500-3b9d-4e4c-e952367beebf job-originating-host-name=localhost time-at-creation=1414388076 time-at-processing=1414388076"
D [27/Oct/2014:05:34:36 +0000] [Job 15] argv[6]="/var/spool/cups/d00015-001"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[2]="CUPS_DOCROOT=/usr/share/doc/cups"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[8]="HOME=/var/spool/cups/tmp"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[10]="SERVER_ADMIN=root@test"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[11]="SOFTWARE=CUPS/1.5.4"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[13]="USER=root"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[14]="CUPS_MAX_MESSAGE=2047"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[17]="IPP_PORT=631"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[18]="CHARSET=utf-8"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[19]="LANG=en.UTF-8"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[20]="PPD=/etc/cups/ppd/Officejet-Pro-K8600.ppd"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[21]="RIP_MAX_CACHE=128m"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[22]="CONTENT_TYPE=text/plain"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[23]="DEVICE_URI=usb://HP/Officejet%20Pro%20K8600?serial=TH28V22060"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[24]="PRINTER_INFO=HP Officejet Pro K8600"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[25]="PRINTER_LOCATION=test"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[26]="PRINTER=Officejet-Pro-K8600"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[27]="PRINTER_STATE_REASONS=none"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[28]="CUPS_FILETYPE=document"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[29]="FINAL_CONTENT_TYPE=printer/Officejet-Pro-K8600"
D [27/Oct/2014:05:34:36 +0000] [Job 15] envp[30]="AUTH_I****"
I [27/Oct/2014:05:34:36 +0000] [Job 15] Started filter /usr/lib/cups/filter/texttops (PID 1928)
I [27/Oct/2014:05:34:36 +0000] [Job 15] Started filter /usr/lib/cups/filter/pstotiff (PID 1929)
I [27/Oct/2014:05:34:36 +0000] [Job 15] Started filter /usr/lib/cups/filter/imagetoraster (PID 1930)
I [27/Oct/2014:05:34:36 +0000] [Job 15] Started filter /usr/lib/cups/filter/hpcups (PID 1931)
I [27/Oct/2014:05:34:36 +0000] [Job 15] Started backend /usr/lib/cups/backend/usb (PID 1932)
D [27/Oct/2014:05:34:36 +0000] Discarding unused job-state-changed event...
D [27/Oct/2014:05:34:36 +0000] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/Officejet-Pro-K8600) from localhost
D [27/Oct/2014:05:34:36 +0000] PID 1928 (/usr/lib/cups/filter/texttops) was terminated normally with signal 9.
D [27/Oct/2014:05:34:36 +0000] PID 1929 (/usr/lib/cups/filter/pstotiff) was terminated normally with signal 9.
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
D [27/Oct/2014:05:34:36 +0000] cupsdReadClient: 13 WAITING Closing on EOF
D [27/Oct/2014:05:34:36 +0000] cupsdCloseClient: 13
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Oct/2014:05:34:36 +0000] PID 1931 (/usr/lib/cups/filter/hpcups) was terminated normally with signal 9.
D [27/Oct/2014:05:34:36 +0000] [Job 15] STATE: +connecting-to-device
D [27/Oct/2014:05:34:36 +0000] Discarding unused printer-state-changed event...
D [27/Oct/2014:05:34:36 +0000] [Job 15] Printer using device file "/dev/usb/lp0"...
D [27/Oct/2014:05:34:36 +0000] [Job 15] STATE: -connecting-to-device
D [27/Oct/2014:05:34:36 +0000] Discarding unused printer-state-changed event...
D [27/Oct/2014:05:34:36 +0000] [Job 15] backendRunLoop(print_fd=0, device_fd=5, snmp_fd=-1, addr=(nil), use_bc=1, side_cb=0xb6f2ad25)
D [27/Oct/2014:05:34:36 +0000] PID 1932 (/usr/lib/cups/backend/usb) exited with no errors.
D [27/Oct/2014:05:34:36 +0000] [Job 15] imagetoraster - copying to temp print file "/var/spool/cups/tmp/0078a54564d45".
D [27/Oct/2014:05:34:36 +0000] [Job 15] Page = 612x792; 9,9 to 603,783
D [27/Oct/2014:05:34:36 +0000] [Job 15] Searching for profile "/Automatic"...
I [27/Oct/2014:05:34:36 +0000] [Job 15] Loading print file.
D [27/Oct/2014:05:34:36 +0000] [Job 15] Set job-printer-state-message to "Loading print file.", current level=INFO
D [27/Oct/2014:05:34:36 +0000] Discarding unused job-progress event...
D [27/Oct/2014:05:34:36 +0000] Discarding unused printer-state-changed event...
E [27/Oct/2014:05:34:36 +0000] [Job 15] The print file could not be opened.
D [27/Oct/2014:05:34:36 +0000] [Job 15] Set job-printer-state-message to "The print file could not be opened.", current level=ERROR
D [27/Oct/2014:05:34:36 +0000] Discarding unused job-progress event...
D [27/Oct/2014:05:34:36 +0000] Discarding unused printer-state-changed event...
D [27/Oct/2014:05:34:36 +0000] PID 1930 (/usr/lib/cups/filter/imagetoraster) stopped with status 1.
D [27/Oct/2014:05:34:36 +0000] Discarding unused job-state-changed event...
E [27/Oct/2014:05:34:36 +0000] [Job 15] Job stopped due to filter errors; please consult the error_log file for details.
D [27/Oct/2014:05:34:36 +0000] cupsdMarkDirty(----J-)
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Oct/2014:05:34:36 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Oct/2014:05:34:36 +0000] Discarding unused printer-state-changed event...
D [27/Oct/2014:05:34:37 +0000] [Job 15] Unloading...
I [27/Oct/2014:05:35:07 +0000] Saving job.cache...
D [27/Oct/2014:05:35:07 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"

Quote:

/var/log/cups/page_log

I am suspicious of erro_log bold letters are.

How Can I solve this problem?

business_kid · 10-27-2014, 10:53 AM

I was using the foo2qxq bit of foo2zjs for HP printers and decided I wanted to try hplip. As fat as I got was that it flashed me up a notice that "This printer needs a foo2xqx wrapper to work."

So I got hplip out of the way and went back to foo2zjs.

EDIT: Have you tried printing directly? Put a symlink in your /usr/share/ghostscript/<version>/lib file to point at your ppd, or choose one of the options from 'gs -h' for $DEVICE. Something like

Code:

gs -dBATCH -dNOPAUSE -dSAFER -sDEVICE=$DEVICE -sOutPutFile=/dev/usb/lp0 somefile.ps

might do it. If not, it's fairly close. man gs is your friend.