[Date Prev][Date Next] [Thread Prev][Thread Next] [Date Index] [Thread Index]

Bug#1119299: cupsd has around 0.5-1% permanent CPU usage

Hi Till,

Thanks for the quick reply!


since the most recent update, I regularly see cups show up in htop when I sort
processes by CPU usage: around half the time, when the system is idle, I see
cups among the top 5 processes by CPU consumption. This is on a full GNOME
desktop system, with multiple chat applications, browsers, and IDEs all running
at the same time -- and yet cupsd manages to show up among those other,
typically much more resource-demanding processes. This is while cups should be
entirely idle, no local printer is even connected, and the two configured
network printers are not reachable either. There are no jobs in any queue.

I restarted cupsd to see if this persists, and it did: after around 4 minutes,
cups accumulated around 2s of CPU usage. That's not much, of course, but cups
does stand out in the process list sorted by total CPU time since it is
constantly moving upwards even though it shouldn't be doing anything.

I did not see this before a recent big 'apt upgrade'.

I could imagine that this is caused by some other application constantly trying
to talk to cups for no good reason, but I am not sure how I would go about
tracing that to its origin, so I am reporting the bug where I see the symptoms.



Could you set the log level of CUPS to debug mode:

$ cupsctl --debug-logging

More verbose:

$ cupsctl LogLevel=debug2

to see what could be hammering on CUPS.
When I run this, I see new stuff pop up in /var/log/cups/error_log all the time. (There's nothing in access_log.) It looks to my untrained eye like something is trying to get the list of printers, fails ("broken pipe"), and then tries again, endlessly?
I will paste 2 seconds of logs below. Now I have to figure out how to disable this logging again. ;)
Could you also try to turn off/disconnect Wi-Fi, Ethernet, D-Bus (one of these at a time) and see whether the load goes away? This way one can find out through which communication channel cupsd gets hammered.
There's no ethernet cable plugged in. I have turned off wifi (via the gnome UI), that changed nothing (as expected since there's no printer on this network and the cups on my laptop also shouldn't be accessible via the network). I am not sure how to turn off d-bus? I surely can't kill the dbus daemon, then my gnome session will just die. ;) 

The promised error log:


D [02/Nov/2025:16:56:16 +0100] [Client 1443] Server address is "/run/cups/cups.sock".
D [02/Nov/2025:16:56:16 +0100] [Client 1443] Accepted from localhost (Domain)
d [02/Nov/2025:16:56:16 +0100] cupsdAddSelect(fd=11, read_cb=0x5601d114d2f0, write_cb=(nil), data=0x5601e8d7c920)
D [02/Nov/2025:16:56:16 +0100] [Client 1443] Waiting for request.
d [02/Nov/2025:16:56:16 +0100] cupsdDeleteTemporaryPrinters: Removing unused temporary printers
d [02/Nov/2025:16:56:16 +0100] select_timeout: JobHistoryUpdate=0
d [02/Nov/2025:16:56:16 +0100] cupsdAcceptClient(lis=0x5601e8d51250(3)) Clients=1
D [02/Nov/2025:16:56:16 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [02/Nov/2025:16:56:16 +0100] [Client 1444] Server address is "/run/cups/cups.sock".
D [02/Nov/2025:16:56:16 +0100] [Client 1444] Accepted from localhost (Domain)
d [02/Nov/2025:16:56:16 +0100] cupsdAddSelect(fd=12, read_cb=0x5601d114d2f0, write_cb=(nil), data=0x5601e8d89770)
D [02/Nov/2025:16:56:16 +0100] [Client 1444] Waiting for request.
d [02/Nov/2025:16:56:16 +0100] [Client 1443] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [02/Nov/2025:16:56:16 +0100] [Client 1443] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Nov/2025:16:56:16 +0100] [Client 1443] Closing connection.
D [02/Nov/2025:16:56:16 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
d [02/Nov/2025:16:56:16 +0100] cupsdRemoveSelect(fd=11)
d [02/Nov/2025:16:56:16 +0100] cupsdRemoveSelect(fd=-1)
d [02/Nov/2025:16:56:16 +0100] cupsdDeleteTemporaryPrinters: Removing unused temporary printers
d [02/Nov/2025:16:56:16 +0100] select_timeout: JobHistoryUpdate=0
d [02/Nov/2025:16:56:16 +0100] [Client 1444] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [02/Nov/2025:16:56:16 +0100] [Client 1444] POST / HTTP/1.1
D [02/Nov/2025:16:56:16 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Nov/2025:16:56:16 +0100] [Client 1444] Read: status=200, state=6
d [02/Nov/2025:16:56:16 +0100] cupsdFindBest: uri="/", limit=10...
d [02/Nov/2025:16:56:16 +0100] cupsdFindBest: Location /admin/log(10) Limit 7f
d [02/Nov/2025:16:56:16 +0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
d [02/Nov/2025:16:56:16 +0100] cupsdFindBest: Location /admin(6) Limit 7f
d [02/Nov/2025:16:56:16 +0100] cupsdFindBest: Location /(1) Limit 7f
d [02/Nov/2025:16:56:16 +0100] cupsdFindBest: best=/
d [02/Nov/2025:16:56:16 +0100] [Client 1444] con->uri="/", con->best=0x5601e8c5ce60(/)
D [02/Nov/2025:16:56:16 +0100] [Client 1444] No authentication data provided.
d [02/Nov/2025:16:56:16 +0100] cupsdIsAuthorized: con->uri="/", con->best=0x5601e8c5ce60(/)
d [02/Nov/2025:16:56:16 +0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [02/Nov/2025:16:56:16 +0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [02/Nov/2025:16:56:16 +0100] [Client 1444] 2.0 CUPS-Get-Printers 1
d [02/Nov/2025:16:56:16 +0100] cupsdProcessIPPRequest(0x5601e8d89770[1444]): operation_id=4002(CUPS-Get-Printers)
d [02/Nov/2025:16:56:16 +0100] cupsdProcessIPPRequest: operation-attributes-tag
d [02/Nov/2025:16:56:16 +0100] cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
d [02/Nov/2025:16:56:16 +0100] cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
d [02/Nov/2025:16:56:16 +0100] cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'd\\\\rajung'
d [02/Nov/2025:16:56:16 +0100] cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,printer-uri-supported,member-uris,printer-location,printer-info,printer-state-message,printer-state-reasons,printer-state,queued-job-count,printer-is-accepting-jobs,job-sheets-supported,job-sheets-default,printer-type,auth-info-required,number-up-default,ipp-versions-supported,multiple-document-handling-supported,copies-supported,number-up-supported,device-uri,printer-is-temporary'
D [02/Nov/2025:16:56:16 +0100] CUPS-Get-Printers
d [02/Nov/2025:16:56:16 +0100] get_printers(0x5601e8d89770[1444], 0)
d [02/Nov/2025:16:56:16 +0100] cupsdFindPolicyOp(p=0x5601e8c5d4d0, op=4002(CUPS-Get-Printers))
d [02/Nov/2025:16:56:16 +0100] cupsdFindPolicyOp: Found wildcard match...
d [02/Nov/2025:16:56:16 +0100] cupsdIsAuthorized: con->uri="/", con->best=0x5601e8c619c0((null))
d [02/Nov/2025:16:56:16 +0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [02/Nov/2025:16:56:16 +0100] cupsdIsAuthorized: op=0(0x0000)
d [02/Nov/2025:16:56:16 +0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [02/Nov/2025:16:56:16 +0100] add_printer_state_reasons(0x5601e8d89770[1444], 0x5601e8c7f470[ETH])
d [02/Nov/2025:16:56:16 +0100] printer-uri-supported="ipp://localhost/printers/ETH"
d [02/Nov/2025:16:56:16 +0100] add_queued_job_count(0x5601e8d89770[1444], 0x5601e8c7f470[ETH])
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8c6e2d0, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8c6e980, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8d40d40, ra=0x5601e8d7a5f0, group=0, quickcopy=-2147483648)
d [02/Nov/2025:16:56:16 +0100] add_printer_state_reasons(0x5601e8d89770[1444], 0x5601e8c6d0f0[ETH-any])
d [02/Nov/2025:16:56:16 +0100] printer-uri-supported="ipp://localhost/printers/ETH-any"
d [02/Nov/2025:16:56:16 +0100] add_queued_job_count(0x5601e8d89770[1444], 0x5601e8c6d0f0[ETH-any])
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8d298a0, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8d29e30, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8d40d40, ra=0x5601e8d7a5f0, group=0, quickcopy=-2147483648)
d [02/Nov/2025:16:56:16 +0100] add_printer_state_reasons(0x5601e8d89770[1444], 0x5601e8d316d0[HP-LaserJet-Professional-P1102])
d [02/Nov/2025:16:56:16 +0100] printer-uri-supported="ipp://localhost/printers/HP-LaserJet-Professional-P1102"
d [02/Nov/2025:16:56:16 +0100] add_queued_job_count(0x5601e8d89770[1444], 0x5601e8d316d0[HP-LaserJet-Professional-P1102])
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8d320e0, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8d33d30, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8d40d40, ra=0x5601e8d7a5f0, group=0, quickcopy=-2147483648)
d [02/Nov/2025:16:56:16 +0100] add_printer_state_reasons(0x5601e8d89770[1444], 0x5601e8d3bbb0[HP-LaserJet-Professional-P1102-2])
d [02/Nov/2025:16:56:16 +0100] printer-uri-supported="ipp://localhost/printers/HP-LaserJet-Professional-P1102-2"
d [02/Nov/2025:16:56:16 +0100] add_queued_job_count(0x5601e8d89770[1444], 0x5601e8d3bbb0[HP-LaserJet-Professional-P1102-2])
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8d32dd0, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8d2e2b0, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:16 +0100] copy_attrs(to=0x5601e8c6a290, from=0x5601e8d40d40, ra=0x5601e8d7a5f0, group=0, quickcopy=-2147483648)
D [02/Nov/2025:16:56:16 +0100] [Client 1444] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [02/Nov/2025:16:56:16 +0100] [Client 1444] Content-Length: 3734
D [02/Nov/2025:16:56:16 +0100] [Client 1444] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
d [02/Nov/2025:16:56:16 +0100] cupsdAddSelect(fd=12, read_cb=0x5601d114d2f0, write_cb=0x5601d114c990, data=0x5601e8d89770)
d [02/Nov/2025:16:56:16 +0100] cupsdDeleteTemporaryPrinters: Removing unused temporary printers
d [02/Nov/2025:16:56:16 +0100] select_timeout: JobHistoryUpdate=0
D [02/Nov/2025:16:56:16 +0100] [Client 1444] con->http=0x5601e8d8a940
D [02/Nov/2025:16:56:16 +0100] [Client 1444] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3734, response=0x5601e8c6a290(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Nov/2025:16:56:16 +0100] [Client 1444] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Nov/2025:16:56:16 +0100] [Client 1444] bytes=0, http_state=0, data_remaining=3734
D [02/Nov/2025:16:56:16 +0100] [Client 1444] Flushing write buffer.
D [02/Nov/2025:16:56:16 +0100] [Client 1444] New state is HTTP_STATE_WAITING
d [02/Nov/2025:16:56:16 +0100] cupsdAddSelect(fd=12, read_cb=0x5601d114d2f0, write_cb=(nil), data=0x5601e8d89770)
D [02/Nov/2025:16:56:16 +0100] [Client 1444] Waiting for request.
D [02/Nov/2025:16:56:16 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
d [02/Nov/2025:16:56:16 +0100] cupsdDeleteTemporaryPrinters: Removing unused temporary printers
d [02/Nov/2025:16:56:16 +0100] select_timeout: JobHistoryUpdate=0
d [02/Nov/2025:16:56:16 +0100] [Client 1444] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [02/Nov/2025:16:56:16 +0100] [Client 1444] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Nov/2025:16:56:16 +0100] [Client 1444] Closing connection.
D [02/Nov/2025:16:56:16 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
d [02/Nov/2025:16:56:16 +0100] cupsdRemoveSelect(fd=12)
d [02/Nov/2025:16:56:16 +0100] cupsdRemoveSelect(fd=-1)
d [02/Nov/2025:16:56:16 +0100] cupsdDeleteTemporaryPrinters: Removing unused temporary printers
d [02/Nov/2025:16:56:16 +0100] select_timeout: JobHistoryUpdate=0
d [02/Nov/2025:16:56:17 +0100] cupsdAcceptClient(lis=0x5601e8d51250(3)) Clients=0
D [02/Nov/2025:16:56:17 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Nov/2025:16:56:17 +0100] [Client 1445] Server address is "/run/cups/cups.sock".
D [02/Nov/2025:16:56:17 +0100] [Client 1445] Accepted from localhost (Domain)
d [02/Nov/2025:16:56:17 +0100] cupsdAddSelect(fd=11, read_cb=0x5601d114d2f0, write_cb=(nil), data=0x5601e8d7c920)
D [02/Nov/2025:16:56:17 +0100] [Client 1445] Waiting for request.
d [02/Nov/2025:16:56:17 +0100] cupsdDeleteTemporaryPrinters: Removing unused temporary printers
d [02/Nov/2025:16:56:17 +0100] select_timeout: JobHistoryUpdate=0
d [02/Nov/2025:16:56:17 +0100] cupsdAcceptClient(lis=0x5601e8d51250(3)) Clients=1
D [02/Nov/2025:16:56:17 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
D [02/Nov/2025:16:56:17 +0100] [Client 1446] Server address is "/run/cups/cups.sock".
D [02/Nov/2025:16:56:17 +0100] [Client 1446] Accepted from localhost (Domain)
d [02/Nov/2025:16:56:17 +0100] cupsdAddSelect(fd=12, read_cb=0x5601d114d2f0, write_cb=(nil), data=0x5601e8d89770)
D [02/Nov/2025:16:56:17 +0100] [Client 1446] Waiting for request.
d [02/Nov/2025:16:56:17 +0100] [Client 1445] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [02/Nov/2025:16:56:17 +0100] [Client 1445] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Nov/2025:16:56:17 +0100] [Client 1445] Closing connection.
D [02/Nov/2025:16:56:17 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
d [02/Nov/2025:16:56:17 +0100] cupsdRemoveSelect(fd=11)
d [02/Nov/2025:16:56:17 +0100] cupsdRemoveSelect(fd=-1)
d [02/Nov/2025:16:56:17 +0100] cupsdDeleteTemporaryPrinters: Removing unused temporary printers
d [02/Nov/2025:16:56:17 +0100] select_timeout: JobHistoryUpdate=0
d [02/Nov/2025:16:56:17 +0100] [Client 1446] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [02/Nov/2025:16:56:17 +0100] [Client 1446] POST / HTTP/1.1
D [02/Nov/2025:16:56:17 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [02/Nov/2025:16:56:17 +0100] [Client 1446] Read: status=200, state=6
d [02/Nov/2025:16:56:17 +0100] cupsdFindBest: uri="/", limit=10...
d [02/Nov/2025:16:56:17 +0100] cupsdFindBest: Location /admin/log(10) Limit 7f
d [02/Nov/2025:16:56:17 +0100] cupsdFindBest: Location /admin/conf(11) Limit 7f
d [02/Nov/2025:16:56:17 +0100] cupsdFindBest: Location /admin(6) Limit 7f
d [02/Nov/2025:16:56:17 +0100] cupsdFindBest: Location /(1) Limit 7f
d [02/Nov/2025:16:56:17 +0100] cupsdFindBest: best=/
d [02/Nov/2025:16:56:17 +0100] [Client 1446] con->uri="/", con->best=0x5601e8c5ce60(/)
D [02/Nov/2025:16:56:17 +0100] [Client 1446] No authentication data provided.
d [02/Nov/2025:16:56:17 +0100] cupsdIsAuthorized: con->uri="/", con->best=0x5601e8c5ce60(/)
d [02/Nov/2025:16:56:17 +0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [02/Nov/2025:16:56:17 +0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
D [02/Nov/2025:16:56:17 +0100] [Client 1446] 2.0 CUPS-Get-Printers 1
d [02/Nov/2025:16:56:17 +0100] cupsdProcessIPPRequest(0x5601e8d89770[1446]): operation_id=4002(CUPS-Get-Printers)
d [02/Nov/2025:16:56:17 +0100] cupsdProcessIPPRequest: operation-attributes-tag
d [02/Nov/2025:16:56:17 +0100] cupsdProcessIPPRequest: attributes-charset charset 'utf-8'
d [02/Nov/2025:16:56:17 +0100] cupsdProcessIPPRequest: attributes-natural-language naturalLanguage 'en-us'
d [02/Nov/2025:16:56:17 +0100] cupsdProcessIPPRequest: requesting-user-name nameWithoutLanguage 'd\\\\rajung'
d [02/Nov/2025:16:56:17 +0100] cupsdProcessIPPRequest: requested-attributes 1setOf keyword 'printer-name,printer-uri-supported,member-uris,printer-location,printer-info,printer-state-message,printer-state-reasons,printer-state,queued-job-count,printer-is-accepting-jobs,job-sheets-supported,job-sheets-default,printer-type,auth-info-required,number-up-default,ipp-versions-supported,multiple-document-handling-supported,copies-supported,number-up-supported,device-uri,printer-is-temporary'
D [02/Nov/2025:16:56:17 +0100] CUPS-Get-Printers
d [02/Nov/2025:16:56:17 +0100] get_printers(0x5601e8d89770[1446], 0)
d [02/Nov/2025:16:56:17 +0100] cupsdFindPolicyOp(p=0x5601e8c5d4d0, op=4002(CUPS-Get-Printers))
d [02/Nov/2025:16:56:17 +0100] cupsdFindPolicyOp: Found wildcard match...
d [02/Nov/2025:16:56:17 +0100] cupsdIsAuthorized: con->uri="/", con->best=0x5601e8c619c0((null))
d [02/Nov/2025:16:56:17 +0100] cupsdIsAuthorized: level=CUPSD_AUTH_ANON, type=None, satisfy=CUPSD_AUTH_SATISFY_ALL, num_names=0
d [02/Nov/2025:16:56:17 +0100] cupsdIsAuthorized: op=0(0x0000)
d [02/Nov/2025:16:56:17 +0100] cupsdIsAuthorized: auth=CUPSD_AUTH_ALLOW...
d [02/Nov/2025:16:56:17 +0100] add_printer_state_reasons(0x5601e8d89770[1446], 0x5601e8c7f470[ETH])
d [02/Nov/2025:16:56:17 +0100] printer-uri-supported="ipp://localhost/printers/ETH"
d [02/Nov/2025:16:56:17 +0100] add_queued_job_count(0x5601e8d89770[1446], 0x5601e8c7f470[ETH])
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8c6e2d0, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8c6e980, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8d40d40, ra=0x5601e8d7a5f0, group=0, quickcopy=-2147483648)
d [02/Nov/2025:16:56:17 +0100] add_printer_state_reasons(0x5601e8d89770[1446], 0x5601e8c6d0f0[ETH-any])
d [02/Nov/2025:16:56:17 +0100] printer-uri-supported="ipp://localhost/printers/ETH-any"
d [02/Nov/2025:16:56:17 +0100] add_queued_job_count(0x5601e8d89770[1446], 0x5601e8c6d0f0[ETH-any])
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8d298a0, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8d29e30, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8d40d40, ra=0x5601e8d7a5f0, group=0, quickcopy=-2147483648)
d [02/Nov/2025:16:56:17 +0100] add_printer_state_reasons(0x5601e8d89770[1446], 0x5601e8d316d0[HP-LaserJet-Professional-P1102])
d [02/Nov/2025:16:56:17 +0100] printer-uri-supported="ipp://localhost/printers/HP-LaserJet-Professional-P1102"
d [02/Nov/2025:16:56:17 +0100] add_queued_job_count(0x5601e8d89770[1446], 0x5601e8d316d0[HP-LaserJet-Professional-P1102])
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8d320e0, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8d33d30, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8d40d40, ra=0x5601e8d7a5f0, group=0, quickcopy=-2147483648)
d [02/Nov/2025:16:56:17 +0100] add_printer_state_reasons(0x5601e8d89770[1446], 0x5601e8d3bbb0[HP-LaserJet-Professional-P1102-2])
d [02/Nov/2025:16:56:17 +0100] printer-uri-supported="ipp://localhost/printers/HP-LaserJet-Professional-P1102-2"
d [02/Nov/2025:16:56:17 +0100] add_queued_job_count(0x5601e8d89770[1446], 0x5601e8d3bbb0[HP-LaserJet-Professional-P1102-2])
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8d32dd0, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8d2e2b0, ra=0x5601e8d7a5f0, group=0, quickcopy=0)
d [02/Nov/2025:16:56:17 +0100] copy_attrs(to=0x5601e8d81310, from=0x5601e8d40d40, ra=0x5601e8d7a5f0, group=0, quickcopy=-2147483648)
D [02/Nov/2025:16:56:17 +0100] [Client 1446] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
D [02/Nov/2025:16:56:17 +0100] [Client 1446] Content-Length: 3734
D [02/Nov/2025:16:56:17 +0100] [Client 1446] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
d [02/Nov/2025:16:56:17 +0100] cupsdAddSelect(fd=12, read_cb=0x5601d114d2f0, write_cb=0x5601d114c990, data=0x5601e8d89770)
d [02/Nov/2025:16:56:17 +0100] cupsdDeleteTemporaryPrinters: Removing unused temporary printers
d [02/Nov/2025:16:56:17 +0100] select_timeout: JobHistoryUpdate=0
D [02/Nov/2025:16:56:17 +0100] [Client 1446] con->http=0x5601e8d8a940
D [02/Nov/2025:16:56:17 +0100] [Client 1446] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3734, response=0x5601e8d81310(IPP_STATE_DATA), pipe_pid=0, file=-1
D [02/Nov/2025:16:56:17 +0100] [Client 1446] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
D [02/Nov/2025:16:56:17 +0100] [Client 1446] bytes=0, http_state=0, data_remaining=3734
D [02/Nov/2025:16:56:17 +0100] [Client 1446] Flushing write buffer.
D [02/Nov/2025:16:56:17 +0100] [Client 1446] New state is HTTP_STATE_WAITING
d [02/Nov/2025:16:56:17 +0100] cupsdAddSelect(fd=12, read_cb=0x5601d114d2f0, write_cb=(nil), data=0x5601e8d89770)
D [02/Nov/2025:16:56:17 +0100] [Client 1446] Waiting for request.
D [02/Nov/2025:16:56:17 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
d [02/Nov/2025:16:56:17 +0100] cupsdDeleteTemporaryPrinters: Removing unused temporary printers
d [02/Nov/2025:16:56:17 +0100] select_timeout: JobHistoryUpdate=0
d [02/Nov/2025:16:56:17 +0100] [Client 1446] cupsdReadClient: error=0, used=0, state=HTTP_STATE_WAITING, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=0, request=(nil)(), file=-1
D [02/Nov/2025:16:56:17 +0100] [Client 1446] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
D [02/Nov/2025:16:56:17 +0100] [Client 1446] Closing connection.
D [02/Nov/2025:16:56:17 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
d [02/Nov/2025:16:56:17 +0100] cupsdRemoveSelect(fd=12)
d [02/Nov/2025:16:56:17 +0100] cupsdRemoveSelect(fd=-1)
d [02/Nov/2025:16:56:17 +0100] cupsdDeleteTemporaryPrinters: Removing unused temporary printers
d [02/Nov/2025:16:56:17 +0100] select_timeout: JobHistoryUpdate=0


Kind regards,
Ralf
Reply to: