cups error "Print-Job client-error-not-authorized"

shawn.abdushakur · 2011-01-25 00:07:53

Hi all,

I can't print a test page from the web admin page. It asks me for a username and password. I enter root...nothing, my user...nothing; it just flashes back at me asking again. I look in the error_log and I see "Print-Job client-error-not-authorized" where I've tried to type in the username and password. Any help would be appreciated.

Thanks.
Shawn

shawn.abdushakur · 2011-01-27 21:20:04

bump

shawn.abdushakur · 2011-01-28 23:51:21

OK, so I fixed that problem with:

sudo lpadmin -p HP_LaserJet_P1005 -u allow:all

I now have a new problem. When I send a document to print, it acts like it prints, it shows completed in the queue on the web interface and lpq shows the printer is ready and no entries for the queue, but nothing prints.

Again, any help would be appreciated.

Shawn

myrkiada · 2011-01-30 18:45:26

This may not have anything to do with your problem, but my printer just started to behave like you describe. Documents are sent to queue, stays there for two seconds or so, and then gets marked as completed. Yet no paper comes out of my printer.

Anyway, the solution for me was to run pacman -Scc to free up some space in /var. Then suddently everything was working like it should. I'm not sure if that is what's causing your problems though, as it seems a bit wierd that we both should run out of space at the same time.

Edit: Did you just install the printer, or have you had it working before?

Last edited by myrkiada (2011-01-30 18:48:54)

shawn.abdushakur · 2011-02-03 00:26:51

I tried pacman -Scc, no dice. Also, the printer was working under Ubuntu server. I switched to Arch to resolve an ongoing problem I was having with Ubuntu which has been resolved under Arch. The printer hasn't worked under Arch though. I've tried installing it a few different times and all appears fine, but nothing ever prints. I followed the instructions here and here. Also, this computer dual boots with Arch and Lubuntu and the printer works fine under Lubuntu.

Thanks.

Shawn

Last edited by shawn.abdushakur (2011-02-06 22:25:15)

shawn.abdushakur · 2011-02-06 22:25:50

bump

shawn.abdushakur · 2011-02-08 16:27:37

Come on guys, is there seriously no one who can help me with this? Should I open this under a new topic?

Last edited by shawn.abdushakur (2011-02-08 16:28:13)

pyther · 2011-02-08 16:41:01

First do you have any idea what pacman -Scc does?

Second: log files

shawn.abdushakur · 2011-02-08 16:46:05

Yes, I do.

https://wiki.archlinux.org/index.php/Pacman

Last edited by shawn.abdushakur (2011-02-08 17:05:21)

shawn.abdushakur · 2011-02-15 04:26:09

Sorry, I didn't see the second part. Here is my cups/error_log with loglevel set to debug. I cleaned the log then restarted cups and printed a plain text document with lp.

I [14/Feb/2011:23:18:27 -0500] Listening to 0.0.0.0:631 (IPv4)
I [14/Feb/2011:23:18:27 -0500] Listening to :::631 (IPv6)
I [14/Feb/2011:23:18:27 -0500] Listening to /var/run/cups/cups.sock (Domain)
I [14/Feb/2011:23:18:27 -0500] Remote access is enabled.
D [14/Feb/2011:23:18:27 -0500] Added auto ServerAlias asus
D [14/Feb/2011:23:18:27 -0500] Added auto ServerAlias localhost.localdomain
I [14/Feb/2011:23:18:27 -0500] Loaded configuration file "/etc/cups/cupsd.conf"
I [14/Feb/2011:23:18:27 -0500] Using default TempDir of /var/spool/cups/tmp...
I [14/Feb/2011:23:18:27 -0500] Configured for up to 100 clients.
I [14/Feb/2011:23:18:27 -0500] Allowing up to 100 client connections per host.
I [14/Feb/2011:23:18:27 -0500] Using policy "default" as the default!
I [14/Feb/2011:23:18:27 -0500] Full reload is required.
I [14/Feb/2011:23:18:27 -0500] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 35 types, 45 filters...
D [14/Feb/2011:23:18:27 -0500] Loading printer HP_LaserJet_P1005...
D [14/Feb/2011:23:18:27 -0500] load_ppd: Loading /var/cache/cups/HP_LaserJet_P1005.ipp4...
D [14/Feb/2011:23:18:27 -0500] cupsdRegisterPrinter(p=0xb9080710(HP_LaserJet_P1005))
I [14/Feb/2011:23:18:27 -0500] Loading job cache file "/var/cache/cups/job.cache"...
D [14/Feb/2011:23:18:27 -0500] [Job 1] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 2] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 3] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 4] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 5] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 6] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 7] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 8] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 9] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 10] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 11] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 12] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 13] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 14] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 15] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 16] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 17] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 18] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 19] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 20] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 21] Loading from cache...
D [14/Feb/2011:23:18:27 -0500] [Job 22] Loading from cache...
I [14/Feb/2011:23:18:27 -0500] Full reload complete.
D [14/Feb/2011:23:18:27 -0500] cupsd_clean_files(path="/var/spool/cups/tmp", pattern="(null)")
I [14/Feb/2011:23:18:27 -0500] Cleaning out old files in "/var/spool/cups/tmp"...
D [14/Feb/2011:23:18:27 -0500] cupsd_clean_files(path="/var/cache/cups", pattern="*.ipp")
I [14/Feb/2011:23:18:27 -0500] Cleaning out old files in "/var/cache/cups"...
I [14/Feb/2011:23:18:27 -0500] Listening to 0.0.0.0:631 on fd 6...
E [14/Feb/2011:23:18:27 -0500] Unable to open listen socket for address :::631 - Address family not supported by protocol.
I [14/Feb/2011:23:18:27 -0500] Listening to /var/run/cups/cups.sock on fd 7...
I [14/Feb/2011:23:18:27 -0500] Resuming new connection processing...
D [14/Feb/2011:23:18:27 -0500] cupsdRegisterPrinter(p=0xb9080710(HP_LaserJet_P1005))
E [14/Feb/2011:23:18:27 -0500] Unable to set ACLs on root certificate "/var/run/cups/certs/0" - Operation not supported
D [14/Feb/2011:23:18:27 -0500] Discarding unused server-started event...
D [14/Feb/2011:23:18:28 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [14/Feb/2011:23:18:28 -0500] cupsdNetIFUpdate: "eth0" = asus.ibadalRahman.local:631
D [14/Feb/2011:23:18:28 -0500] Report: clients=0
D [14/Feb/2011:23:18:28 -0500] Report: jobs=22
D [14/Feb/2011:23:18:28 -0500] Report: jobs-active=0
D [14/Feb/2011:23:18:28 -0500] Report: printers=1
D [14/Feb/2011:23:18:28 -0500] Report: printers-implicit=0
D [14/Feb/2011:23:18:28 -0500] Report: stringpool-string-count=9095
D [14/Feb/2011:23:18:28 -0500] Report: stringpool-alloc-bytes=8344
D [14/Feb/2011:23:18:28 -0500] Report: stringpool-total-bytes=165480
I [14/Feb/2011:23:18:34 -0500] Scheduler shutting down normally.
D [14/Feb/2011:23:18:34 -0500] Discarding unused server-stopped event...
D [14/Feb/2011:23:18:34 -0500] cupsdDeregisterPrinter(p=0xb9080710(HP_LaserJet_P1005), removeit=1)
I [14/Feb/2011:23:18:34 -0500] Saving job cache file "/var/cache/cups/job.cache"...
I [14/Feb/2011:23:18:35 -0500] Listening to 0.0.0.0:631 (IPv4)
I [14/Feb/2011:23:18:35 -0500] Listening to :::631 (IPv6)
I [14/Feb/2011:23:18:35 -0500] Listening to /var/run/cups/cups.sock (Domain)
I [14/Feb/2011:23:18:35 -0500] Remote access is enabled.
D [14/Feb/2011:23:18:35 -0500] Added auto ServerAlias asus
D [14/Feb/2011:23:18:35 -0500] Added auto ServerAlias localhost.localdomain
I [14/Feb/2011:23:18:35 -0500] Loaded configuration file "/etc/cups/cupsd.conf"
I [14/Feb/2011:23:18:35 -0500] Using default TempDir of /var/spool/cups/tmp...
I [14/Feb/2011:23:18:35 -0500] Configured for up to 100 clients.
I [14/Feb/2011:23:18:35 -0500] Allowing up to 100 client connections per host.
I [14/Feb/2011:23:18:35 -0500] Using policy "default" as the default!
I [14/Feb/2011:23:18:35 -0500] Full reload is required.
I [14/Feb/2011:23:18:35 -0500] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 35 types, 45 filters...
D [14/Feb/2011:23:18:35 -0500] Loading printer HP_LaserJet_P1005...
D [14/Feb/2011:23:18:35 -0500] load_ppd: Loading /var/cache/cups/HP_LaserJet_P1005.ipp4...
D [14/Feb/2011:23:18:35 -0500] cupsdRegisterPrinter(p=0xb7c4f710(HP_LaserJet_P1005))
I [14/Feb/2011:23:18:35 -0500] Loading job cache file "/var/cache/cups/job.cache"...
D [14/Feb/2011:23:18:35 -0500] [Job 1] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 2] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 3] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 4] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 5] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 6] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 7] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 8] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 9] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 10] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 11] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 12] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 13] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 14] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 15] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 16] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 17] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 18] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 19] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 20] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 21] Loading from cache...
D [14/Feb/2011:23:18:35 -0500] [Job 22] Loading from cache...
I [14/Feb/2011:23:18:35 -0500] Full reload complete.
D [14/Feb/2011:23:18:35 -0500] cupsd_clean_files(path="/var/spool/cups/tmp", pattern="(null)")
I [14/Feb/2011:23:18:35 -0500] Cleaning out old files in "/var/spool/cups/tmp"...
D [14/Feb/2011:23:18:35 -0500] cupsd_clean_files(path="/var/cache/cups", pattern="*.ipp")
I [14/Feb/2011:23:18:35 -0500] Cleaning out old files in "/var/cache/cups"...
I [14/Feb/2011:23:18:35 -0500] Listening to 0.0.0.0:631 on fd 6...
E [14/Feb/2011:23:18:35 -0500] Unable to open listen socket for address :::631 - Address family not supported by protocol.
I [14/Feb/2011:23:18:35 -0500] Listening to /var/run/cups/cups.sock on fd 7...
I [14/Feb/2011:23:18:35 -0500] Resuming new connection processing...
D [14/Feb/2011:23:18:35 -0500] cupsdRegisterPrinter(p=0xb7c4f710(HP_LaserJet_P1005))
E [14/Feb/2011:23:18:35 -0500] Unable to set ACLs on root certificate "/var/run/cups/certs/0" - Operation not supported
D [14/Feb/2011:23:18:36 -0500] Discarding unused server-started event...
D [14/Feb/2011:23:18:37 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [14/Feb/2011:23:18:37 -0500] cupsdNetIFUpdate: "eth0" = asus.ibadalRahman.local:631
D [14/Feb/2011:23:18:37 -0500] Report: clients=0
D [14/Feb/2011:23:18:37 -0500] Report: jobs=22
D [14/Feb/2011:23:18:37 -0500] Report: jobs-active=0
D [14/Feb/2011:23:18:37 -0500] Report: printers=1
D [14/Feb/2011:23:18:37 -0500] Report: printers-implicit=0
D [14/Feb/2011:23:18:37 -0500] Report: stringpool-string-count=9095
D [14/Feb/2011:23:18:37 -0500] Report: stringpool-alloc-bytes=8344
D [14/Feb/2011:23:18:37 -0500] Report: stringpool-total-bytes=165480
D [14/Feb/2011:23:18:45 -0500] cupsdAcceptClient: 11 from localhost (Domain)
D [14/Feb/2011:23:18:45 -0500] cupsdReadClient: 11 POST / HTTP/1.1
D [14/Feb/2011:23:18:45 -0500] cupsdSetBusyState: Active clients
D [14/Feb/2011:23:18:45 -0500] cupsdAuthorize: No authentication data provided.
D [14/Feb/2011:23:18:45 -0500] cupsdReadClient: 11 1.1 CUPS-Get-Default 1
D [14/Feb/2011:23:18:45 -0500] CUPS-Get-Default
D [14/Feb/2011:23:18:45 -0500] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [14/Feb/2011:23:18:45 -0500] cupsdSetBusyState: Not busy
D [14/Feb/2011:23:18:45 -0500] cupsdReadClient: 11 POST /printers/HP_LaserJet_P1005 HTTP/1.1
D [14/Feb/2011:23:18:45 -0500] cupsdSetBusyState: Active clients
D [14/Feb/2011:23:18:45 -0500] cupsdAuthorize: No authentication data provided.
D [14/Feb/2011:23:18:45 -0500] cupsdReadClient: 11 1.1 Create-Job 1
D [14/Feb/2011:23:18:45 -0500] Create-Job ipp://localhost:631/printers/HP_LaserJet_P1005
D [14/Feb/2011:23:18:45 -0500] cupsdMarkDirty(----J-)
D [14/Feb/2011:23:18:45 -0500] cupsdSetBusyState: Active clients and dirty files
D [14/Feb/2011:23:18:45 -0500] add_job: requesting-user-name="shawn"
I [14/Feb/2011:23:18:45 -0500] [Job 23] Adding start banner page "none".
D [14/Feb/2011:23:18:45 -0500] Discarding unused job-created event...
I [14/Feb/2011:23:18:45 -0500] [Job 23] Queued on "HP_LaserJet_P1005" by "shawn".
D [14/Feb/2011:23:18:45 -0500] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/HP_LaserJet_P1005) from localhost
D [14/Feb/2011:23:18:45 -0500] cupsdSetBusyState: Dirty files
D [14/Feb/2011:23:18:45 -0500] cupsdReadClient: 11 POST /printers/HP_LaserJet_P1005 HTTP/1.1
D [14/Feb/2011:23:18:45 -0500] cupsdSetBusyState: Active clients and dirty files
D [14/Feb/2011:23:18:45 -0500] cupsdAuthorize: No authentication data provided.
D [14/Feb/2011:23:18:45 -0500] cupsdReadClient: 11 1.1 Send-Document 1
D [14/Feb/2011:23:18:45 -0500] Send-Document ipp://localhost:631/printers/HP_LaserJet_P1005
D [14/Feb/2011:23:18:45 -0500] cupsdIsAuthorized: requesting-user-name="shawn"
D [14/Feb/2011:23:18:45 -0500] [Job 23] Auto-typing file...
D [14/Feb/2011:23:18:45 -0500] [Job 23] Request file type is text/plain.
D [14/Feb/2011:23:18:45 -0500] cupsdMarkDirty(----J-)
I [14/Feb/2011:23:18:45 -0500] [Job 23] File of type text/plain queued by "shawn".
I [14/Feb/2011:23:18:45 -0500] [Job 23] Adding end banner page "none".
D [14/Feb/2011:23:18:45 -0500] cupsdMarkDirty(----J-)
D [14/Feb/2011:23:18:45 -0500] cupsdMarkDirty(----J-)
D [14/Feb/2011:23:18:45 -0500] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [14/Feb/2011:23:18:45 -0500] Discarding unused printer-state-changed event...
D [14/Feb/2011:23:18:45 -0500] [Job 23] job-sheets=none,none
D [14/Feb/2011:23:18:45 -0500] [Job 23] argv[0]="HP_LaserJet_P1005"
D [14/Feb/2011:23:18:45 -0500] [Job 23] argv[1]="23"
D [14/Feb/2011:23:18:45 -0500] [Job 23] argv[2]="shawn"
D [14/Feb/2011:23:18:45 -0500] [Job 23] argv[3]="movies to get.txt"
D [14/Feb/2011:23:18:45 -0500] [Job 23] argv[4]="1"
D [14/Feb/2011:23:18:45 -0500] [Job 23] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:277f26c4-7082-3c60-4419-899eb44da915 job-originating-host-name=localhost time-at-creation=1297743525 time-at-processing=1297743525 AP_D_InputSlot="
D [14/Feb/2011:23:18:45 -0500] [Job 23] argv[6]="/var/spool/cups/d00023-001"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[8]="HOME=/var/spool/cups/tmp"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[10]="SERVER_ADMIN=root@localhost.localdomain"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[11]="SOFTWARE=CUPS/1.4.6"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[13]="USER=root"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[16]="IPP_PORT=631"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[17]="CHARSET=utf-8"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[18]="LANG=en_US.UTF-8"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[19]="PPD=/etc/cups/ppd/HP_LaserJet_P1005.ppd"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[20]="RIP_MAX_CACHE=8m"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[21]="CONTENT_TYPE=text/plain"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[22]="DEVICE_URI=hp:/usb/HP_LaserJet_P1005?serial=BC1569M"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[23]="PRINTER_INFO=HP LaserJet P1005"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[24]="PRINTER_LOCATION=Basement"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[25]="PRINTER=HP_LaserJet_P1005"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[26]="CUPS_FILETYPE=document"
D [14/Feb/2011:23:18:45 -0500] [Job 23] envp[27]="FINAL_CONTENT_TYPE=printer/HP_LaserJet_P1005"
I [14/Feb/2011:23:18:45 -0500] [Job 23] Started filter /usr/lib/cups/filter/texttops (PID 1161)
I [14/Feb/2011:23:18:45 -0500] [Job 23] Started filter /usr/lib/cups/filter/pstops (PID 1162)
I [14/Feb/2011:23:18:45 -0500] [Job 23] Started filter /usr/lib/cups/filter/foomatic-rip (PID 1163)
I [14/Feb/2011:23:18:45 -0500] [Job 23] Started backend /usr/lib/cups/backend/hp (PID 1164)
D [14/Feb/2011:23:18:45 -0500] Discarding unused job-state-changed event...
D [14/Feb/2011:23:18:45 -0500] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/HP_LaserJet_P1005) from localhost
D [14/Feb/2011:23:18:45 -0500] cupsdSetBusyState: Printing jobs and dirty files
D [14/Feb/2011:23:18:45 -0500] cupsdReadClient: 11 WAITING Closing on EOF
D [14/Feb/2011:23:18:45 -0500] cupsdCloseClient: 11
D [14/Feb/2011:23:18:45 -0500] [Job 23] Getting input from file 
D [14/Feb/2011:23:18:45 -0500] [Job 23] foomatic-rip version 4.0.6.230 running...
D [14/Feb/2011:23:18:45 -0500] [Job 23] Parsing PPD file ...
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option PageSize
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option Quality
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option Resolution
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option ImageableArea
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option PaperDimension
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option InputSlot
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option MediaType
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option Density
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option Duplex
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option Copies
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option halftone
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option NupOrient
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option NupPages
D [14/Feb/2011:23:18:45 -0500] [Job 23] Added option Font
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] Parameter Summary
D [14/Feb/2011:23:18:45 -0500] [Job 23] -----------------
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] Spooler: cups
D [14/Feb/2011:23:18:45 -0500] [Job 23] Printer: HP_LaserJet_P1005
D [14/Feb/2011:23:18:45 -0500] [Job 23] Shell: /bin/bash
D [14/Feb/2011:23:18:45 -0500] [Job 23] PPD file: /etc/cups/ppd/HP_LaserJet_P1005.ppd
D [14/Feb/2011:23:18:45 -0500] [Job 23] ATTR file: 
D [14/Feb/2011:23:18:45 -0500] [Job 23] Printer model: HP LaserJet P1005 Foomatic/foo2xqx (recommended)
D [14/Feb/2011:23:18:45 -0500] [Job 23] Job title: movies to get.txt
D [14/Feb/2011:23:18:45 -0500] [Job 23] File(s) to be printed:
D [14/Feb/2011:23:18:45 -0500] [Job 23] <STDIN>
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [14/Feb/2011:23:18:45 -0500] [Job 23] Printing system options:
D [14/Feb/2011:23:18:45 -0500] [Job 23] Pondering option 'finishings=3'
D [14/Feb/2011:23:18:45 -0500] [Job 23] Unknown option finishings=3.
D [14/Feb/2011:23:18:45 -0500] [Job 23] Pondering option 'number-up=1'
D [14/Feb/2011:23:18:45 -0500] [Job 23] Unknown option number-up=1.
D [14/Feb/2011:23:18:45 -0500] [Job 23] Pondering option 'job-uuid=urn:uuid:277f26c4-7082-3c60-4419-899eb44da915'
D [14/Feb/2011:23:18:45 -0500] [Job 23] Unknown option job-uuid=urn:uuid:277f26c4-7082-3c60-4419-899eb44da915.
D [14/Feb/2011:23:18:45 -0500] [Job 23] Pondering option 'job-originating-host-name=localhost'
D [14/Feb/2011:23:18:45 -0500] [Job 23] Unknown option job-originating-host-name=localhost.
D [14/Feb/2011:23:18:45 -0500] [Job 23] Pondering option 'time-at-creation=1297743525'
D [14/Feb/2011:23:18:45 -0500] [Job 23] Unknown option time-at-creation=1297743525.
D [14/Feb/2011:23:18:45 -0500] [Job 23] Pondering option 'time-at-processing=1297743525'
D [14/Feb/2011:23:18:45 -0500] [Job 23] Unknown option time-at-processing=1297743525.
D [14/Feb/2011:23:18:45 -0500] [Job 23] Pondering option 'AP_D_InputSlot='
D [14/Feb/2011:23:18:45 -0500] [Job 23] Unknown option AP_D_InputSlot=.
D [14/Feb/2011:23:18:45 -0500] [Job 23] Options from the PPD file:
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] ================================================
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] File: <STDIN>
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] ================================================
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] prnt/backend/hp.c 559: dBus Connection Error (Failed to connect to socket /var/run/dbus/system_bus_socket: No such file or directory)!
D [14/Feb/2011:23:18:45 -0500] [Job 23] Page = 612x792; 11,11 to 601,781
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->num_fonts = 35
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[0] = AvantGarde-Book
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[1] = AvantGarde-BookOblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[2] = AvantGarde-Demi
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[3] = AvantGarde-DemiOblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[4] = Bookman-Demi
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[5] = Bookman-DemiItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[6] = Bookman-Light
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[7] = Bookman-LightItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[8] = Courier
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[9] = Courier-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[10] = Courier-BoldOblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[11] = Courier-Oblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[12] = Helvetica
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[13] = Helvetica-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[14] = Helvetica-BoldOblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[15] = Helvetica-Narrow
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[16] = Helvetica-Narrow-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[17] = Helvetica-Narrow-BoldOblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[18] = Helvetica-Narrow-Oblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[19] = Helvetica-Oblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[20] = NewCenturySchlbk-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[21] = NewCenturySchlbk-BoldItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[22] = NewCenturySchlbk-Italic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[23] = NewCenturySchlbk-Roman
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[24] = Palatino-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[25] = Palatino-BoldItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[26] = Palatino-Italic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[27] = Palatino-Roman
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[28] = Symbol
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[29] = Times-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[30] = Times-BoldItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[31] = Times-Italic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[32] = Times-Roman
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[33] = ZapfChancery-MediumItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[34] = ZapfDingbats
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->num_fonts = 35
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[0] = AvantGarde-Book
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[1] = AvantGarde-BookOblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[2] = AvantGarde-Demi
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[3] = AvantGarde-DemiOblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[4] = Bookman-Demi
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[5] = Bookman-DemiItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[6] = Bookman-Light
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[7] = Bookman-LightItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[8] = Courier
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[9] = Courier-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[10] = Courier-BoldOblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[11] = Courier-Oblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[12] = Helvetica
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[13] = Helvetica-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[14] = Helvetica-BoldOblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[15] = Helvetica-Narrow
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[16] = Helvetica-Narrow-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[17] = Helvetica-Narrow-BoldOblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[18] = Helvetica-Narrow-Oblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[19] = Helvetica-Oblique
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[20] = NewCenturySchlbk-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[21] = NewCenturySchlbk-BoldItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[22] = NewCenturySchlbk-Italic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[23] = NewCenturySchlbk-Roman
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[24] = Palatino-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[25] = Palatino-BoldItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[26] = Palatino-Italic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[27] = Palatino-Roman
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[28] = Symbol
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[29] = Times-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[30] = Times-BoldItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[31] = Times-Italic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[32] = Times-Roman
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[33] = ZapfChancery-MediumItalic
D [14/Feb/2011:23:18:45 -0500] [Job 23] ppd->fonts[34] = ZapfDingbats
D [14/Feb/2011:23:18:45 -0500] [Job 23] Page = 612x792; 11,11 to 601,781
D [14/Feb/2011:23:18:45 -0500] [Job 23] slow_collate=0, slow_duplex=0, slow_order=0
D [14/Feb/2011:23:18:45 -0500] [Job 23] Before copy_comments - %!PS-Adobe-3.0
D [14/Feb/2011:23:18:45 -0500] [Job 23] %!PS-Adobe-3.0
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%BoundingBox: 0 0 612 792
D [14/Feb/2011:23:18:45 -0500] [Job 23] %cupsRotation: 0
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%Creator: texttops/CUPS v1.4.6
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%CreationDate: Mon Feb 14 23:18:45 2011
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%Title: (movies to get.txt)
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%For: (shawn)
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%Pages: (atend)
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%DocumentNeededResources: font Monospace-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%+ font Monospace
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%DocumentSuppliedResources: procset texttops 1.1 0
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%+ font Monospace-Bold
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%+ font Monospace
D [14/Feb/2011:23:18:45 -0500] [Job 23] %%EndComments
D [14/Feb/2011:23:18:45 -0500] [Job 23] Before copy_prolog - %%BeginProlog
D [14/Feb/2011:23:18:45 -0500] [Job 23] Filetype: PostScript
D [14/Feb/2011:23:18:45 -0500] [Job 23] Reading PostScript input ...
D [14/Feb/2011:23:18:45 -0500] [Job 23] --> This document is DSC-conforming!
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found %RBINumCopies: 1
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] -----------
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginProlog
D [14/Feb/2011:23:18:45 -0500] PID 1161 (/usr/lib/cups/filter/texttops) exited with no errors.
D [14/Feb/2011:23:18:45 -0500] [Job 23] Before copy_setup - %%Page: 1 1
D [14/Feb/2011:23:18:45 -0500] [Job 23] Before page loop - %%Page: 1 1
D [14/Feb/2011:23:18:45 -0500] [Job 23] Copying page 1...
D [14/Feb/2011:23:18:45 -0500] [Job 23] pagew = 589.3, pagel = 769.3
D [14/Feb/2011:23:18:45 -0500] [Job 23] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792
D [14/Feb/2011:23:18:45 -0500] [Job 23] PageLeft = 11.3, PageRight = 600.7
D [14/Feb/2011:23:18:45 -0500] [Job 23] PageTop = 780.7, PageBottom = 11.3
D [14/Feb/2011:23:18:45 -0500] [Job 23] PageWidth = 612.0, PageLength = 792.0
D [14/Feb/2011:23:18:45 -0500] [Job 23] Wrote 1 pages...
D [14/Feb/2011:23:18:45 -0500] PID 1162 (/usr/lib/cups/filter/pstops) exited with no errors.
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%EndProlog
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] -----------
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginSetup
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginFeature: *Quality normal
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: Quality=normal
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %% FoomaticRIPOptionSetting: Quality=normal
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: Quality=normal
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginFeature: *halftone default
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: halftone=default
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %% FoomaticRIPOptionSetting: halftone=default
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: halftone=default
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginFeature: *Resolution 1200x600dpi
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: Resolution=1200x600dpi
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %% FoomaticRIPOptionSetting: Resolution=1200x600dpi
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: Resolution=1200x600dpi
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginFeature: *PageSize Letter
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: PageSize=Letter
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %% FoomaticRIPOptionSetting: PageSize=Letter
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: PageSize=Letter
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginFeature: *Density Density3
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: Density=Density3
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %% FoomaticRIPOptionSetting: Density=Density3
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: Density=Density3
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginFeature: *MediaType Plain
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: MediaType=Plain
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %% FoomaticRIPOptionSetting: MediaType=Plain
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: MediaType=Plain
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginFeature: *NupOrient port
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: NupOrient=port
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %% FoomaticRIPOptionSetting: NupOrient=port
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: NupOrient=port
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginFeature: *NupPages 1up
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: NupPages=1up
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %% FoomaticRIPOptionSetting: NupPages=1up
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: NupPages=1up
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginFeature: *Duplex None
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: Duplex=None
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %% FoomaticRIPOptionSetting: Duplex=None
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: Duplex=None
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginFeature: *Copies 1
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: Copies=1
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %% FoomaticRIPOptionSetting: Copies=1
D [14/Feb/2011:23:18:45 -0500] [Job 23] Option: Copies=1
D [14/Feb/2011:23:18:45 -0500] [Job 23] Setting option
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%EndSetup
D [14/Feb/2011:23:18:45 -0500] [Job 23] Inserting PostScript code for CUPS' page accounting
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] -----------
D [14/Feb/2011:23:18:45 -0500] [Job 23] New page: %%Page: 1 1
D [14/Feb/2011:23:18:45 -0500] [Job 23] 
D [14/Feb/2011:23:18:45 -0500] [Job 23] Found: %%BeginPageSetup
D [14/Feb/2011:23:18:45 -0500] [Job 23] Inserting option code into "PageSetup" section.
D [14/Feb/2011:23:18:45 -0500] [Job 23] Flushing FIFO.
D [14/Feb/2011:23:18:46 -0500] [Job 23] 
D [14/Feb/2011:23:18:46 -0500] [Job 23] Starting renderer with command: "foo2xqx-wrapper     -r1200x600 -p1 -T3 -m1 -s7   -d1 -n1 "
D [14/Feb/2011:23:18:46 -0500] [Job 23] Starting process "kid3" (generation 1)
D [14/Feb/2011:23:18:46 -0500] [Job 23] Starting process "kid4" (generation 2)
D [14/Feb/2011:23:18:46 -0500] [Job 23] Starting process "renderer" (generation 2)
D [14/Feb/2011:23:18:46 -0500] [Job 23] /bin/bash: foo2xqx-wrapper: command not found
D [14/Feb/2011:23:18:46 -0500] [Job 23] JCL: %-12345X@PJL
D [14/Feb/2011:23:18:46 -0500] [Job 23] <job data> 
D [14/Feb/2011:23:18:46 -0500] [Job 23] 
D [14/Feb/2011:23:18:46 -0500] [Job 23] renderer exited with status 127
D [14/Feb/2011:23:18:46 -0500] [Job 23] prnt/backend/hp.c 839: ERROR: null print job total=0
D [14/Feb/2011:23:18:46 -0500] PID 1164 (/usr/lib/cups/backend/hp) exited with no errors.
D [14/Feb/2011:23:18:46 -0500] PID 1163 (/usr/lib/cups/filter/foomatic-rip) did not catch or ignore signal 13.
D [14/Feb/2011:23:18:46 -0500] Discarding unused job-completed event...
I [14/Feb/2011:23:18:46 -0500] [Job 23] Job completed.
D [14/Feb/2011:23:18:46 -0500] cupsdMarkDirty(----J-)
D [14/Feb/2011:23:18:46 -0500] Discarding unused printer-state-changed event...
D [14/Feb/2011:23:18:47 -0500] [Job 23] Unloading...
I [14/Feb/2011:23:19:16 -0500] Saving job cache file "/var/cache/cups/job.cache"...
D [14/Feb/2011:23:19:16 -0500] cupsdSetBusyState: Not busy
D [14/Feb/2011:23:19:49 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [14/Feb/2011:23:19:49 -0500] cupsdNetIFUpdate: "eth0" = asus.ibadalRahman.local:631
D [14/Feb/2011:23:19:49 -0500] Report: clients=0
D [14/Feb/2011:23:19:49 -0500] Report: jobs=23
D [14/Feb/2011:23:19:49 -0500] Report: jobs-active=0
D [14/Feb/2011:23:19:49 -0500] Report: printers=1
D [14/Feb/2011:23:19:49 -0500] Report: printers-implicit=0
D [14/Feb/2011:23:19:49 -0500] Report: stringpool-string-count=9098
D [14/Feb/2011:23:19:49 -0500] Report: stringpool-alloc-bytes=8384
D [14/Feb/2011:23:19:49 -0500] Report: stringpool-total-bytes=165544
D [14/Feb/2011:23:20:51 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [14/Feb/2011:23:20:51 -0500] cupsdNetIFUpdate: "eth0" = asus.ibadalRahman.local:631
D [14/Feb/2011:23:20:51 -0500] Report: clients=0
D [14/Feb/2011:23:20:51 -0500] Report: jobs=23
D [14/Feb/2011:23:20:51 -0500] Report: jobs-active=0
D [14/Feb/2011:23:20:51 -0500] Report: printers=1
D [14/Feb/2011:23:20:51 -0500] Report: printers-implicit=0
D [14/Feb/2011:23:20:51 -0500] Report: stringpool-string-count=9098
D [14/Feb/2011:23:20:51 -0500] Report: stringpool-alloc-bytes=8384
D [14/Feb/2011:23:20:51 -0500] Report: stringpool-total-bytes=165544
D [14/Feb/2011:23:21:53 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [14/Feb/2011:23:21:53 -0500] cupsdNetIFUpdate: "eth0" = asus.ibadalRahman.local:631
D [14/Feb/2011:23:21:53 -0500] Report: clients=0
D [14/Feb/2011:23:21:53 -0500] Report: jobs=23
D [14/Feb/2011:23:21:53 -0500] Report: jobs-active=0
D [14/Feb/2011:23:21:53 -0500] Report: printers=1
D [14/Feb/2011:23:21:53 -0500] Report: printers-implicit=0
D [14/Feb/2011:23:21:53 -0500] Report: stringpool-string-count=9098
D [14/Feb/2011:23:21:53 -0500] Report: stringpool-alloc-bytes=8384
D [14/Feb/2011:23:21:53 -0500] Report: stringpool-total-bytes=165544
D [14/Feb/2011:23:22:55 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [14/Feb/2011:23:22:55 -0500] cupsdNetIFUpdate: "eth0" = asus.ibadalRahman.local:631
D [14/Feb/2011:23:22:55 -0500] Report: clients=0
D [14/Feb/2011:23:22:55 -0500] Report: jobs=23
D [14/Feb/2011:23:22:55 -0500] Report: jobs-active=0
D [14/Feb/2011:23:22:55 -0500] Report: printers=1
D [14/Feb/2011:23:22:55 -0500] Report: printers-implicit=0
D [14/Feb/2011:23:22:55 -0500] Report: stringpool-string-count=9098
D [14/Feb/2011:23:22:55 -0500] Report: stringpool-alloc-bytes=8384
D [14/Feb/2011:23:22:55 -0500] Report: stringpool-total-bytes=165544
D [14/Feb/2011:23:23:57 -0500] cupsdNetIFUpdate: "lo" = localhost:631
D [14/Feb/2011:23:23:57 -0500] cupsdNetIFUpdate: "eth0" = asus.ibadalRahman.local:631
D [14/Feb/2011:23:23:57 -0500] Report: clients=0
D [14/Feb/2011:23:23:57 -0500] Report: jobs=23
D [14/Feb/2011:23:23:57 -0500] Report: jobs-active=0
D [14/Feb/2011:23:23:57 -0500] Report: printers=1
D [14/Feb/2011:23:23:57 -0500] Report: printers-implicit=0
D [14/Feb/2011:23:23:57 -0500] Report: stringpool-string-count=9098
D [14/Feb/2011:23:23:57 -0500] Report: stringpool-alloc-bytes=8384
D [14/Feb/2011:23:23:57 -0500] Report: stringpool-total-bytes=165544

Thanks for the help.

Shawn

Last edited by shawn.abdushakur (2011-02-15 04:28:04)

bernarcher · 2011-02-15 08:33:23

Poking in the dark there is this message in your log:

[Job 23] prnt/backend/hp.c 559: dBus Connection Error (Failed to connect to socket /var/run/dbus/system_bus_socket: No such file or directory)!

So the first thing you should check is whether dbus is running properly.
Second, which drivers are actually installed? Is there hplip? Did the test pages print properly after all?
Third, search the forums. There are threads with similar problems abound. Maybe some will apply to your situation.

shawn.abdushakur · 2011-02-18 02:25:08

Dbus is running now; it wasn't. That didn't help though. I tried blacklisting usblp in rc.conf as suggested from another post with a similiar issue. That didn't help either. I'm now trying to reinstall the printer, another recommendation, and I have a question. I get several options when adding the printer, two of which appear to be my printer. Could someone tell me which one I should chose or better yet, how would i know which one to choose?

Here's a screenshot:

[img]/home/shawn/Pictures/add-printer.png[/img]

Scratch that, can't get the screenshot show, likely a problem with the way I'm typing it. So the choices are:

HP LaserJet P1005 (HP LaserJet P1005)
HP LaserJet P1005 USB BC1569M HPLIP (HP LaserJet P1005)

Thanks.
Shawn

Last edited by shawn.abdushakur (2011-02-18 02:27:08)

shawn.abdushakur · 2011-02-19 22:09:18

I solved it. Turns out that the printer device needs to be setup in the cups web admin page using the root account, not your own account. Thanks for all the help.

Shawn

Arch Linux

#1 2011-01-25 00:07:53

cups error "Print-Job client-error-not-authorized"

#2 2011-01-27 21:20:04

Re: cups error "Print-Job client-error-not-authorized"

#3 2011-01-28 23:51:21

Re: cups error "Print-Job client-error-not-authorized"

#4 2011-01-30 18:45:26

Re: cups error "Print-Job client-error-not-authorized"

#5 2011-02-03 00:26:51

Re: cups error "Print-Job client-error-not-authorized"

#6 2011-02-06 22:25:50

Re: cups error "Print-Job client-error-not-authorized"

#7 2011-02-08 16:27:37

Re: cups error "Print-Job client-error-not-authorized"

#8 2011-02-08 16:41:01

Re: cups error "Print-Job client-error-not-authorized"

#9 2011-02-08 16:46:05

Re: cups error "Print-Job client-error-not-authorized"

#10 2011-02-15 04:26:09

Re: cups error "Print-Job client-error-not-authorized"

#11 2011-02-15 08:33:23

Re: cups error "Print-Job client-error-not-authorized"

#12 2011-02-18 02:25:08

Re: cups error "Print-Job client-error-not-authorized"

#13 2011-02-19 22:09:18

Re: cups error "Print-Job client-error-not-authorized"

Board footer