Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

HP printing failed with message /usr/lib/cups/filter/hpcups failed #4740

Closed
michaelrsweet opened this issue Nov 10, 2015 · 15 comments
Closed
Labels
third-party This issue is in a third-party component
Milestone

Comments

@michaelrsweet
Copy link
Collaborator

Version: 1.5.3
CUPS.org User: isonthomas

Hi,

I have installed cups in my Beagleboard black running on Debian 7 using 'apt-get install' command. I am trying to print to HP deskjet 1112 usb printer from system line but nothing is getting printed and the output of 'lpstat -t' shows the error : " /usr/lib/cups/filter/hpcups failed "

The error_log output is :

I [10/Nov/2015:09:44:51 +0000] Saving job.cache...
I [10/Nov/2015:09:44:52 +0000] Listening to [v1.::1]:631 (IPv6)
I [10/Nov/2015:09:44:52 +0000] Listening to 127.0.0.1:631 (IPv4)
I [10/Nov/2015:09:44:52 +0000] Listening to /var/run/cups/cups.sock (Domain)
I [10/Nov/2015:09:44:52 +0000] Remote access is disabled.
D [10/Nov/2015:09:44:52 +0000] Added auto ServerAlias beaglebone
I [10/Nov/2015:09:44:52 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
I [10/Nov/2015:09:44:52 +0000] Using default TempDir of /var/spool/cups/tmp...
I [10/Nov/2015:09:44:52 +0000] Configured for up to 100 clients.
I [10/Nov/2015:09:44:52 +0000] Allowing up to 100 client connections per host.
I [10/Nov/2015:09:44:52 +0000] Using policy "default" as the default.
I [10/Nov/2015:09:44:52 +0000] Full reload is required.
I [10/Nov/2015:09:44:52 +0000] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 39 types, 56 filters...
D [10/Nov/2015:09:44:52 +0000] Loading printer HP...
D [10/Nov/2015:09:44:52 +0000] load_ppd: Loading /var/cache/cups/HP.data...
D [10/Nov/2015:09:44:52 +0000] Calling DeleteDevice(cups-HP)
D [10/Nov/2015:09:44:52 +0000] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, (s)', does not match expected type(o)'
D [10/Nov/2015:09:44:52 +0000] Using profile id of HP-Gray..
D [10/Nov/2015:09:44:52 +0000] Calling CreateProfile(HP-Gray..,temp)
D [10/Nov/2015:09:44:52 +0000] created profile /org/freedesktop/ColorManager/profiles/HP_Gray__
D [10/Nov/2015:09:44:52 +0000] Using profile id of HP-RGB..
D [10/Nov/2015:09:44:52 +0000] Calling CreateProfile(HP-RGB..,temp)
D [10/Nov/2015:09:44:52 +0000] created profile /org/freedesktop/ColorManager/profiles/HP_RGB__
I [10/Nov/2015:09:44:52 +0000] Registering ICC color profiles for "HP"
D [10/Nov/2015:09:44:52 +0000] Calling CreateDevice(cups-HP,temp)
D [10/Nov/2015:09:44:52 +0000] created device /org/freedesktop/ColorManager/devices/cups_HP
D [10/Nov/2015:09:44:52 +0000] Calling /org/freedesktop/ColorManager/devices/cups_HP:AddProfile(/org/freedesktop/ColorManager/profiles/HP_Gray__) [soft]
D [10/Nov/2015:09:44:52 +0000] Calling /org/freedesktop/ColorManager/devices/cups_HP:AddProfile(/org/freedesktop/ColorManager/profiles/HP_RGB__) [soft]
D [10/Nov/2015:09:44:52 +0000] cupsdRegisterPrinter(p=0xb6feb638(HP))
I [10/Nov/2015:09:44:52 +0000] Loading job cache file "/var/cache/cups/job.cache"...
I [10/Nov/2015:09:44:52 +0000] Full reload complete.
D [10/Nov/2015:09:44:52 +0000] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [10/Nov/2015:09:44:52 +0000] Cleaning out old files in "/var/spool/cups/tmp"...
D [10/Nov/2015:09:44:52 +0000] cupsdCleanFiles(path="/var/cache/cups", pattern=".ipp")
I [10/Nov/2015:09:44:52 +0000] Cleaning out old files in "/var/cache/cups"...
E [10/Nov/2015:09:44:52 +0000] Unable to open listen socket for address [v1.::1]:631 - Address family not supported by protocol.
I [10/Nov/2015:09:44:52 +0000] Listening to 127.0.0.1:631 on fd 9...
I [10/Nov/2015:09:44:52 +0000] Listening to /var/run/cups/cups.sock:631 on fd 10...
I [10/Nov/2015:09:44:52 +0000] Resuming new connection processing...
D [10/Nov/2015:09:44:52 +0000] Avahi client started
D [10/Nov/2015:09:44:52 +0000] cupsdRegisterPrinter(p=0xb6feb638(HP))
D [10/Nov/2015:09:44:52 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [10/Nov/2015:09:44:52 +0000] Discarding unused server-started event...
D [10/Nov/2015:09:44:53 +0000] Report: clients=0
D [10/Nov/2015:09:44:53 +0000] Report: jobs=0
D [10/Nov/2015:09:44:53 +0000] Report: jobs-active=0
D [10/Nov/2015:09:44:53 +0000] Report: printers=1
D [10/Nov/2015:09:44:53 +0000] Report: printers-implicit=0
D [10/Nov/2015:09:44:53 +0000] Report: stringpool-string-count=2200
D [10/Nov/2015:09:44:53 +0000] Report: stringpool-alloc-bytes=9256
D [10/Nov/2015:09:44:53 +0000] Report: stringpool-total-bytes=41544
D [10/Nov/2015:09:44:59 +0000] cupsdAcceptClient: 15 from localhost (Domain)
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [10/Nov/2015:09:44:59 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [10/Nov/2015:09:44:59 +0000] CUPS-Get-Printers
D [10/Nov/2015:09:44:59 +0000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [10/Nov/2015:09:44:59 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [10/Nov/2015:09:44:59 +0000] CUPS-Get-Default
D [10/Nov/2015:09:44:59 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [10/Nov/2015:09:44:59 +0000] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [10/Nov/2015:09:44:59 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:44:59 +0000] cupsdAcceptClient: 16 from localhost (Domain)
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 16 WAITING Closing on EOF
D [10/Nov/2015:09:44:59 +0000] cupsdCloseClient: 16
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Classes 1
D [10/Nov/2015:09:44:59 +0000] CUPS-Get-Classes
D [10/Nov/2015:09:44:59 +0000] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [10/Nov/2015:09:44:59 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [10/Nov/2015:09:44:59 +0000] CUPS-Get-Printers
D [10/Nov/2015:09:44:59 +0000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [10/Nov/2015:09:44:59 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [10/Nov/2015:09:44:59 +0000] CUPS-Get-Printers
D [10/Nov/2015:09:44:59 +0000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [10/Nov/2015:09:44:59 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [10/Nov/2015:09:44:59 +0000] CUPS-Get-Printers
D [10/Nov/2015:09:44:59 +0000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [10/Nov/2015:09:44:59 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 1.1 Get-Jobs 1
D [10/Nov/2015:09:44:59 +0000] Get-Jobs ipp://localhost/
D [10/Nov/2015:09:44:59 +0000] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [10/Nov/2015:09:44:59 +0000] cupsdReadClient: 15 WAITING Closing on EOF
D [10/Nov/2015:09:44:59 +0000] cupsdCloseClient: 15
D [10/Nov/2015:09:44:59 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [10/Nov/2015:09:45:08 +0000] cupsdAcceptClient: 15 from localhost (Domain)
D [10/Nov/2015:09:45:08 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [10/Nov/2015:09:45:08 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:45:08 +0000] cupsdReadClient: 15 1.1 Get-Printer-Attributes 1
D [10/Nov/2015:09:45:08 +0000] Get-Printer-Attributes ipp://localhost:631/printers/HP
D [10/Nov/2015:09:45:08 +0000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/HP) from localhost
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [10/Nov/2015:09:45:08 +0000] cupsdReadClient: 15 POST /printers/HP HTTP/1.1
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [10/Nov/2015:09:45:08 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:45:08 +0000] cupsdReadClient: 15 1.1 Create-Job 1
D [10/Nov/2015:09:45:08 +0000] Create-Job ipp://localhost:631/printers/HP
D [10/Nov/2015:09:45:08 +0000] cupsdMarkDirty(----J-)
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [10/Nov/2015:09:45:08 +0000] add_job: requesting-user-name="root"
I [10/Nov/2015:09:45:08 +0000] [Job 39] Adding start banner page "none".
D [10/Nov/2015:09:45:08 +0000] Discarding unused job-created event...
I [10/Nov/2015:09:45:08 +0000] [Job 39] Queued on "HP" by "root".
D [10/Nov/2015:09:45:08 +0000] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/HP) from localhost
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:08 +0000] cupsdReadClient: 15 POST /printers/HP HTTP/1.1
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [10/Nov/2015:09:45:08 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:45:08 +0000] cupsdReadClient: 15 1.1 Send-Document 1
D [10/Nov/2015:09:45:08 +0000] Send-Document ipp://localhost:631/printers/HP
D [10/Nov/2015:09:45:08 +0000] cupsdIsAuthorized: requesting-user-name="root"
D [10/Nov/2015:09:45:08 +0000] [Job 39] Auto-typing file...
D [10/Nov/2015:09:45:08 +0000] [Job 39] Request file type is text/plain.
D [10/Nov/2015:09:45:08 +0000] cupsdMarkDirty(----J-)
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [10/Nov/2015:09:45:08 +0000] [Job 39] File of type text/plain queued by "root".
I [10/Nov/2015:09:45:08 +0000] [Job 39] Adding end banner page "none".
D [10/Nov/2015:09:45:08 +0000] cupsdMarkDirty(----J-)
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:08 +0000] cupsdMarkDirty(----J-)
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:08 +0000] Discarding unused printer-state-changed event...
D [10/Nov/2015:09:45:08 +0000] [Job 39] job-sheets=none,none
D [10/Nov/2015:09:45:08 +0000] [Job 39] argv[0]="HP"
D [10/Nov/2015:09:45:08 +0000] [Job 39] argv[1]="39"
D [10/Nov/2015:09:45:08 +0000] [Job 39] argv[2]="root"
D [10/Nov/2015:09:45:08 +0000] [Job 39] argv[3]="abc.txt"
D [10/Nov/2015:09:45:08 +0000] [Job 39] argv[4]="1"
D [10/Nov/2015:09:45:08 +0000] [Job 39] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:1e507137-011d-38f5-5887-ae080a59a6e9 job-originating-host-name=localhost time-at-creation=1447148708 time-at-processing=1447148708"
D [10/Nov/2015:09:45:08 +0000] [Job 39] argv[6]="/var/spool/cups/d00039-001"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[8]="HOME=/var/spool/cups/tmp"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[10]="SERVER_ADMIN=root@beaglebone"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[11]="SOFTWARE=CUPS/1.5.3"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[13]="TZ=Etc/UTC"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[14]="USER=root"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[17]="IPP_PORT=631"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[18]="CHARSET=utf-8"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[19]="LANG=en_US.UTF-8"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[20]="PPD=/etc/cups/ppd/HP.ppd"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[21]="RIP_MAX_CACHE=128m"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[22]="CONTENT_TYPE=text/plain"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[23]="DEVICE_URI=hp:/usb/DeskJet_1110_series?serial=CN55D1N02S065W"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[24]="PRINTER_INFO=HP"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[25]="PRINTER_LOCATION="
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[26]="PRINTER=HP"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[27]="PRINTER_STATE_REASONS=none"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[28]="CUPS_FILETYPE=document"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[29]="FINAL_CONTENT_TYPE=printer/HP"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[30]="AUTH_I
**"
I [10/Nov/2015:09:45:08 +0000] [Job 39] Started filter /usr/lib/cups/filter/texttopdf (PID 22756)
I [10/Nov/2015:09:45:08 +0000] [Job 39] Started filter /usr/lib/cups/filter/pdftopdf (PID 22757)
I [10/Nov/2015:09:45:08 +0000] [Job 39] Started filter /usr/lib/cups/filter/gstoraster (PID 22758)
I [10/Nov/2015:09:45:08 +0000] [Job 39] Started filter /usr/lib/cups/filter/hpcups (PID 22759)
I [10/Nov/2015:09:45:08 +0000] [Job 39] Started backend /usr/lib/cups/backend/hp (PID 22760)
D [10/Nov/2015:09:45:08 +0000] Discarding unused job-state-changed event...
D [10/Nov/2015:09:45:08 +0000] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/HP) from localhost
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:08 +0000] cupsdReadClient: 15 WAITING Closing on EOF
D [10/Nov/2015:09:45:08 +0000] cupsdCloseClient: 15
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [10/Nov/2015:09:45:08 +0000] [Job 39] Page = 595x842; 8,41 to 587,833
D [10/Nov/2015:09:45:08 +0000] PID 22756 (/usr/lib/cups/filter/texttopdf) exited with no errors.
D [10/Nov/2015:09:45:08 +0000] [Job 39] PPD uses qualifier 'RGB.Plain.'
D [10/Nov/2015:09:45:08 +0000] PID 22757 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [10/Nov/2015:09:45:08 +0000] [Job 39] Calling FindDeviceById(HP)
D [10/Nov/2015:09:45:08 +0000] [Job 39] Failed to send: org.freedesktop.ColorManager.Failed:device id 'HP' does not exists
D [10/Nov/2015:09:45:08 +0000] [Job 39] Failed to get profile filename!
I [10/Nov/2015:09:45:08 +0000] [Job 39] no profiles specified in PPD
D [10/Nov/2015:09:45:08 +0000] [Job 39] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
D [10/Nov/2015:09:45:08 +0000] Discarding unused job-progress event...
D [10/Nov/2015:09:45:08 +0000] Discarding unused printer-state-changed event...
D [10/Nov/2015:09:45:08 +0000] [Job 39] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -sOutputType=0 -r600x600 -dMediaPosition=1 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=26 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c -f -

D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[8]="HOME=/var/spool/cups/tmp"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[10]="SERVER_ADMIN=root@beaglebone"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[11]="SOFTWARE=CUPS/1.5.3"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[13]="TZ=Etc/UTC"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[14]="USER=root"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[17]="IPP_PORT=631"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[18]="CHARSET=utf-8"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[19]="LANG=en_US.UTF-8"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[20]="PPD=/etc/cups/ppd/HP.ppd"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[21]="RIP_MAX_CACHE=128m"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[22]="CONTENT_TYPE=text/plain"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[23]="DEVICE_URI=hp:/usb/DeskJet_1110_series?serial=CN55D1N02S065W"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[24]="PRINTER_INFO=HP"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[25]="PRINTER_LOCATION="
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[26]="PRINTER=HP"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[27]="PRINTER_STATE_REASONS=none"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[28]="CUPS_FILETYPE=document"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[29]="FINAL_CONTENT_TYPE=printer/HP"
D [10/Nov/2015:09:45:08 +0000] [Job 39] envp[30]="AUTH_INFO_REQUIRED=none"
I [10/Nov/2015:09:45:08 +0000] [Job 39] Start rendering...
D [10/Nov/2015:09:45:08 +0000] [Job 39] Set job-printer-state-message to "Start rendering...", current level=INFO
D [10/Nov/2015:09:45:08 +0000] Discarding unused job-progress event...
D [10/Nov/2015:09:45:08 +0000] Discarding unused printer-state-changed event...
I [10/Nov/2015:09:45:08 +0000] [Job 39] Processing page 1...
D [10/Nov/2015:09:45:08 +0000] [Job 39] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [10/Nov/2015:09:45:08 +0000] [Job 39] ***_ Unable to open the initial device, quitting.
D [10/Nov/2015:09:45:08 +0000] Discarding unused job-progress event...
D [10/Nov/2015:09:45:08 +0000] Discarding unused printer-state-changed event...
D [10/Nov/2015:09:45:08 +0000] [Job 39] prnt/hpcups/HPCupsFilter.cpp 565: cupsRasterOpen failed, fd = 0
D [10/Nov/2015:09:45:08 +0000] PID 22758 (/usr/lib/cups/filter/gstoraster) exited with no errors.
D [10/Nov/2015:09:45:08 +0000] [Job 39] prnt/backend/hp.c 919: ERROR: null print job total=0
D [10/Nov/2015:09:45:08 +0000] PID 22759 (/usr/lib/cups/filter/hpcups) stopped with status 1.
D [10/Nov/2015:09:45:08 +0000] PID 22760 (/usr/lib/cups/backend/hp) exited with no errors.
D [10/Nov/2015:09:45:08 +0000] Discarding unused job-state-changed event...
E [10/Nov/2015:09:45:08 +0000] [Job 39] Job stopped due to filter errors; please consult the error_log file for details.
D [10/Nov/2015:09:45:08 +0000] cupsdMarkDirty(----J-)
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [10/Nov/2015:09:45:08 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [10/Nov/2015:09:45:08 +0000] Discarding unused printer-state-changed event...
D [10/Nov/2015:09:45:09 +0000] [Job 39] Unloading...
D [10/Nov/2015:09:45:11 +0000] cupsdAcceptClient: 15 from localhost (Domain)
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [10/Nov/2015:09:45:11 +0000] CUPS-Get-Printers
D [10/Nov/2015:09:45:11 +0000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [10/Nov/2015:09:45:11 +0000] CUPS-Get-Default
D [10/Nov/2015:09:45:11 +0000] CUPS-Get-Default client-error-not-found: No default printer.
D [10/Nov/2015:09:45:11 +0000] Returning IPP client-error-not-found for CUPS-Get-Default (no URI) from localhost
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:45:11 +0000] cupsdAcceptClient: 16 from localhost (Domain)
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 16 WAITING Closing on EOF
D [10/Nov/2015:09:45:11 +0000] cupsdCloseClient: 16
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Classes 1
D [10/Nov/2015:09:45:11 +0000] CUPS-Get-Classes
D [10/Nov/2015:09:45:11 +0000] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [10/Nov/2015:09:45:11 +0000] CUPS-Get-Printers
D [10/Nov/2015:09:45:11 +0000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [10/Nov/2015:09:45:11 +0000] CUPS-Get-Printers
D [10/Nov/2015:09:45:11 +0000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [10/Nov/2015:09:45:11 +0000] CUPS-Get-Printers
D [10/Nov/2015:09:45:11 +0000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdAuthorize: No authentication data provided.
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 1.1 Get-Jobs 1
D [10/Nov/2015:09:45:11 +0000] Get-Jobs ipp://localhost/
D [10/Nov/2015:09:45:11 +0000] [Job 39] Loading attributes...
D [10/Nov/2015:09:45:11 +0000] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [10/Nov/2015:09:45:11 +0000] cupsdReadClient: 15 WAITING Closing on EOF
D [10/Nov/2015:09:45:11 +0000] cupsdCloseClient: 15
D [10/Nov/2015:09:45:11 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
I [10/Nov/2015:09:45:39 +0000] Saving job.cache...
D [10/Nov/2015:09:45:40 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"

Request help to identify the issue

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: jsmeix.suse

Only FYI:

As far as I see this has nothing to do with CUPS.

As far as I see the first error message comes from Ghostscript:
"Unable to open the initial device, quitting"
(this is in Ghostscript's gs_init.ps file).

Therefore Ghostscript does not produce any output which
lets then the filter from HP's HPLIP driver software
error out with "cupsRasterOpen failed" and finally
the HP backend errors out with "ERROR: null print job".

Neither Ghostscript nor HP's HPLIP driver software
belong to CUPS so that the issue does not belong to CUPS
(according to my analysis).

I guess that somehow the "Ghostscript command line"
is not correct or does not work as it should.
Usually this is alos no bug in Ghostscript but
"somewhere else" where the Ghostscript command line
with all its parameters is created.

I recommed to analyze what the actual root cause is
that you report this issue at your Linux distribution.

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: jsmeix.suse

Addendum FYI:

On my SLES12 system with CUPS 1.7.5 and Ghostscript 9.18

that "Ghostscript command line" works:

cat /usr/share/ghostscript/9.18/examples/colorcir.ps \

| /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH
-dNOINTERPOLATE -sDEVICE=cups -sstdout=%stderr
-sOutputFile=%stdout -sOutputType=0 -r600x600
-dMediaPosition=1 -dDEVICEWIDTHPOINTS=595
-dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8
-dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=26
-scupsPageSizeName=A4 -I/usr/share/cups/fonts -c -f -_
| cat - >/tmp/gs.out
INFO: Start rendering...
INFO: Processing page 1...
INFO: Processing page 2...
INFO: Rendering completed

file /tmp/gs.out

/tmp/gs.out: Cups Raster version 3, Little Endian, 600x600 dpi, 4958x7008 pixels 8 bits/color 32 bits/pixel ColorOrder=Chunky ColorSpace=RGBW

ls -lh /tmp/gs.out

-rw-r--r-- 1 root root 133M Nov 10 11:47 /tmp/gs.out

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: isonthomas

Thank you for the comments.. I will raise this in the distributor's forum.

Meanwhile I tried to use cups 1.7.5 but after building and installing it, I am getting error :

lpstat: symbol lookup error: lpstat: undefined symbol: _cupsStrDate

I recompiled with -fPIC in CFLAGS and CXXFLAGS but the error is still coming..
'
.
.
.
.

Using ALL_CFLAGS=-I.. -D_CUPS_SOURCE -fPIC -I/usr/include/dbus-1.0 -I/usr/lib/arm-linux-gnueabihf/dbus-1.0/include -DDBUS_API_SUBJECT_TO_CHAN
GE -D_REENTRANT -DOPENSSL_DISABLE_OLD_DES_SUPPORT -D_LARGEFILE_SOURCE -D_LARGEFILE64_SOURCE -D_FILE_OFFSET_BITS=64 -D_THREAD_SAFE -D_REENTRAN
T
Using ALL_CXXFLAGS=-I.. -D_CUPS_SOURCE -fPIC -DOPENSSL_DISABLE_OLD_DES_SUPPORT -D_LARGEFILE_SOURCE -D_LARGEFILE64_SOURCE -D_FILE_OFFSET_BITS=64
-D_THREAD_SAFE -D_REENTRANT
Using CC=cc
Using CXX=cc
.
.
.
'

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: mike

We do not support or develop the software/driver you are using. Please contact the developer for assistance.

"hpcups" is part of the HPLIP driver package.

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: isonthomas

Hi Joahnnes,

I have reinstalled CUPS in a fresh OS and tried to print but the error is still occuring. However the error log message is a bit different. If you are able to deduct something from this, it would be a great help.

In the first lines itself, we can see error message unable to open listen socket. Also FindDevicebyID is not able to find the installed printer.

E [11/Nov/2015:03:33:41 +0000] Unable to open listen socket for address [v1.::1]:631 - Address family not supported by protocol.
W [11/Nov/2015:03:33:48 +0000] [cups-driverd] Unable to execute "/usr/lib/cups/driver/foomatic-db-compressed-ppds": Cannot allocate memory
W [11/Nov/2015:03:33:48 +0000] [cups-driverd] Unable to execute "/usr/lib/cups/driver/postscript-hp": Cannot allocate memory
W [11/Nov/2015:03:33:48 +0000] [cups-driverd] Unable to execute "/usr/lib/cups/driver/m2300w": Cannot allocate memory
E [11/Nov/2015:03:34:33 +0000] Unable to open listen socket for address [v1.::1]:631 - Address family not supported by protocol.
W [11/Nov/2015:03:36:51 +0000] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP-Gray..' already exists
W [11/Nov/2015:03:36:51 +0000] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP-RGB..' already exists
W [11/Nov/2015:03:36:51 +0000] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-HP' already exists
E [11/Nov/2015:03:37:23 +0000] [Job 1] Job stopped due to filter errors; please consult the error_log file for details.
D [11/Nov/2015:03:37:23 +0000] [Job 1] The following messages were recorded from 03:37:22 AM to 03:37:23 AM
D [11/Nov/2015:03:37:23 +0000] [Job 1] Adding start banner page "none".
D [11/Nov/2015:03:37:23 +0000] [Job 1] Queued on "HP" by "root".
D [11/Nov/2015:03:37:23 +0000] [Job 1] Auto-typing file...
D [11/Nov/2015:03:37:23 +0000] [Job 1] Request file type is text/plain.
D [11/Nov/2015:03:37:23 +0000] [Job 1] File of type text/plain queued by "root".
D [11/Nov/2015:03:37:23 +0000] [Job 1] Adding end banner page "none".
D [11/Nov/2015:03:37:23 +0000] [Job 1] job-sheets=none,none
D [11/Nov/2015:03:37:23 +0000] [Job 1] argv[0]="HP"
D [11/Nov/2015:03:37:23 +0000] [Job 1] argv[1]="1"
D [11/Nov/2015:03:37:23 +0000] [Job 1] argv[2]="root"
D [11/Nov/2015:03:37:23 +0000] [Job 1] argv[3]="abc.txt"
D [11/Nov/2015:03:37:23 +0000] [Job 1] argv[4]="1"
D [11/Nov/2015:03:37:23 +0000] [Job 1] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:b40d34ad-3d49-3a38-6bca-55af99426b8d job-originating-host-name=localhost time-at-creation=1447213042 time-at-processing=1447213042"
D [11/Nov/2015:03:37:23 +0000] [Job 1] argv[6]="/var/spool/cups/d00001-001"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[8]="HOME=/var/spool/cups/tmp"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[10]="SERVER_ADMIN=root@beaglebone"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[11]="SOFTWARE=CUPS/1.5.3"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[13]="TZ=Etc/UTC"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[14]="USER=root"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[17]="IPP_PORT=631"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[18]="CHARSET=utf-8"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[19]="LANG=en_US.UTF-8"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[20]="PPD=/etc/cups/ppd/HP.ppd"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[21]="RIP_MAX_CACHE=128m"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[22]="CONTENT_TYPE=text/plain"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[23]="DEVICE_URI=hp:/usb/DeskJet_1110_series?serial=CN55D1N02S065W"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[24]="PRINTER_INFO=HP"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[25]="PRINTER_LOCATION="
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[26]="PRINTER=HP"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[27]="PRINTER_STATE_REASONS=none"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[28]="CUPS_FILETYPE=document"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[29]="FINAL_CONTENT_TYPE=printer/HP"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[30]="AUTH_I***"
D [11/Nov/2015:03:37:23 +0000] [Job 1] Started filter /usr/lib/cups/filter/texttopdf (PID 6249)
D [11/Nov/2015:03:37:23 +0000] [Job 1] Started filter /usr/lib/cups/filter/pdftopdf (PID 6250)
D [11/Nov/2015:03:37:23 +0000] [Job 1] Started filter /usr/lib/cups/filter/gstoraster (PID 6251)
D [11/Nov/2015:03:37:23 +0000] [Job 1] Started filter /usr/lib/cups/filter/hpcups (PID 6252)
D [11/Nov/2015:03:37:23 +0000] [Job 1] Started backend /usr/lib/cups/backend/hp (PID 6253)
D [11/Nov/2015:03:37:23 +0000] [Job 1] Page = 595x842; 8,41 to 587,833
D [11/Nov/2015:03:37:23 +0000] [Job 1] PPD uses qualifier 'RGB.Plain.'
D [11/Nov/2015:03:37:23 +0000] [Job 1] Calling FindDeviceById(HP)
D [11/Nov/2015:03:37:23 +0000] [Job 1] Failed to send: org.freedesktop.ColorManager.Failed:device id 'HP' does not exists
D [11/Nov/2015:03:37:23 +0000] [Job 1] Failed to get profile filename!
D [11/Nov/2015:03:37:23 +0000] [Job 1] no profiles specified in PPD
D [11/Nov/2015:03:37:23 +0000] [Job 1] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
D [11/Nov/2015:03:37:23 +0000] [Job 1] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -sOutputType=0 -r600x600 -dMediaPosition=1 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=17 -dcupsInteger0=26 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c -f -

D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[8]="HOME=/var/spool/cups/tmp"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[10]="SERVER_ADMIN=root@beaglebone"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[11]="SOFTWARE=CUPS/1.5.3"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[13]="TZ=Etc/UTC"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[14]="USER=root"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[17]="IPP_PORT=631"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[18]="CHARSET=utf-8"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[19]="LANG=en_US.UTF-8"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[20]="PPD=/etc/cups/ppd/HP.ppd"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[21]="RIP_MAX_CACHE=128m"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[22]="CONTENT_TYPE=text/plain"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[23]="DEVICE_URI=hp:/usb/DeskJet_1110_series?serial=CN55D1N02S065W"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[24]="PRINTER_INFO=HP"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[25]="PRINTER_LOCATION="
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[26]="PRINTER=HP"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[27]="PRINTER_STATE_REASONS=none"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[28]="CUPS_FILETYPE=document"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[29]="FINAL_CONTENT_TYPE=printer/HP"
D [11/Nov/2015:03:37:23 +0000] [Job 1] envp[30]="AUTH_INFO_REQUIRED=none"
D [11/Nov/2015:03:37:23 +0000] [Job 1] Start rendering...
D [11/Nov/2015:03:37:23 +0000] [Job 1] Set job-printer-state-message to "Start rendering...", current level=INFO
D [11/Nov/2015:03:37:23 +0000] [Job 1] Processing page 1...
D [11/Nov/2015:03:37:23 +0000] [Job 1] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [11/Nov/2015:03:37:23 +0000] [Job 1] ***_ Unable to open the initial device, quitting.
D [11/Nov/2015:03:37:23 +0000] [Job 1] prnt/hpcups/HPCupsFilter.cpp 536: cupsRasterOpen failed, fd = 0
D [11/Nov/2015:03:37:23 +0000] [Job 1] prnt/backend/hp.c 839: ERROR: null print job total=0
D [11/Nov/2015:03:37:23 +0000] [Job 1] End of messages
D [11/Nov/2015:03:37:23 +0000] [Job 1] printer-state=3(idle)
D [11/Nov/2015:03:37:23 +0000] [Job 1] printer-state-message="/usr/lib/cups/filter/hpcups failed"
D [11/Nov/2015:03:37:23 +0000] [Job 1] printer-state-reasons=none
E [11/Nov/2015:03:42:24 +0000] [Job 1] Stopping unresponsive job!

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: jsmeix.suse

You can ignore the messages that belong to CUPS itself
(consider them as "false-positives" in your case) because
they have nothing to do with your failed print job processing.

In your case the cupsd does what it is supposed to do.
It accepts your print job and it starts the filters and the backend.
Then the filters and backend need to do the actual print job
processing work.
The cupsd does not do any actual print job processing work.
The cupsd only waits for the filters and backend
until they are completed.

In your case the filters fail as I already described.

The filters and backend belong to various software packages
(e.g. cups-filters, Ghostscript, HPLIP) that all are totally
separated from CUPS (in particular cups-filters does not belong
to CUPS but to OpenPrinting.org).

You need to trust us at least initially that when our initial
analysis indicates that the issue does not belong to CUPS,
then it really does not belong to CUPS (with very high probability)
so that it does not make much sense when you do something with CUPS.

Note that usually it is also no bug in Ghostscript itself
when a particular Ghostscript command line fails.

In particular "Unable to open the initial device, quitting"
is no bug in Ghostscript itself (with probability one,
cf. https://en.wikipedia.org/wiki/Almost_surely)
but "somewhere else" that made the Ghostscript command line,

see gs_init.ps

% If the initial device parameters are invalid, the setdevice may fail.
% Trap this and produce a reasonable error message.
{ setdevice } % does an erasepage
INITDEBUG { exec //false } { .internalstopped } ifelse {
(**** Unable to open the initial device, quitting.) = flush 1 .quit

} if

Accordingly it looks as if one or more of the various parameters
in your Ghostscript command line are invalid.

For example on my SLES12 system with CUPS 1.7.5 and Ghostscript 9.18

the following simpler Ghostscript command line also works:

cat /usr/share/ghostscript/9.18/examples/colorcir.ps \

| /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH
-dNOINTERPOLATE -sDEVICE=cups -sstdout=%stderr
-sOutputFile=%stdout -c -f -_
| cat - >/tmp/gs.out

INFO: Start rendering...
INFO: Processing page 1...
INFO: Processing page 2...

INFO: Rendering completed

What do the above Ghostscript command lines result on your system?
Does it work with all those parameters?
Does the simpler one work?
If it fails with all those parameters but the simpler one works,
find out by trial and error what exact parameter(s) make it fail
and then report that to your Linux distribution.

You really need to analyze at your Linux distribution
what the actual root cause is why the Ghostscript
command line fails in your particular case.

Afterwards - i.e. when that specific root cause is found - it
might indicate an issue in whatever specific part of the overall
printing related software stack so that finally that specific
issue can be reported at the matching upstream software project.

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: h.blischke

Ison, are you sure your Ghostscript has the cups device compiled in?
Tot test this, execute
gs -h | grep cups
the result should look similar to
chp2200 cif cljet5 cljet5c cljet5pr coslw2p coslwxl cp50 cups declj250
^^^^^
if the cups device is compiled in.

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: isonthomas

Thank you Johannes, I will try to change the ghostscript command options (I am new to this and need to figure out how... ).

@h. Blischke, I confirmed that the ghostscript has the cups device is compiled in. The output of gs -h | grep cups is:

chp2200 cif cljet5 cljet5c cljet5pr coslw2p coslwxl cp50 cups declj250
/usr/share/cups/fonts : /usr/share/ghostscript/fonts :
Thank you

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: jsmeix.suse

Helge Blischke, only FYI:

When calling Ghostscript with a device that it does not know

the error message is different:

cat /usr/share/ghostscript/9.18/examples/colorcir.ps \

| /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH
-sDEVICE=qqq -sstdout=%stderr -sOutputFile=%stdout -c -f -_
| cat - >/tmp/gs.out

Unknown device: qqq
Unrecoverable error: syntaxerror in --nostringval--
Operand stack:
defaultdevice false
Unrecoverable error: undefined in .uninstallpagedevice
Operand stack:

defaultdevice false

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: isonthomas

Thank you H. Blischke

From what I understood in your comment and Johannes', we can rule out the possibility of device detection issue.

@johannes,

I ran :


cat /usr/share/ghostscript/9.18/examples/colorcir.ps \

| /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH
-sDEVICE=qqq -sstdout=%stderr -sOutputFile=%stdout -c -f -_ \

| cat - >/tmp/gs.out

on my system and got


INFO: Start rendering...
INFO: Processing page 1...
INFO: Processing page 2...

INFO: Rendering completed

as the output.

Then I replaced this ghostscript command with the one shown in the error log:


cat /usr/share/ghostscript/9.18/examples/colorcir.ps | /usr/bin/gs -dQQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -sDEVICE=cups -sstdout=%sstderr -sOutputFile=stdout -sOutputType=0 -r600x600 -dMediaPosition=1 -dDEVICEWIIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8 dcupsColorOrder=0 --dcupsColorSpace=17 -dcupsInteger0=26 -scupsPageSizeName=A4 -I/usr/share/cups/fonnts -c -f -_ | cat - >/tmp/gs.out

for which the output was the same :


INFO: Start rendering...
INFO: Processing page 1...
INFO: Processing page 2...

INFO: Rendering completed

Since both the commands (simpler one that you showed and the one in error log) give the same result, does it mean that ghostscript command parameters are not the problem?

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: isonthomas

Hi,

I tried printing to another printer as well(HP 1022n). I configured it using Generic PCL ppd file.

This time the error message was

/usr/lib/cups/filter/foomatic-rip failed

error log file is


D [12/Nov/2015:11:13:23 +0000] [Job 14] Job title: abc.txt
D [12/Nov/2015:11:13:23 +0000] [Job 14] Printer found with device ID: MFG:Hewlett
t-Packard;MDL:HP LaserJet 1022n;CMD:ACL;CLS:PRINTER;DES:HP LaserJet 1022n;FWVER::
20050401; Device URI: usb://HP/LaserJet%201022n?serial=JN00NP0
D [12/Nov/2015:11:13:23 +0000] [Job 14] File(s) to be printed:
D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14] Ghostscript extra search path ('GS_LIB'))
: /usr/share/cups/fonts
D [12/Nov/2015:11:13:23 +0000] [Job 14] Printing system options:
D [12/Nov/2015:11:13:23 +0000] [Job 14] Device protocol: 2
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'finishings=3'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Forced not re-attaching the usblp kernell
module after the job via "usb-no-reattach" option.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option finishings=3.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'number-up=1'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option number-up=1.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'usb-no-reattach'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown boolean option "usb-no-reattach""
.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'job-uuid=urn:uuid:b2d77
9450-7e91-3ba9-4e4d-a3244e8dceb3'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option job-uuid=urn:uuid:b2d79455
0-7e91-3ba9-4e4d-a3244e8dceb3.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'job-originating-host-nn
ame=localhost'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option job-originating-host-namee
=localhost.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Sending data to printer.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Set job-printer-state-message to "Sendinn
g data to printer.", current level=INFO
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'time-at-creation=144733
26794'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option time-at-creation=144732677
94.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'time-at-processing=1444
7326794'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option time-at-processing=1447322
6794.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Options from the PPD file:
D [12/Nov/2015:11:13:23 +0000] [Job 14]

D [12/Nov/2015:11:13:23 +0000] [Job 14] =========================================

D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14] File:
D [12/Nov/2015:11:13:23 +0000] [Job 14]

D [12/Nov/2015:11:13:23 +0000] [Job 14] =========================================

D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14] Filetype: PDF
D [12/Nov/2015:11:13:23 +0000] [Job 14] Storing temporary files in /var/spool/cuu
ps/tmp
D [12/Nov/2015:11:13:23 +0000] [Job 14] File contains 1 pages
D [12/Nov/2015:11:13:23 +0000] [Job 14] Starting renderer with command: gs -dFirr
stPage=1 -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -dNOINTERPOLATE -sDEVICE==
ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="HP LL
aserJet" -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -dDuplex=false -r300 --
sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:MediaType=0,Quality:Penn
Set=0,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=- /var/spool/cups/tmp/ff
oomatic-8ahvX9
D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14] Spooler: cups
D [12/Nov/2015:11:13:23 +0000] [Job 14] Printer: HP5
D [12/Nov/2015:11:13:23 +0000] [Job 14] Shell: /bin/bash
D [12/Nov/2015:11:13:23 +0000] [Job 14] PPD file: /etc/cups/ppd/HP5.ppd
D [12/Nov/2015:11:13:23 +0000] [Job 14] ATTR file:
D [12/Nov/2015:11:13:23 +0000] [Job 14] Printer model: Generic PCL 5e Printer Foo
omatic/hpijs-pcl5e (recommended)
D [12/Nov/2015:11:13:23 +0000] [Job 14] Page = 612x792; 18,14 to 594,778
D [12/Nov/2015:11:13:23 +0000] [Job 14] Job title: abc.txt
D [12/Nov/2015:11:13:23 +0000] [Job 14] Printer found with device ID: MFG:Hewlett
t-Packard;MDL:HP LaserJet 1022n;CMD:ACL;CLS:PRINTER;DES:HP LaserJet 1022n;FWVER::
20050401; Device URI: usb://HP/LaserJet%201022n?serial=JN00NP0
D [12/Nov/2015:11:13:23 +0000] [Job 14] File(s) to be printed:
D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14] Ghostscript extra search path ('GS_LIB'))
: /usr/share/cups/fonts
D [12/Nov/2015:11:13:23 +0000] [Job 14] Printing system options:
D [12/Nov/2015:11:13:23 +0000] [Job 14] Device protocol: 2
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'finishings=3'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Forced not re-attaching the usblp kernell
module after the job via "usb-no-reattach" option.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option finishings=3.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'number-up=1'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option number-up=1.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'usb-no-reattach'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown boolean option "usb-no-reattach""
.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'job-uuid=urn:uuid:b2d77
9450-7e91-3ba9-4e4d-a3244e8dceb3'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option job-uuid=urn:uuid:b2d79455
0-7e91-3ba9-4e4d-a3244e8dceb3.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'job-originating-host-nn
ame=localhost'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option job-originating-host-namee
=localhost.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Sending data to printer.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Set job-printer-state-message to "Sendinn
g data to printer.", current level=INFO
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'time-at-creation=144733
26794'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option time-at-creation=144732677
94.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Pondering option 'time-at-processing=1444
7326794'
D [12/Nov/2015:11:13:23 +0000] [Job 14] Unknown option time-at-processing=1447322
6794.
D [12/Nov/2015:11:13:23 +0000] [Job 14] Options from the PPD file:
D [12/Nov/2015:11:13:23 +0000] [Job 14]

D [12/Nov/2015:11:13:23 +0000] [Job 14] =========================================

D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14] File:
D [12/Nov/2015:11:13:23 +0000] [Job 14]

D [12/Nov/2015:11:13:23 +0000] [Job 14] =========================================

D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14] Filetype: PDF
D [12/Nov/2015:11:13:23 +0000] [Job 14] Storing temporary files in /var/spool/cuu
ps/tmp
D [12/Nov/2015:11:13:23 +0000] [Job 14] File contains 1 pages
D [12/Nov/2015:11:13:23 +0000] [Job 14] Starting renderer with command: gs -dFirr
stPage=1 -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -dNOINTERPOLATE -sDEVICE==
ijs -sIjsServer=hpijs -sDeviceManufacturer="HEWLETT-PACKARD" -sDeviceModel="HP LL
aserJet" -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -dDuplex=false -r300 --
sIjsParams=Quality:Quality=0,Quality:ColorMode=0,Quality:MediaType=0,Quality:Penn
Set=0,PS:MediaPosition=7 -dIjsUseOutputFD -sOutputFile=- /var/spool/cups/tmp/ff
oomatic-8ahvX9
D [12/Nov/2015:11:13:23 +0000] [Job 14] Starting process "kid3" (generation 1)
D [12/Nov/2015:11:13:23 +0000] [Job 14] Starting process "kid4" (generation 2)
D [12/Nov/2015:11:13:23 +0000] [Job 14] Starting process "renderer" (generation
2)
D [12/Nov/2015:11:13:23 +0000] [Job 14] JCL: ^[%-12345X@PJL
D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14]
D [12/Nov/2015:11:13:23 +0000] [Job 14] renderer received signal 11
D [12/Nov/2015:11:13:23 +0000] [Job 14] Kid3 exit status: 1
D [12/Nov/2015:11:13:23 +0000] [Job 14] Sent 0 bytes...
D [12/Nov/2015:11:13:23 +0000] [Job 14] Waiting for read thread to exit...
D [12/Nov/2015:11:13:23 +0000] [Job 14] Read thread still active, aborting the pp
ending read...
D [12/Nov/2015:11:13:23 +0000] [Job 14] End of messages
D [12/Nov/2015:11:13:23 +0000] [Job 14] printer-state=3(idle)
D [12/Nov/2015:11:13:23 +0000] [Job 14] printer-state-message="/usr/lib/cups/fill
ter/foomatic-rip failed"
D [12/Nov/2015:11:13:23 +0000] [Job 14] printer-state-reasons=none


Here the "**** Unable to open the initial device, quitting" message is not present.

Also, when I give the print command "lp -d ",


usblp0:removed

message is shown on the screen. I executed


lpadmin -p -o usb-no-reattach-default=true

command to prevent this (as seen in error log) but even after this, the message is showing up.

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: jsmeix.suse

I do not understand why the Ghostscript command line
that fails in the printing system does no longer fail
when you run it directly on the command line.

In your last CUPS error_log there is (excerpt):

... [Job 14] Starting renderer with command: gs ...
...

... [Job 14] renderer received signal 11

Again it is Ghostscript that fails but now with
"signal 11" i.e. SIGSEGV = segmentation fault.

Because Ghostscript fails in various ways for you
it seems from my current point of view
that "something is wrong" with Ghostscript
in your particular case.

I cannot tell what exactly is wrong in your particular system.

I think only together with your Linux distribution
you could make real progress in analyzing
what the actual root cause is why Ghostscript
fails in various ways your particular case.

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: isonthomas

Thank you for the suggestions Johannes.. I have raised this querry in debian forums and am waiting for a reply. Hope to find the root cause soon.

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: isonthomas

The issue got resolved. The problem was that the system was running out of memory when ghostscript command was invoked in the printing system. That is why the command ran successfully when explicitly executed in command line. After increasing the RAM size, printing is working without issues

Thank you Johannes and H. Blischke for the support.

@michaelrsweet
Copy link
Collaborator Author

CUPS.org User: jsmeix.suse

See

http://bugs.ghostscript.com/show_bug.cgi?id=696348#c5

@michaelrsweet michaelrsweet added P0 - Unassigned (New) third-party This issue is in a third-party component labels Mar 17, 2016
@michaelrsweet michaelrsweet added this to the Stable milestone Mar 17, 2016
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
third-party This issue is in a third-party component
Projects
None yet
Development

No branches or pull requests

1 participant