Attachment 10921 Details for Bug 24189 – Filtered output from journal with cups debug enabled, cups started on 3rd attempt

Filtered output from journal with cups debug enabled, cups started on 3rd attempt

cups-startup.log (text/plain), 34.84 KB, created by Martin Whitaker on 2019-04-13 13:05:04 CEST

(hide)

Description:

Filename:

MIME Type:

Creator: Martin Whitaker

Created: 2019-04-13 13:05:04 CEST

Size: 34.84 KB

patch

obsolete

>Apr 13 11:39:27 localhost systemd[1]: Created slice system-configure\x2dprinter.slice.
>Apr 13 11:39:27 localhost kernel: usblp 4-1.6:1.0: usblp0: USB Bidirectional printer dev 3 if 0 alt 0 proto 2 vid 0x0482 pid 0x000E
>Apr 13 11:39:29 localhost systemd[1]: Listening on CUPS Scheduler.
>Apr 13 11:39:29 localhost systemd[1]: Started CUPS Scheduler.
>Apr 13 11:39:31 localhost service_harddrake[968]: PRINTER took:  0:00:00
>Apr 13 11:39:31 localhost systemd[1]: Started Configure Plugged-In Printer.
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: task-printing-server-2018-5.mga7
>Apr 13 11:39:32 localhost systemd[1]: Starting CUPS Scheduler...
>Apr 13 11:39:32 localhost cupsd[1142]: Listening to 0.0.0.0:631 (IPv4)
>Apr 13 11:39:32 localhost cupsd[1142]: Listening to [v1.::]:631 (IPv6)
>Apr 13 11:39:32 localhost cupsd[1142]: Listening to /var/run/cups/cups.sock (Domain)
>Apr 13 11:39:32 localhost cupsd[1142]: Remote access is enabled.
>Apr 13 11:39:32 localhost cupsd[1142]: Added auto ServerAlias localhost
>Apr 13 11:39:32 localhost cupsd[1142]: Added auto ServerAlias localhost.local.net
>Apr 13 11:39:32 localhost cupsd[1142]: Loaded configuration file "/etc/cups/cupsd.conf"
>Apr 13 11:39:32 localhost cupsd[1142]: Using keychain "/etc/cups/ssl" for server name "localhost.local.net".
>Apr 13 11:39:32 localhost cupsd[1142]: Using default TempDir of /var/spool/cups/tmp...
>Apr 13 11:39:32 localhost cupsd[1142]: Configured for up to 100 clients.
>Apr 13 11:39:32 localhost cupsd[1142]: Allowing up to 100 client connections per host.
>Apr 13 11:39:32 localhost cupsd[1142]: Using policy "default" as the default.
>Apr 13 11:39:32 localhost cupsd[1142]: Full reload is required.
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: â cups.service - CUPS Scheduler
>Apr 13 11:39:32 localhost udev-configure-printer[1021]:    Loaded: loaded (/usr/lib/systemd/system/cups.service; enabled; vendor preset: disabled)
>Apr 13 11:39:32 localhost udev-configure-printer[1021]:    Active: activating (start) since Sat 2019-04-13 11:39:32 BST; 17ms ago
>Apr 13 11:39:32 localhost udev-configure-printer[1021]:      Docs: man:cupsd(8)
>Apr 13 11:39:32 localhost udev-configure-printer[1021]:  Main PID: 1142 (cupsd)
>Apr 13 11:39:32 localhost udev-configure-printer[1021]:    Memory: 1.5M
>Apr 13 11:39:32 localhost udev-configure-printer[1021]:    CGroup: /system.slice/cups.service
>Apr 13 11:39:32 localhost udev-configure-printer[1021]:            ââ1142 /usr/sbin/cupsd -l
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Remote access is enabled.
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Added auto ServerAlias localhost
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Added auto ServerAlias localhost.local.net
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Loaded configuration file "/etc/cups/cupsd.conf"
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Using keychain "/etc/cups/ssl" for server name "localhost.local.net".
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Using default TempDir of /var/spool/cups/tmp...
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Configured for up to 100 clients.
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Allowing up to 100 client connections per host.
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Using policy "default" as the default.
>Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Full reload is required.
>Apr 13 11:39:32 localhost systemd[1]: Stopping Configure Plugged-In Printer...
>Apr 13 11:39:32 localhost systemd[1]: cups.service: Main process exited, code=killed, status=15/TERM
>Apr 13 11:39:32 localhost systemd[1]: cups.service: Succeeded.
>Apr 13 11:39:32 localhost systemd[1]: Stopped CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Main process exited, code=killed, status=15/TERM
>Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Succeeded.
>Apr 13 11:39:32 localhost systemd[1]: Stopped Configure Plugged-In Printer.
>Apr 13 11:39:32 localhost systemd[1]: cups.path: Succeeded.
>Apr 13 11:39:32 localhost systemd[1]: Stopped CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Stopping CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Started CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: cups.socket: Succeeded.
>Apr 13 11:39:32 localhost systemd[1]: Closed CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Stopping CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Listening on CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Started Configure Plugged-In Printer.
>Apr 13 11:39:32 localhost systemd[1]: Starting CUPS Scheduler...
>Apr 13 11:39:32 localhost cupsd[1162]: Listening to 0.0.0.0:631 (IPv4)
>Apr 13 11:39:32 localhost cupsd[1162]: Listening to [v1.::]:631 (IPv6)
>Apr 13 11:39:32 localhost cupsd[1162]: Listening to /var/run/cups/cups.sock (Domain)
>Apr 13 11:39:32 localhost cupsd[1162]: Remote access is enabled.
>Apr 13 11:39:32 localhost cupsd[1162]: Added auto ServerAlias localhost
>Apr 13 11:39:32 localhost cupsd[1162]: Added auto ServerAlias localhost.local.net
>Apr 13 11:39:32 localhost cupsd[1162]: Loaded configuration file "/etc/cups/cupsd.conf"
>Apr 13 11:39:32 localhost cupsd[1162]: Using keychain "/etc/cups/ssl" for server name "localhost.local.net".
>Apr 13 11:39:32 localhost cupsd[1162]: Using default TempDir of /var/spool/cups/tmp...
>Apr 13 11:39:32 localhost cupsd[1162]: Configured for up to 100 clients.
>Apr 13 11:39:32 localhost cupsd[1162]: Allowing up to 100 client connections per host.
>Apr 13 11:39:32 localhost cupsd[1162]: Using policy "default" as the default.
>Apr 13 11:39:32 localhost cupsd[1162]: Full reload is required.
>Apr 13 11:39:32 localhost cupsd[1162]: Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 78 types, 118 filters...
>Apr 13 11:39:32 localhost cupsd[1162]: Loading printer laser...
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: task-printing-server-2018-5.mga7
>Apr 13 11:39:32 localhost cupsd[1162]: load_ppd: Loading /var/cache/cups/laser.data...
>Apr 13 11:39:32 localhost cupsd[1162]: cupsdRegisterPrinter(p=0x56449c9169d0(laser))
>Apr 13 11:39:32 localhost cupsd[1162]: Loading job cache file "/var/cache/cups/job.cache"...
>Apr 13 11:39:32 localhost cupsd[1162]: Loading from cache...
>Apr 13 11:39:32 localhost cupsd[1162]: Full reload complete.
>Apr 13 11:39:32 localhost cupsd[1162]: cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
>Apr 13 11:39:32 localhost cupsd[1162]: Cleaning out old files in "/var/spool/cups/tmp".
>Apr 13 11:39:32 localhost cupsd[1162]: cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
>Apr 13 11:39:32 localhost cupsd[1162]: Cleaning out old files in "/var/cache/cups".
>Apr 13 11:39:32 localhost cupsd[1162]: service_checkin: pid=1162
>Apr 13 11:39:32 localhost cupsd[1162]: service_checkin: 1 listeners.
>Apr 13 11:39:32 localhost cupsd[1162]: service_add_listener: Listener #1 at fd 3, "/var/run/cups/cups.sock".
>Apr 13 11:39:32 localhost cupsd[1162]: service_add_listener: Matched existing listener #1 to /var/run/cups/cups.sock.
>Apr 13 11:39:32 localhost cupsd[1162]: Creating KeepAlive/PID file "/var/cache/cups/org.cups.cupsd".
>Apr 13 11:39:32 localhost cupsd[1162]: Calling FindDeviceById(cups-laser)
>Apr 13 11:39:32 localhost cupsd[1162]: FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: â cups.service - CUPS Scheduler
>Apr 13 11:39:32 localhost udev-configure-printer[1161]:    Loaded: loaded (/usr/lib/systemd/system/cups.service; enabled; vendor preset: disabled)
>Apr 13 11:39:32 localhost udev-configure-printer[1161]:    Active: activating (start) since Sat 2019-04-13 11:39:32 BST; 13ms ago
>Apr 13 11:39:32 localhost udev-configure-printer[1161]:      Docs: man:cupsd(8)
>Apr 13 11:39:32 localhost udev-configure-printer[1161]:  Main PID: 1162 (cupsd)
>Apr 13 11:39:32 localhost udev-configure-printer[1161]:    Memory: 1.8M
>Apr 13 11:39:32 localhost udev-configure-printer[1161]:    CGroup: /system.slice/cups.service
>Apr 13 11:39:32 localhost udev-configure-printer[1161]:            ââ1162 /usr/sbin/cupsd -l
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: Cleaning out old files in "/var/spool/cups/tmp".
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: Cleaning out old files in "/var/cache/cups".
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: service_checkin: pid=1162
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: service_checkin: 1 listeners.
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: service_add_listener: Listener #1 at fd 3, "/var/run/cups/cups.sock".
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: service_add_listener: Matched existing listener #1 to /var/run/cups/cups.sock.
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: Creating KeepAlive/PID file "/var/cache/cups/org.cups.cupsd".
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: Calling FindDeviceById(cups-laser)
>Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
>Apr 13 11:39:32 localhost cupsd[1162]: Using profile ID "laser-Gray..".
>Apr 13 11:39:32 localhost cupsd[1162]: Calling CreateProfile(laser-Gray..,temp)
>Apr 13 11:39:32 localhost cupsd[1162]: CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
>Apr 13 11:39:32 localhost cupsd[1162]: Registering ICC color profiles for "laser".
>Apr 13 11:39:32 localhost cupsd[1162]: Calling CreateDevice(cups-laser,temp)
>Apr 13 11:39:32 localhost cupsd[1162]: CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
>Apr 13 11:39:32 localhost cupsd[1162]: Listening to 0.0.0.0:631 on fd 7...
>Apr 13 11:39:32 localhost cupsd[1162]: Listening to [v1.::]:631 on fd 8...
>Apr 13 11:39:32 localhost cupsd[1162]: Listening to /var/run/cups/cups.sock on fd 3...
>Apr 13 11:39:32 localhost cupsd[1162]: Resuming new connection processing...
>Apr 13 11:39:32 localhost cupsd[1162]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>Apr 13 11:39:32 localhost cupsd[1162]: cupsdAddCert: Adding certificate for PID 0
>Apr 13 11:39:32 localhost cupsd[1162]: Discarding unused server-started event...
>Apr 13 11:39:32 localhost cupsd[1162]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>Apr 13 11:39:32 localhost systemd[1]: Started CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Reached target Printer.
>Apr 13 11:39:32 localhost systemd[1]: Stopping CUPS Scheduler...
>Apr 13 11:39:32 localhost cupsd[1162]: Scheduler shutting down normally.
>Apr 13 11:39:32 localhost cupsd[1162]: Discarding unused server-stopped event...
>Apr 13 11:39:32 localhost cupsd[1162]: Creating KeepAlive/PID file "/var/cache/cups/org.cups.cupsd".
>Apr 13 11:39:32 localhost systemd[1]: Stopping Configure Plugged-In Printer...
>Apr 13 11:39:32 localhost cupsd[1162]: Saving job.cache...
>Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Main process exited, code=killed, status=15/TERM
>Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Succeeded.
>Apr 13 11:39:32 localhost systemd[1]: Stopped Configure Plugged-In Printer.
>Apr 13 11:39:32 localhost cupsd[1162]: cupsdStopSelect()
>Apr 13 11:39:32 localhost systemd[1]: cups.service: Succeeded.
>Apr 13 11:39:32 localhost systemd[1]: Stopped CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: cups.path: Succeeded.
>Apr 13 11:39:32 localhost systemd[1]: Stopped CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Stopping CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Started CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: cups.socket: Succeeded.
>Apr 13 11:39:32 localhost systemd[1]: Closed CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Stopping CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Listening on CUPS Scheduler.
>Apr 13 11:39:32 localhost systemd[1]: Started Configure Plugged-In Printer.
>Apr 13 11:39:32 localhost systemd[1]: Starting CUPS Scheduler...
>Apr 13 11:39:32 localhost cupsd[1177]: Listening to 0.0.0.0:631 (IPv4)
>Apr 13 11:39:32 localhost cupsd[1177]: Listening to [v1.::]:631 (IPv6)
>Apr 13 11:39:32 localhost cupsd[1177]: Listening to /var/run/cups/cups.sock (Domain)
>Apr 13 11:39:32 localhost cupsd[1177]: Remote access is enabled.
>Apr 13 11:39:32 localhost cupsd[1177]: Added auto ServerAlias localhost
>Apr 13 11:39:32 localhost cupsd[1177]: Added auto ServerAlias localhost.local.net
>Apr 13 11:39:32 localhost cupsd[1177]: Loaded configuration file "/etc/cups/cupsd.conf"
>Apr 13 11:39:32 localhost cupsd[1177]: Using keychain "/etc/cups/ssl" for server name "localhost.local.net".
>Apr 13 11:39:32 localhost cupsd[1177]: Using default TempDir of /var/spool/cups/tmp...
>Apr 13 11:39:32 localhost cupsd[1177]: Configured for up to 100 clients.
>Apr 13 11:39:32 localhost cupsd[1177]: Allowing up to 100 client connections per host.
>Apr 13 11:39:32 localhost cupsd[1177]: Using policy "default" as the default.
>Apr 13 11:39:32 localhost cupsd[1177]: Full reload is required.
>Apr 13 11:39:32 localhost cupsd[1177]: Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 78 types, 118 filters...
>Apr 13 11:39:32 localhost cupsd[1177]: Loading printer laser...
>Apr 13 11:39:32 localhost cupsd[1177]: load_ppd: Loading /var/cache/cups/laser.data...
>Apr 13 11:39:32 localhost cupsd[1177]: cupsdRegisterPrinter(p=0x5610f80a69d0(laser))
>Apr 13 11:39:32 localhost cupsd[1177]: Loading job cache file "/var/cache/cups/job.cache"...
>Apr 13 11:39:32 localhost cupsd[1177]: Loading from cache...
>Apr 13 11:39:32 localhost cupsd[1177]: Full reload complete.
>Apr 13 11:39:32 localhost cupsd[1177]: cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
>Apr 13 11:39:32 localhost cupsd[1177]: Cleaning out old files in "/var/spool/cups/tmp".
>Apr 13 11:39:32 localhost cupsd[1177]: cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
>Apr 13 11:39:32 localhost cupsd[1177]: Cleaning out old files in "/var/cache/cups".
>Apr 13 11:39:32 localhost cupsd[1177]: service_checkin: pid=1177
>Apr 13 11:39:32 localhost cupsd[1177]: service_checkin: 1 listeners.
>Apr 13 11:39:32 localhost cupsd[1177]: service_add_listener: Listener #1 at fd 3, "/var/run/cups/cups.sock".
>Apr 13 11:39:32 localhost cupsd[1177]: service_add_listener: Matched existing listener #1 to /var/run/cups/cups.sock.
>Apr 13 11:39:32 localhost cupsd[1177]: Creating KeepAlive/PID file "/var/cache/cups/org.cups.cupsd".
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: task-printing-server-2018-5.mga7
>Apr 13 11:39:32 localhost cupsd[1177]: Calling FindDeviceById(cups-laser)
>Apr 13 11:39:32 localhost cupsd[1177]: FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
>Apr 13 11:39:32 localhost cupsd[1177]: Using profile ID "laser-Gray..".
>Apr 13 11:39:32 localhost cupsd[1177]: Calling CreateProfile(laser-Gray..,temp)
>Apr 13 11:39:32 localhost cupsd[1177]: CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
>Apr 13 11:39:32 localhost cupsd[1177]: Registering ICC color profiles for "laser".
>Apr 13 11:39:32 localhost cupsd[1177]: Calling CreateDevice(cups-laser,temp)
>Apr 13 11:39:32 localhost cupsd[1177]: CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
>Apr 13 11:39:32 localhost cupsd[1177]: Listening to 0.0.0.0:631 on fd 7...
>Apr 13 11:39:32 localhost cupsd[1177]: Listening to [v1.::]:631 on fd 8...
>Apr 13 11:39:32 localhost cupsd[1177]: Listening to /var/run/cups/cups.sock on fd 3...
>Apr 13 11:39:32 localhost cupsd[1177]: Resuming new connection processing...
>Apr 13 11:39:32 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>Apr 13 11:39:32 localhost cupsd[1177]: cupsdAddCert: Adding certificate for PID 0
>Apr 13 11:39:32 localhost cupsd[1177]: Discarding unused server-started event...
>Apr 13 11:39:32 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>Apr 13 11:39:32 localhost systemd[1]: Started CUPS Scheduler.
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: â cups.service - CUPS Scheduler
>Apr 13 11:39:32 localhost udev-configure-printer[1176]:    Loaded: loaded (/usr/lib/systemd/system/cups.service; enabled; vendor preset: disabled)
>Apr 13 11:39:32 localhost udev-configure-printer[1176]:    Active: active (running) since Sat 2019-04-13 11:39:32 BST; 1ms ago
>Apr 13 11:39:32 localhost udev-configure-printer[1176]:      Docs: man:cupsd(8)
>Apr 13 11:39:32 localhost udev-configure-printer[1176]:  Main PID: 1177 (cupsd)
>Apr 13 11:39:32 localhost udev-configure-printer[1176]:    Status: "Scheduler is running..."
>Apr 13 11:39:32 localhost udev-configure-printer[1176]:    Memory: 1.9M
>Apr 13 11:39:32 localhost udev-configure-printer[1176]:    CGroup: /system.slice/cups.service
>Apr 13 11:39:32 localhost udev-configure-printer[1176]:            ââ1177 /usr/sbin/cupsd -l
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: Listening to 0.0.0.0:631 on fd 7...
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: Listening to [v1.::]:631 on fd 8...
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: Listening to /var/run/cups/cups.sock on fd 3...
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: Resuming new connection processing...
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: cupsdAddCert: Adding certificate for PID 0
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: Discarding unused server-started event...
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost systemd[1]: Started CUPS Scheduler.
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: add usb-004-003
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: device devpath is /devices/pci0000:00/0000:00:1d.0/usb4/4-1/4-1.6
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: MFG:Kyocera MDL:FS-1020D SERN:- serial:XHW5608369
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: CheckAndInstallDrivers()
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: CheckInstalledDrivers()
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: MissingDriver()
>Apr 13 11:39:32 localhost udev-configure-printer[1176]: FAIL HERE
>Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Main process exited, code=exited, status=1/FAILURE
>Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Failed with result 'exit-code'.
>Apr 13 11:39:33 localhost cupsd[1177]: Report: clients=0
>Apr 13 11:39:33 localhost cupsd[1177]: Report: jobs=1
>Apr 13 11:39:33 localhost cupsd[1177]: Report: jobs-active=0
>Apr 13 11:39:33 localhost cupsd[1177]: Report: printers=1
>Apr 13 11:39:33 localhost cupsd[1177]: Report: stringpool-string-count=696
>Apr 13 11:39:33 localhost cupsd[1177]: Report: stringpool-alloc-bytes=9072
>Apr 13 11:39:33 localhost cupsd[1177]: Report: stringpool-total-bytes=13096
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Server address is "/var/run/cups/cups.sock".
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Accepted from localhost (Domain)
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Waiting for request.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] POST / HTTP/1.1
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=200, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] No authentication data provided.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] 2.0 CUPS-Get-Printers 1
>Apr 13 11:39:38 localhost cupsd[1177]: CUPS-Get-Printers
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Content-Length: 1225
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] con->http=0x5610f80dcb50
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1225, response=0x5610f80d6b40(IPP_STATE_DATA), pipe_pid=0, file=-1
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] bytes=0, http_state=0, data_remaining=1225
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Flushing write buffer.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] New state is HTTP_STATE_WAITING
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Waiting for request.
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] POST / HTTP/1.1
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=200, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] No authentication data provided.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] 2.0 CUPS-Get-Default 2
>Apr 13 11:39:38 localhost cupsd[1177]: CUPS-Get-Default
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Content-Length: 9942
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] con->http=0x5610f80dcb50
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9942, response=0x5610f80f4ae0(IPP_STATE_DATA), pipe_pid=0, file=-1
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] bytes=0, http_state=0, data_remaining=9942
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Flushing write buffer.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] New state is HTTP_STATE_WAITING
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Waiting for request.
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] POST / HTTP/1.1
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=200, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] No authentication data provided.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] 2.0 CUPS-Get-Default 3
>Apr 13 11:39:38 localhost cupsd[1177]: CUPS-Get-Default
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Content-Length: 9942
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] con->http=0x5610f80dcb50
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9942, response=0x5610f80ef410(IPP_STATE_DATA), pipe_pid=0, file=-1
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] bytes=0, http_state=0, data_remaining=9942
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Flushing write buffer.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] New state is HTTP_STATE_WAITING
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Waiting for request.
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Server address is "/var/run/cups/cups.sock".
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Accepted from localhost (Domain)
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Waiting for request.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] POST / HTTP/1.1
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Read: status=200, state=6
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] No authentication data provided.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] 2.0 Create-Printer-Subscriptions 4
>Apr 13 11:39:38 localhost cupsd[1177]: Create-Printer-Subscriptions /
>Apr 13 11:39:38 localhost cupsd[1177]: create_subscriptions(con=0x5610f80e0050(2), uri="/")
>Apr 13 11:39:38 localhost cupsd[1177]: recipient="dbus://"
>Apr 13 11:39:38 localhost cupsd[1177]: pullmethod="ippget"
>Apr 13 11:39:38 localhost cupsd[1177]: notify-lease-duration=3600
>Apr 13 11:39:38 localhost cupsd[1177]: notify-time-interval=0
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdAddSubscription(mask=798f, dest=(nil)(), job=(nil)(0), uri="dbus://")
>Apr 13 11:39:38 localhost cupsd[1177]: Added subscription #24 for server.
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdMarkDirty(----S)
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Content-Length: 107
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] con->http=0x5610f80f75e0
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x5610f80d7e40(IPP_STATE_DATA), pipe_pid=0, file=-1
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] bytes=0, http_state=0, data_remaining=107
>Apr 13 11:39:38 localhost cupsd[1177]: REQUEST localhost - - "POST / HTTP/1.1" 200 362 Create-Printer-Subscriptions successful-ok
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Flushing write buffer.
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] New state is HTTP_STATE_WAITING
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Waiting for request.
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
>Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Closing connection.
>Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
>Apr 13 11:39:39 localhost cupsd[1177]: Expiring subscriptions...
>Apr 13 11:39:40 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Server address is "/var/run/cups/cups.sock".
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Accepted from localhost (Domain)
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Waiting for request.
>Apr 13 11:39:40 localhost cupsd[1177]: Expiring subscriptions...
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] POST / HTTP/1.1
>Apr 13 11:39:40 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Read: status=200, state=6
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] No authentication data provided.
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] 2.0 CUPS-Get-Printers 1
>Apr 13 11:39:40 localhost cupsd[1177]: CUPS-Get-Printers
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Content-Length: 369
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] con->http=0x5610f80f75e0
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=369, response=0x5610f80ee060(IPP_STATE_DATA), pipe_pid=0, file=-1
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] bytes=0, http_state=0, data_remaining=369
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Flushing write buffer.
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] New state is HTTP_STATE_WAITING
>Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Waiting for request.
>Apr 13 11:39:40 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
>Apr 13 11:39:41 localhost cupsd[1177]: Expiring subscriptions...
>Apr 13 11:40:09 localhost cupsd[1177]: Saving subscriptions.conf...
>Apr 13 11:40:09 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
>Apr 13 11:40:09 localhost cupsd[1177]: Expiring subscriptions...
>Apr 13 11:44:39 localhost cupsd[1177]: Expiring subscriptions...
>Apr 13 11:44:39 localhost cupsd[1177]: cupsdAddCert: Adding certificate for PID 0
>Apr 13 11:44:39 localhost cupsd[1177]: Closing client 1 after 300 seconds of inactivity.
>Apr 13 11:44:39 localhost cupsd[1177]: [Client 1] Closing connection.
>Apr 13 11:44:39 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
>Apr 13 11:44:39 localhost cupsd[1177]: Report: clients=1
>Apr 13 11:44:39 localhost cupsd[1177]: Report: jobs=1
>Apr 13 11:44:39 localhost cupsd[1177]: Report: jobs-active=0
>Apr 13 11:44:39 localhost cupsd[1177]: Report: printers=1
>Apr 13 11:44:39 localhost cupsd[1177]: Report: stringpool-string-count=698
>Apr 13 11:44:39 localhost cupsd[1177]: Report: stringpool-alloc-bytes=9136
>Apr 13 11:44:39 localhost cupsd[1177]: Report: stringpool-total-bytes=13144
>Apr 13 11:44:41 localhost cupsd[1177]: Expiring subscriptions...
>Apr 13 11:44:41 localhost cupsd[1177]: Closing client 3 after 300 seconds of inactivity.
>Apr 13 11:44:41 localhost cupsd[1177]: [Client 3] Closing connection.
>Apr 13 11:44:41 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy"

Apr 13 11:39:27 localhost systemd[1]: Created slice system-configure\x2dprinter.slice.
Apr 13 11:39:27 localhost kernel: usblp 4-1.6:1.0: usblp0: USB Bidirectional printer dev 3 if 0 alt 0 proto 2 vid 0x0482 pid 0x000E
Apr 13 11:39:29 localhost systemd[1]: Listening on CUPS Scheduler.
Apr 13 11:39:29 localhost systemd[1]: Started CUPS Scheduler.
Apr 13 11:39:31 localhost service_harddrake[968]: PRINTER took:  0:00:00
Apr 13 11:39:31 localhost systemd[1]: Started Configure Plugged-In Printer.
Apr 13 11:39:32 localhost udev-configure-printer[1021]: task-printing-server-2018-5.mga7
Apr 13 11:39:32 localhost systemd[1]: Starting CUPS Scheduler...
Apr 13 11:39:32 localhost cupsd[1142]: Listening to 0.0.0.0:631 (IPv4)
Apr 13 11:39:32 localhost cupsd[1142]: Listening to [v1.::]:631 (IPv6)
Apr 13 11:39:32 localhost cupsd[1142]: Listening to /var/run/cups/cups.sock (Domain)
Apr 13 11:39:32 localhost cupsd[1142]: Remote access is enabled.
Apr 13 11:39:32 localhost cupsd[1142]: Added auto ServerAlias localhost
Apr 13 11:39:32 localhost cupsd[1142]: Added auto ServerAlias localhost.local.net
Apr 13 11:39:32 localhost cupsd[1142]: Loaded configuration file "/etc/cups/cupsd.conf"
Apr 13 11:39:32 localhost cupsd[1142]: Using keychain "/etc/cups/ssl" for server name "localhost.local.net".
Apr 13 11:39:32 localhost cupsd[1142]: Using default TempDir of /var/spool/cups/tmp...
Apr 13 11:39:32 localhost cupsd[1142]: Configured for up to 100 clients.
Apr 13 11:39:32 localhost cupsd[1142]: Allowing up to 100 client connections per host.
Apr 13 11:39:32 localhost cupsd[1142]: Using policy "default" as the default.
Apr 13 11:39:32 localhost cupsd[1142]: Full reload is required.
Apr 13 11:39:32 localhost udev-configure-printer[1021]: â cups.service - CUPS Scheduler
Apr 13 11:39:32 localhost udev-configure-printer[1021]:    Loaded: loaded (/usr/lib/systemd/system/cups.service; enabled; vendor preset: disabled)
Apr 13 11:39:32 localhost udev-configure-printer[1021]:    Active: activating (start) since Sat 2019-04-13 11:39:32 BST; 17ms ago
Apr 13 11:39:32 localhost udev-configure-printer[1021]:      Docs: man:cupsd(8)
Apr 13 11:39:32 localhost udev-configure-printer[1021]:  Main PID: 1142 (cupsd)
Apr 13 11:39:32 localhost udev-configure-printer[1021]:    Memory: 1.5M
Apr 13 11:39:32 localhost udev-configure-printer[1021]:    CGroup: /system.slice/cups.service
Apr 13 11:39:32 localhost udev-configure-printer[1021]:            ââ1142 /usr/sbin/cupsd -l
Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Remote access is enabled.
Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Added auto ServerAlias localhost
Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Added auto ServerAlias localhost.local.net
Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Loaded configuration file "/etc/cups/cupsd.conf"
Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Using keychain "/etc/cups/ssl" for server name "localhost.local.net".
Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Using default TempDir of /var/spool/cups/tmp...
Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Configured for up to 100 clients.
Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Allowing up to 100 client connections per host.
Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Using policy "default" as the default.
Apr 13 11:39:32 localhost udev-configure-printer[1021]: Apr 13 11:39:32 localhost cupsd[1142]: Full reload is required.
Apr 13 11:39:32 localhost systemd[1]: Stopping Configure Plugged-In Printer...
Apr 13 11:39:32 localhost systemd[1]: cups.service: Main process exited, code=killed, status=15/TERM
Apr 13 11:39:32 localhost systemd[1]: cups.service: Succeeded.
Apr 13 11:39:32 localhost systemd[1]: Stopped CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Main process exited, code=killed, status=15/TERM
Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Succeeded.
Apr 13 11:39:32 localhost systemd[1]: Stopped Configure Plugged-In Printer.
Apr 13 11:39:32 localhost systemd[1]: cups.path: Succeeded.
Apr 13 11:39:32 localhost systemd[1]: Stopped CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Stopping CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Started CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: cups.socket: Succeeded.
Apr 13 11:39:32 localhost systemd[1]: Closed CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Stopping CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Listening on CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Started Configure Plugged-In Printer.
Apr 13 11:39:32 localhost systemd[1]: Starting CUPS Scheduler...
Apr 13 11:39:32 localhost cupsd[1162]: Listening to 0.0.0.0:631 (IPv4)
Apr 13 11:39:32 localhost cupsd[1162]: Listening to [v1.::]:631 (IPv6)
Apr 13 11:39:32 localhost cupsd[1162]: Listening to /var/run/cups/cups.sock (Domain)
Apr 13 11:39:32 localhost cupsd[1162]: Remote access is enabled.
Apr 13 11:39:32 localhost cupsd[1162]: Added auto ServerAlias localhost
Apr 13 11:39:32 localhost cupsd[1162]: Added auto ServerAlias localhost.local.net
Apr 13 11:39:32 localhost cupsd[1162]: Loaded configuration file "/etc/cups/cupsd.conf"
Apr 13 11:39:32 localhost cupsd[1162]: Using keychain "/etc/cups/ssl" for server name "localhost.local.net".
Apr 13 11:39:32 localhost cupsd[1162]: Using default TempDir of /var/spool/cups/tmp...
Apr 13 11:39:32 localhost cupsd[1162]: Configured for up to 100 clients.
Apr 13 11:39:32 localhost cupsd[1162]: Allowing up to 100 client connections per host.
Apr 13 11:39:32 localhost cupsd[1162]: Using policy "default" as the default.
Apr 13 11:39:32 localhost cupsd[1162]: Full reload is required.
Apr 13 11:39:32 localhost cupsd[1162]: Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 78 types, 118 filters...
Apr 13 11:39:32 localhost cupsd[1162]: Loading printer laser...
Apr 13 11:39:32 localhost udev-configure-printer[1161]: task-printing-server-2018-5.mga7
Apr 13 11:39:32 localhost cupsd[1162]: load_ppd: Loading /var/cache/cups/laser.data...
Apr 13 11:39:32 localhost cupsd[1162]: cupsdRegisterPrinter(p=0x56449c9169d0(laser))
Apr 13 11:39:32 localhost cupsd[1162]: Loading job cache file "/var/cache/cups/job.cache"...
Apr 13 11:39:32 localhost cupsd[1162]: Loading from cache...
Apr 13 11:39:32 localhost cupsd[1162]: Full reload complete.
Apr 13 11:39:32 localhost cupsd[1162]: cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
Apr 13 11:39:32 localhost cupsd[1162]: Cleaning out old files in "/var/spool/cups/tmp".
Apr 13 11:39:32 localhost cupsd[1162]: cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
Apr 13 11:39:32 localhost cupsd[1162]: Cleaning out old files in "/var/cache/cups".
Apr 13 11:39:32 localhost cupsd[1162]: service_checkin: pid=1162
Apr 13 11:39:32 localhost cupsd[1162]: service_checkin: 1 listeners.
Apr 13 11:39:32 localhost cupsd[1162]: service_add_listener: Listener #1 at fd 3, "/var/run/cups/cups.sock".
Apr 13 11:39:32 localhost cupsd[1162]: service_add_listener: Matched existing listener #1 to /var/run/cups/cups.sock.
Apr 13 11:39:32 localhost cupsd[1162]: Creating KeepAlive/PID file "/var/cache/cups/org.cups.cupsd".
Apr 13 11:39:32 localhost cupsd[1162]: Calling FindDeviceById(cups-laser)
Apr 13 11:39:32 localhost cupsd[1162]: FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
Apr 13 11:39:32 localhost udev-configure-printer[1161]: â cups.service - CUPS Scheduler
Apr 13 11:39:32 localhost udev-configure-printer[1161]:    Loaded: loaded (/usr/lib/systemd/system/cups.service; enabled; vendor preset: disabled)
Apr 13 11:39:32 localhost udev-configure-printer[1161]:    Active: activating (start) since Sat 2019-04-13 11:39:32 BST; 13ms ago
Apr 13 11:39:32 localhost udev-configure-printer[1161]:      Docs: man:cupsd(8)
Apr 13 11:39:32 localhost udev-configure-printer[1161]:  Main PID: 1162 (cupsd)
Apr 13 11:39:32 localhost udev-configure-printer[1161]:    Memory: 1.8M
Apr 13 11:39:32 localhost udev-configure-printer[1161]:    CGroup: /system.slice/cups.service
Apr 13 11:39:32 localhost udev-configure-printer[1161]:            ââ1162 /usr/sbin/cupsd -l
Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: Cleaning out old files in "/var/spool/cups/tmp".
Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: Cleaning out old files in "/var/cache/cups".
Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: service_checkin: pid=1162
Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: service_checkin: 1 listeners.
Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: service_add_listener: Listener #1 at fd 3, "/var/run/cups/cups.sock".
Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: service_add_listener: Matched existing listener #1 to /var/run/cups/cups.sock.
Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: Creating KeepAlive/PID file "/var/cache/cups/org.cups.cupsd".
Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: Calling FindDeviceById(cups-laser)
Apr 13 11:39:32 localhost udev-configure-printer[1161]: Apr 13 11:39:32 localhost cupsd[1162]: FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
Apr 13 11:39:32 localhost cupsd[1162]: Using profile ID "laser-Gray..".
Apr 13 11:39:32 localhost cupsd[1162]: Calling CreateProfile(laser-Gray..,temp)
Apr 13 11:39:32 localhost cupsd[1162]: CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
Apr 13 11:39:32 localhost cupsd[1162]: Registering ICC color profiles for "laser".
Apr 13 11:39:32 localhost cupsd[1162]: Calling CreateDevice(cups-laser,temp)
Apr 13 11:39:32 localhost cupsd[1162]: CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
Apr 13 11:39:32 localhost cupsd[1162]: Listening to 0.0.0.0:631 on fd 7...
Apr 13 11:39:32 localhost cupsd[1162]: Listening to [v1.::]:631 on fd 8...
Apr 13 11:39:32 localhost cupsd[1162]: Listening to /var/run/cups/cups.sock on fd 3...
Apr 13 11:39:32 localhost cupsd[1162]: Resuming new connection processing...
Apr 13 11:39:32 localhost cupsd[1162]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
Apr 13 11:39:32 localhost cupsd[1162]: cupsdAddCert: Adding certificate for PID 0
Apr 13 11:39:32 localhost cupsd[1162]: Discarding unused server-started event...
Apr 13 11:39:32 localhost cupsd[1162]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
Apr 13 11:39:32 localhost systemd[1]: Started CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Reached target Printer.
Apr 13 11:39:32 localhost systemd[1]: Stopping CUPS Scheduler...
Apr 13 11:39:32 localhost cupsd[1162]: Scheduler shutting down normally.
Apr 13 11:39:32 localhost cupsd[1162]: Discarding unused server-stopped event...
Apr 13 11:39:32 localhost cupsd[1162]: Creating KeepAlive/PID file "/var/cache/cups/org.cups.cupsd".
Apr 13 11:39:32 localhost systemd[1]: Stopping Configure Plugged-In Printer...
Apr 13 11:39:32 localhost cupsd[1162]: Saving job.cache...
Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Main process exited, code=killed, status=15/TERM
Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Succeeded.
Apr 13 11:39:32 localhost systemd[1]: Stopped Configure Plugged-In Printer.
Apr 13 11:39:32 localhost cupsd[1162]: cupsdStopSelect()
Apr 13 11:39:32 localhost systemd[1]: cups.service: Succeeded.
Apr 13 11:39:32 localhost systemd[1]: Stopped CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: cups.path: Succeeded.
Apr 13 11:39:32 localhost systemd[1]: Stopped CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Stopping CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Started CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: cups.socket: Succeeded.
Apr 13 11:39:32 localhost systemd[1]: Closed CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Stopping CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Listening on CUPS Scheduler.
Apr 13 11:39:32 localhost systemd[1]: Started Configure Plugged-In Printer.
Apr 13 11:39:32 localhost systemd[1]: Starting CUPS Scheduler...
Apr 13 11:39:32 localhost cupsd[1177]: Listening to 0.0.0.0:631 (IPv4)
Apr 13 11:39:32 localhost cupsd[1177]: Listening to [v1.::]:631 (IPv6)
Apr 13 11:39:32 localhost cupsd[1177]: Listening to /var/run/cups/cups.sock (Domain)
Apr 13 11:39:32 localhost cupsd[1177]: Remote access is enabled.
Apr 13 11:39:32 localhost cupsd[1177]: Added auto ServerAlias localhost
Apr 13 11:39:32 localhost cupsd[1177]: Added auto ServerAlias localhost.local.net
Apr 13 11:39:32 localhost cupsd[1177]: Loaded configuration file "/etc/cups/cupsd.conf"
Apr 13 11:39:32 localhost cupsd[1177]: Using keychain "/etc/cups/ssl" for server name "localhost.local.net".
Apr 13 11:39:32 localhost cupsd[1177]: Using default TempDir of /var/spool/cups/tmp...
Apr 13 11:39:32 localhost cupsd[1177]: Configured for up to 100 clients.
Apr 13 11:39:32 localhost cupsd[1177]: Allowing up to 100 client connections per host.
Apr 13 11:39:32 localhost cupsd[1177]: Using policy "default" as the default.
Apr 13 11:39:32 localhost cupsd[1177]: Full reload is required.
Apr 13 11:39:32 localhost cupsd[1177]: Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 78 types, 118 filters...
Apr 13 11:39:32 localhost cupsd[1177]: Loading printer laser...
Apr 13 11:39:32 localhost cupsd[1177]: load_ppd: Loading /var/cache/cups/laser.data...
Apr 13 11:39:32 localhost cupsd[1177]: cupsdRegisterPrinter(p=0x5610f80a69d0(laser))
Apr 13 11:39:32 localhost cupsd[1177]: Loading job cache file "/var/cache/cups/job.cache"...
Apr 13 11:39:32 localhost cupsd[1177]: Loading from cache...
Apr 13 11:39:32 localhost cupsd[1177]: Full reload complete.
Apr 13 11:39:32 localhost cupsd[1177]: cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
Apr 13 11:39:32 localhost cupsd[1177]: Cleaning out old files in "/var/spool/cups/tmp".
Apr 13 11:39:32 localhost cupsd[1177]: cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
Apr 13 11:39:32 localhost cupsd[1177]: Cleaning out old files in "/var/cache/cups".
Apr 13 11:39:32 localhost cupsd[1177]: service_checkin: pid=1177
Apr 13 11:39:32 localhost cupsd[1177]: service_checkin: 1 listeners.
Apr 13 11:39:32 localhost cupsd[1177]: service_add_listener: Listener #1 at fd 3, "/var/run/cups/cups.sock".
Apr 13 11:39:32 localhost cupsd[1177]: service_add_listener: Matched existing listener #1 to /var/run/cups/cups.sock.
Apr 13 11:39:32 localhost cupsd[1177]: Creating KeepAlive/PID file "/var/cache/cups/org.cups.cupsd".
Apr 13 11:39:32 localhost udev-configure-printer[1176]: task-printing-server-2018-5.mga7
Apr 13 11:39:32 localhost cupsd[1177]: Calling FindDeviceById(cups-laser)
Apr 13 11:39:32 localhost cupsd[1177]: FindDeviceById failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
Apr 13 11:39:32 localhost cupsd[1177]: Using profile ID "laser-Gray..".
Apr 13 11:39:32 localhost cupsd[1177]: Calling CreateProfile(laser-Gray..,temp)
Apr 13 11:39:32 localhost cupsd[1177]: CreateProfile failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
Apr 13 11:39:32 localhost cupsd[1177]: Registering ICC color profiles for "laser".
Apr 13 11:39:32 localhost cupsd[1177]: Calling CreateDevice(cups-laser,temp)
Apr 13 11:39:32 localhost cupsd[1177]: CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
Apr 13 11:39:32 localhost cupsd[1177]: Listening to 0.0.0.0:631 on fd 7...
Apr 13 11:39:32 localhost cupsd[1177]: Listening to [v1.::]:631 on fd 8...
Apr 13 11:39:32 localhost cupsd[1177]: Listening to /var/run/cups/cups.sock on fd 3...
Apr 13 11:39:32 localhost cupsd[1177]: Resuming new connection processing...
Apr 13 11:39:32 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
Apr 13 11:39:32 localhost cupsd[1177]: cupsdAddCert: Adding certificate for PID 0
Apr 13 11:39:32 localhost cupsd[1177]: Discarding unused server-started event...
Apr 13 11:39:32 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
Apr 13 11:39:32 localhost systemd[1]: Started CUPS Scheduler.
Apr 13 11:39:32 localhost udev-configure-printer[1176]: â cups.service - CUPS Scheduler
Apr 13 11:39:32 localhost udev-configure-printer[1176]:    Loaded: loaded (/usr/lib/systemd/system/cups.service; enabled; vendor preset: disabled)
Apr 13 11:39:32 localhost udev-configure-printer[1176]:    Active: active (running) since Sat 2019-04-13 11:39:32 BST; 1ms ago
Apr 13 11:39:32 localhost udev-configure-printer[1176]:      Docs: man:cupsd(8)
Apr 13 11:39:32 localhost udev-configure-printer[1176]:  Main PID: 1177 (cupsd)
Apr 13 11:39:32 localhost udev-configure-printer[1176]:    Status: "Scheduler is running..."
Apr 13 11:39:32 localhost udev-configure-printer[1176]:    Memory: 1.9M
Apr 13 11:39:32 localhost udev-configure-printer[1176]:    CGroup: /system.slice/cups.service
Apr 13 11:39:32 localhost udev-configure-printer[1176]:            ââ1177 /usr/sbin/cupsd -l
Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: CreateDevice failed: org.freedesktop.DBus.Error.ServiceUnknown:The name org.freedesktop.ColorManager was not provided by any .service files
Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: Listening to 0.0.0.0:631 on fd 7...
Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: Listening to [v1.::]:631 on fd 8...
Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: Listening to /var/run/cups/cups.sock on fd 3...
Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: Resuming new connection processing...
Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: cupsdAddCert: Adding certificate for PID 0
Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: Discarding unused server-started event...
Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
Apr 13 11:39:32 localhost udev-configure-printer[1176]: Apr 13 11:39:32 localhost systemd[1]: Started CUPS Scheduler.
Apr 13 11:39:32 localhost udev-configure-printer[1176]: add usb-004-003
Apr 13 11:39:32 localhost udev-configure-printer[1176]: device devpath is /devices/pci0000:00/0000:00:1d.0/usb4/4-1/4-1.6
Apr 13 11:39:32 localhost udev-configure-printer[1176]: MFG:Kyocera MDL:FS-1020D SERN:- serial:XHW5608369
Apr 13 11:39:32 localhost udev-configure-printer[1176]: CheckAndInstallDrivers()
Apr 13 11:39:32 localhost udev-configure-printer[1176]: CheckInstalledDrivers()
Apr 13 11:39:32 localhost udev-configure-printer[1176]: MissingDriver()
Apr 13 11:39:32 localhost udev-configure-printer[1176]: FAIL HERE
Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Main process exited, code=exited, status=1/FAILURE
Apr 13 11:39:32 localhost systemd[1]: configure-printer@usb-004-003.service: Failed with result 'exit-code'.
Apr 13 11:39:33 localhost cupsd[1177]: Report: clients=0
Apr 13 11:39:33 localhost cupsd[1177]: Report: jobs=1
Apr 13 11:39:33 localhost cupsd[1177]: Report: jobs-active=0
Apr 13 11:39:33 localhost cupsd[1177]: Report: printers=1
Apr 13 11:39:33 localhost cupsd[1177]: Report: stringpool-string-count=696
Apr 13 11:39:33 localhost cupsd[1177]: Report: stringpool-alloc-bytes=9072
Apr 13 11:39:33 localhost cupsd[1177]: Report: stringpool-total-bytes=13096
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Server address is "/var/run/cups/cups.sock".
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Accepted from localhost (Domain)
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Waiting for request.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] POST / HTTP/1.1
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=200, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] No authentication data provided.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] 2.0 CUPS-Get-Printers 1
Apr 13 11:39:38 localhost cupsd[1177]: CUPS-Get-Printers
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Content-Length: 1225
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] con->http=0x5610f80dcb50
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1225, response=0x5610f80d6b40(IPP_STATE_DATA), pipe_pid=0, file=-1
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] bytes=0, http_state=0, data_remaining=1225
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Flushing write buffer.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] New state is HTTP_STATE_WAITING
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Waiting for request.
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] POST / HTTP/1.1
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=200, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] No authentication data provided.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=100, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] 2.0 CUPS-Get-Default 2
Apr 13 11:39:38 localhost cupsd[1177]: CUPS-Get-Default
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Content-Length: 9942
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] con->http=0x5610f80dcb50
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9942, response=0x5610f80f4ae0(IPP_STATE_DATA), pipe_pid=0, file=-1
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] bytes=0, http_state=0, data_remaining=9942
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Flushing write buffer.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] New state is HTTP_STATE_WAITING
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Waiting for request.
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] POST / HTTP/1.1
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Read: status=200, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] No authentication data provided.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] 2.0 CUPS-Get-Default 3
Apr 13 11:39:38 localhost cupsd[1177]: CUPS-Get-Default
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Content-Length: 9942
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] con->http=0x5610f80dcb50
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9942, response=0x5610f80ef410(IPP_STATE_DATA), pipe_pid=0, file=-1
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] bytes=0, http_state=0, data_remaining=9942
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Flushing write buffer.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] New state is HTTP_STATE_WAITING
Apr 13 11:39:38 localhost cupsd[1177]: [Client 1] Waiting for request.
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Server address is "/var/run/cups/cups.sock".
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Accepted from localhost (Domain)
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Waiting for request.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] POST / HTTP/1.1
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients", busy="Active clients"
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Read: status=200, state=6
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] No authentication data provided.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] 2.0 Create-Printer-Subscriptions 4
Apr 13 11:39:38 localhost cupsd[1177]: Create-Printer-Subscriptions /
Apr 13 11:39:38 localhost cupsd[1177]: create_subscriptions(con=0x5610f80e0050(2), uri="/")
Apr 13 11:39:38 localhost cupsd[1177]: recipient="dbus://"
Apr 13 11:39:38 localhost cupsd[1177]: pullmethod="ippget"
Apr 13 11:39:38 localhost cupsd[1177]: notify-lease-duration=3600
Apr 13 11:39:38 localhost cupsd[1177]: notify-time-interval=0
Apr 13 11:39:38 localhost cupsd[1177]: cupsdAddSubscription(mask=798f, dest=(nil)(), job=(nil)(0), uri="dbus://")
Apr 13 11:39:38 localhost cupsd[1177]: Added subscription #24 for server.
Apr 13 11:39:38 localhost cupsd[1177]: cupsdMarkDirty(----S)
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Returning IPP successful-ok for Create-Printer-Subscriptions (/) from localhost.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Content-Length: 107
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] con->http=0x5610f80f75e0
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x5610f80d7e40(IPP_STATE_DATA), pipe_pid=0, file=-1
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] bytes=0, http_state=0, data_remaining=107
Apr 13 11:39:38 localhost cupsd[1177]: REQUEST localhost - - "POST / HTTP/1.1" 200 362 Create-Printer-Subscriptions successful-ok
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Flushing write buffer.
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] New state is HTTP_STATE_WAITING
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Waiting for request.
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] HTTP_STATE_WAITING Closing for error 32 (Broken pipe)
Apr 13 11:39:38 localhost cupsd[1177]: [Client 2] Closing connection.
Apr 13 11:39:38 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
Apr 13 11:39:39 localhost cupsd[1177]: Expiring subscriptions...
Apr 13 11:39:40 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Server address is "/var/run/cups/cups.sock".
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Accepted from localhost (Domain)
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Waiting for request.
Apr 13 11:39:40 localhost cupsd[1177]: Expiring subscriptions...
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] POST / HTTP/1.1
Apr 13 11:39:40 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Read: status=200, state=6
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] No authentication data provided.
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] 2.0 CUPS-Get-Printers 1
Apr 13 11:39:40 localhost cupsd[1177]: CUPS-Get-Printers
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost.
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Content-Length: 369
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] cupsdSendHeader: code=200, type="application/ipp", auth_type=0
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] con->http=0x5610f80f75e0
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=369, response=0x5610f80ee060(IPP_STATE_DATA), pipe_pid=0, file=-1
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Writing IPP response, ipp_state=IPP_STATE_DATA, old wused=0, new wused=0
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] bytes=0, http_state=0, data_remaining=369
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Flushing write buffer.
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] New state is HTTP_STATE_WAITING
Apr 13 11:39:40 localhost cupsd[1177]: [Client 3] Waiting for request.
Apr 13 11:39:40 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
Apr 13 11:39:41 localhost cupsd[1177]: Expiring subscriptions...
Apr 13 11:40:09 localhost cupsd[1177]: Saving subscriptions.conf...
Apr 13 11:40:09 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
Apr 13 11:40:09 localhost cupsd[1177]: Expiring subscriptions...
Apr 13 11:44:39 localhost cupsd[1177]: Expiring subscriptions...
Apr 13 11:44:39 localhost cupsd[1177]: cupsdAddCert: Adding certificate for PID 0
Apr 13 11:44:39 localhost cupsd[1177]: Closing client 1 after 300 seconds of inactivity.
Apr 13 11:44:39 localhost cupsd[1177]: [Client 1] Closing connection.
Apr 13 11:44:39 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
Apr 13 11:44:39 localhost cupsd[1177]: Report: clients=1
Apr 13 11:44:39 localhost cupsd[1177]: Report: jobs=1
Apr 13 11:44:39 localhost cupsd[1177]: Report: jobs-active=0
Apr 13 11:44:39 localhost cupsd[1177]: Report: printers=1
Apr 13 11:44:39 localhost cupsd[1177]: Report: stringpool-string-count=698
Apr 13 11:44:39 localhost cupsd[1177]: Report: stringpool-alloc-bytes=9136
Apr 13 11:44:39 localhost cupsd[1177]: Report: stringpool-total-bytes=13144
Apr 13 11:44:41 localhost cupsd[1177]: Expiring subscriptions...
Apr 13 11:44:41 localhost cupsd[1177]: Closing client 3 after 300 seconds of inactivity.
Apr 13 11:44:41 localhost cupsd[1177]: [Client 3] Closing connection.
Apr 13 11:44:41 localhost cupsd[1177]: cupsdSetBusyState: newbusy="Not busy", busy="Not busy"

Actions: View

Attachments on bug 24189: 10889 | 10891 | 10921 | 11443