printing using cups only printing out test pages

foolish · 2024-12-05 21:51:26

A few days ago i decided to set up printing using cups, after setting it up i chose to print out a test page and it printed one out. However that is all it would print, i tried printing an image from the internet, and it printed out another test page, same thing happened when attempting to print from the command line. I used lpq and lpstat to see ongoing processes and made sure there were none. I tried to reconfigure the printer and use the official drivers for the type of printer i had. I tried to uninstall and reinstall cups. i also updated my system.
None of these worked and im very confused, i would appreciate any help

I am also using an Epson ET-3760 printer, and downloaded and used the epson-inkjet-printer-escpr2 package from the AUR for the driver.

seth · 2024-12-06 07:22:54

At some point we need a thread of the funniest bugs

https://wiki.archlinux.org/title/CUPS/T … #Debug_log
Raise the debug level, restart the cups service, try to print something and post the log.

On a limb:
https://aur.archlinux.org/packages/epso … ter-escpr2 depends on ghostscript and at least all the broadcom drivers silently rely on https://archlinux.org/packages/core/x86_64/which/ to find it - maybe the epson filters have the same "problem"…

foolish · 2024-12-06 18:13:27

Heres the log after attempting to print an image from my browser:

D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 1] POST / HTTP/1.1
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 1] Read: status=200, state=6
D [06/Dec/2024:11:59:26 -0600] [Client 1] No authentication data provided.
D [06/Dec/2024:11:59:26 -0600] [Client 1] 2.0 CUPS-Get-Default 17
D [06/Dec/2024:11:59:26 -0600] CUPS-Get-Default
D [06/Dec/2024:11:59:26 -0600] [Client 1] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
D [06/Dec/2024:11:59:26 -0600] [Client 1] Content-Length: 11280
D [06/Dec/2024:11:59:26 -0600] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Dec/2024:11:59:26 -0600] [Client 1] con->http=0x5602ed28eef0
D [06/Dec/2024:11:59:26 -0600] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=11280, response=0x5602ed2968b0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Dec/2024:11:59:26 -0600] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Dec/2024:11:59:26 -0600] [Client 1] bytes=0, http_state=0, data_remaining=11280
D [06/Dec/2024:11:59:26 -0600] [Client 1] Flushing write buffer.
D [06/Dec/2024:11:59:26 -0600] [Client 1] New state is HTTP_STATE_WAITING
D [06/Dec/2024:11:59:26 -0600] [Client 1] Waiting for request.
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 18] Server address is "/run/cups/cups.sock".
D [06/Dec/2024:11:59:26 -0600] [Client 18] Accepted from localhost (Domain)
D [06/Dec/2024:11:59:26 -0600] [Client 18] Waiting for request.
D [06/Dec/2024:11:59:26 -0600] [Client 18] POST / HTTP/1.1
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 18] Read: status=200, state=6
D [06/Dec/2024:11:59:26 -0600] [Client 18] No authentication data provided.
D [06/Dec/2024:11:59:26 -0600] [Client 18] 2.0 Get-Notifications 18
D [06/Dec/2024:11:59:26 -0600] Get-Notifications /
D [06/Dec/2024:11:59:26 -0600] cupsdIsAuthorized: requesting-user-name="kale"
D [06/Dec/2024:11:59:26 -0600] [Client 18] Returning IPP successful-ok for Get-Notifications (/) from localhost.
D [06/Dec/2024:11:59:26 -0600] [Client 18] Content-Length: 921
D [06/Dec/2024:11:59:26 -0600] [Client 18] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Dec/2024:11:59:26 -0600] [Client 18] con->http=0x5602ed2b1170
D [06/Dec/2024:11:59:26 -0600] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=921, response=0x5602ed296900(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Dec/2024:11:59:26 -0600] [Client 18] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Dec/2024:11:59:26 -0600] [Client 18] bytes=0, http_state=0, data_remaining=921
D [06/Dec/2024:11:59:26 -0600] [Client 18] Flushing write buffer.
D [06/Dec/2024:11:59:26 -0600] [Client 18] New state is HTTP_STATE_WAITING
D [06/Dec/2024:11:59:26 -0600] [Client 18] Waiting for request.
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 18] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [06/Dec/2024:11:59:26 -0600] [Client 18] Closing connection.
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 1] POST / HTTP/1.1
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 1] Read: status=200, state=6
D [06/Dec/2024:11:59:26 -0600] [Client 1] No authentication data provided.
D [06/Dec/2024:11:59:26 -0600] [Client 1] 2.0 CUPS-Get-Printers 19
D [06/Dec/2024:11:59:26 -0600] CUPS-Get-Printers
D [06/Dec/2024:11:59:26 -0600] [Client 1] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [06/Dec/2024:11:59:26 -0600] [Client 1] Content-Length: 489
D [06/Dec/2024:11:59:26 -0600] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Dec/2024:11:59:26 -0600] [Client 1] con->http=0x5602ed28eef0
D [06/Dec/2024:11:59:26 -0600] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=489, response=0x5602ed2a8ac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Dec/2024:11:59:26 -0600] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Dec/2024:11:59:26 -0600] [Client 1] bytes=0, http_state=0, data_remaining=489
D [06/Dec/2024:11:59:26 -0600] [Client 1] Flushing write buffer.
D [06/Dec/2024:11:59:26 -0600] [Client 1] New state is HTTP_STATE_WAITING
D [06/Dec/2024:11:59:26 -0600] [Client 1] Waiting for request.
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 1] POST / HTTP/1.1
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 1] Read: status=200, state=6
D [06/Dec/2024:11:59:26 -0600] [Client 1] No authentication data provided.
D [06/Dec/2024:11:59:26 -0600] [Client 1] 2.0 CUPS-Get-Classes 20
D [06/Dec/2024:11:59:26 -0600] CUPS-Get-Classes
D [06/Dec/2024:11:59:26 -0600] [Client 1] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost.
D [06/Dec/2024:11:59:26 -0600] [Client 1] Content-Length: 75
D [06/Dec/2024:11:59:26 -0600] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Dec/2024:11:59:26 -0600] [Client 1] con->http=0x5602ed28eef0
D [06/Dec/2024:11:59:26 -0600] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x5602ed296900(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Dec/2024:11:59:26 -0600] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Dec/2024:11:59:26 -0600] [Client 1] bytes=0, http_state=0, data_remaining=75
D [06/Dec/2024:11:59:26 -0600] [Client 1] Flushing write buffer.
D [06/Dec/2024:11:59:26 -0600] [Client 1] New state is HTTP_STATE_WAITING
D [06/Dec/2024:11:59:26 -0600] [Client 1] Waiting for request.
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 1] POST / HTTP/1.1
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [06/Dec/2024:11:59:26 -0600] [Client 1] Read: status=200, state=6
D [06/Dec/2024:11:59:26 -0600] [Client 1] No authentication data provided.
D [06/Dec/2024:11:59:26 -0600] [Client 1] 2.0 CUPS-Get-Default 21
D [06/Dec/2024:11:59:26 -0600] CUPS-Get-Default
D [06/Dec/2024:11:59:26 -0600] [Client 1] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
D [06/Dec/2024:11:59:26 -0600] [Client 1] Content-Length: 11280
D [06/Dec/2024:11:59:26 -0600] [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
D [06/Dec/2024:11:59:26 -0600] [Client 1] con->http=0x5602ed28eef0
D [06/Dec/2024:11:59:26 -0600] [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=11280, response=0x5602ed2a8ac0(IPP_STATE_DATA), pipe_pid=0, file=-1
D [06/Dec/2024:11:59:26 -0600] [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [06/Dec/2024:11:59:26 -0600] [Client 1] bytes=0, http_state=0, data_remaining=11280
D [06/Dec/2024:11:59:26 -0600] [Client 1] Flushing write buffer.
D [06/Dec/2024:11:59:26 -0600] [Client 1] New state is HTTP_STATE_WAITING
D [06/Dec/2024:11:59:26 -0600] [Client 1] Waiting for request.
D [06/Dec/2024:11:59:26 -0600] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [06/Dec/2024:11:59:27 -0600] Expiring subscriptions...
I [06/Dec/2024:11:59:30 -0600] Saving printers.conf...
I [06/Dec/2024:11:59:30 -0600] Saving job.cache...
I [06/Dec/2024:11:59:30 -0600] Saving subscriptions.conf...
D [06/Dec/2024:11:59:30 -0600] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [06/Dec/2024:11:59:30 -0600] Expiring subscriptions...

Last edited by foolish (2024-12-06 20:06:01)

seth · 2024-12-06 18:50:47

Please use [code][/code] tags. Edit your post in this regard.

Edit: there's some interaction w/ the webservice (localhost:631) but no apparent print job.
The tail in the wiki is just an example to look at, well, the tail of the log.

If the log file is too large (very possible) post it to 0x0.st

cat /var/log/cups/error_log | curl -F 'file=@-' 0x0.st

Last edited by seth (2024-12-06 18:53:30)

foolish · 2024-12-06 20:30:42

Heres the full log https://0x0.st/X75i.txt

seth · 2024-12-07 08:17:42

E [01/Dec/2024:18:01:45 -0600] [Job 1] cfFilterGhostscript: cfFilterGhostscript: Unable to launch Ghostscript: gs: No such file or directory

All jobs but 27 & 28 failed for missing ghostscript.

Job 28 seems to succeed - did you forget to clear the filter queue of all your failed jobs?
Also check /var/spool/cups

foolish · 2024-12-07 18:06:27

how do i clear the filter queue of failed jobs?

lprm -

? Thats the only thing i can think of

seth · 2024-12-07 21:28:02

You could also look at the open jobs in the webinterface and the forementioned /var/spool/cups (which is root:cups, so you'll probably have to sudo that)
Notably /var/spool/cups/tmp/ should be empty.

you btw. cancelled job 27, so the only thing that ever could have worked was job 28 on 06/Dec/2024:12:05:10 -0600

foolish · 2024-12-07 22:42:14

Hello, i got the printer working, and it was very easy actually. All i had to do was choose the "find network printer" option, put in the printers IP, click "find". Then i chose the one named Epson-ET-3760, and it worked! i was manually putting in the printers ip and other settings in the past, which was probably why it wasnt working. Thanks for the help

Arch Linux

#1 2024-12-05 21:51:26

printing using cups only printing out test pages

#2 2024-12-06 07:22:54

Re: printing using cups only printing out test pages

#3 2024-12-06 18:13:27

Re: printing using cups only printing out test pages

#4 2024-12-06 18:50:47

Re: printing using cups only printing out test pages

#5 2024-12-06 20:30:42

Re: printing using cups only printing out test pages

#6 2024-12-07 08:17:42

Re: printing using cups only printing out test pages

#7 2024-12-07 18:06:27

Re: printing using cups only printing out test pages

#8 2024-12-07 21:28:02

Re: printing using cups only printing out test pages

#9 2024-12-07 22:42:14

Re: printing using cups only printing out test pages

Board footer