Perhaps you will be able to see something I don't. If not let me know
how could do whatever you need with the debug levels or log file in a
better way

>Maybe you've to enable Loglevel "info" or "debug" in /etc/cups/cupsd.conf

# date; cat /etc/cups/cupsd.conf | grep LogLevel
Mo 6. Apr 22:37:44 CEST 2020
LogLevel debug
#

# cupsctl --debug-logging
# cupsctl LogLevel=debug2

# grep -v "not idle any more" /var/log/cups/error_log | less

E [06/Apr/2020:00:31:51 +0200] [Job 4] Job stopped due to filter
errors; please consult the error_log file for details.
D [06/Apr/2020:00:31:51 +0200] [Job 4] The following messages were
recorded from 00:31:51 to 00:31:51
D [06/Apr/2020:00:31:51 +0200] [Job 4] Adding start banner page "none".
D [06/Apr/2020:00:31:51 +0200] [Job 4] Queued on "Phaser-6125N" by "debuserme".
D [06/Apr/2020:00:31:51 +0200] [Job 4] Auto-typing file...
D [06/Apr/2020:00:31:51 +0200] [Job 4] Request file type is
application/postscript.
D [06/Apr/2020:00:31:51 +0200] [Job 4] File of type
application/postscript queued by "debuserme".
D [06/Apr/2020:00:31:51 +0200] [Job 4] Adding end banner page "none".
D [06/Apr/2020:00:31:51 +0200] [Job 4] time-at-processing=1586125911
D [06/Apr/2020:00:31:51 +0200] [Job 4] 2 filters for job:
D [06/Apr/2020:00:31:51 +0200] [Job 4] pstops (application/postscript
to application/vnd.cups-postscript, cost 66)
D [06/Apr/2020:00:31:51 +0200] [Job 4] /usr/lib/cups/filter/FXM_PF
(application/vnd.cups-postscript to printer/Phaser-6125N, cost 0)
D [06/Apr/2020:00:31:51 +0200] [Job 4] job-sheets=none,none
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[0]="Phaser-6125N"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[1]="4"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[2]="debuserme"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[3]="2020-04-03_draft08.pdf"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[4]="1"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[5]="noCollate
Duplex=DuplexNoTumble finishings=3 fit-to-page media=A4 number-up=1
outputorder=normal page-bottom=12 page-left=11 page-right=12
page-top=11 portrait sides=one-sided
job-uuid=urn:uuid:5a8f06ec-1d43-3cfa-6138-560befd55620
job-originating-host-name=localhost date-time-at-creation=
date-time-at-processing= time-at-creation=1586125911
time-at-processing=1586125911 document-name-supplied=okularDB4703.ps
PageSize=A4"
D [06/Apr/2020:00:31:51 +0200] [Job 4] argv[6]="/var/spool/cups/d00004-001"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4]
envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [06/Apr/2020:00:31:51 +0200] [Job 4]
envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [06/Apr/2020:00:31:51 +0200] [Job 4]
envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[8]="HOME=/var/spool/cups/tmp"
D [06/Apr/2020:00:31:51 +0200] [Job 4]
envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[10]="SERVER_ADMIN=root@lbrtchx"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[11]="SOFTWARE=CUPS/2.2.1"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [06/Apr/2020:00:31:51 +0200] [Job 4] envp[13]="USER=root"



> Have a closer look at /var/log/cups/error_log

the last lines in the error_log are:

http://localhost:631/admin/log/error_log
...
D [06/Apr/2020:22:51:06 +0200] [Client 96] Server address is "[v1.::1]".
D [06/Apr/2020:22:51:06 +0200] [Client 96] Accepted from localhost:45526 (IPv6)
d [06/Apr/2020:22:51:06 +0200] cupsdAddSelect(fd=15,
read_cb=0x5559df967db0, write_cb=(nil), data=0x5559e175d430)
D [06/Apr/2020:22:51:06 +0200] [Client 96] Waiting for request.
I [06/Apr/2020:22:51:06 +0200] Expiring subscriptions...
d [06/Apr/2020:22:51:06 +0200] cupsdCheckJobs: 2 active jobs,
sleeping=0, ac-power=-1, reload=0, curtime=1586206266
d [06/Apr/2020:22:51:06 +0200] cupsdCheckJobs: Job 5 -
dest="Xerox_Phaser_6125N", printer=(nil), state=6, cancel_time=0,
hold_until=1586129075, kill_time=0, pending_cost=0, pending_timeout=0
d [06/Apr/2020:22:51:06 +0200] cupsdCheckJobs: Job 6 -
dest="Xerox_Phaser_6125N", printer=(nil), state=6, cancel_time=0,
hold_until=1586206257, kill_time=0, pending_cost=0, pending_timeout=0
d [06/Apr/2020:22:51:06 +0200] select_timeout: JobHistoryUpdate=0
d [06/Apr/2020:22:51:06 +0200] [Client 96] cupsdReadClient: error=0,
used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH,
data_remaining=0, request=(nil)(), file=-1
D [06/Apr/2020:22:51:06 +0200] [Client 96] GET /admin/log/error_log HTTP/1.1
D [06/Apr/2020:22:51:06 +0200] cupsdSetBusyState: newbusy="Active
clients", busy="Not busy"
D [06/Apr/2020:22:51:06 +0200] [Client 96] Read: status=200
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest:
uri="/admin/log/error_log", limit=2...
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest: Location /admin/log(10) Limit 7f
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest: Location /admin/conf(11) Limit 7f
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest: Location /admin(6) Limit 7f
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest: Location /(1) Limit 7f
d [06/Apr/2020:22:51:06 +0200] cupsdFindBest: best=/admin/log
d [06/Apr/2020:22:51:06 +0200] [Client 96]
con->uri="/admin/log/error_log", con->best=0x5559e16d8a80(/admin/log)
D [06/Apr/2020:22:51:06 +0200] [Client 96] Authorized as "debuserme"
using Basic.
d [06/Apr/2020:22:51:06 +0200] cupsdIsAuthorized:
con->uri="/admin/log/error_log", con->best=0x5559e16d8a80(/admin/log)
d [06/Apr/2020:22:51:06 +0200] cupsdIsAuthorized:
level=CUPSD_AUTH_USER, type=Basic, satisfy=CUPSD_AUTH_SATISFY_ALL,
num_names=1
d [06/Apr/2020:22:51:06 +0200] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [06/Apr/2020:22:51:06 +0200] cupsdIsAuthorized: username="debuserme"
d [06/Apr/2020:22:51:06 +0200] cupsdIsAuthorized: Checking user membership...
d [06/Apr/2020:22:51:06 +0200] cupsdCheckGroup(username="debuserme",
user=0x7fafb092ed80, groupname="lpadmin")
D [06/Apr/2020:22:51:06 +0200] [Client 96] Processing GET /admin/log/error_log

Reply via email to