May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Server address is "/run/cups/cups.sock".
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Accepted from localhost (Domain)
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Waiting for request.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] POST / HTTP/1.1
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Read: status=200, state=6
May 18 18:06:30 localhost cupsd[42076]: [Client 1] No authentication data provided.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] 2.0 Get-Printer-Attributes 1
May 18 18:06:30 localhost cupsd[42076]: Get-Printer-Attributes ipp://localhost:631/printers/DCP-J315W
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/DCP-J315W) from localhost.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Content-Length: 1540
May 18 18:06:30 localhost cupsd[42076]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] con->http=0x55610c2581e0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1540, response=0x55610c255a00(IPP_STATE_DATA), pipe_pid=0, file=-1
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] bytes=0, http_state=0, data_remaining=1540
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Flushing write buffer.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] New state is HTTP_STATE_WAITING
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Waiting for request.
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] POST / HTTP/1.1
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Read: status=200, state=6
May 18 18:06:30 localhost cupsd[42076]: [Client 1] No authentication data provided.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] 2.0 Get-Printer-Attributes 2
May 18 18:06:30 localhost cupsd[42076]: Get-Printer-Attributes ipp://localhost:631/printers/DCP-J315W
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost:631/printers/DCP-J315W) from localhost.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Content-Length: 1540
May 18 18:06:30 localhost cupsd[42076]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] con->http=0x55610c2581e0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1540, response=0x55610c234820(IPP_STATE_DATA), pipe_pid=0, file=-1
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] bytes=0, http_state=0, data_remaining=1540
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Flushing write buffer.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] New state is HTTP_STATE_WAITING
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Waiting for request.
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] POST /printers/DCP-J315W HTTP/1.1
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Read: status=200, state=6
May 18 18:06:30 localhost cupsd[42076]: [Client 1] No authentication data provided.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] 2.0 Get-Printer-Attributes 3
May 18 18:06:30 localhost cupsd[42076]: Get-Printer-Attributes ipp://localhost/printers/DCP-J315W
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/DCP-J315W) from localhost.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Content-Length: 13437
May 18 18:06:30 localhost cupsd[42076]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] con->http=0x55610c2581e0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=13437, response=0x55610c255a00(IPP_STATE_DATA), pipe_pid=0, file=-1
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] bytes=0, http_state=0, data_remaining=13437
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Flushing write buffer.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] New state is HTTP_STATE_WAITING
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Waiting for request.
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] POST /printers/DCP-J315W HTTP/1.1
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Read: status=200, state=6
May 18 18:06:30 localhost cupsd[42076]: [Client 1] No authentication data provided.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] 2.0 Create-Job 4
May 18 18:06:30 localhost cupsd[42076]: Create-Job ipp://localhost/printers/DCP-J315W
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Applying default options...
May 18 18:06:30 localhost cupsd[42076]: add_job: requesting-user-name="soltysh"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Adding start banner page "none".
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-created event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Queued on "DCP-J315W" by "soltysh".
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Returning IPP successful-ok for Create-Job (ipp://localhost/printers/DCP-J315W) from localhost.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Content-Length: 201
May 18 18:06:30 localhost cupsd[42076]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] con->http=0x55610c2581e0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=201, response=0x55610c238100(IPP_STATE_IDLE), pipe_pid=0, file=-1
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] bytes=0, http_state=0, data_remaining=201
May 18 18:06:30 localhost cupsd[42076]: REQUEST localhost - - "POST /printers/DCP-J315W HTTP/1.1" 200 370 Create-Job successful-ok
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Flushing write buffer.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] New state is HTTP_STATE_WAITING
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Waiting for request.
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] POST /printers/DCP-J315W HTTP/1.1
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Read: status=200, state=6
May 18 18:06:30 localhost cupsd[42076]: [Client 1] No authentication data provided.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] 2.0 Send-Document 5
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Read: status=100, state=6
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Read: status=100, state=6
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Read: status=100, state=6
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Read: status=100, state=6
May 18 18:06:30 localhost cupsd[42076]: Send-Document ipp://localhost:631/printers/DCP-J315W
May 18 18:06:30 localhost cupsd[42076]: cupsdIsAuthorized: requesting-user-name="soltysh"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Auto-typing file...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Request file type is application/postscript.
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] File of type application/postscript queued by "soltysh".
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Adding end banner page "none".
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] time-at-processing=1684425990
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] 3 filters for job:
May 18 18:06:30 localhost cupsd[42076]: [Job 177] - (application/postscript to application/vnd.universal-input, cost 0)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] universal (application/vnd.universal-input to application/vnd.cups-postscript, cost 0)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] brlpdwrapperdcpj315w (application/vnd.cups-postscript to printer/DCP-J315W, cost 0)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] job-sheets=none,none
May 18 18:06:30 localhost cupsd[42076]: [Job 177] argv[0]="DCP-J315W"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] argv[1]="177"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] argv[2]="soltysh"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] argv[3]="test.ps"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] argv[4]="1"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] argv[5]="finishings=3 number-up=1 print-color-mode=color psdebug job-uuid=urn:uuid:74c16e72-2cbb-3eba-678c-966e87eb2d40 job-originating-host-name=localhost date-time-at-creation= date-time-at-processing= time-at-creation=1684425990 time-at-processing=1684425990 document-name-supplied=test.ps"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] argv[6]="/var/spool/cups/d00177-001"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[1]="CUPS_DATADIR=/usr/share/cups"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[3]="CUPS_REQUESTROOT=/var/spool/cups"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[4]="CUPS_SERVERBIN=/usr/lib/cups"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[5]="CUPS_SERVERROOT=/etc/cups"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[6]="CUPS_STATEDIR=/run/cups"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[7]="HOME=/var/spool/cups/tmp"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[9]="SERVER_ADMIN=root@localhost"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[10]="SOFTWARE=CUPS/2.4.2"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[11]="TMPDIR=/var/spool/cups/tmp"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[12]="USER=root"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[13]="CUPS_MAX_MESSAGE=2047"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[14]="CUPS_SERVER=/run/cups/cups.sock"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[15]="CUPS_ENCRYPTION=IfRequested"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[16]="IPP_PORT=631"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[17]="CHARSET=utf-8"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[18]="LANG=en_US.UTF-8"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[19]="PPD=/etc/cups/ppd/DCP-J315W.ppd"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[20]="CONTENT_TYPE=application/postscript"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[21]="DEVICE_URI=socket://192.168.1.75"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[22]="PRINTER_INFO=DCPJ315W"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[23]="PRINTER_LOCATION="
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[24]="PRINTER=DCP-J315W"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[25]="PRINTER_STATE_REASONS=none"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[26]="CUPS_FILETYPE=document"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[27]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] envp[28]="AUTH_I****"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Started filter /usr/lib/cups/filter/universal (PID 42082)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Started filter /usr/lib/cups/filter/brlpdwrapperdcpj315w (PID 42083)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Started backend /usr/lib/cups/backend/socket (PID 42084)
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/DCP-J315W) from localhost.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Content-Length: 171
May 18 18:06:30 localhost cupsd[42076]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] con->http=0x55610c2581e0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=171, response=0x55610c250780(IPP_STATE_DATA), pipe_pid=0, file=-1
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
May 18 18:06:30 localhost cupsd[42076]: [Client 1] bytes=0, http_state=0, data_remaining=171
May 18 18:06:30 localhost cupsd[42076]: REQUEST localhost - - "POST /printers/DCP-J315W HTTP/1.1" 200 31810 Send-Document successful-ok
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Flushing write buffer.
May 18 18:06:30 localhost cupsd[42076]: [Client 1] New state is HTTP_STATE_WAITING
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Waiting for request.
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Client 1] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
May 18 18:06:30 localhost cupsd[42076]: [Client 1] Closing connection.
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: +connecting-to-device
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Looking up \"192.168.1.75\"...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -connecting-to-device
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] 192.168.1.75=192.168.1.75
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterLoadPPD: Color profile qualifier determined from job and PPD data \'..\'
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterLoadPPD: No ICC profiles specified in PPD
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterLoadPPD: Searching for profile \"-/-\"...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterLoadPPD: Last filter determined by the PPD: brlpdwrapperdcpj315w; Final data MIME type: application/vnd.cups-postscript => PDF filter will not log pages in page_log.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterUniversal: \"*cupsFilter(2): ...\" lines in the PPD file:
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterUniversal:    application/vnd.cups-raw application/octet-stream 0 -
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterUniversal:    application/vnd.cups-postscript 0 brlpdwrapperdcpj315w
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterUniversal: PPD uses \"*cupsFilter: ...\" lines, so we always convert to format given by FINAL_CONTENT_TYPE
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterUniversal: Converting from application/postscript to application/vnd.cups-postscript
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: Running filter: universal
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: Running filter: universal", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: Running filter: pdftops
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: Running filter: pdftops", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: universal (PID 42095) started.
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: universal (PID 42095) started.", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterUniversal: Converting from application/postscript to application/vnd.cups-pdf
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterUniversal: Final output format for job: application/vnd.cups-postscript
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterUniversal: Adding ghostscript to chain
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterUniversal: Adding pdftopdf to chain
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: Running filter: ghostscript
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: Running filter: ghostscript", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: Running filter: pdftopdf
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: Running filter: pdftopdf", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: pdftops (PID 42096) started.
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: pdftops (PID 42096) started.", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: ghostscript (PID 42097) started.
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: ghostscript (PID 42097) started.", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: Copying input to temp file \"/var/spool/cups/tmp/0a470647194de\"
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterGhostscript: Output format: PDF
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterGhostscript: Copying input to temp file \"/var/spool/cups/tmp/0a471647195a3\"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: pdftopdf (PID 42099) started.
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: pdftopdf (PID 42099) started.", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterPDFToPDF: Suppressed page logging selected by options.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ATTR: marker-colors=#000000,#FFFF00,#00FFFF,#FF00FF
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(P----)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ATTR: marker-names=\'\"Black Ink Cartridge\"\',\'\"Yellow Ink Cartridge\"\',\'\"Cyan Ink Cartridge\"\',\'\"Magenta Ink Cartridge\"\'
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(P----)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ATTR: marker-types=ink-cartridge,ink-cartridge,ink-cartridge,ink-cartridge
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(P----)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ATTR: marker-levels=-1,-1,-1,-1
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(P----)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] new_supply_state=0, change_state=ffff
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -developer-low-report
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -developer-empty-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -marker-supply-low-report
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -marker-supply-empty-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -opc-near-eol-report
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -opc-life-over-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -toner-low-report
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -toner-empty-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -waste-receptacle-almost-full-report
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -waste-receptacle-full-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -cleaner-life-almost-over-report
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -cleaner-life-over-warning
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] new_state=0, change_state=ffff
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -media-empty-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -door-open-report
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -media-jam-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -input-tray-missing-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -output-tray-missing-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -marker-supply-missing-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -output-area-almost-full-report
May 18 18:06:30 localhost cupsd[42076]: [Job 177] STATE: -output-area-full-warning
May 18 18:06:30 localhost cupsd[42076]: [Job 177] backendWaitLoop(snmp_fd=5, addr=0x56105c3d64c8, side_cb=0x56105a91ce00)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterGhostscript: Input format: PostScript
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Color space requested: Default
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Final output format: CUPS Raster
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Print rendering intent = auto
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Color Manager: Calibration Mode/Off
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Calling FindDeviceById(cups-DCP-J315W)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Found device /org/freedesktop/ColorManager/devices/cups_DCP_J315W
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Calling org.freedesktop.ColorManager.Device.Get(ProfilingInhibitors)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Calling FindDeviceById(cups-DCP-J315W)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Found device /org/freedesktop/ColorManager/devices/cups_DCP_J315W
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Calling GetProfileForQualifiers(.....)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Found profile /org/freedesktop/ColorManager/profiles/DCP_J315W_RGB__
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Calling org.freedesktop.ColorManager.Profile.Get(Filename)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Failed to get profile filename for cups-DCP-J315W
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Color Manager: ICC Profile:
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterGhostscript: Ghostscript using Any-Part-of-Pixel method to fill paths.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterGhostscript: Ghostscript command line: gs -dQUIET -dSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -dNOMEDIAATTRS -dUsePDFX3Profile -sstdout=%stderr -sOutputFile=%stdout -sDEVICE=pdfwrite -dDoNumCopies -dShowAcroForm -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false -dNOPLATFONTS -dColorImageFilter=/FlateEncode -dPDFSETTINGS=/default -dColorConversionStrategy=/LeaveColorUnchanged -r300x300 -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -dcupsManualCopies -I/usr/share/cups/fonts -sOutputICCProfile=srgb.icc -c -f -_
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterGhostscript: Started Ghostscript (PID 42102)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterGhostscript: Started logging (PID 42103)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterGhostscript: Input data feed completed
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterGhostscript: Ghostscript (PID 42102) exited with no errors.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterGhostscript: Logging (PID 42103) exited with no errors.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: ghostscript completed with status 0.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterPDFToPDF: Processing PDF input with QPDF: Page-ranges, page-set, number-up, booklet, size adjustment, ...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterPDFToPDF: \"print-scaling\" IPP attribute: auto
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterPDFToPDF: Print scaling mode: Do not scale, center, crop if needed
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterPDFToPDF: After Cropping: 595.000000 842.000000 595.275574 841.889771
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: pdftopdf completed with status 0.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: ghostscript (PID 42097) exited with no errors.
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: ghostscript (PID 42097) exited with no errors.", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: Printer make and model: Brother DCP-J315W CUPS
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: Switching to Poppler\'s pdftops instead of Ghostscript for Brother, Minolta, Konica Minolta, Dell, and Apple LaserWriter printers to work around bugs in the printer\'s PS interpreters
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: No resolution information found in the PPD file.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: Using image rendering resolution 300 dpi
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: Running command line for pdftops: pdftops -level2 -origpagesizes -nocenter -r 300 /var/spool/cups/tmp/0a470647194de -
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: Started filter pdftops (PID 42104)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: pdftopdf (PID 42099) exited with no errors.
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: pdftopdf (PID 42099) exited with no errors.", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: universal completed with status 0.
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: Started filter pstops (PID 42105)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Page = 595x842; 14,14 to 581,825
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: slow_collate=0, slow_duplex=0, slow_order=1
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: universal (PID 42095) exited with no errors.
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: universal (PID 42095) exited with no errors.", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: Before copy_comments - %!PS-Adobe-3.0
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: %!PS-Adobe-3.0
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: %Produced by poppler pdftops version: 23.02.0 (http://poppler.freedesktop.org)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: %%Creator: Writer
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: %%LanguageLevel: 2
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: %%DocumentSuppliedResources: (atend)
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: %%DocumentMedia: A4 595 842 0 () ()
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: %%BoundingBox: 0 0 596 842
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: %%Pages: 1
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: %%EndComments
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: Before copy_prolog - %%BeginProlog
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: Before copy_setup - %%BeginSetup
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: Before page loop - %%Page: 1 1
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: Copying page 1...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: pagew = 567.0, pagel = 811.0
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: PageLeft = 14.0, PageRight = 581.0
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: PageTop = 825.0, PageBottom = 14.0
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: PageWidth = 595.0, PageLength = 842.0
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPSToPS: Wrote 1 pages...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: PID 42104 (pdftops) exited with no errors.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: PID 42105 (pstops) exited with no errors.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] ppdFilterPDFToPS: Closing files ...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: pdftops completed with status 0.
May 18 18:06:30 localhost cupsd[42076]: [Job 177] cfFilterChain: pdftops (PID 42096) exited with no errors.
May 18 18:06:30 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:30 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:30 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "cfFilterChain: pdftops (PID 42096) exited with no errors.", current level=INFO
May 18 18:06:30 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:30 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:30 localhost cupsd[42076]: [Job 177] PID 42082 (/usr/lib/cups/filter/universal) exited with no errors.
May 18 18:06:31 localhost cupsd[42076]: [Job 177] No pages found!
May 18 18:06:31 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:31 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:31 localhost cupsd[42076]: [Job 177] Set job-printer-state-message to "No pages found!", current level=ERROR
May 18 18:06:31 localhost cupsd[42076]: Discarding unused job-progress event...
May 18 18:06:31 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:31 localhost cupsd[42076]: [Job 177] PID 42083 (/usr/lib/cups/filter/brlpdwrapperdcpj315w) exited with no errors.
May 18 18:06:31 localhost cupsd[42076]: [Job 177] PID 42084 (/usr/lib/cups/backend/socket) exited with no errors.
May 18 18:06:31 localhost cupsd[42076]: DCP-J315W soltysh 177 [18/May/2023:18:06:31 +0200] total 0 - localhost test.ps - -
May 18 18:06:31 localhost cupsd[42076]: [Job 177] time-at-completed=1684425991
May 18 18:06:31 localhost cupsd[42076]: Discarding unused job-completed event...
May 18 18:06:31 localhost cupsd[42076]: [Job 177] Job completed.
May 18 18:06:31 localhost cupsd[42076]: cupsdMarkDirty(---J-)
May 18 18:06:31 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:31 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
May 18 18:06:31 localhost cupsd[42076]: Discarding unused printer-state-changed event...
May 18 18:06:32 localhost cupsd[42076]: [Job 177] Unloading...
May 18 18:07:01 localhost cupsd[42076]: Saving printers.conf...
May 18 18:07:01 localhost cupsd[42076]: Saving job.cache...
May 18 18:07:01 localhost cupsd[42076]: cupsdSetBusyState: newbusy="Not busy", busy="Printing jobs and dirty files"