search

apple / cups

Apple CUPS Sources
https://www.cups.org
Apache License 2.0
1.95k stars 464 forks source link

cups 1.7.5/2.1.3 no printing of text/plain files on lexmark e360d postscript printer possible #4949

Closed paneologist closed 7 years ago

paneologist commented 7 years ago

I hope this is the right address, as I could not find "CUPS mailing lists" mentioned in the guidelines. Also I think this is an non openSuSE related/upstream issue: I never had problems with cups and postscript-capable printers until upgrade to openSuSE Leap 42.2 and cups 1.7.5 with my Lexmark E360d printer (previous versions <= 1.5) . Something must differ with the new filtering layout, created from texttopdf / pdftopdf / pdftops / pstops, resulting in (fort his kind of printer) unprintable ps-data. also tried to upgrade to cups 2.1.3, with the exactly same result.

Printing of PDF files and printing e.g. from LibreOffice works. Self-Test Page leads to only a box and "Lexmark E360d
3011.010 20081117
318480588" printed (previously some nice cups graphics) in. When trying to print plain text, printer claims: "ERROR: ioerror OFFENDING COMMAND: StartData STACK:

0
( cryptic signs ) arraytype"

maybe it's just a missing font (shouldn't exotic font data be included?).. no Idea how to check/fix pdf files /var/spool/cups/tmp/foomatic-* look ok, with embedded fonts. manually running dftops -level3 -origpagesizes -nocenter -r 600 results in a ps-file, which evince can show.

below some data, if anything needed more, i can provide. unfortunately i do not have possibility to test old cups versions, nor another postscript printer available. printing command used for below data: "echo test | lpr"

I also looked into the data actually sent to the printer (hex trace printout): it starts with PJL data, and then postscript, but I see very often some the keyword pdf in it. Data (captured from ipp stream, being sent from network print server 1:1 to printer via parallel port) at the end of mail..

all cups packages installed are from openSuSE Main oss repository: (i actually did create even a new /etc/cups directory, parts of old config was still from SuSE 6.4 iirc)

rpm -qav | grep cups

liboyranos0-cups-0.9.5-1.6.x86_64
libgnomecups-lang-0.2.3-141.3.noarch cups-filters-ghostscript-1.8.2-1.5.x86_64 cups-filters-foomatic-rip-1.8.2-1.5.x86_64 cups-libs-32bit-1.7.5-7.10.x86_64 cups-1.7.5-7.10.x86_64 python3-cupshelpers-.5.7-7.1.noarch cups-backends-1.1-7.1.noarch cups-client-1.7.5-7.10.x86_64 cups-libs-1.7.5-7.10.x86_64 cups-filters-1.8.2-1.5.x86_64 cups-filters-cups-browsed-1.8.2-1.5.x86_64 python-cups-1.9.72-3.2.x86_64 monitoring-plugins-cups-2.2-29.1.x86_64 python3-cups-1.9.72-4.2.x86_64 cups-pk-helper-lang-0.2.5-6.4.noarch cups-pk-helper-0.2.5-6.4.x86_64 libgnomecups-0.2.3-141.3.x86_64

the ppd used is also original:

rpm -qf /usr/share/cups/model/OpenPrintingPPDs/postscript/Lexmark-E360d.Postscript.ppd.gz

OpenPrintingPPDs-postscript-4.0.0.2-4.1.noarch (file did not change in any version used)

cups logging: I [26/Jan/2017:11:24:15 +0100] Saving printers.conf... I [26/Jan/2017:11:24:15 +0100] Saving job.cache... D [26/Jan/2017:11:24:15 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files" I [26/Jan/2017:11:24:16 +0100] Scheduler shutting down normally. D [26/Jan/2017:11:24:16 +0100] Discarding unused server-stopped event... I [26/Jan/2017:11:24:16 +0100] Saving job.cache... D [26/Jan/2017:11:24:16 +0100] cupsdStopSelect() I [26/Jan/2017:11:24:16 +0100] Listening to 172.18.0.1:631 (IPv4) I [26/Jan/2017:11:24:16 +0100] Listening to 172.17.0.1:631 (IPv4) I [26/Jan/2017:11:24:16 +0100] Listening to [v1.::1]:631 (IPv6) I [26/Jan/2017:11:24:16 +0100] Listening to 127.0.0.1:631 (IPv4) I [26/Jan/2017:11:24:16 +0100] Listening to /run/cups/cups.sock (Domain) I [26/Jan/2017:11:24:16 +0100] Remote access is enabled. D [26/Jan/2017:11:24:16 +0100] Added auto ServerAlias alpha D [26/Jan/2017:11:24:16 +0100] Added auto ServerAlias alpha.swabian.net I [26/Jan/2017:11:24:16 +0100] Loaded configuration file "/etc/cups/cupsd.conf" D [26/Jan/2017:11:24:16 +0100] Repairing access permissions of "/etc/cups/classes.conf" I [26/Jan/2017:11:24:16 +0100] Using default TempDir of /var/spool/cups/tmp... I [26/Jan/2017:11:24:16 +0100] Configured for up to 100 clients. I [26/Jan/2017:11:24:16 +0100] Allowing up to 100 client connections per host. I [26/Jan/2017:11:24:16 +0100] Full reload is required. I [26/Jan/2017:11:24:16 +0100] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 44 types, 79 filters... D [26/Jan/2017:11:24:16 +0100] Loading printer E360d... D [26/Jan/2017:11:24:16 +0100] load_ppd: Loading /var/cache/cups/E360d.data... D [26/Jan/2017:11:24:16 +0100] cupsdRegisterPrinter(p=0x557750f23ed0(E360d)) I [26/Jan/2017:11:24:16 +0100] Loading job cache file "/var/cache/cups/job.cache"... D [26/Jan/2017:11:24:16 +0100] [Job 715] Loading from cache... [... D [26/Jan/2017:11:24:16 +0100] [Job 1214] Loading from cache... D [26/Jan/2017:11:24:16 +0100] [Job 715] Removing from history. I [26/Jan/2017:11:24:16 +0100] Full reload complete. D [26/Jan/2017:11:24:16 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)") I [26/Jan/2017:11:24:16 +0100] Cleaning out old files in "/var/spool/cups/tmp". D [26/Jan/2017:11:24:16 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp/.cache", pattern="(null)") I [26/Jan/2017:11:24:16 +0100] Cleaning out old files in "/var/spool/cups/tmp/.cache". D [26/Jan/2017:11:24:16 +0100] cupsdCleanFiles(path="/var/spool/cups/tmp/.cache/fontconfig", pattern="(null)") I [26/Jan/2017:11:24:16 +0100] Cleaning out old files in "/var/spool/cups/tmp/.cache/fontconfig". D [26/Jan/2017:11:24:16 +0100] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp") I [26/Jan/2017:11:24:16 +0100] Cleaning out old files in "/var/cache/cups". D [26/Jan/2017:11:24:16 +0100] Calling FindDeviceById(cups-E360d) D [26/Jan/2017:11:24:16 +0100] FindDeviceById failed: org.freedesktop.ColorManager.NotFound:device id 'cups-E360d' does not exist D [26/Jan/2017:11:24:16 +0100] Using profile ID "E360d-Gray..". D [26/Jan/2017:11:24:16 +0100] Calling CreateProfile(E360d-Gray..,temp) D [26/Jan/2017:11:24:16 +0100] Created profile "/org/freedesktop/ColorManager/profiles/E360d_Gray". D [26/Jan/2017:11:24:16 +0100] Using profile ID "E360d-RGB..". D [26/Jan/2017:11:24:16 +0100] Calling CreateProfile(E360d-RGB..,temp) D [26/Jan/2017:11:24:16 +0100] Created profile "/org/freedesktop/ColorManager/profiles/E360d_RGB__". I [26/Jan/2017:11:24:16 +0100] Registering ICC color profiles for "E360d". D [26/Jan/2017:11:24:16 +0100] Calling CreateDevice(cups-E360d,temp) D [26/Jan/2017:11:24:16 +0100] Created device "/org/freedesktop/ColorManager/devices/cups_E360d". D [26/Jan/2017:11:24:16 +0100] Calling /org/freedesktop/ColorManager/devices/cups_E360d:AddProfile(/org/freedesktop/ColorManager/profiles/E360d_Gray) [soft] D [26/Jan/2017:11:24:16 +0100] Calling /org/freedesktop/ColorManager/devices/cups_E360d:AddProfile(/org/freedesktop/ColorManager/profiles/E360d_RGB__) [soft] I [26/Jan/2017:11:24:16 +0100] Listening to 172.18.0.1:631 on fd 8... I [26/Jan/2017:11:24:16 +0100] Listening to 172.17.0.1:631 on fd 9... E [26/Jan/2017:11:24:16 +0100] Unable to bind socket for address [v1.::1]:631 - Cannot assign requested address. I [26/Jan/2017:11:24:16 +0100] Listening to 127.0.0.1:631 on fd 10... I [26/Jan/2017:11:24:16 +0100] Listening to /run/cups/cups.sock:631 on fd 11... I [26/Jan/2017:11:24:16 +0100] Resuming new connection processing... D [26/Jan/2017:11:24:16 +0100] Avahi server connection now available, registering printers for Bonjour broadcasting. D [26/Jan/2017:11:24:16 +0100] Registering "Lexmark E360d Foomatic/Postscript @ alpha" with DNS-SD type "_printer._tcp". D [26/Jan/2017:11:24:16 +0100] Registering "Lexmark E360d Foomatic/Postscript @ alpha" with DNS-SD type "_ipps._tcp". D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-type=0x80B01E D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-state=3 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Duplex=T D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Color=T D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" TLS=1.2 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" UUID=e65febf1-6c2d-37ec-515f-8223cbcaa78c D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" product=(Lexmark E360dn) D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" priority=0 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" adminurl=https://alpha.local:631/printers/E360d D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" ty=Unknown D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" rp=printers/E360d D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" qtotal=1 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" txtvers=1 D [26/Jan/2017:11:24:16 +0100] Registering "Lexmark E360d Foomatic/Postscript @ alpha" with DNS-SD type "_ipp._tcp". D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-type=0x80B01E D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-state=3 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Duplex=T D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Color=T D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" TLS=1.2 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" UUID=e65febf1-6c2d-37ec-515f-8223cbcaa78c D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" product=(Lexmark E360dn) D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" priority=0 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" adminurl=https://alpha.local:631/printers/E360d D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" ty=Unknown D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" rp=printers/E360d D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" qtotal=1 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" txtvers=1 D [26/Jan/2017:11:24:16 +0100] Registering "Lexmark E360d Foomatic/Postscript @ alpha" with DNS-SD type "_printer._tcp". D [26/Jan/2017:11:24:16 +0100] Registering "Lexmark E360d Foomatic/Postscript @ alpha" with DNS-SD type "_ipps._tcp". D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-type=0x80B01E D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-state=3 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Duplex=T D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Color=T D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" TLS=1.2 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" UUID=e65febf1-6c2d-37ec-515f-8223cbcaa78c D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" product=(Lexmark E360dn) D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" priority=0 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" adminurl=https://alpha.local:631/printers/E360d D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" ty=Unknown D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" rp=printers/E360d D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" qtotal=1 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" txtvers=1 D [26/Jan/2017:11:24:16 +0100] Registering "Lexmark E360d Foomatic/Postscript @ alpha" with DNS-SD type "_ipp._tcp". D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-type=0x80B01E D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-state=3 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Duplex=T D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Color=T D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" TLS=1.2 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" UUID=e65febf1-6c2d-37ec-515f-8223cbcaa78c D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" product=(Lexmark E360dn) D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" priority=0 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" adminurl=https://alpha.local:631/printers/E360d D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" ty=Unknown D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" rp=printers/E360d D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" qtotal=1 D [26/Jan/2017:11:24:16 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" txtvers=1 D [26/Jan/2017:11:24:16 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy" D [26/Jan/2017:11:24:16 +0100] Discarding unused server-started event... D [26/Jan/2017:11:24:17 +0100] Report: clients=0 D [26/Jan/2017:11:24:17 +0100] Report: jobs=499 D [26/Jan/2017:11:24:17 +0100] Report: jobs-active=0 D [26/Jan/2017:11:24:17 +0100] Report: printers=1 D [26/Jan/2017:11:24:17 +0100] Report: stringpool-string-count=1127 D [26/Jan/2017:11:24:17 +0100] Report: stringpool-alloc-bytes=6656 D [26/Jan/2017:11:24:17 +0100] Report: stringpool-total-bytes=20920 D [26/Jan/2017:11:24:19 +0100] [Client 17] Accepted from localhost (Domain) D [26/Jan/2017:11:24:19 +0100] [Client 17] Waiting for request. D [26/Jan/2017:11:24:19 +0100] [Client 17] POST / HTTP/1.1 D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [26/Jan/2017:11:24:19 +0100] [Client 17] No authentication data provided. D [26/Jan/2017:11:24:19 +0100] [Client 17] 2.0 CUPS-Get-Default 1 D [26/Jan/2017:11:24:19 +0100] CUPS-Get-Default D [26/Jan/2017:11:24:19 +0100] [Client 17] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost D [26/Jan/2017:11:24:19 +0100] [Client 17] Content-Length: 1116 D [26/Jan/2017:11:24:19 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1116, response=0x557750fbd340(IPP_IDLE), pipe_pid=0, file=-1 D [26/Jan/2017:11:24:19 +0100] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [26/Jan/2017:11:24:19 +0100] [Client 17] bytes=0, http_state=0, data_remaining=0 D [26/Jan/2017:11:24:19 +0100] [Client 17] Waiting for request. D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients" D [26/Jan/2017:11:24:19 +0100] [Client 17] POST /printers/E360d HTTP/1.1 D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy" D [26/Jan/2017:11:24:19 +0100] [Client 17] No authentication data provided. D [26/Jan/2017:11:24:19 +0100] [Client 17] 2.0 Create-Job 2 D [26/Jan/2017:11:24:19 +0100] Create-Job ipp://localhost:631/printers/E360d D [26/Jan/2017:11:24:19 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients" D [26/Jan/2017:11:24:19 +0100] add_job: requesting-user-name="root" I [26/Jan/2017:11:24:19 +0100] [Job 1215] Adding start banner page "none". D [26/Jan/2017:11:24:19 +0100] Discarding unused job-created event... I [26/Jan/2017:11:24:19 +0100] [Job 1215] Queued on "E360d" by "root". D [26/Jan/2017:11:24:19 +0100] [Client 17] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/E360d) from localhost D [26/Jan/2017:11:24:19 +0100] [Client 17] Content-Length: 184 D [26/Jan/2017:11:24:19 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=184, response=0x557750fbce70(IPP_IDLE), pipe_pid=0, file=-1 D [26/Jan/2017:11:24:19 +0100] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [26/Jan/2017:11:24:19 +0100] [Client 17] bytes=0, http_state=0, data_remaining=0 D [26/Jan/2017:11:24:19 +0100] [Client 17] Waiting for request. D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files" D [26/Jan/2017:11:24:19 +0100] [Client 17] POST /printers/E360d HTTP/1.1 D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files" D [26/Jan/2017:11:24:19 +0100] [Client 17] No authentication data provided. D [26/Jan/2017:11:24:19 +0100] [Client 17] 2.0 Send-Document 3 D [26/Jan/2017:11:24:19 +0100] Send-Document ipp://localhost:631/printers/E360d D [26/Jan/2017:11:24:19 +0100] cupsdIsAuthorized: requesting-user-name="root" D [26/Jan/2017:11:24:19 +0100] [Job 1215] Auto-typing file... D [26/Jan/2017:11:24:19 +0100] [Job 1215] Request file type is text/plain. D [26/Jan/2017:11:24:19 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" I [26/Jan/2017:11:24:19 +0100] [Job 1215] File of type text/plain queued by "root". I [26/Jan/2017:11:24:19 +0100] [Job 1215] Adding end banner page "none". D [26/Jan/2017:11:24:19 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [26/Jan/2017:11:24:19 +0100] [Job 1215] time-at-processing=1485426259 D [26/Jan/2017:11:24:19 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files" D [26/Jan/2017:11:24:19 +0100] Discarding unused printer-state-changed event... D [26/Jan/2017:11:24:19 +0100] [Job 1215] 3 filters for job: D [26/Jan/2017:11:24:19 +0100] [Job 1215] texttopdf (text/plain to application/pdf, cost 32) D [26/Jan/2017:11:24:19 +0100] [Job 1215] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66) D [26/Jan/2017:11:24:19 +0100] [Job 1215] foomatic-rip (application/vnd.cups-pdf to printer/E360d, cost 0) D [26/Jan/2017:11:24:19 +0100] [Job 1215] job-sheets=none,none D [26/Jan/2017:11:24:19 +0100] [Job 1215] argv[0]="E360d" D [26/Jan/2017:11:24:19 +0100] [Job 1215] argv[1]="1215" D [26/Jan/2017:11:24:19 +0100] [Job 1215] argv[2]="root" D [26/Jan/2017:11:24:19 +0100] [Job 1215] argv[3]="(stdin)" D [26/Jan/2017:11:24:19 +0100] [Job 1215] argv[4]="1" D [26/Jan/2017:11:24:19 +0100] [Job 1215] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:3542e92d-aae6-34f7-5b1c-1ff7d9d7a9da job-originating-host-name=localhost time-at-creation=1485426259 time-at-processing=1485426259" D [26/Jan/2017:11:24:19 +0100] [Job 1215] argv[6]="/var/spool/cups/d01215-001" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[0]="CUPS_CACHEDIR=/var/cache/cups" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[1]="CUPS_DATADIR=/usr/share/cups" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[2]="CUPS_DOCROOT=/usr/share/cups/webcontent" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[4]="CUPS_REQUESTROOT=/var/spool/cups" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[5]="CUPS_SERVERBIN=/usr/lib/cups" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[6]="CUPS_SERVERROOT=/etc/cups" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[7]="CUPS_STATEDIR=/run/cups" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[8]="HOME=/var/spool/cups/tmp" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[10]="SERVER_ADMIN=root@alpha.swabian.net" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[11]="SOFTWARE=CUPS/1.7.5" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[12]="TMPDIR=/var/spool/cups/tmp" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[13]="USER=root" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[14]="CUPS_MAX_MESSAGE=2047" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[15]="CUPS_SERVER=/run/cups/cups.sock" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[16]="CUPS_ENCRYPTION=IfRequested" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[17]="IPP_PORT=631" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[18]="CHARSET=utf-8" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[19]="LANG=en_US.UTF-8" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[20]="PPD=/etc/cups/ppd/E360d.ppd" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[21]="RIP_MAX_CACHE=128m" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[22]="CONTENT_TYPE=text/plain" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[23]="DEVICE_URI=ipp://printserver1.swabian.net/LPT2" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[24]="PRINTER_INFO=Lexmark E360d Foomatic/Postscript" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[25]="PRINTER_LOCATION=" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[26]="PRINTER=E360d" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[27]="PRINTER_STATE_REASONS=none" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[28]="CUPS_FILETYPE=document" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-pdf" D [26/Jan/2017:11:24:19 +0100] [Job 1215] envp[30]="AUTH_I****" I [26/Jan/2017:11:24:19 +0100] [Job 1215] Started filter /usr/lib/cups/filter/texttopdf (PID 22942) I [26/Jan/2017:11:24:19 +0100] [Job 1215] Started filter /usr/lib/cups/filter/pdftopdf (PID 22943) I [26/Jan/2017:11:24:19 +0100] [Job 1215] Started filter /usr/lib/cups/filter/foomatic-rip (PID 22944) I [26/Jan/2017:11:24:19 +0100] [Job 1215] Started backend /usr/lib/cups/backend/ipp (PID 22945) D [26/Jan/2017:11:24:19 +0100] Discarding unused job-state-changed event... D [26/Jan/2017:11:24:19 +0100] [Client 17] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/E360d) from localhost D [26/Jan/2017:11:24:19 +0100] [Client 17] Content-Length: 176 D [26/Jan/2017:11:24:19 +0100] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=176, response=0x557750fba710(IPP_IDLE), pipe_pid=0, file=-1 D [26/Jan/2017:11:24:19 +0100] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0 D [26/Jan/2017:11:24:19 +0100] [Client 17] bytes=0, http_state=0, data_remaining=0 D [26/Jan/2017:11:24:19 +0100] [Client 17] Waiting for request. D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files" D [26/Jan/2017:11:24:19 +0100] [Client 17] HTTP_STATE_WAITING Closing on EOF D [26/Jan/2017:11:24:19 +0100] [Client 17] Closing connection. D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [26/Jan/2017:11:24:19 +0100] [Job 1215] Sending stdin for job... D [26/Jan/2017:11:24:19 +0100] [Job 1215] update_reasons(attr=0(), s="+connecting-to-device") D [26/Jan/2017:11:24:19 +0100] [Job 1215] STATE: +connecting-to-device D [26/Jan/2017:11:24:19 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:19 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files" D [26/Jan/2017:11:24:19 +0100] [Job 1215] Looking up "printserver1.swabian.net"... D [26/Jan/2017:11:24:19 +0100] Discarding unused printer-state-changed event... D [26/Jan/2017:11:24:19 +0100] [Job 1215] backendWaitLoop(snmp_fd=5, addr=0x55fc40671938, side_cb=0x55fc402b2a00) D [26/Jan/2017:11:24:19 +0100] [Job 1215] Page = 595x842; 18,36 to 577,806 D [26/Jan/2017:11:24:19 +0100] [Job 1215] Calling FindDeviceById(cups-E360d) D [26/Jan/2017:11:24:19 +0100] [Job 1215] Found device /org/freedesktop/ColorManager/devices/cups_E360d D [26/Jan/2017:11:24:19 +0100] [Job 1215] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors) D [26/Jan/2017:11:24:19 +0100] [Job 1215] 'CM Color Calibration' Mode in SPOOLER-LESS: Off D [26/Jan/2017:11:24:19 +0100] [Job 1215] Getting input from file D [26/Jan/2017:11:24:19 +0100] [Job 1215] foomatic-rip version 1.8.2 running... D [26/Jan/2017:11:24:19 +0100] [Job 1215] Parsing PPD file ... D [26/Jan/2017:11:24:19 +0100] [Job 1215] Added option ColorSpace D [26/Jan/2017:11:24:19 +0100] [Job 1215] Added option PageSize D [26/Jan/2017:11:24:19 +0100] [Job 1215] CUPS page accounting disabled by driver. D [26/Jan/2017:11:24:19 +0100] [Job 1215] Added option ImageableArea D [26/Jan/2017:11:24:19 +0100] [Job 1215] Added option PaperDimension D [26/Jan/2017:11:24:19 +0100] [Job 1215] Added option InputSlot D [26/Jan/2017:11:24:19 +0100] [Job 1215] Added option Duplex D [26/Jan/2017:11:24:19 +0100] [Job 1215] Added option Resolution D [26/Jan/2017:11:24:19 +0100] [Job 1215] Added option Font D [26/Jan/2017:11:24:19 +0100] [Job 1215] Parameter Summary D [26/Jan/2017:11:24:19 +0100] [Job 1215] ----------------- D [26/Jan/2017:11:24:19 +0100] [Job 1215] Spooler: cups D [26/Jan/2017:11:24:19 +0100] [Job 1215] Printer: E360d D [26/Jan/2017:11:24:19 +0100] [Job 1215] Shell: /bin/bash D [26/Jan/2017:11:24:19 +0100] [Job 1215] PPD file: /etc/cups/ppd/E360d.ppd D [26/Jan/2017:11:24:19 +0100] [Job 1215] ATTR file: D [26/Jan/2017:11:24:19 +0100] [Job 1215] Printer model: Lexmark E360d Foomatic/Postscript D [26/Jan/2017:11:24:19 +0100] [Job 1215] Job title: stdin D [26/Jan/2017:11:24:19 +0100] [Job 1215] File(s) to be printed: D [26/Jan/2017:11:24:19 +0100] [Job 1215] D [26/Jan/2017:11:24:19 +0100] [Job 1215] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts D [26/Jan/2017:11:24:19 +0100] [Job 1215] Printing system options: D [26/Jan/2017:11:24:19 +0100] [Job 1215] Pondering option 'finishings=3' D [26/Jan/2017:11:24:19 +0100] [Job 1215] Unknown option finishings=3. D [26/Jan/2017:11:24:19 +0100] [Job 1215] Pondering option 'number-up=1' D [26/Jan/2017:11:24:19 +0100] [Job 1215] Unknown option number-up=1. D [26/Jan/2017:11:24:19 +0100] [Job 1215] Pondering option 'job-uuid=urn:uuid:3542e92d-aae6-34f7-5b1c-1ff7d9d7a9da' D [26/Jan/2017:11:24:19 +0100] [Job 1215] Unknown option job-uuid=urn:uuid:3542e92d-aae6-34f7-5b1c-1ff7d9d7a9da. D [26/Jan/2017:11:24:19 +0100] [Job 1215] Pondering option 'job-originating-host-name=localhost' D [26/Jan/2017:11:24:19 +0100] [Job 1215] Unknown option job-originating-host-name=localhost. D [26/Jan/2017:11:24:19 +0100] [Job 1215] Pondering option 'time-at-creation=1485426259' D [26/Jan/2017:11:24:19 +0100] [Job 1215] Unknown option time-at-creation=1485426259. D [26/Jan/2017:11:24:19 +0100] [Job 1215] Pondering option 'time-at-processing=1485426259' D [26/Jan/2017:11:24:19 +0100] [Job 1215] Unknown option time-at-processing=1485426259. D [26/Jan/2017:11:24:19 +0100] [Job 1215] CM Color Calibration Mode in CUPS: Off D [26/Jan/2017:11:24:19 +0100] [Job 1215] Options from the PPD file: D [26/Jan/2017:11:24:19 +0100] [Job 1215] ================================================ D [26/Jan/2017:11:24:19 +0100] [Job 1215] File: D [26/Jan/2017:11:24:19 +0100] [Job 1215] ================================================ D [26/Jan/2017:11:24:21 +0100] [Job 1215] PID 22942 (/usr/lib/cups/filter/texttopdf) exited with no errors. D [26/Jan/2017:11:24:21 +0100] [Job 1215] Filetype: PDF D [26/Jan/2017:11:24:21 +0100] [Job 1215] PostScript option found: PageSize=Letter: "<</PageSize[612 792]/ImagingBBox null>>setpagedevice" D [26/Jan/2017:11:24:21 +0100] [Job 1215] Driver does not understand PDF input, converting to PostScript D [26/Jan/2017:11:24:21 +0100] [Job 1215] Storing temporary files in /var/spool/cups/tmp D [26/Jan/2017:11:24:21 +0100] [Job 1215] PID 22943 (/usr/lib/cups/filter/pdftopdf) exited with no errors. D [26/Jan/2017:11:24:21 +0100] [Job 1215] Starting process "pdf-to-ps" (generation 1) D [26/Jan/2017:11:24:21 +0100] [Job 1215] Printer make and model: Lexmark Lexmark E360dn D [26/Jan/2017:11:24:21 +0100] [Job 1215] Running command line for pstops: pstops 1215 root stdin 1 ' finishings=3 job-uuid=urn:uuid:3542e92d-aae6-34f7-5b1c-1ff7d9d7a9da job-originating-host-name=localhost time-at-creation=1485426259 time-at-processing=1485426259' D [26/Jan/2017:11:24:21 +0100] [Job 1215] Using image rendering resolution 600 dpi D [26/Jan/2017:11:24:21 +0100] [Job 1215] Running command line for pdftops: pdftops -level3 -origpagesizes -nocenter -r 600 /var/spool/cups/tmp/foomatic-fOwfSD - D [26/Jan/2017:11:24:21 +0100] [Job 1215] Started filter pdftops (PID 22948) D [26/Jan/2017:11:24:21 +0100] [Job 1215] Started filter pstops (PID 22949) D [26/Jan/2017:11:24:21 +0100] [Job 1215] Page = 595x842; 18,36 to 577,806 D [26/Jan/2017:11:24:21 +0100] [Job 1215] slow_collate=0, slow_duplex=0, slow_order=0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] Before copy_comments - %!PS-Adobe-3.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] %!PS-Adobe-3.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] %Produced by poppler pdftops version: 0.43.0 (http://poppler.freedesktop.org) D [26/Jan/2017:11:24:21 +0100] [Job 1215] %%Creator: texttopdf/1.8.2 D [26/Jan/2017:11:24:21 +0100] [Job 1215] %%LanguageLevel: 3 D [26/Jan/2017:11:24:21 +0100] [Job 1215] %%DocumentSuppliedResources: (atend) D [26/Jan/2017:11:24:21 +0100] [Job 1215] %%DocumentMedia: A4 595 842 0 () () D [26/Jan/2017:11:24:21 +0100] [Job 1215] %%BoundingBox: 0 0 595 842 D [26/Jan/2017:11:24:21 +0100] [Job 1215] %%Pages: 4 D [26/Jan/2017:11:24:21 +0100] [Job 1215] %%EndComments D [26/Jan/2017:11:24:21 +0100] [Job 1215] Before copy_prolog - %%BeginProlog D [26/Jan/2017:11:24:21 +0100] [Job 1215] Filetype: PostScript D [26/Jan/2017:11:24:21 +0100] [Job 1215] Reading PostScript input ... D [26/Jan/2017:11:24:21 +0100] [Job 1215] --> This document is DSC-conforming! D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found %RBINumCopies: 1 D [26/Jan/2017:11:24:21 +0100] [Job 1215] ----------- D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found: %%BeginProlog D [26/Jan/2017:11:24:21 +0100] [Job 1215] Inserting option code into "Prolog" section. D [26/Jan/2017:11:24:21 +0100] [Job 1215] Before copy_setup - %%BeginSetup D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found: %%EndProlog D [26/Jan/2017:11:24:21 +0100] [Job 1215] ----------- D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found: %%BeginSetup D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found: %%BeginFeature: Resolution 600x600dpi D [26/Jan/2017:11:24:21 +0100] [Job 1215] Option: Resolution=600x600dpi D [26/Jan/2017:11:24:21 +0100] [Job 1215] --> Option will be set by PostScript interpreter D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found: %%BeginFeature: PageSize A4 D [26/Jan/2017:11:24:21 +0100] [Job 1215] Option: PageSize=A4 D [26/Jan/2017:11:24:21 +0100] [Job 1215] --> Option will be set by PostScript interpreter D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found: %%BeginFeature: InputSlot Multipurpose D [26/Jan/2017:11:24:21 +0100] [Job 1215] Option: InputSlot=Multipurpose D [26/Jan/2017:11:24:21 +0100] [Job 1215] --> Option will be set by PostScript interpreter D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found: %%BeginFeature: Duplex None D [26/Jan/2017:11:24:21 +0100] [Job 1215] Option: Duplex=None D [26/Jan/2017:11:24:21 +0100] [Job 1215] --> Option will be set by PostScript interpreter D [26/Jan/2017:11:24:21 +0100] [Job 1215] Before page loop - %%Page: 1 1 D [26/Jan/2017:11:24:21 +0100] [Job 1215] Copying page 1... D [26/Jan/2017:11:24:21 +0100] [Job 1215] pagew = 559.0, pagel = 770.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842 D [26/Jan/2017:11:24:21 +0100] [Job 1215] PageLeft = 18.0, PageRight = 577.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] PageTop = 806.0, PageBottom = 36.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] PageWidth = 595.0, PageLength = 842.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found: %%EndSetup D [26/Jan/2017:11:24:21 +0100] [Job 1215] Inserting option code into "Setup" section. D [26/Jan/2017:11:24:21 +0100] [Job 1215] ----------- D [26/Jan/2017:11:24:21 +0100] [Job 1215] New page: %%Page: 1 1 D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found: %%BeginPageSetup D [26/Jan/2017:11:24:21 +0100] [Job 1215] Inserting option code into "PageSetup" section. D [26/Jan/2017:11:24:21 +0100] [Job 1215] Copying page 2... D [26/Jan/2017:11:24:21 +0100] [Job 1215] pagew = 559.0, pagel = 770.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842 D [26/Jan/2017:11:24:21 +0100] [Job 1215] PageLeft = 18.0, PageRight = 577.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] PageTop = 806.0, PageBottom = 36.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] PageWidth = 595.0, PageLength = 842.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] New page found but previous not printed, print it now. D [26/Jan/2017:11:24:21 +0100] [Job 1215] Found: %%Page: 2 2 D [26/Jan/2017:11:24:21 +0100] [Job 1215] --> Output goes directly to the renderer now. D [26/Jan/2017:11:24:21 +0100] [Job 1215] Copying page 3... D [26/Jan/2017:11:24:21 +0100] [Job 1215] pagew = 559.0, pagel = 770.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842 D [26/Jan/2017:11:24:21 +0100] [Job 1215] PageLeft = 18.0, PageRight = 577.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] PageTop = 806.0, PageBottom = 36.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] PageWidth = 595.0, PageLength = 842.0 D [26/Jan/2017:11:24:21 +0100] [Job 1215] PID 22948 (pdftops) exited with no errors. D [26/Jan/2017:11:24:21 +0100] [Job 1215] Starting renderer with command: "cat" D [26/Jan/2017:11:24:21 +0100] [Job 1215] Starting process "kid3" (generation 1) D [26/Jan/2017:11:24:21 +0100] [Job 1215] Starting process "kid4" (generation 2) D [26/Jan/2017:11:24:21 +0100] [Job 1215] Starting process "renderer" (generation 2) D [26/Jan/2017:11:24:21 +0100] [Job 1215] JCL: 2345X@PJL D [26/Jan/2017:11:24:21 +0100] [Job 1215] D [26/Jan/2017:11:24:21 +0100] [Job 1215] Connecting to printserver1.swabian.net:631 I [26/Jan/2017:11:24:21 +0100] [Job 1215] Connecting to printer. D [26/Jan/2017:11:24:21 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:21 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files" D [26/Jan/2017:11:24:21 +0100] [Job 1215] Set job-printer-state-message to "Connecting to printer.", current level=INFO D [26/Jan/2017:11:24:21 +0100] Discarding unused job-progress event... D [26/Jan/2017:11:24:21 +0100] Discarding unused printer-state-changed event... D [26/Jan/2017:11:24:21 +0100] [Job 1215] update_reasons(attr=0(), s="-cups-certificate-error") D [26/Jan/2017:11:24:21 +0100] [Job 1215] update_reasons(attr=0(), s="-connecting-to-device") D [26/Jan/2017:11:24:21 +0100] [Job 1215] STATE: -connecting-to-device D [26/Jan/2017:11:24:21 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:21 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files" I [26/Jan/2017:11:24:21 +0100] [Job 1215] Connected to printer. D [26/Jan/2017:11:24:21 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:21 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [26/Jan/2017:11:24:21 +0100] [Job 1215] Set job-printer-state-message to "Connected to printer.", current level=INFO D [26/Jan/2017:11:24:21 +0100] [Job 1215] Connected to 172.18.0.41:631... D [26/Jan/2017:11:24:21 +0100] [Job 1215] Getting supported attributes... D [26/Jan/2017:11:24:21 +0100] Discarding unused job-progress event... D [26/Jan/2017:11:24:21 +0100] Discarding unused printer-state-changed event... D [26/Jan/2017:11:24:21 +0100] [Job 1215] Get-Printer-Attributes: server-error-version-not-supported (server-error-version-not-supported) D [26/Jan/2017:11:24:21 +0100] [Job 1215] Get-Printer-Attributes returned server-error-version-not-supported. I [26/Jan/2017:11:24:21 +0100] [Job 1215] Preparing to print. D [26/Jan/2017:11:24:21 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:21 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [26/Jan/2017:11:24:21 +0100] [Job 1215] Set job-printer-state-message to "Preparing to print.", current level=INFO D [26/Jan/2017:11:24:21 +0100] [Job 1215] The printer does not support IPP/2.0, trying IPP/1.1. D [26/Jan/2017:11:24:21 +0100] Discarding unused job-progress event... D [26/Jan/2017:11:24:21 +0100] Discarding unused printer-state-changed event... D [26/Jan/2017:11:24:21 +0100] [Job 1215] Getting supported attributes... D [26/Jan/2017:11:24:21 +0100] [Job 1215] Get-Printer-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:21 +0100] [Job 1215] document-format-supported (1 values) D [26/Jan/2017:11:24:21 +0100] [Job 1215] [0] = "application/octet-stream" D [26/Jan/2017:11:24:21 +0100] [Job 1215] operations-supported (7 values) D [26/Jan/2017:11:24:21 +0100] [Job 1215] [0] = Print-Job D [26/Jan/2017:11:24:21 +0100] [Job 1215] [1] = Validate-Job D [26/Jan/2017:11:24:21 +0100] [Job 1215] [2] = Purge-Jobs D [26/Jan/2017:11:24:21 +0100] [Job 1215] [3] = Cancel-Job D [26/Jan/2017:11:24:21 +0100] [Job 1215] [4] = Get-Job-Attributes D [26/Jan/2017:11:24:21 +0100] [Job 1215] [5] = Get-Jobs D [26/Jan/2017:11:24:21 +0100] [Job 1215] [6] = Get-Printer-Attributes D [26/Jan/2017:11:24:21 +0100] [Job 1215] update_reasons(attr=1(none-report), s="(null)") D [26/Jan/2017:11:24:21 +0100] [Job 1215] STATE: -none D [26/Jan/2017:11:24:21 +0100] [Job 1215] final_content_type="application/vnd.cups-pdf", document_format="application/octet-stream" D [26/Jan/2017:11:24:21 +0100] [Job 1215] Validate-Job IPP/1.1 D [26/Jan/2017:11:24:21 +0100] [Job 1215] printer-uri="ipp://printserver1.swabian.net:631/LPT2" D [26/Jan/2017:11:24:21 +0100] [Job 1215] requesting-user-name="root" D [26/Jan/2017:11:24:21 +0100] [Job 1215] job-name="1215 - (stdin)" D [26/Jan/2017:11:24:21 +0100] [Job 1215] document-format="application/octet-stream" D [26/Jan/2017:11:24:21 +0100] [Job 1215] Adding all operation/job attributes. D [26/Jan/2017:11:24:21 +0100] [Job 1215] IPP/1.1 Validate-Job #3 D [26/Jan/2017:11:24:21 +0100] [Job 1215] ---- operation-attributes-tag ---- D [26/Jan/2017:11:24:21 +0100] [Job 1215] attributes-charset charset utf-8 D [26/Jan/2017:11:24:21 +0100] [Job 1215] attributes-natural-language naturalLanguage en-us D [26/Jan/2017:11:24:21 +0100] [Job 1215] printer-uri uri ipp://printserver1.swabian.net:631/LPT2 D [26/Jan/2017:11:24:21 +0100] [Job 1215] requesting-user-name nameWithoutLanguage root D [26/Jan/2017:11:24:21 +0100] [Job 1215] job-name nameWithoutLanguage 1215 - (stdin) D [26/Jan/2017:11:24:21 +0100] [Job 1215] document-format mimeMediaType application/octet-stream D [26/Jan/2017:11:24:21 +0100] [Job 1215] ---- job-attributes-tag ---- D [26/Jan/2017:11:24:21 +0100] [Job 1215] finishings enum none D [26/Jan/2017:11:24:21 +0100] [Job 1215] job-originating-host-name nameWithoutLanguage localhost D [26/Jan/2017:11:24:21 +0100] [Job 1215] job-uuid uri urn:uuid:3542e92d-aae6-34f7-5b1c-1ff7d9d7a9da D [26/Jan/2017:11:24:21 +0100] [Job 1215] number-up integer 1 D [26/Jan/2017:11:24:21 +0100] [Job 1215] ---- end-of-attributes-tag ---- D [26/Jan/2017:11:24:21 +0100] [Job 1215] Validate-Job: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:21 +0100] [Job 1215] Print-Job IPP/1.1 D [26/Jan/2017:11:24:21 +0100] [Job 1215] printer-uri="ipp://printserver1.swabian.net:631/LPT2" D [26/Jan/2017:11:24:21 +0100] [Job 1215] requesting-user-name="root" D [26/Jan/2017:11:24:21 +0100] [Job 1215] job-name="1215 - (stdin)" D [26/Jan/2017:11:24:21 +0100] [Job 1215] document-format="application/octet-stream" D [26/Jan/2017:11:24:21 +0100] [Job 1215] Adding all operation/job attributes. D [26/Jan/2017:11:24:21 +0100] [Job 1215] IPP/1.1 Print-Job #5 D [26/Jan/2017:11:24:21 +0100] [Job 1215] ---- operation-attributes-tag ---- D [26/Jan/2017:11:24:21 +0100] [Job 1215] attributes-charset charset utf-8 D [26/Jan/2017:11:24:21 +0100] [Job 1215] attributes-natural-language naturalLanguage en-us D [26/Jan/2017:11:24:21 +0100] [Job 1215] printer-uri uri ipp://printserver1.swabian.net:631/LPT2 D [26/Jan/2017:11:24:21 +0100] [Job 1215] requesting-user-name nameWithoutLanguage root D [26/Jan/2017:11:24:21 +0100] [Job 1215] job-name nameWithoutLanguage 1215 - (stdin) D [26/Jan/2017:11:24:21 +0100] [Job 1215] document-format mimeMediaType application/octet-stream D [26/Jan/2017:11:24:21 +0100] [Job 1215] document-format mimeMediaType application/octet-stream D [26/Jan/2017:11:24:21 +0100] [Job 1215] ---- job-attributes-tag ---- D [26/Jan/2017:11:24:21 +0100] [Job 1215] finishings enum none D [26/Jan/2017:11:24:21 +0100] [Job 1215] job-originating-host-name nameWithoutLanguage localhost D [26/Jan/2017:11:24:21 +0100] [Job 1215] job-uuid uri urn:uuid:3542e92d-aae6-34f7-5b1c-1ff7d9d7a9da D [26/Jan/2017:11:24:21 +0100] [Job 1215] number-up integer 1 D [26/Jan/2017:11:24:21 +0100] [Job 1215] ---- end-of-attributes-tag ---- D [26/Jan/2017:11:24:21 +0100] [Job 1215] Sending file using HTTP/1.1 chunking... D [26/Jan/2017:11:24:21 +0100] [Job 1215] update_reasons(attr=1(none-report), s="(null)") D [26/Jan/2017:11:24:21 +0100] [Job 1215] Get-Printer-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:21 +0100] [Job 1215] (monitor) Get-Jobs: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:21 +0100] [Job 1215] (monitor) job-state=pending D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] update_reasons(attr=1(none-report), s="(null)") D [26/Jan/2017:11:24:22 +0100] [Job 1215] Get-Printer-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:22 +0100] [Job 1215] (monitor) Get-Job-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:22 +0100] [Job 1215] (monitor) job-state=pending D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:22 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] ----------- D [26/Jan/2017:11:24:23 +0100] [Job 1215] New page: %%Page: 2 2 D [26/Jan/2017:11:24:23 +0100] [Job 1215] Found: %%Page: 2 2 D [26/Jan/2017:11:24:23 +0100] [Job 1215] --> Output goes to the FIFO buffer now. D [26/Jan/2017:11:24:23 +0100] [Job 1215] Found: %%BeginPageSetup D [26/Jan/2017:11:24:23 +0100] [Job 1215] Inserting option code into "PageSetup" section. D [26/Jan/2017:11:24:23 +0100] [Job 1215] Copying page 4... D [26/Jan/2017:11:24:23 +0100] [Job 1215] pagew = 559.0, pagel = 770.0 D [26/Jan/2017:11:24:23 +0100] [Job 1215] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842 D [26/Jan/2017:11:24:23 +0100] [Job 1215] PageLeft = 18.0, PageRight = 577.0 D [26/Jan/2017:11:24:23 +0100] [Job 1215] PageTop = 806.0, PageBottom = 36.0 D [26/Jan/2017:11:24:23 +0100] [Job 1215] PageWidth = 595.0, PageLength = 842.0 D [26/Jan/2017:11:24:23 +0100] [Job 1215] Wrote 4 pages... D [26/Jan/2017:11:24:23 +0100] [Job 1215] New page found but previous not printed, print it now. D [26/Jan/2017:11:24:23 +0100] [Job 1215] Found: %%Page: 3 3 D [26/Jan/2017:11:24:23 +0100] [Job 1215] --> Output goes directly to the renderer now. D [26/Jan/2017:11:24:23 +0100] [Job 1215] ----------- D [26/Jan/2017:11:24:23 +0100] [Job 1215] New page: %%Page: 3 3 D [26/Jan/2017:11:24:23 +0100] [Job 1215] Found: %%Page: 3 3 D [26/Jan/2017:11:24:23 +0100] [Job 1215] --> Output goes to the FIFO buffer now. D [26/Jan/2017:11:24:23 +0100] [Job 1215] Found: %%BeginPageSetup D [26/Jan/2017:11:24:23 +0100] [Job 1215] Inserting option code into "PageSetup" section. D [26/Jan/2017:11:24:23 +0100] [Job 1215] PID 22949 (pstops) exited with no errors. D [26/Jan/2017:11:24:23 +0100] [Job 1215] New page found but previous not printed, print it now. D [26/Jan/2017:11:24:23 +0100] [Job 1215] Found: %%Page: 4 4 D [26/Jan/2017:11:24:23 +0100] [Job 1215] --> Output goes directly to the renderer now. D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] ----------- D [26/Jan/2017:11:24:23 +0100] [Job 1215] New page: %%Page: 4 4 D [26/Jan/2017:11:24:23 +0100] [Job 1215] Found: %%Page: 4 4 D [26/Jan/2017:11:24:23 +0100] [Job 1215] --> Output goes to the FIFO buffer now. D [26/Jan/2017:11:24:23 +0100] [Job 1215] Found: %%BeginPageSetup D [26/Jan/2017:11:24:23 +0100] [Job 1215] Inserting option code into "PageSetup" section. D [26/Jan/2017:11:24:23 +0100] [Job 1215] Flushing FIFO. D [26/Jan/2017:11:24:23 +0100] [Job 1215] Closing renderer D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] renderer exited with status 0 D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] update_reasons(attr=1(none-report), s="(null)") D [26/Jan/2017:11:24:23 +0100] [Job 1215] Get-Printer-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:23 +0100] [Job 1215] (monitor) Get-Job-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:23 +0100] [Job 1215] (monitor) job-state=completed D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] kid4 exited with status 0 D [26/Jan/2017:11:24:23 +0100] [Job 1215] kid3 finished D [26/Jan/2017:11:24:23 +0100] [Job 1215] kid3 exited with status 0 D [26/Jan/2017:11:24:23 +0100] [Job 1215] pdf-to-ps exited with status 0 D [26/Jan/2017:11:24:23 +0100] [Job 1215] Closing foomatic-rip. D [26/Jan/2017:11:24:23 +0100] [Job 1215] PID 22944 (/usr/lib/cups/filter/foomatic-rip) exited with no errors. D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 16384 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Read 10818 bytes... D [26/Jan/2017:11:24:23 +0100] [Job 1215] Print-Job: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:23 +0100] [Job 1215] Print job accepted - job ID 33554541. I [26/Jan/2017:11:24:23 +0100] [Job 1215] Waiting for job to complete. D [26/Jan/2017:11:24:23 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [26/Jan/2017:11:24:23 +0100] [Job 1215] Set job-printer-state-message to "Waiting for job to complete.", current level=INFO D [26/Jan/2017:11:24:23 +0100] Discarding unused job-progress event... D [26/Jan/2017:11:24:23 +0100] Discarding unused printer-state-changed event... D [26/Jan/2017:11:24:23 +0100] [Job 1215] update_reasons(attr=1(none-report), s="(null)") D [26/Jan/2017:11:24:23 +0100] [Job 1215] Get-Printer-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:23 +0100] [Job 1215] Get-Job-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:23 +0100] [Job 1215] update_reasons(attr=1(none-report), s="(null)") D [26/Jan/2017:11:24:23 +0100] [Job 1215] Get-Printer-Attributes: successful-ok-ignored-or-substituted-attributes (successful-ok-ignored-or-substituted-attributes) D [26/Jan/2017:11:24:23 +0100] [Job 1215] ATTR: auth-info-required=none D [26/Jan/2017:11:24:23 +0100] load_ppd: Loading /var/cache/cups/E360d.data... D [26/Jan/2017:11:24:23 +0100] cupsdRegisterPrinter(p=0x557750f23ed0(E360d)) D [26/Jan/2017:11:24:23 +0100] Registering "Lexmark E360d Foomatic/Postscript @ alpha" with DNS-SD type "_printer._tcp". D [26/Jan/2017:11:24:23 +0100] Registering "Lexmark E360d Foomatic/Postscript @ alpha" with DNS-SD type "_ipps._tcp". D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-type=0x80B01E D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-state=4 D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Duplex=T D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Color=T D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" TLS=1.2 D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" UUID=e65febf1-6c2d-37ec-515f-8223cbcaa78c D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" product=(Lexmark E360dn) D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" priority=0 D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" adminurl=https://alpha.local:631/printers/E360d D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" ty=Unknown D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" rp=printers/E360d D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" qtotal=1 D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" txtvers=1 D [26/Jan/2017:11:24:23 +0100] Registering "Lexmark E360d Foomatic/Postscript @ alpha" with DNS-SD type "_ipp._tcp". D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-type=0x80B01E D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" printer-state=4 D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Duplex=T D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" Color=T D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" TLS=1.2 D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" UUID=e65febf1-6c2d-37ec-515f-8223cbcaa78c D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" pdl=application/octet-stream,application/pdf,application/postscript,image/jpeg,image/png,image/pwg-raster D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" product=(Lexmark E360dn) D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" priority=0 D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" adminurl=https://alpha.local:631/printers/E360d D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" ty=Unknown D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" rp=printers/E360d D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" qtotal=1 D [26/Jan/2017:11:24:23 +0100] DNS_SD "Lexmark E360d Foomatic/Postscript @ alpha" txtvers=1 D [26/Jan/2017:11:24:23 +0100] cupsdMarkDirty(P----) D [26/Jan/2017:11:24:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [26/Jan/2017:11:24:23 +0100] [Job 1215] PID 22945 (/usr/lib/cups/backend/ipp) exited with no errors. D [26/Jan/2017:11:24:23 +0100] [Job 1215] time-at-completed=1485426263 D [26/Jan/2017:11:24:23 +0100] Discarding unused job-completed event... I [26/Jan/2017:11:24:23 +0100] [Job 1215] Job completed. D [26/Jan/2017:11:24:23 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [26/Jan/2017:11:24:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [26/Jan/2017:11:24:23 +0100] Discarding unused printer-state-changed event... D [26/Jan/2017:11:24:23 +0100] cupsdMarkDirty(---J-) D [26/Jan/2017:11:24:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files" D [26/Jan/2017:11:24:24 +0100] [Job 1215] Unloading...

and beginning of ps-data sent to printer: %!PS-Adobe-3.0 %Produced by poppler pdftops version: 0.43.0 (http://poppler.freedesktop.org) %%Creator: texttopdf/1.8.2 %%LanguageLevel: 3 %%DocumentSuppliedResources: (atend) %%DocumentMedia: A4 595 842 0 () () %%For: (root) %%Title: (stdin) %RBINumCopies: 1 %%Pages: (atend) %%BoundingBox: (atend) %%EndComments %%BeginProlog %%BeginResource: procset xpdf 3.00 0 %%Copyright: Copyright 1996-2011 Glyph & Cog, LLC /xpdf 75 dict def xpdf begin % PDF special state /pdfDictSize 15 def /pdfSetup { /setpagedevice where { pop 2 dict begin /Policies 1 dict dup begin /PageSize 6 def end def { /Duplex true def } if currentdict end setpagedevice } { pop } ifelse } def /pdfSetupPaper { % Change paper size, but only if different from previous paper size otherwise % duplex fails. PLRM specifies a tolerance of 5 pts when matching paper size % so we use the same when checking if the size changes. /setpagedevice where { pop currentpagedevice /PageSize known { 2 copy currentpagedevice /PageSize get aload pop exch 4 1 roll sub abs 5 gt 3 1 roll sub abs 5 gt or } { true } ifelse { 2 array astore 2 dict begin /PageSize exch def /ImagingBBox null def currentdict end setpagedevice } { pop pop } ifelse } { pop } ifelse } def /pdfStartPage { pdfDictSize dict begin /pdfFillCS [] def /pdfFillXf�Xc ��@�Z��m�nE��J@@ ���)�wW �Dt��PrHorm {} def /pdfStrokeCS [] def /pdfStrokeXform {} def /pdfFill [0] def /pdfStroke [0] def /pdfFillOP false def /pdfStrokeOP false def /pdfOPM false def /pdfLastFill false def /pdfLastStroke false def /pdfTextMat [1 0 0 1 0 0] def /pdfFontSize 0 def /pdfCharSpacing 0 def /pdfTextRender 0 def /pdfPatternCS false def /pdfTextRise 0 def /pdfWordSpacing 0 def /pdfHorizScaling 1 def /pdfTextClipPath [] def } def /pdfEndPage { end } def % PDF color state /opm { dup /pdfOPM exch def /setoverprintmode where{pop setoverprintmode}{pop}ifelse } def /cs { /pdfFillXform exch def dup /pdfFillCS exch def setcolorspace } def /CS { /pdfStrokeXform exch def dup /pdfStrokeCS exch def setcolorspace } def /sc { pdfLastFill not { pdfFillCS setcolorspace } if dup /pdfFill exch def aload pop pdfFillXform setcolor /pdfLastFill true def /pdfLastStroke false def } def /SC { pdfLastStroke not { pdfStrokeCS setcolorspace } if dup /pdfStroke exch def aload pop pdfStrokeXform setcolor /pdfLastStroke true def /pdfLastFill false def } def /op { /pdfFillOP exch def pdfLastFill { pdfFillOP setoverprint } if } def /OP { /pdfStrokeOP exch def pdfLastStroke { pdfStrokeOP setoverprint } if } def /fCol { pdfLastFill not { pdfFillCS setcolorspace pdfFill aload pop pdfFillXform setcolor pdfFillOP setoverprint /pdfLastFill true def /pdfLastStroke false def } if } def /sCol�X c ��@�Z��m�nE��K@@ ���)�wW ��t��Pr� { pdfLastStroke not { pdfStrokeCS setcolorspace pdfStroke aload pop pdfStrokeXform setcolor pdfStrokeOP setoverprint /pdfLastStroke true def /pdfLastFill false def } if } def % build a font /pdfMakeFont { 4 3 roll findfont 4 2 roll matrix scale makefont dup length dict begin { 1 index /FID ne { def } { pop pop } ifelse } forall /Encoding exch def currentdict end definefont pop } def /pdfMakeFont16 { exch findfont dup length dict begin { 1 index /FID ne { def } { pop pop } ifelse } forall /WMode exch def currentdict end definefont pop } def /pdfMakeFont16L3 { 1 index /CIDFont resourcestatus { pop pop 1 index /CIDFont findresource /CIDFontType known } { false } ifelse { 0 eq { /Identity-H } { /Identity-V } ifelse exch 1 array astore composefont pop } { pdfMakeFont16 } ifelse } def % graphics state operators /q { gsave pdfDictSize dict begin } def /Q { end grestore /pdfLastFill where { pop pdfLastFill { pdfFillOP setoverprint } { pdfStrokeOP setoverprint } ifelse } if /pdfOPM where { pop pdfOPM /setoverprintmode where{pop setoverprintmode}{pop}ifelse } if } def /cm { concat } def /d { setdash } def /i { setflat } def /j { setlinejoin } def /J { setlinecap } def /M { setmiterlimit } def /w { setlinewidth } def % path segment operators /m { moveto } def /l { lineto } def /c { curveto } def /re { 4 2 ro�X2c ��@�Z��m�nE��L@@ ���)�wW �t��Pr��ll moveto 1 index 0 rlineto 0 exch rlineto neg 0 rlineto closepath } def /h { closepath } def % path painting operators /S { sCol stroke } def /Sf { fCol stroke } def /f { fCol fill } def /f { fCol eofill } def % clipping operators /W { clip newpath } def /W { eoclip newpath } def /Ws { strokepath clip newpath } def % text state operators /Tc { /pdfCharSpacing exch def } def /Tf { dup /pdfFontSize exch def dup pdfHorizScaling mul exch matrix scale pdfTextMat matrix concatmatrix dup 4 0 put dup 5 0 put exch findfont exch makefont setfont } def /Tr { /pdfTextRender exch def } def /Tp { /pdfPatternCS exch def } def /Ts { /pdfTextRise exch def } def /Tw { /pdfWordSpacing exch def } def /Tz { /pdfHorizScaling exch def } def % text positioning operators /Td { pdfTextMat transform moveto } def /Tm { /pdfTextMat exch def } def % text string operators /xyshow where { pop /xyshow2 { dup length array 0 2 2 index length 1 sub { 2 index 1 index 2 copy get 3 1 roll 1 add get pdfTextMat dtransform 4 2 roll 2 copy 6 5 roll put 1 add 3 1 roll dup 4 2 roll put } for exch pop xyshow } def }{ /xyshow2 { currentfont /FontType get 0 eq { 0 2 3 index length 1 sub { currentpoint 4 index 3 index 2 getinterval show moveto 2 copy get 2 index 3 2 roll 1 add get pdfTextMat dtransform rmoveto } for } { 0 1 3 index length 1 sub { currentpo�XJc ��@�Z��m�nE��M@@ ���)�wW �`t��Pr�int 4 index 3 index 1 getinterval show moveto 2 copy 2 mul get 2 index 3 2 roll 2 mul 1 add get pdfTextMat dtransform rmoveto } for } ifelse pop pop } def } ifelse /cshow where { pop /xycp { 0 3 2 roll { pop pop currentpoint 3 2 roll 1 string dup 0 4 3 roll put false charpath moveto 2 copy get 2 index 2 index 1 add get pdfTextMat dtransform rmoveto 2 add } exch cshow pop pop } def }{ /xycp { currentfont /FontType get 0 eq { 0 2 3 index length 1 sub { currentpoint 4 index 3 index 2 getinterval false charpath moveto 2 copy get 2 index 3 2 roll 1 add get pdfTextMat dtransform rmoveto } for } { 0 1 3 index length 1 sub { currentpoint 4 index 3 index 1 getinterval false charpath moveto 2 copy 2 mul get 2 index 3 2 roll 2 mul 1 add get pdfTextMat dtransform rmoveto } for } ifelse pop pop } def } ifelse /Tj { fCol 0 pdfTextRise pdfTextMat dtransform rmoveto currentpoint 4 2 roll pdfTextRender 1 and 0 eq { 2 copy xyshow2 } if pdfTextRender 3 and dup 1 eq exch 2 eq or { 3 index 3 index moveto 2 copy currentfont /FontType get 3 eq { fCol } { sCol } ifelse xycp currentpoint stroke moveto } if pdfTextRender 4 and 0 ne { 4 2 roll moveto xycp /pdfTextClipPath [ pdfTextClipPath aload pop {/moveto cvx} {/lineto cvx} {/�X^c ��@�Z��m�nE��N@@ ��)�wW �t��Pr40curveto cvx} {/closepath cvx} pathforall ] def currentpoint newpath moveto } { pop pop pop pop } ifelse 0 pdfTextRise neg pdfTextMat dtransform rmoveto } def /TJm { 0.001 mul pdfFontSize mul pdfHorizScaling mul neg 0 pdfTextMat dtransform rmoveto } def /TJmV { 0.001 mul pdfFontSize mul neg 0 exch pdfTextMat dtransform rmoveto } def /Tclip { pdfTextClipPath cvx exec clip newpath /pdfTextClipPath [] def } def /Tclip { pdfTextClipPath cvx exec eoclip newpath /pdfTextClipPath [] def } def % Level 2/3 image operators /pdfImBuf 100 string def /pdfImStr { 2 copy exch length lt { 2 copy get exch 1 add exch } { () } ifelse } def /skipEOD { { currentfile pdfImBuf readline not { pop exit } if (%-EOD-) eq { exit } if } loop } def /pdfIm { image skipEOD } def /pdfMask { /ReusableStreamDecode filter skipEOD /maskStream exch def } def /pdfMaskEnd { maskStream closefile } def /pdfMaskInit { /maskArray exch def /maskIdx 0 def } def /pdfMaskSrc { maskIdx maskArray length lt { maskArray maskIdx get /maskIdx maskIdx 1 add def } { () } ifelse } def /pdfImM { fCol imagemask skipEOD } def /pr { 2 index 2 index 3 2 roll putinterval 4 add } def /pdfImClip { gsave 0 2 4 index length 1 sub { dup 4 index exch 2 copy get 5 index div put 1 add 3 index exch 2 copy get 3 index div put } for pop pop rectclip } def /pdfImClipEnd { grestor�X�c ��@�Z��m�nE��O@@ ~��)�wW ��t��Prk�e } def % shading operators /colordelta { false 0 1 3 index length 1 sub { dup 4 index exch get 3 index 3 2 roll get sub abs 0.004 gt { pop true } if } for exch pop exch pop } def /funcCol { func n array astore } def /funcSH { dup 0 eq { true } { dup 6 eq { false } { 4 index 4 index funcCol dup 6 index 4 index funcCol dup 3 1 roll colordelta 3 1 roll 5 index 5 index funcCol dup 3 1 roll colordelta 3 1 roll 6 index 8 index funcCol dup 3 1 roll colordelta 3 1 roll colordelta or or or } ifelse } ifelse { 1 add 4 index 3 index add 0.5 mul exch 4 index 3 index add 0.5 mul exch 6 index 6 index 4 index 4 index 4 index funcSH 2 index 6 index 6 index 4 index 4 index funcSH 6 index 2 index 4 index 6 index 4 index funcSH 5 3 roll 3 2 roll funcSH pop pop } { pop 3 index 2 index add 0.5 mul 3 index 2 index add 0.5 mul funcCol sc dup 4 index exch mat transform m 3 index 3 index mat transform l 1 index 3 index mat transform l mat transform l pop pop h f } ifelse } def /axialCol { dup 0 lt { pop t0 } { dup 1 gt { pop t1 } { dt mul t0 add } ifelse } ifelse func n array astore } def /axialSH { dup 0 eq { true } { dup 8 eq { false } { 2 index axialCol 2 index axialCol colordelta } ifelse } ifelse { 1 add 3 1 roll 2 copy add 0.5 mul�X�c ��@�Z��m�nE��P@@ }��)�wW |t��Pr�� dup 4 3 roll exch 4 index axialSH exch 3 2 roll axialSH } { pop 2 copy add 0.5 mul axialCol sc exch dup dx mul x0 add exch dy mul y0 add 3 2 roll dup dx mul x0 add exch dy mul y0 add dx abs dy abs ge { 2 copy yMin sub dy mul dx div add yMin m yMax sub dy mul dx div add yMax l 2 copy yMax sub dy mul dx div add yMax l yMin sub dy mul dx div add yMin l h f } { exch 2 copy xMin sub dx mul dy div add xMin exch m xMax sub dx mul dy div add xMax exch l exch 2 copy xMax sub dx mul dy div add xMax exch l xMin sub dx mul dy div add xMin exch l h f } ifelse } ifelse } def /radialCol { dup t0 lt { pop t0 } { dup t1 gt { pop t1 } if } ifelse func n array astore } def /radialSH { dup 0 eq { true } { dup 8 eq { false } { 2 index dt mul t0 add radialCol 2 index dt mul t0 add radialCol colordelta } ifelse } ifelse { 1 add 3 1 roll 2 copy add 0.5 mul dup 4 3 roll exch 4 index radialSH exch 3 2 roll radialSH } { pop 2 copy add 0.5 mul dt mul t0 add radialCol sc encl { exch dup dx mul x0 add exch dup dy mul y0 add exch dr mul r0 add 0 360 arc h dup dx mul x0 add exch dup dy mul y0 add exch dr mul r0 add 360 0 arcn h f } { 2 copy dup dx mul x0 add exch dup dy mul y0 add exch dr mul r0 add a1 a2 arcn dup dx mul x0�X�c ��@�Z��m�nE��Q@@ |��)�wW 0t��Pr+g add exch dup dy mul y0 add exch dr mul r0 add a2 a1 arcn h dup dx mul x0 add exch dup dy mul y0 add exch dr mul r0 add a1 a2 arc dup dx mul x0 add exch dup dy mul y0 add exch dr mul r0 add a2 a1 arc h f } ifelse } ifelse } def end %%EndResource /CIDInit /ProcSet findresource begin 10 dict begin begincmap /CMapType 1 def /CMapName /Identity-H def /CIDSystemInfo 3 dict dup begin /Registry (Adobe) def /Ordering (Identity) def /Supplement 0 def end def 1 begincodespacerange

<0000> endcodespacerange 0 usefont 1 begincidrange <0000> 0 endcidrange endcmap currentdict CMapName exch /CMap defineresource pop end 10 dict begin begincmap /CMapType 1 def /CMapName /Identity-V def /CIDSystemInfo 3 dict dup begin /Registry (Adobe) def /Ordering (Identity) def /Supplement 0 def end def /WMode 1 def 1 begincodespacerange <0000> endcodespacerange 0 usefont 1 begincidrange <0000> 0 endcidrange endcmap currentdict CMapName exch /CMap defineresource pop end end %%EndProlog %%BeginSetup % Disable CTRL-D as an end-of-file marker... userdict dup(\004)cvn{}put (\004\004)cvn{}put [{ %%BeginFeature: *Resolution 600x600dpi <>setpagedevice %%EndFeature } stopped cleartomark [{ %%BeginFeature: *PageSize A4 <>setpagedevice %%EndFeature } stopped cleartoma�X.i <<�m�n4@�ZE(�-�OT�)�w�t��W ېPD �S�X`i ��@�Z��m�nE��R@@ {��)�wW ý├ýýP⎼V3⎼┐ [π %%B␊±␋┼F␊▒├┤⎼␊: *I┼⎻┤├S┌⎺├ M┤┌├␋⎻┤⎼⎻⎺⎽␊ <>⎽␊├⎻▒±␊␍␊┴␋␌␊ %%E┼␍F␊▒├┤⎼␊ £ ⎽├⎺⎻⎻␊␍ ␌┌␊▒⎼├⎺└▒⎼┐ [π %%B␊±␋┼F␊▒├┤⎼␊: *D┤⎻┌␊│ N⎺┼␊ <>⎽␊├⎻▒±␊␍␊┴␋␌␊ %%E┼␍F␊▒├┤⎼␊ £ ⎽├⎺⎻⎻␊␍ ␌┌␊▒⎼├⎺└▒⎼┐ % │ ≤ ┬ ␤ ESP⎼␌ - C┌␋⎻ ├⎺ ▒ ⎼␊␌├▒┼±┌␊. ┤⎽␊⎼␍␋␌├/ESP⎼␌/⎼␊␌├␌┌␋⎻ ┬␤␊⎼␊π⎻⎺⎻/⎼␊␌├␌┌␋⎻ ┌⎺▒␍£ ππ┼␊┬⎻▒├␤ 4 2 ⎼⎺┌┌ └⎺┴␊├⎺ 1 ␋┼␍␊│ 0 ⎼┌␋┼␊├⎺ 0 ␊│␌␤ ⎼┌␋┼␊├⎺ ┼␊± 0 ⎼┌␋┼␊├⎺ ␌┌⎺⎽␊⎻▒├␤ ␌┌␋⎻ ┼␊┬⎻▒├␤£␉␋┼␍£␋°␊┌⎽␊ ⎻┤├ % │ ≤ ┬ ␤ ESP⎼° - F␋┌┌ ▒ ⎼␊␌├▒┼±┌␊. ┤⎽␊⎼␍␋␌├/ESP⎼°/⎼␊␌├°␋┌┌ ┬␤␊⎼␊π⎻⎺⎻/⎼␊␌├°␋┌┌ ┌⎺▒␍£ ππ±⎽▒┴␊ ┼␊┬⎻▒├␤ 4 2 ⎼⎺┌┌ └⎺┴␊├⎺ 1 ␋┼␍␊│ 0 ⎼┌␋┼␊├⎺ 0 ␊│␌␤ ⎼┌␋┼␊├⎺ ┼␊± 0 ⎼┌␋┼␊├⎺ ␌┌⎺⎽␊⎻▒├␤ °␋┌┌ ±⎼␊⎽├⎺⎼␊£␉␋┼␍£␋°␊┌⎽␊ ⎻┤├ % │ ≤ ┬ ␤ ESP⎼⎽ - S├⎼⎺┐␊ ▒ ⎼␊␌├▒┼±┌␊. ┤⎽␊⎼␍␋␌├/ESP⎼⎽/⎼␊␌├⎽├⎼⎺┐␊ ┬␤␊⎼␊π⎻⎺⎻/⎼␊␌├⎽├⎼⎺┐␊ ┌⎺▒␍£ ππ±⎽▒┴␊ ┼␊┬⎻▒├␤ 4 2 ⎼⎺┌┌ └⎺┴␊├⎺ 1 ␋┼␍␊│ 0 ⎼┌␋┼␊├⎺ 0 ␊│␌␤ ⎼┌␋┼␊├⎺ ┼␊± 0 ⎼┌␋┼␊├⎺ ␌┌⎺⎽␊⎻▒├␤ ⎽├⎼⎺┐␊ ±⎼␊⎽├⎺⎼␊£␉␋┼␍£␋°␊┌⎽␊ ⎻┤├ ┤⎽␊⎼␍␋␌├/ESP┬┌π£␉␋┼␍ ⎻┤├ │⎻␍° ␉␊±␋┼ %%B␊±␋┼R␊⎽⎺┤⎼␌␊: °⎺┼├ S⎺┤⎼␌␊C⎺␍␊P⎼⎺-B⎺┌␍ /CIDI┼␋├ /P⎼⎺␌S␊├ °␋┼␍⎼␊⎽⎺┤⎼␌␊ ␉␊±␋┼ 20 ␍␋␌├ ␉␊±␋┼ /CIDF⎺┼├N▒└␊ /S⎺┤⎼␌␊C⎺␍␊P⎼⎺-B⎺┌␍ ␍␊° /CIDF⎺┼├T≤⎻␊ 0 ␍␊° /CIDS≤⎽├␊└I┼°⎺ 3 ␍␋␌├ ␍┤⎻ ␉␊±␋┼ /R␊±␋⎽├⎼≤ (A␍⎺␉␊) ␍␊° /O⎼␍␊⎼␋┼± (I␍␊┼├␋├≤) ␍␊° /S┤⎻⎻┌␊└␊┼├ 0 ␍␊° ␊┼␍ ␍␊° /F⎺┼├M▒├⎼␋│ [0.001 0 0 0.001 0 0] ␍␊° /F⎺┼├BB⎺│ [-208 -400 808 1060] ␍␊° /F⎺┼├I┼°⎺ 1 ␍␋␌├ ␍┤⎻ ␉␊±␋┼ /FST≤⎻␊ 8 ␍␊° ␊┼␍ ␍␊° /CIDC⎺┤┼├ 1549 ␍␊° /FDB≤├␊⎽ 1 ␍␊° /GDB≤├␊⎽ 3 ␍␊° /CIDM▒⎻O°°⎽␊├ 0 ␍␊° /FDA⎼⎼▒≤ 1 ▒⎼⎼▒≤ ␍┤⎻ 0 10 ␍␋␌├ ␉␊±␋┼ /F⎺┼├T≤⎻␊ 1 ␍␊° /F⎺┼├M▒├⎼␋│ [1 0 0 1 0 0] ␍␊° /P▒␋┼├T≤⎻␊ 0 ␍␊° /P⎼␋┴▒├␊ 32 ␍␋␌├ ␉␊±␋┼ /BýX⎻␋ ýý@ýZýý└ý┼EýýS@@ ≥ýý)ý┬W ý├ýýP⎼JlueValues [-12 0 496 508 578 590 635 647 652 664 700 712] def /OtherBlues [-196 -184] def /FamilyBlues [-12 0 486 498 574 586 638 650 656 668 712 724] def /FamilyOtherBlues [-217 -205] def /BlueScale 0.0625 def /BlueFuzz 0 def /StdHW [115] def /StdVW [148] def /StemSnapH [115] def /StemSnapV [148] def currentdict end def currentdict end put def (Hex) 145652 StartData 0000183800001899000018a2000018f700001951000019ac 000019f700001a3600001a6e00001ade00001b2000001b5b
michaelrsweet commented 7 years ago

You'll need to report this to the cups-filters developers, as this is not a CUPS issue.

Also, if you go to "www.cups.org" you will see an item in the navbar titled "Lists" - click/tap on it and then pick "User" for the CUPS mailing list.

jsmeix commented 7 years ago

@paneologist only FYI regarding some basic steps how to diagnose the cause of a printing issue you may have a look at https://en.opensuse.org/SDB:How_to_Report_a_Printing_Issue

And @michaelrsweet is right that this issue is not in CUPS but elsewhere - likely in cups-filters - but it could be also a shortcoming or even a bug of the PostScript interpreter in your particular PostScript printer that is triggered now.

According to "technical data" that I found on the Lexmark web sites the Lexmark E360d printer supports those printer languages: PCL 5e, PCL 6, PostScript 3. If you like or need to hack a workaround to make it somehow work on that printer in your particular case you may have a look at https://en.opensuse.org/SDB:Using_Your_Own_Filters_to_Print_with_CUPS

jsmeix commented 7 years ago

@paneologist FYI what the right upstream project is whereto report an issue about that their PostScript cannot be processed by a Lexmark E360d PostScript printer see the "beginning of ps-data sent to printer":

%Produced by poppler pdftops version: 0.43.0 (http://poppler.freedesktop.org)

Intentionally I wrote "report an issue" and not "report a bug" because the actual bug could be in the PostScript interpreter in the Lexmark E360d PostScript printer - i.e. you should also contact Lexmark about that issue.

paneologist commented 7 years ago

thank you for these information. i clearly could identify pdftops from package "poppler-tools" as the root cause. as pdf is correct including embedded fonts, using pdftiops und sending directly to printer leads to that:

cat foomatic-fOwfSD.ps | nc printserver1.swabian.net 9902 @PJL USTATUS DEVICE CODE=10023 DISPLAY="Belegt" ONLINE=TRUE

%%[ Error: ioerror; Offending Command: StartData]%% @PJL USTATUS DEVICE CODE=10024 DISPLAY="Wartet" ONLINE=TRUE

@PJL USTATUS DEVICE CODE=10023 DISPLAY="Belegt" ONLINE=TRUE

@PJL USTATUS DEVICE CODE=10023 DISPLAY="Belegt" ONLINE=TRUE

@PJL USTATUS DEVICE CODE=10024 DISPLAY="Wartet" ONLINE=TRUE

@PJL USTATUS DEVICE CODE=10023 DISPLAY="Belegt" ONLINE=TRUE

i'll try my luck there, or will configure another filter to convert to ps... until cups 1.5 I actually never had problems. But first I try to update to new firmware. the used one is obiously outdated.

jsmeix commented 7 years ago

The issue is not directly about the CUPS version but indirectly because since CUPS >= 1.6 there is the new PDF filtering workflow, cf. "PDF: The recent printing data format" at https://en.opensuse.org/Concepts_printing see also the section about "CUPS Version Upgrade to 1.7" and the subsequent sections in https://www.suse.com/releasenotes/x86_64/SUSE-SLES/12/ and basically the same but less information in https://doc.opensuse.org/release-notes/x86_64/openSUSE/Leap/42.1/ and https://doc.opensuse.org/release-notes/x86_64/openSUSE/Leap/42.2/