Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

jamc · 2017-08-14 17:35:58

Hello
I'm trying a fresh installation of ArchLinux and I can't get printer working with this driver. All jobs finished with the message "Filter failed" in Status in localhost:631. However, if I install driver epson-inkjet-printer-escpr then printer works ok but I don't have access many of the options of my printer (two-side printing, e.g.)
In the previous install, a few months ago, all gone well with same printer and same configuration. I've tried with cinnamon and mate as desktop environments, same results.
Packages installed for printing are:
cups
system-config-printer
gtk3-print-backends
epson-inkjet-printer-201106w

Printer is connected to the lan with a fixed IP and cups and system-config-printer finds it with no problems.

Lone_Wolf · 2017-08-15 15:29:00

epson-inkjet-printer-escpr is a generic driver with limited functionality.

You did do a fresh build of the epson-inkjet-printer-201106w package ?

Check https://wiki.archlinux.org/index.php/CU … leshooting , increase loglevel .
set printer to the 201106w driver, and post log please

Last edited by Lone_Wolf (2017-08-15 15:29:16)

jamc · 2017-08-15 17:46:17

I installed epson-inkjet-printer-201106w from aur and compiles and install with no problems. Loglevel is in debug level. I try to print a test page with the web interface of cups and the job finished with the message "Filter failed". File /var/log/cups/error.log is (very long file, only lines concerning Job 14) is:

I [15/Aug/2017:19:17:56 +0200] [Job 14] Adding start banner page "none".
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [15/Aug/2017:19:17:56 +0200] [Job 14] Adding end banner page "none".
I [15/Aug/2017:19:17:56 +0200] [Job 14] File of type application/vnd.cups-pdf-banner queued by "root".
D [15/Aug/2017:19:17:56 +0200] [Job 14] hold_until=0
I [15/Aug/2017:19:17:56 +0200] [Job 14] Queued on "Epson_Stylus_Office_BX535WD" by "root".
D [15/Aug/2017:19:17:56 +0200] [Job 14] time-at-processing=1502817476
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Job 14] 4 filters for job:
D [15/Aug/2017:19:17:56 +0200] [Job 14] bannertopdf (application/vnd.cups-pdf-banner to application/pdf, cost 32)
D [15/Aug/2017:19:17:56 +0200] [Job 14] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)
D [15/Aug/2017:19:17:56 +0200] [Job 14] gstoraster (application/vnd.cups-pdf to application/vnd.cups-raster, cost 99)
D [15/Aug/2017:19:17:56 +0200] [Job 14] /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter (application/vnd.cups-raster to printer/Epson_Stylus_Office_BX535WD, cost 0)
D [15/Aug/2017:19:17:56 +0200] [Job 14] job-sheets=none,none
D [15/Aug/2017:19:17:56 +0200] [Job 14] argv[0]="Epson_Stylus_Office_BX535WD"
D [15/Aug/2017:19:17:56 +0200] [Job 14] argv[1]="14"
D [15/Aug/2017:19:17:56 +0200] [Job 14] argv[2]="root"
D [15/Aug/2017:19:17:56 +0200] [Job 14] argv[3]="Test Page"
D [15/Aug/2017:19:17:56 +0200] [Job 14] argv[4]="1"
D [15/Aug/2017:19:17:56 +0200] [Job 14] argv[5]="job-uuid=urn:uuid:eb14dbe5-3e86-3fda-7df7-e0e0bd45f838 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1502817476 time-at-processing=1502817476"
D [15/Aug/2017:19:17:56 +0200] [Job 14] argv[6]="/var/spool/cups/d00014-001"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[7]="CUPS_STATEDIR=/run/cups"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[8]="HOME=/var/spool/cups/tmp"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[10]="SERVER_ADMIN=root@galileo"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[11]="SOFTWARE=CUPS/2.2.4"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[13]="USER=root"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[14]="CUPS_MAX_MESSAGE=2047"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[15]="CUPS_SERVER=/run/cups/cups.sock"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[17]="IPP_PORT=631"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[18]="CHARSET=utf-8"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[19]="LANG=es_ES.UTF-8"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[20]="PPD=/etc/cups/ppd/Epson_Stylus_Office_BX535WD.ppd"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[21]="RIP_MAX_CACHE=128m"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[22]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[23]="DEVICE_URI=lpd://192.168.0.10:515/PASSTHRU"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[24]="PRINTER_INFO=Epson Stylus Office BX535WD"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[25]="PRINTER_LOCATION="
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[26]="PRINTER=Epson_Stylus_Office_BX535WD"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[27]="PRINTER_STATE_REASONS=none"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[28]="CUPS_FILETYPE=document"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[29]="FINAL_CONTENT_TYPE=application/vnd.cups-raster"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[30]="AUTH_I****"
I [15/Aug/2017:19:17:56 +0200] [Job 14] Started filter /usr/lib/cups/filter/bannertopdf (PID 1315)
I [15/Aug/2017:19:17:56 +0200] [Job 14] Started filter /usr/lib/cups/filter/pdftopdf (PID 1316)
I [15/Aug/2017:19:17:56 +0200] [Job 14] Started filter /usr/lib/cups/filter/gstoraster (PID 1317)
I [15/Aug/2017:19:17:56 +0200] [Job 14] Started filter /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter (PID 1318)
I [15/Aug/2017:19:17:56 +0200] [Job 14] Started backend /usr/lib/cups/backend/lpd (PID 1319)
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Client 53] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/Epson_Stylus_Office_BX535WD) from localhost
D [15/Aug/2017:19:17:56 +0200] [Client 53] Content-Length: 192
D [15/Aug/2017:19:17:56 +0200] [Client 53] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [15/Aug/2017:19:17:56 +0200] [Client 53] con->http=0x6f1f1e47b0
D [15/Aug/2017:19:17:56 +0200] [Client 53] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=192, response=0x6f1f26e930(IPP_STATE_IDLE), pipe_pid=0, file=-1
D [15/Aug/2017:19:17:56 +0200] [Client 53] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [15/Aug/2017:19:17:56 +0200] [Client 53] bytes=0, http_state=0, data_remaining=192
D [15/Aug/2017:19:17:56 +0200] [Client 53] Flushing write buffer.
D [15/Aug/2017:19:17:56 +0200] [Client 53] New state is HTTP_STATE_WAITING
D [15/Aug/2017:19:17:56 +0200] [Client 53] Waiting for request.
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:56 +0200] [Notifier] JobCreated
D [15/Aug/2017:19:17:56 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:56 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:56 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:56 +0200] [Notifier] JobState
D [15/Aug/2017:19:17:56 +0200] [Client 54] Server address is "/run/cups/cups.sock".
D [15/Aug/2017:19:17:56 +0200] [Client 54] Accepted from localhost (Domain)
D [15/Aug/2017:19:17:56 +0200] [Client 54] Waiting for request.
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: REMOTE_USER=\"root\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.4\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: REMOTE_USER=\"root\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.4\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetArray: job_uri[0]=\"/jobs/14\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetArray: job_id[0]=\"14\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetArray: job_state[0]=\"3\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetArray: job_state_message[0]=\"\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetArray: job_state_reasons[0]=\"none\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: refresh_page=\"2;URL=/printers/Epson_Stylus_Office_BX535WD\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: TITLE=\"Imprimir página de prueba\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: SERVER_NAME=\"localhost\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: REMOTE_USER=\"root\"
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: CUPS_VERSION=\"CUPS v2.2.4\"
D [15/Aug/2017:19:17:56 +0200] [Client 38] CGI data ready to be sent.
D [15/Aug/2017:19:17:56 +0200] [Client 54] POST / HTTP/1.1
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Client 54] Read: status=200, state=6
D [15/Aug/2017:19:17:56 +0200] [Client 54] No authentication data provided.
D [15/Aug/2017:19:17:56 +0200] [Client 54] 2.0 Get-Job-Attributes 16
D [15/Aug/2017:19:17:56 +0200] Get-Job-Attributes ipp://localhost/jobs/14
D [15/Aug/2017:19:17:56 +0200] [Client 54] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/14) from localhost
D [15/Aug/2017:19:17:56 +0200] [Client 54] Content-Length: 75
D [15/Aug/2017:19:17:56 +0200] [Client 54] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [15/Aug/2017:19:17:56 +0200] [CGI] cgiSetVariable: PRINTER_NAME=\"Epson_Stylus_Office_BX535WD\"
D [15/Aug/2017:19:17:56 +0200] [Client 54] con->http=0x6f1f26b580
D [15/Aug/2017:19:17:56 +0200] [Client 54] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x6f1f1e8070(IPP_STATE_DATA), pipe_pid=0, file=-1
D [15/Aug/2017:19:17:56 +0200] [Client 54] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [15/Aug/2017:19:17:56 +0200] [Client 54] bytes=0, http_state=0, data_remaining=75
D [15/Aug/2017:19:17:56 +0200] [Client 54] Flushing write buffer.
D [15/Aug/2017:19:17:56 +0200] [Client 54] New state is HTTP_STATE_WAITING
D [15/Aug/2017:19:17:56 +0200] [Client 54] Waiting for request.
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Client 38] con->http=0x6f1f22c270
D [15/Aug/2017:19:17:56 +0200] [Client 38] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2147483647, response=(nil)(), pipe_pid=1314, file=18
D [15/Aug/2017:19:17:56 +0200] [Client 38] Waiting for CGI data.
D [15/Aug/2017:19:17:56 +0200] [Client 38] Script header: Content-Type: text/html;charset=utf-8
D [15/Aug/2017:19:17:56 +0200] [Client 38] Script header: 
D [15/Aug/2017:19:17:56 +0200] [Client 38] Sending status 200 for CGI.
D [15/Aug/2017:19:17:56 +0200] [Client 38] cupsdSendHeader: code=200, type="(null)", auth_type=0
D [15/Aug/2017:19:17:56 +0200] [Client 38] con->http=0x6f1f22c270
D [15/Aug/2017:19:17:56 +0200] [Client 38] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1314, file=18
D [15/Aug/2017:19:17:56 +0200] [Client 38] Waiting for CGI data.
D [15/Aug/2017:19:17:56 +0200] [Client 54] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [15/Aug/2017:19:17:56 +0200] [Client 54] Closing connection.
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Client 55] Server address is "/run/cups/cups.sock".
D [15/Aug/2017:19:17:56 +0200] [Client 55] Accepted from localhost (Domain)
D [15/Aug/2017:19:17:56 +0200] [Client 55] Waiting for request.
D [15/Aug/2017:19:17:56 +0200] [Client 55] POST / HTTP/1.1
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Client 55] Read: status=200, state=6
D [15/Aug/2017:19:17:56 +0200] [Client 55] No authentication data provided.
D [15/Aug/2017:19:17:56 +0200] [Client 55] 2.0 Get-Job-Attributes 17
D [15/Aug/2017:19:17:56 +0200] Get-Job-Attributes ipp://localhost/jobs/14
D [15/Aug/2017:19:17:56 +0200] [Client 55] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/14) from localhost
D [15/Aug/2017:19:17:56 +0200] [Client 55] Content-Length: 75
D [15/Aug/2017:19:17:56 +0200] [Client 55] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [15/Aug/2017:19:17:56 +0200] [Client 55] con->http=0x6f1f26b580
D [15/Aug/2017:19:17:56 +0200] [Client 55] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x6f1f1e8070(IPP_STATE_DATA), pipe_pid=0, file=-1
D [15/Aug/2017:19:17:56 +0200] [Client 55] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [15/Aug/2017:19:17:56 +0200] [Client 55] bytes=0, http_state=0, data_remaining=75
D [15/Aug/2017:19:17:56 +0200] [Client 55] Flushing write buffer.
D [15/Aug/2017:19:17:56 +0200] [Client 55] New state is HTTP_STATE_WAITING
D [15/Aug/2017:19:17:56 +0200] [Client 55] Waiting for request.
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Client 55] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [15/Aug/2017:19:17:56 +0200] [Client 55] Closing connection.
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Job 14] STATE: +connecting-to-device
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Job 14] Looking up \"192.168.0.10\"...
D [15/Aug/2017:19:17:56 +0200] [Job 14] STATE: -connecting-to-device
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Job 14] 192.168.0.10=192.168.0.10
D [15/Aug/2017:19:17:56 +0200] [Job 14] backendWaitLoop(snmp_fd=6, addr=0xd480402ae8, side_cb=0xd47fac7cf0)
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:56 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:56 +0200] [Job 14] OUTFORMAT=\"(null)\", so output format will be CUPS/PWG Raster
D [15/Aug/2017:19:17:56 +0200] [Job 14] pdftopdf: Last filter determined by the PPD: epson_inkjet_printer_filter; FINAL_CONTENT_TYPE: application/vnd.cups-raster => pdftopdf will not log pages in page_log.
D [15/Aug/2017:19:17:56 +0200] [Client 38] CGI data ready to be sent.
D [15/Aug/2017:19:17:56 +0200] [Client 38] con->http=0x6f1f22c270
D [15/Aug/2017:19:17:56 +0200] [Client 38] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1314, file=18
D [15/Aug/2017:19:17:56 +0200] [Client 38] Waiting for CGI data.
D [15/Aug/2017:19:17:56 +0200] PID 1314 (/usr/lib/cups/cgi-bin/printers.cgi) exited with no errors.
D [15/Aug/2017:19:17:56 +0200] [Client 38] con->http=0x6f1f22c270
D [15/Aug/2017:19:17:56 +0200] [Client 38] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1314, file=18
D [15/Aug/2017:19:17:56 +0200] [Client 38] Waiting for CGI data.
D [15/Aug/2017:19:17:56 +0200] [Client 53] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [15/Aug/2017:19:17:56 +0200] [Client 53] Closing connection.
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Client 38] CGI data ready to be sent.
D [15/Aug/2017:19:17:56 +0200] [Client 38] con->http=0x6f1f22c270
D [15/Aug/2017:19:17:56 +0200] [Client 38] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_CHUNKED, data_remaining=0, response=(nil)(), pipe_pid=1314, file=18
D [15/Aug/2017:19:17:56 +0200] [Client 38] Waiting for CGI data.
D [15/Aug/2017:19:17:56 +0200] [Client 38] Sending 0-length chunk.
D [15/Aug/2017:19:17:56 +0200] [Client 38] Flushing write buffer.
D [15/Aug/2017:19:17:56 +0200] [Client 38] New state is HTTP_STATE_WAITING
D [15/Aug/2017:19:17:56 +0200] [Client 38] Waiting for request.
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Job 14] PDF template file doesn\'t have form. It\'s okay.
D [15/Aug/2017:19:17:56 +0200] [Job 14] PID 1315 (/usr/lib/cups/filter/bannertopdf) exited with no errors.
D [15/Aug/2017:19:17:56 +0200] [Job 14] PID 1316 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [15/Aug/2017:19:17:56 +0200] [Job 14] Color Manager: Calibration Mode/Off
D [15/Aug/2017:19:17:56 +0200] [Job 14] Calling FindDeviceById(cups-Epson_Stylus_Office_BX535WD)
D [15/Aug/2017:19:17:56 +0200] [Job 14] Found device /org/freedesktop/ColorManager/devices/cups_Epson_Stylus_Office_BX535WD
D [15/Aug/2017:19:17:56 +0200] [Job 14] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
D [15/Aug/2017:19:17:56 +0200] [Job 14] Calling FindDeviceById(cups-Epson_Stylus_Office_BX535WD)
D [15/Aug/2017:19:17:56 +0200] [Job 14] Found device /org/freedesktop/ColorManager/devices/cups_Epson_Stylus_Office_BX535WD
D [15/Aug/2017:19:17:56 +0200] [Job 14] Calling GetProfileForQualifiers(RGB.PLAIN....)
D [15/Aug/2017:19:17:56 +0200] [Job 14] Failed to send: org.freedesktop.ColorManager.Device.NothingMatched:nothing matched expression \'RGB.PLAIN.,RGB.PLAIN.*,RGB.*.,RGB.*.*,*\'
D [15/Aug/2017:19:17:56 +0200] [Job 14] Failed to get profile filename for cups-Epson_Stylus_Office_BX535WD
D [15/Aug/2017:19:17:56 +0200] [Job 14] Color Manager: no profiles specified in PPD
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Job 14] Set job-printer-state-message to "Color Manager: no profiles specified in PPD", current level=INFO
D [15/Aug/2017:19:17:56 +0200] [Job 14] Color Manager: ICC Profile: None
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Job 14] Ghostscript using Any-Part-of-Pixel method to fill paths.
D [15/Aug/2017:19:17:56 +0200] [Job 14] Ghostscript command line: gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=cups -r360x360 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=841 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=1 -dcupsCompression=1 -scupsPageSizeName=A4 -I/usr/share/cups/fonts -c \'<</.HWMargins[8.400000 8.400000 8.399963 8.400024] /Margins[0 0]>>setpagedevice\' -f -_
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[0]=\"CUPS_CACHEDIR=/var/cache/cups\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[1]=\"CUPS_DATADIR=/usr/share/cups\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[2]=\"CUPS_DOCROOT=/usr/share/cups/doc\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[3]=\"CUPS_FONTPATH=/usr/share/cups/fonts\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[4]=\"CUPS_REQUESTROOT=/var/spool/cups\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[5]=\"CUPS_SERVERBIN=/usr/lib/cups\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[6]=\"CUPS_SERVERROOT=/etc/cups\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[7]=\"CUPS_STATEDIR=/run/cups\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[8]=\"HOME=/var/spool/cups/tmp\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[9]=\"PATH=/usr/lib/cups/filter:/usr/bin:/usr/bin:/bin:/usr/bin\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[10]=\"SERVER_ADMIN=root@galileo\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[11]=\"SOFTWARE=CUPS/2.2.4\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[12]=\"TMPDIR=/var/spool/cups/tmp\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[13]=\"USER=root\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[14]=\"CUPS_MAX_MESSAGE=2047\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[15]=\"CUPS_SERVER=/run/cups/cups.sock\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[16]=\"CUPS_ENCRYPTION=IfRequested\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[17]=\"IPP_PORT=631\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[18]=\"CHARSET=utf-8\"
D [15/Aug/2017:19:17:56 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:56 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[19]=\"LANG=es_ES.UTF-8\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[20]=\"PPD=/etc/cups/ppd/Epson_Stylus_Office_BX535WD.ppd\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[21]=\"RIP_MAX_CACHE=128m\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[22]=\"CONTENT_TYPE=application/vnd.cups-pdf-banner\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[23]=\"DEVICE_URI=lpd://192.168.0.10:515/PASSTHRU\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[24]=\"PRINTER_INFO=Epson Stylus Office BX535WD\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[25]=\"PRINTER_LOCATION=\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[26]=\"PRINTER=Epson_Stylus_Office_BX535WD\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[27]=\"PRINTER_STATE_REASONS=none\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[28]=\"CUPS_FILETYPE=document\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[29]=\"FINAL_CONTENT_TYPE=application/vnd.cups-raster\"
D [15/Aug/2017:19:17:56 +0200] [Job 14] envp[30]=\"AUTH_INFO_REQUIRED=none\"
D [15/Aug/2017:19:17:56 +0200] [Client 56] Server address is "/run/cups/cups.sock".
D [15/Aug/2017:19:17:56 +0200] [Client 56] Accepted from localhost (Domain)
D [15/Aug/2017:19:17:56 +0200] [Client 56] Waiting for request.
D [15/Aug/2017:19:17:56 +0200] [Client 56] POST / HTTP/1.1
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Client 56] Read: status=200, state=6
D [15/Aug/2017:19:17:56 +0200] [Client 56] No authentication data provided.
D [15/Aug/2017:19:17:56 +0200] [Client 56] 2.0 Get-Jobs 5
D [15/Aug/2017:19:17:56 +0200] Get-Jobs ipp://localhost/printers/
D [15/Aug/2017:19:17:56 +0200] [Client 56] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [15/Aug/2017:19:17:56 +0200] [Client 56] Content-Length: 75
D [15/Aug/2017:19:17:56 +0200] [Client 56] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [15/Aug/2017:19:17:56 +0200] [Client 56] con->http=0x6f1f1e47b0
D [15/Aug/2017:19:17:56 +0200] [Client 56] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x6f1f247410(IPP_STATE_DATA), pipe_pid=0, file=-1
D [15/Aug/2017:19:17:56 +0200] [Client 56] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [15/Aug/2017:19:17:56 +0200] [Client 56] bytes=0, http_state=0, data_remaining=75
D [15/Aug/2017:19:17:56 +0200] [Client 56] Flushing write buffer.
D [15/Aug/2017:19:17:56 +0200] [Client 56] New state is HTTP_STATE_WAITING
D [15/Aug/2017:19:17:56 +0200] [Client 56] Waiting for request.
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Client 56] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [15/Aug/2017:19:17:56 +0200] [Client 56] Closing connection.
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Job 14] Start rendering...
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Job 14] Set job-printer-state-message to "Start rendering...", current level=INFO
D [15/Aug/2017:19:17:56 +0200] [Job 14] Processing page 1...
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Job 14] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:56 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:56 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:56 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:56 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:57 +0200] [Client 57] Server address is "/run/cups/cups.sock".
D [15/Aug/2017:19:17:57 +0200] [Client 57] Accepted from localhost (Domain)
D [15/Aug/2017:19:17:57 +0200] [Client 57] Waiting for request.
I [15/Aug/2017:19:17:57 +0200] Expiring subscriptions...
D [15/Aug/2017:19:17:57 +0200] [Client 57] POST / HTTP/1.1
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Client 57] Read: status=200, state=6
D [15/Aug/2017:19:17:57 +0200] [Client 57] No authentication data provided.
D [15/Aug/2017:19:17:57 +0200] [Client 57] 2.0 Get-Jobs 6
D [15/Aug/2017:19:17:57 +0200] Get-Jobs ipp://localhost/printers/
D [15/Aug/2017:19:17:57 +0200] [Client 57] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [15/Aug/2017:19:17:57 +0200] [Client 57] Content-Length: 75
D [15/Aug/2017:19:17:57 +0200] [Client 57] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [15/Aug/2017:19:17:57 +0200] [Client 57] con->http=0x6f1f1e47b0
D [15/Aug/2017:19:17:57 +0200] [Client 57] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x6f1f1ea730(IPP_STATE_DATA), pipe_pid=0, file=-1
D [15/Aug/2017:19:17:57 +0200] [Client 57] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [15/Aug/2017:19:17:57 +0200] [Client 57] bytes=0, http_state=0, data_remaining=75
D [15/Aug/2017:19:17:57 +0200] [Client 57] Flushing write buffer.
D [15/Aug/2017:19:17:57 +0200] [Client 57] New state is HTTP_STATE_WAITING
D [15/Aug/2017:19:17:57 +0200] [Client 57] Waiting for request.
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Client 57] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [15/Aug/2017:19:17:57 +0200] [Client 57] Closing connection.
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Job 14] Copiando datos de impresión.
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Job 14] Set job-printer-state-message to "Copiando datos de impresión.", current level=INFO
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Job 14] backendRunLoop(print_fd=-1, device_fd=7, snmp_fd=6, addr=0xd480402ae8, use_bc=0, side_cb=0xd47fac7cf0)
D [15/Aug/2017:19:17:57 +0200] [Job 14] Read 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:57 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:57 +0200] [Job 14] Wrote 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Read 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Wrote 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Read 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Wrote 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Read 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Wrote 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Read 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Wrote 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Read 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Wrote 8192 bytes of print data...
D [15/Aug/2017:19:17:57 +0200] [Client 58] Server address is "/run/cups/cups.sock".
D [15/Aug/2017:19:17:57 +0200] [Client 58] Accepted from localhost (Domain)
D [15/Aug/2017:19:17:57 +0200] [Client 58] Waiting for request.
D [15/Aug/2017:19:17:57 +0200] [Client 58] POST / HTTP/1.1
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Client 58] Read: status=200, state=6
D [15/Aug/2017:19:17:57 +0200] [Client 58] No authentication data provided.
D [15/Aug/2017:19:17:57 +0200] [Client 58] 2.0 Get-Jobs 7
D [15/Aug/2017:19:17:57 +0200] Get-Jobs ipp://localhost/printers/
D [15/Aug/2017:19:17:57 +0200] [Client 58] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [15/Aug/2017:19:17:57 +0200] [Client 58] Content-Length: 75
D [15/Aug/2017:19:17:57 +0200] [Client 58] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [15/Aug/2017:19:17:57 +0200] [Client 58] con->http=0x6f1f1e47b0
D [15/Aug/2017:19:17:57 +0200] [Client 58] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x6f1f26a060(IPP_STATE_DATA), pipe_pid=0, file=-1
D [15/Aug/2017:19:17:57 +0200] [Client 58] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [15/Aug/2017:19:17:57 +0200] [Client 58] bytes=0, http_state=0, data_remaining=75
D [15/Aug/2017:19:17:57 +0200] [Client 58] Flushing write buffer.
D [15/Aug/2017:19:17:57 +0200] [Client 58] New state is HTTP_STATE_WAITING
D [15/Aug/2017:19:17:57 +0200] [Client 58] Waiting for request.
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Client 58] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [15/Aug/2017:19:17:57 +0200] [Client 58] Closing connection.
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Job 14] Connecting to 192.168.0.10:515 for printer PASSTHRU
D [15/Aug/2017:19:17:57 +0200] [Job 14] Conectando a la impresora.
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Job 14] Set job-printer-state-message to "Conectando a la impresora.", current level=INFO
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Job 14] PID 1318 (/opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter) crashed on signal 11.
D [15/Aug/2017:19:17:57 +0200] [Job 14] STATE: -connecting-to-device
D [15/Aug/2017:19:17:57 +0200] [Job 14] Conectado a la impresora.
D [15/Aug/2017:19:17:57 +0200] [Job 14] Connected to 192.168.0.10:515 (local port 1023)...
D [15/Aug/2017:19:17:57 +0200] [Job 14] lpd_command 02 PASSTHRU
D [15/Aug/2017:19:17:57 +0200] [Job 14] Sending command string (10 bytes)...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Reading command status...
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:57 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:57 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:57 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:57 +0200] [Job 14] lpd_command returning 0
D [15/Aug/2017:19:17:57 +0200] [Job 14] Control file is:
D [15/Aug/2017:19:17:57 +0200] [Job 14] Hgalileo.localdomain
D [15/Aug/2017:19:17:57 +0200] [Job 14] Proot
D [15/Aug/2017:19:17:57 +0200] [Job 14] JTest Page
D [15/Aug/2017:19:17:57 +0200] [Job 14] ldfA319galileo.localdo
D [15/Aug/2017:19:17:57 +0200] [Job 14] UdfA319galileo.localdo
D [15/Aug/2017:19:17:57 +0200] [Job 14] NTest Page
D [15/Aug/2017:19:17:57 +0200] [Job 14] lpd_command 02 95 cfA319galileo.localdo
D [15/Aug/2017:19:17:57 +0200] [Job 14] Sending command string (26 bytes)...
D [15/Aug/2017:19:17:57 +0200] [Job 14] Reading command status...
D [15/Aug/2017:19:17:57 +0200] [Job 14] lpd_command returning 0
D [15/Aug/2017:19:17:57 +0200] [Job 14] Sending control file (95 bytes)
D [15/Aug/2017:19:17:57 +0200] [Job 14] Archivo de control enviado correctamente.
D [15/Aug/2017:19:17:57 +0200] [Job 14] lpd_command 03 65536 dfA319galileo.localdo
D [15/Aug/2017:19:17:57 +0200] [Job 14] Sending command string (29 bytes)...
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Job 14] Reading command status...
D [15/Aug/2017:19:17:57 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:57 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:57 +0200] [Job 14] lpd_command returning 0
D [15/Aug/2017:19:17:57 +0200] [Job 14] Sending data file (65536 bytes)
D [15/Aug/2017:19:17:57 +0200] [Job 14] Guardando trabajo en cola, 0% completado.
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:57 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:57 +0200] [Job 14] Guardando trabajo en cola, 50% completado.
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:57 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:57 +0200] [Job 14] Archivo de datos enviado correctamente.
D [15/Aug/2017:19:17:57 +0200] [Job 14] STATE: +cups-waiting-for-job-completed
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(P----)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Job 14] PID 1319 (/usr/lib/cups/backend/lpd) exited with no errors.
D [15/Aug/2017:19:17:57 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:57 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:57 +0200] [Client 59] Server address is "/run/cups/cups.sock".
D [15/Aug/2017:19:17:57 +0200] [Client 59] Accepted from localhost (Domain)
D [15/Aug/2017:19:17:57 +0200] [Client 59] Waiting for request.
D [15/Aug/2017:19:17:57 +0200] [Client 59] POST / HTTP/1.1
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Client 59] Read: status=200, state=6
D [15/Aug/2017:19:17:57 +0200] [Client 59] No authentication data provided.
D [15/Aug/2017:19:17:57 +0200] [Client 59] 2.0 Get-Jobs 8
D [15/Aug/2017:19:17:57 +0200] Get-Jobs ipp://localhost/printers/
D [15/Aug/2017:19:17:57 +0200] [Client 59] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [15/Aug/2017:19:17:57 +0200] [Client 59] Content-Length: 75
D [15/Aug/2017:19:17:57 +0200] [Client 59] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [15/Aug/2017:19:17:57 +0200] [Client 59] con->http=0x6f1f25a000
D [15/Aug/2017:19:17:57 +0200] [Client 59] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x6f1f25d210(IPP_STATE_DATA), pipe_pid=0, file=-1
D [15/Aug/2017:19:17:57 +0200] [Client 59] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [15/Aug/2017:19:17:57 +0200] [Client 59] bytes=0, http_state=0, data_remaining=75
D [15/Aug/2017:19:17:57 +0200] [Client 59] Flushing write buffer.
D [15/Aug/2017:19:17:57 +0200] [Client 59] New state is HTTP_STATE_WAITING
D [15/Aug/2017:19:17:57 +0200] [Client 59] Waiting for request.
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [15/Aug/2017:19:17:57 +0200] [Client 59] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [15/Aug/2017:19:17:57 +0200] [Client 59] Closing connection.
D [15/Aug/2017:19:17:57 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:58 +0200] [Job 14] Processing page 2...
D [15/Aug/2017:19:17:58 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:58 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:58 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:58 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [15/Aug/2017:19:17:58 +0200] Expiring subscriptions...
D [15/Aug/2017:19:17:58 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:58 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:58 +0200] [Job 14] Rendering completed
D [15/Aug/2017:19:17:58 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:58 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:58 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:58 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:58 +0200] [Notifier] state=3
D [15/Aug/2017:19:17:58 +0200] [Notifier] PrinterStateChanged
D [15/Aug/2017:19:17:58 +0200] [Job 14] PID 1317 (/usr/lib/cups/filter/gstoraster) exited with no errors.
D [15/Aug/2017:19:17:58 +0200] cupsdMarkDirty(P----)
D [15/Aug/2017:19:17:58 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:58 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:58 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
E [15/Aug/2017:19:17:58 +0200] [Job 14] Job stopped due to filter errors; please consult the error_log file for details.
D [15/Aug/2017:19:17:58 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:58 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:58 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:58 +0200] cupsdMarkDirty(----S)
D [15/Aug/2017:19:17:58 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:58 +0200] cupsdMarkDirty(---J-)
D [15/Aug/2017:19:17:58 +0200] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [15/Aug/2017:19:17:58 +0200] [Job 14] The following messages were recorded from 19:17:57 to 19:17:57
D [15/Aug/2017:19:17:58 +0200] [Job 14] hrDeviceDesc=\"EPSON Epson Stylus Office BX535WD\"
D [15/Aug/2017:19:17:58 +0200] [Job 14] End of messages
D [15/Aug/2017:19:17:58 +0200] [Job 14] printer-state=3(idle)
D [15/Aug/2017:19:17:58 +0200] [Job 14] printer-state-message="Rendering completed"
D [15/Aug/2017:19:17:58 +0200] [Job 14] printer-state-reasons=none

loqs · 2017-08-15 18:44:10

D [15/Aug/2017:19:17:57 +0200] [Job 14] PID 1318 (/opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter) crashed on signal 11.

Signal 11 is segmentation fault. Can you run /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter from a shell does it crash and core dump?

jamc · 2017-08-15 18:53:06

juan@galileo ~ $ file /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
/opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter: ELF 64-bit LSB shared object, x86-64, version 1 (SYSV), dynamically linked, interpreter /lib64/ld-linux-x86-64.so.2, for GNU/Linux 2.6.32, BuildID[sha1]=9048b8960f52126a292cf86247873b663a020461, stripped

juan@galileo ~ $ /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Insufficient options.juan@galileo ~ $

loqs · 2017-08-15 19:10:00

To https://aur.archlinux.org/cgit/aur.git/ … 01106w#n27 can you add --enable-debug as noted on the previous line rebuild and retry and see if /tmp/epson-inkjet-printer-filter.txt is produced.
Edit:
Also check

$ coredumpctl

To see if a core has been recorded from epson_inkjet_printer_filter
If it fails again without producing /tmp/epson-inkjet-printer-filter.txt please try adding a line to the PKGBUILD below depends

depends=('cups' 'ghostscript')
options=('!buildflags')
#makedepends=('libtool' 'make' 'automake' 'autoconf')
source=(http://download.ebz.epson.net/dsc/op/stable/SRPMS/${pkgname}-${pkgver}-${_suffix})

Last edited by loqs (2017-08-15 19:37:59)

jamc · 2017-08-15 19:43:11

Ok. I'll rebuild and install with that option in configure line and I'll send you the results.

jamc · 2017-08-15 19:52:22

Well. I rebuild and install the package and I've tried printing a test page. The file created in /tmp is:

[printJob:483] TRACE IN
[load_core_library:164] TRACE IN
[get_ppd_attr:140] PPD Attribute epcgCoreLibrary -> libEpson_201106w.so.1.0.1
[load_core_library:226] Model name : Epson Stylus Office BX535WD Series
[load_core_library:242] TRACE OUT=0
[setup_option:249] TRACE IN
[get_ppd_attr:140] PPD Attribute epcgResourceData -> Epson_201106w.1.data
[setup_option:297] Job Options =job-uuid=urn:uuid:c2b09f35-0cd4-3b9f-6c7e-507334480d4e job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1502826366 time-at-processing=1502826366
[setup_option:310] Option=MediaType Choice=PLAIN
[setup_option:310] Option=PrintQuality Choice=TextImage
[setup_option:310] Option=PageSize Choice=A4
[setup_option:310] Option=Color Choice=Color
[setup_option:310] Option=Borderless Choice=Off
[setup_option:310] Option=FaceDown Choice=Off
[setup_option:310] Option=ReduceEnlarge Choice=Off
[setup_option:310] Option=OutputPaper Choice=A4
[setup_option:310] Option=ScaleRatio Choice=100
[setup_option:310] Option=Duplex Choice=None
[setup_option:310] Option=AdjustPrintDensity Choice=Text
[setup_option:310] Option=CorrectionColor Choice=EPSONVivid
[setup_option:310] Option=GammaValue Choice=2.2
[setup_option:310] Option=BrightnessValue Choice=0
[setup_option:310] Option=ContrastValue Choice=0
[setup_option:310] Option=SaturationValue Choice=0
[setup_option:310] Option=CyanValue Choice=0
[setup_option:310] Option=MagentaValue Choice=0
[setup_option:310] Option=YellowValue Choice=0
[setup_option:327] TRACE OUT=0
[printJob:500] Job name : 16
[print_page:349] TRACE IN
[setup_filter_option:216] TRACE IN
[get_filter_option:206] Option=PosterPrinting Choice=Off
[get_filter_option:206] Option=Rotate180 Choice=Off
[get_filter_option:206] Option=MirrorImage Choice=Off
[get_ppd_attr:140] PPD Attribute epcgWatermarkData -> /opt/epson-inkjet-printer-201106w/watermark/WEPCG00.EID
[get_ppd_attr:140] PPD Attribute epcgWatermarkData -> /opt/epson-inkjet-printer-201106w/watermark/WEPCG01.EID
[get_ppd_attr:140] PPD Attribute epcgWatermarkData -> /opt/epson-inkjet-printer-201106w/watermark/WEPCG02.EID
[get_ppd_attr:140] PPD Attribute epcgWatermarkData -> /opt/epson-inkjet-printer-201106w/watermark/WEPCG03.EID
[get_ppd_attr:140] PPD Attribute epcgWatermarkData -> /opt/epson-inkjet-printer-201106w/watermark/WEPCG04.EID
[get_ppd_attr:140] PPD Attribute epcgWatermarkData -> /opt/epson-inkjet-printer-201106w/watermark/WEPCG05.EID
[get_ppd_attr:140] PPD Attribute epcgWatermarkData -> /opt/epson-inkjet-printer-201106w/watermark/WEPCG06.EID
[get_ppd_attr:140] PPD Attribute epcgWatermarkData -> /opt/epson-inkjet-printer-201106w/watermark/WEPCG07.EID
[get_ppd_attr:140] PPD Attribute epcgWatermarkData -> /opt/epson-inkjet-printer-201106w/watermark/WEPCG08.EID
[get_ppd_attr:140] PPD Attribute epcgWatermarkData -> /opt/epson-inkjet-printer-201106w/watermark/WEPCG09.EID
[setup_filter_option:273] Option=Watermark Choice=None
[get_filter_option:206] Option=PositionWatermark Choice=Center
[get_filter_option:206] Option=DensityWatermark Choice=Level4
[get_filter_option:206] Option=ColurWatermark Choice=Red
[get_filter_option:206] Option=SizeWatermark Choice=70
[setup_filter_option:301] TRACE OUT=0
[pageManagerCreate:101] pageManager Created.
[subPageManagerCreate:38] subPageManager Created.
[subPageManagerCreate:42] Before: width = 2892, height = 4125, bytesPerLine = 8676
[subPageManagerCreate:88] After: width = 2892, height = 4125, bytesPerLine = 8676
[subPageCreate:36] subPage Created.
[raster_helper_create_pipeline:80] Pipeline Processing Mode : FETCHING
[raster_helper_create_pipeline:81] bytes_per_pixel : 3
[raster_helper_create_pipeline:82] src_print_area_x : 2892
[raster_helper_create_pipeline:83] src_print_area_y : 4125
[raster_helper_create_pipeline:84] prt_print_area_x : 2892
[raster_helper_create_pipeline:85] prt_print_area_y : 4125
[raster_helper_create_pipeline:86] scale : 0
[raster_helper_create_pipeline:87] mirror : 0
[raster_helper_create_pipeline:88] reverse : 0
[raster_helper_create_pipeline:89] watermark.use : 0

jamc · 2017-08-15 19:58:51

Yes. A core dump file (428K) has been recorded from epson-inkjet-printer-201106w in /var/lib/systemd/coredump. How can I attach that file in the post?

loqs · 2017-08-15 20:09:20

No need at the moment. Core_dump#Examining_a_core_dump
The output of `coredumpctl info` please for that coredump which should contain a backtrace which may help identify the issue.

jamc · 2017-08-15 20:15:09

This is the core dump generated:
https://drive.google.com/open?id=0B8EnW … nFySWZzNlE

jamc · 2017-08-15 20:19:34

juan@galileo ~ $ coredumpctl list
TIME                            PID   UID   GID SIG COREFILE  EXE
Mon 2017-08-14 13:38:01 CEST  11417     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Mon 2017-08-14 18:58:27 CEST   5301     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Mon 2017-08-14 18:59:15 CEST   5386     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Mon 2017-08-14 19:37:15 CEST  11740     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Mon 2017-08-14 23:09:29 CEST  15012     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Mon 2017-08-14 23:10:04 CEST  15035     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Mon 2017-08-14 23:11:40 CEST  15112     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Tue 2017-08-15 08:39:20 CEST    373     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Tue 2017-08-15 08:58:31 CEST   1370     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Tue 2017-08-15 09:00:36 CEST   1456     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Tue 2017-08-15 19:17:57 CEST   1318     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Tue 2017-08-15 19:54:52 CEST   1570     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
Tue 2017-08-15 21:46:06 CEST   9105     2     7  11 error     /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter

juan@galileo ~ $ coredumpctl info 9105
           PID: 9105 (epson_inkjet_pr)
           UID: 2 (daemon)
           GID: 7 (lp)
        Signal: 11 (SEGV)
     Timestamp: Tue 2017-08-15 21:46:06 CEST (33min ago)
  Command Line: Epson_Stylus_Office_BX535WD 16 root Test Page 1 job-uuid=urn:uuid:c2b09f35-0cd4-3b9f-6c7e-507334480d4e job-originating-host-name=localhost date-time-at
    Executable: /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
 Control Group: /system.slice/org.cups.cupsd.service
          Unit: org.cups.cupsd.service
         Slice: system.slice
       Boot ID: bbce2b16b29f463eaeead832b894fa31
    Machine ID: e616a90d90ac4b30a4972fb32e68f92b
      Hostname: galileo
       Storage: /var/lib/systemd/coredump/core.epson_inkjet_pr.2.bbce2b16b29f463eaeead832b894fa31.9105.1502826366000000.lz4 (inaccessible)
       Message: Process 9105 (epson_inkjet_pr) of user 2 dumped core.
                
                Stack trace of thread 9105:
                #0  0x0000008604b306e2 n/a (epson_inkjet_printer_filter)
                #1  0x0000008604b2f16b n/a (epson_inkjet_printer_filter)
                #2  0x0000008604b2dfc9 n/a (epson_inkjet_printer_filter)
                #3  0x0000008604b2eb20 n/a (epson_inkjet_printer_filter)
                #4  0x0000008604b2d710 n/a (epson_inkjet_printer_filter)
                #5  0x00007f9ca928a4ca __libc_start_main (libc.so.6)
                #6  0x0000008604b2d81a n/a (epson_inkjet_printer_filter)

loqs · 2017-08-15 21:07:53

Please add this line to the PKGBUILD rebuild rerun and post the output of the new backtrace

options=('debug' '!strip' '!buildflags')

the addition of '!buildflags' may cause the filter to stop segfaulting it may also make the issue worse.

jamc · 2017-08-15 21:25:39

Ok. I added that line, rebuild, rerun and same results. Printer stopped with filter failed message. The result of the command:

juan@galileo ~ $ coredumpctl info 16798
           PID: 16798 (epson_inkjet_pr)
           UID: 2 (daemon)
           GID: 7 (lp)
        Signal: 11 (SEGV)
     Timestamp: Tue 2017-08-15 23:21:28 CEST (53s ago)
  Command Line: Epson_Stylus_Office_BX535WD 17 root Test Page 1 job-uuid=urn:uuid:581be242-57ce-3dd2-422a-e43d71920576 job-originating-host-name=localhost date-time-at
    Executable: /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
 Control Group: /system.slice/org.cups.cupsd.service
          Unit: org.cups.cupsd.service
         Slice: system.slice
       Boot ID: bbce2b16b29f463eaeead832b894fa31
    Machine ID: e616a90d90ac4b30a4972fb32e68f92b
      Hostname: galileo
       Storage: /var/lib/systemd/coredump/core.epson_inkjet_pr.2.bbce2b16b29f463eaeead832b894fa31.16798.1502832088000000.lz4 (inaccessible)
       Message: Process 16798 (epson_inkjet_pr) of user 2 dumped core.
                
                Stack trace of thread 16798:
                #0  0x000000fc5993a315 eps_raster_init (epson_inkjet_printer_filter)
                #1  0x000000fc599396f2 pageManagerCreate (epson_inkjet_printer_filter)
                #2  0x000000fc59938e77 print_page (epson_inkjet_printer_filter)
                #3  0x000000fc5993954a printJob (epson_inkjet_printer_filter)
                #4  0x000000fc599387d4 main (epson_inkjet_printer_filter)
                #5  0x00007f4cf854e4ca __libc_start_main (libc.so.6)
                #6  0x000000fc599388ca _start (epson_inkjet_printer_filter)

jamc · 2017-08-15 21:30:29

And this is the core dump generated:
https://drive.google.com/open?id=0B8EnW … 0xQeWpmX0k

loqs · 2017-08-15 23:25:06

Can you please install gdb then run `coredumpctl gdb 16798` then bt and there should be a backtrace similar to the one from coredumptctl.
Hopefully it may provide a line number in raster.c.

jamc · 2017-08-16 00:16:35

juan@galileo ~ :( $ sudo coredumpctl gdb 16798
           PID: 16798 (epson_inkjet_pr)
           UID: 2 (daemon)
           GID: 7 (lp)
        Signal: 11 (SEGV)
     Timestamp: Tue 2017-08-15 23:21:28 CEST (2h 51min ago)
  Command Line: Epson_Stylus_Office_BX535WD 17 root Test Page 1 job-uuid=urn:uuid:581be242-57ce-3dd2-422a-e43d71920576 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1502832088 time-at-processing=1502832088
    Executable: /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
 Control Group: /system.slice/org.cups.cupsd.service
          Unit: org.cups.cupsd.service
         Slice: system.slice
       Boot ID: bbce2b16b29f463eaeead832b894fa31
    Machine ID: e616a90d90ac4b30a4972fb32e68f92b
      Hostname: galileo
       Storage: /var/lib/systemd/coredump/core.epson_inkjet_pr.2.bbce2b16b29f463eaeead832b894fa31.16798.1502832088000000.lz4
       Message: Process 16798 (epson_inkjet_pr) of user 2 dumped core.
                
                Stack trace of thread 16798:
                #0  0x000000fc5993a315 eps_raster_init (epson_inkjet_printer_filter)
                #1  0x000000fc599396f2 pageManagerCreate (epson_inkjet_printer_filter)
                #2  0x000000fc59938e77 print_page (epson_inkjet_printer_filter)
                #3  0x000000fc5993954a printJob (epson_inkjet_printer_filter)
                #4  0x000000fc599387d4 main (epson_inkjet_printer_filter)
                #5  0x00007f4cf854e4ca __libc_start_main (libc.so.6)
                #6  0x000000fc599388ca _start (epson_inkjet_printer_filter)

GNU gdb (GDB) 8.0
Copyright (C) 2017 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.  Type "show copying"
and "show warranty" for details.
This GDB was configured as "x86_64-pc-linux-gnu".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>.
Find the GDB manual and other documentation resources online at:
<http://www.gnu.org/software/gdb/documentation/>.
For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter...(no debugging symbols found)...done.

warning: core file may not match specified executable file.
[New LWP 16798]
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/usr/lib/libthread_db.so.1".
Core was generated by `Epson_Stylus_Office_BX535WD 17 root Test Page 1 job-uuid=urn:uuid:581be242-57ce'.
Program terminated with signal SIGSEGV, Segmentation fault.
#0  0x000000fc5993a315 in eps_raster_init ()
(gdb) bt
#0  0x000000fc5993a315 in eps_raster_init ()
#1  0x000000fc599396f2 in pageManagerCreate ()
#2  0x000000fc59938e77 in print_page ()
#3  0x000000fc5993954a in printJob ()
#4  0x000000fc599387d4 in main ()

jamc · 2017-08-16 07:47:30

I tried rebuild the driver with (I guess) proper options in PKGBUILD file and now I get more information from the core dump:

juan@galileo ~ $ sudo coredumpctl gdb 9586
           PID: 9586 (epson_inkjet_pr)
           UID: 2 (daemon)
           GID: 7 (lp)
        Signal: 11 (SEGV)
     Timestamp: Wed 2017-08-16 09:40:50 CEST (2min 56s ago)
  Command Line: Epson_Stylus_Office_BX535WD 19 root Test Page 1 job-uuid=urn:uuid:4f72daee-dbac-371b-58da-4642a84d039c job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1502869249 time-at-processing=1502869249
    Executable: /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter
 Control Group: /system.slice/org.cups.cupsd.service
          Unit: org.cups.cupsd.service
         Slice: system.slice
       Boot ID: 201c4beb772f4b939fe3f828a1254cc5
    Machine ID: e616a90d90ac4b30a4972fb32e68f92b
      Hostname: galileo
       Storage: /var/lib/systemd/coredump/core.epson_inkjet_pr.2.201c4beb772f4b939fe3f828a1254cc5.9586.1502869250000000.lz4
       Message: Process 9586 (epson_inkjet_pr) of user 2 dumped core.
                
                Stack trace of thread 9586:
                #0  0x00000020879239cc eps_raster_init (epson_inkjet_printer_filter)
                #1  0x0000002087921c78 pageManagerCreate (epson_inkjet_printer_filter)
                #2  0x0000002087920bb4 print_page (epson_inkjet_printer_filter)
                #3  0x00000020879211e4 printJob (epson_inkjet_printer_filter)
                #4  0x000000208791fd32 main (epson_inkjet_printer_filter)
                #5  0x00007f549994e4ca __libc_start_main (libc.so.6)
                #6  0x000000208791f91a _start (epson_inkjet_printer_filter)

GNU gdb (GDB) 8.0
Copyright (C) 2017 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.  Type "show copying"
and "show warranty" for details.
This GDB was configured as "x86_64-pc-linux-gnu".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>.
Find the GDB manual and other documentation resources online at:
<http://www.gnu.org/software/gdb/documentation/>.
For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from /opt/epson-inkjet-printer-201106w/cups/lib/filter/epson_inkjet_printer_filter...done.

warning: core file may not match specified executable file.
[New LWP 9586]
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/usr/lib/libthread_db.so.1".
Core was generated by `Epson_Stylus_Office_BX535WD 19 root Test Page 1 job-uuid=urn:uuid:4f72daee-dbac'.
Program terminated with signal SIGSEGV, Segmentation fault.
#0  0x00000020879239cc in eps_raster_init (handle=0x208910547c, data=0x7fff24ae3f10, 
    pipeline=0xffffffff891148a4) at raster.c:183
183	raster.c: No existe el fichero o el directorio.
(gdb) bt
#0  0x00000020879239cc in eps_raster_init (handle=0x208910547c, data=0x7fff24ae3f10, 
    pipeline=0xffffffff891148a4) at raster.c:183
#1  0x0000002087921c78 in pageManagerCreate (pageRegion=..., filterPrintOption=..., 
    rasterSource=0x208791ff06 <rasterSource>) at pagemanager.c:156
#2  0x0000002087920bb4 in print_page () at raster_to_epson.c:392
#3  0x00000020879211e4 in printJob () at raster_to_epson.c:509
#4  0x000000208791fd32 in main (argc=6, argv=0x7fff24ae47b8) at main.c:126

loqs · 2017-08-16 18:04:06

@Lone_Wolf perhaps my approach is wrong any thoughts?

Lone_Wolf · 2017-08-16 22:19:55

There's a comment on the aur page from 2017-03-26 that indicates the package was working fine then.
The major change in building after that was the buildflags.

This is from building now :

running CONFIG_SHELL=/bin/sh /bin/sh ./configure 
LDFLAGS=-Wl,-O1,--sort-common,--as-needed,-z,relro,-z,now -Wl,--no-as-needed 
--prefix=/opt/epson-inkjet-printer-201106w 
CFLAGS=-march=x86-64 -mtune=generic -O2 -pipe -fstack-protector-strong -fno-plt 
CPPFLAGS=-D_FORTIFY_SOURCE=2 --no-create --no-recursion

I think we should try to set the buildflags as they were when the package was build by the user from that comment, so makepkg.conf from pacman 5.0.2-1 .

jamc, please download https://archive.archlinux.org/packages/ … pkg.tar.xz
(since this is a fresh install you won't have that version in pacman cache).

extract the file /etc/makepkg.conf from it, and put it in the buildfolder as makepkg-5.0.2-1.conf

then run

makepkg --config makepkg-5.0.2-1.conf --log

post the log files it creates (better use a pastebin client, see wiki).
install the package and test.

Last edited by Lone_Wolf (2017-08-16 22:21:46)

loqs · 2017-08-16 22:46:13

There is also https://git.archlinux.org/svntogit/pack … 5b7605a65b

Lone_Wolf · 2017-08-17 12:35:33

That could indeed also have to do with it, if reverting makepkg flags doesn't help we should look into building against different gcc settings.

Condor · 2017-12-02 21:29:29

It seems the OP has either found a solution or decided to abandon further analysis. I hope I do not transgress by picking up where you guys left off.

My problem is exactly the same as described by the OP: Print jobs are accepted by CUPS, but fail with

Job stopped due to filter errors; please consult the error_log file for details.

The only difference is that I have a slightly different printer model: BX635FWD instead of BX535WD. The driver and filter are the same.

Following the advice given before, I have

modified the PKGBUILD file to add --enable-debug as mentioned in reply #6
added options=('debug' '!strip' '!buildflags') as asked in reply #13
obtained the makepkg.conf file from pacman 5.0.2-1 (reply #20 refers)

built the package with

makepkg --config makepkg-5.0.2-1.conf --log

and installed it.

This is the build log; alongside a package.log file was created, but it is empty.

Printing again fails as previously with “filter failed“. (I tried printing from Firefox.) More specifically, the printer lights up and shows Printing… but it stops just short of feeding paper. At that point, the status entry in CUPS’s queue changes to Stopped.

After the printing attempt, the file /tmp/epson-inkjet-printer-filter.txt exists, please follow the link for a pastebin.

Also, coredumpctl shows that a core dump is available, please see transfer.sh epson-filter.coredump.lz4.

An excerpt of coredumpctl gdb (see coredumpctl pastebin for full details):

[Thread debugging using libthread_db enabled]
Using host libthread_db library "/usr/lib/libthread_db.so.1".
Core was generated by `IPP-Epson-Stylus-Office-BX635FWD 58 peter Post a reply / Arch Linux Forums 1 Ro'.
Program terminated with signal SIGSEGV, Segmentation fault.
#0  0x000055faff90c9bc in eps_raster_init (handle=0x55fb019067ec, data=0x7ffd88dac3e0, pipeline=0x196dc84) at raster.c:183
183			if (p->pipeline->process_mode == EPS_RASTER_PROCESS_MODE_FETCHING) {
(gdb) bt
#0  0x000055faff90c9bc in eps_raster_init (handle=0x55fb019067ec, data=0x7ffd88dac3e0, pipeline=0x196dc84) at raster.c:183
#1  0x000055faff90ac68 in pageManagerCreate (pageRegion=..., filterPrintOption=..., rasterSource=0x55faff908ef6 <rasterSource>)
    at pagemanager.c:156
#2  0x000055faff909ba4 in print_page () at raster_to_epson.c:392
#3  0x000055faff90a1d4 in printJob () at raster_to_epson.c:509
#4  0x000055faff908d22 in main (argc=6, argv=0x7ffd88dacc88) at main.c:126

This is as far as my first experience in using gdb brings me.

Any chance you guys could point me from here? Fore example, how would I go about testing building the package with different gcc settings as suggested by Lone_Wolf?

Last edited by Condor (2017-12-02 21:30:25)

Lone_Wolf · 2017-12-03 19:35:09

Previously i only looked briefly at the PKGBUILD.
I've looked at it now and feel it's setup wrong.

  aclocal
  libtoolize
  chmod +x configure

That would be much cleaner (and have a bigger chance of working correctly) if autoreconf was used.
I doubt very much the install in /opt is needed, as there only appears to be one non-standard folder , now under /opt/pkgname/cups .
Why the package() function uses cp instead of make install is a mystery to me.

Also using a global variable FILTER_FILE ?

I guess the original epson printer PKGBUILD has been copy-pasted many times .
Working on a cleaner PKGBUILD now.

edit
I still dislike the PKGBUILD, but the build system used is so old (automake 1.11 ) that those weird things appear to be necessary.
I'm tempted to suggest to setup a RHEL4 VM for printing (that's how old the build system feels) , but there hopefully will be a better solution.

Last edited by Lone_Wolf (2017-12-03 20:10:35)

loqs · 2017-12-03 19:45:31

If it still fails after reworking the PKGBUILD possibly try with --no-pie -fno-stack-protector as well as the old makepkg options.

Arch Linux

#1 2017-08-14 17:35:58

Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#2 2017-08-15 15:29:00

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#3 2017-08-15 17:46:17

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#4 2017-08-15 18:44:10

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#5 2017-08-15 18:53:06

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#6 2017-08-15 19:10:00

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#7 2017-08-15 19:43:11

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#8 2017-08-15 19:52:22

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#9 2017-08-15 19:58:51

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#10 2017-08-15 20:09:20

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#11 2017-08-15 20:15:09

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#12 2017-08-15 20:19:34

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#13 2017-08-15 21:07:53

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#14 2017-08-15 21:25:39

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#15 2017-08-15 21:30:29

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#16 2017-08-15 23:25:06

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#17 2017-08-16 00:16:35

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#18 2017-08-16 07:47:30

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#19 2017-08-16 18:04:06

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#20 2017-08-16 22:19:55

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#21 2017-08-16 22:46:13

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#22 2017-08-17 12:35:33

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#23 2017-12-02 21:29:29

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#24 2017-12-03 19:35:09

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

#25 2017-12-03 19:45:31

Re: Printing problems EPSON BX535WD driver epson-inkjet-printer-201106w

Board footer