I [22/Apr/2012:10:25:58 -0700] Listening to 0.0.0.0:631 (IPv4)
I [22/Apr/2012:10:25:58 -0700] Listening to :::631 (IPv6)
I [22/Apr/2012:10:25:58 -0700] Listening to /var/run/cups/cups.sock (Domain)
I [22/Apr/2012:10:25:58 -0700] Remote access is enabled.
D [22/Apr/2012:10:25:58 -0700] Added auto ServerAlias KISE-005
I [22/Apr/2012:10:25:58 -0700] Loaded configuration file "/etc/cups/cupsd.conf"
I [22/Apr/2012:10:25:58 -0700] Using default TempDir of /var/spool/cups/tmp...
I [22/Apr/2012:10:25:58 -0700] Configured for up to 100 clients.
I [22/Apr/2012:10:25:58 -0700] Allowing up to 100 client connections per host.
I [22/Apr/2012:10:25:58 -0700] Using policy "default" as the default!
D [22/Apr/2012:10:25:58 -0700] load_ppd: Loading /var/cache/cups/MG5300LAN.ipp2...
D [22/Apr/2012:10:25:59 -0700] cupsdRegisterPrinter(p=0x7ff756595700(MG5300LAN))
D [22/Apr/2012:10:25:59 -0700] load_ppd: Loading /var/cache/cups/MG5300USB.ipp2...
D [22/Apr/2012:10:25:59 -0700] cupsdRegisterPrinter(p=0x7ff75669a1f0(MG5300USB))
D [22/Apr/2012:10:25:59 -0700] cupsdMarkDirty(---p--)
D [22/Apr/2012:10:25:59 -0700] cupsdSetBusyState: Printing jobs and dirty files
I [22/Apr/2012:10:25:59 -0700] Partial reload complete.
I [22/Apr/2012:10:25:59 -0700] Listening to 0.0.0.0:631 on fd 3...
I [22/Apr/2012:10:25:59 -0700] Listening to :::631 on fd 6...
I [22/Apr/2012:10:25:59 -0700] Listening to /var/run/cups/cups.sock on fd 7...
I [22/Apr/2012:10:25:59 -0700] Resuming new connection processing...
D [22/Apr/2012:10:25:59 -0700] cupsdRegisterPrinter(p=0x7ff756595700(MG5300LAN))
D [22/Apr/2012:10:25:59 -0700] cupsdRegisterPrinter(p=0x7ff75669a1f0(MG5300USB))
D [22/Apr/2012:10:25:59 -0700] Discarding unused server-restarted event...
D [22/Apr/2012:10:26:00 -0700] cupsdAcceptClient: 13 from localhost (Domain)
D [22/Apr/2012:10:26:00 -0700] Report: clients=1
D [22/Apr/2012:10:26:00 -0700] Report: jobs=1
D [22/Apr/2012:10:26:00 -0700] Report: jobs-active=1
D [22/Apr/2012:10:26:00 -0700] Report: printers=2
D [22/Apr/2012:10:26:00 -0700] Report: printers-implicit=0
D [22/Apr/2012:10:26:00 -0700] Report: stringpool-string-count=18700
D [22/Apr/2012:10:26:00 -0700] Report: stringpool-alloc-bytes=9968
D [22/Apr/2012:10:26:00 -0700] Report: stringpool-total-bytes=345768
D [22/Apr/2012:10:26:04 -0700] cupsdReadClient: 13 GET /admin/log/error_log HTTP/1.1
D [22/Apr/2012:10:26:04 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:04 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:04 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:04 -0700] cupsdReadClient: 13 POST / HTTP/1.1
D [22/Apr/2012:10:26:04 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:04 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:04 -0700] cupsdReadClient: 13 1.1 Get-Jobs 1
D [22/Apr/2012:10:26:04 -0700] Get-Jobs ipp://localhost/printers/
D [22/Apr/2012:10:26:04 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [22/Apr/2012:10:26:04 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:04 -0700] cupsdReadClient: 13 POST / HTTP/1.1
D [22/Apr/2012:10:26:04 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:04 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:04 -0700] cupsdReadClient: 13 1.1 Get-Jobs 1
D [22/Apr/2012:10:26:04 -0700] Get-Jobs ipp://localhost/printers/
D [22/Apr/2012:10:26:04 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [22/Apr/2012:10:26:04 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:04 -0700] cupsdReadClient: 13 POST / HTTP/1.1
D [22/Apr/2012:10:26:04 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:04 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:04 -0700] cupsdReadClient: 13 1.1 Create-Printer-Subscription 1
D [22/Apr/2012:10:26:04 -0700] Create-Printer-Subscription /
D [22/Apr/2012:10:26:04 -0700] cupsdCreateSubscription(con=0x7ff756613cd0(13), uri="/")
D [22/Apr/2012:10:26:04 -0700] pullmethod="ippget"
D [22/Apr/2012:10:26:04 -0700] notify-lease-duration=86400
D [22/Apr/2012:10:26:04 -0700] notify-time-interval=0
D [22/Apr/2012:10:26:04 -0700] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")
D [22/Apr/2012:10:26:04 -0700] Added subscription 5 for server
D [22/Apr/2012:10:26:04 -0700] cupsdMarkDirty(-----S)
D [22/Apr/2012:10:26:04 -0700] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [22/Apr/2012:10:26:04 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:05 -0700] cupsdReadClient: 13 POST / HTTP/1.1
D [22/Apr/2012:10:26:05 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:05 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:05 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1
D [22/Apr/2012:10:26:05 -0700] Get-Notifications /
D [22/Apr/2012:10:26:05 -0700] cupsdIsAuthorized: requesting-user-name="ljohnson"
D [22/Apr/2012:10:26:05 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/Apr/2012:10:26:05 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:12 -0700] cupsdAcceptClient: 14 from localhost (Domain)
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 14 POST /printers/MG5300LAN HTTP/1.1
D [22/Apr/2012:10:26:12 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:12 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 14 1.1 Print-Job 1
D [22/Apr/2012:10:26:12 -0700] Print-Job ipp://localhost/printers/MG5300LAN
D [22/Apr/2012:10:26:12 -0700] [Job ???] Auto-typing file...
I [22/Apr/2012:10:26:12 -0700] [Job ???] Request file type is application/vnd.cups-banner.
D [22/Apr/2012:10:26:12 -0700] cupsdMarkDirty(----J-)
D [22/Apr/2012:10:26:12 -0700] add_job: requesting-user-name="ljohnson"
D [22/Apr/2012:10:26:12 -0700] Adding default job-sheets values "none,none"...
I [22/Apr/2012:10:26:12 -0700] [Job 2] Adding start banner page "none".
D [22/Apr/2012:10:26:12 -0700] cupsdMarkDirty(-----S)
D [22/Apr/2012:10:26:12 -0700] cupsdMarkDirty(----J-)
I [22/Apr/2012:10:26:12 -0700] [Job 2] Adding end banner page "none".
I [22/Apr/2012:10:26:12 -0700] [Job 2] File of type application/vnd.cups-banner queued by "ljohnson".
D [22/Apr/2012:10:26:12 -0700] [Job 2] hold_until=0
I [22/Apr/2012:10:26:12 -0700] [Job 2] Queued on "MG5300LAN" by "ljohnson".
D [22/Apr/2012:10:26:12 -0700] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/MG5300LAN) from localhost
D [22/Apr/2012:10:26:12 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:12 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 16 POST / HTTP/1.1
D [22/Apr/2012:10:26:12 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:12 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 16 1.1 Get-Jobs 1
D [22/Apr/2012:10:26:12 -0700] Get-Jobs ipp://localhost/printers/
D [22/Apr/2012:10:26:12 -0700] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [22/Apr/2012:10:26:12 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:12 -0700] cupsdAcceptClient: 17 from localhost (Domain)
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [22/Apr/2012:10:26:12 -0700] cupsdCloseClient: 16
D [22/Apr/2012:10:26:12 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 17 POST / HTTP/1.1
D [22/Apr/2012:10:26:12 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:12 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 16 POST / HTTP/1.1
D [22/Apr/2012:10:26:12 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 17 1.1 Get-Notifications 1
D [22/Apr/2012:10:26:12 -0700] Get-Notifications /
D [22/Apr/2012:10:26:12 -0700] cupsdIsAuthorized: requesting-user-name="ljohnson"
D [22/Apr/2012:10:26:12 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 16 1.1 Get-Notifications 1
D [22/Apr/2012:10:26:12 -0700] Get-Notifications /
D [22/Apr/2012:10:26:12 -0700] cupsdIsAuthorized: requesting-user-name="ljohnson"
D [22/Apr/2012:10:26:12 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/Apr/2012:10:26:12 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 17 WAITING Closing on EOF
D [22/Apr/2012:10:26:12 -0700] cupsdCloseClient: 17
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 16 POST / HTTP/1.1
D [22/Apr/2012:10:26:12 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:12 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 13 POST / HTTP/1.1
D [22/Apr/2012:10:26:12 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 16 1.1 Get-Job-Attributes 1
D [22/Apr/2012:10:26:12 -0700] Get-Job-Attributes ipp://localhost/jobs/2
D [22/Apr/2012:10:26:12 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/2) from localhost
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 13 1.1 Get-Notifications 1
D [22/Apr/2012:10:26:12 -0700] Get-Notifications /
D [22/Apr/2012:10:26:12 -0700] cupsdIsAuthorized: requesting-user-name="ljohnson"
D [22/Apr/2012:10:26:12 -0700] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [22/Apr/2012:10:26:12 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:12 -0700] cupsdReadClient: 16 WAITING Closing on EOF
D [22/Apr/2012:10:26:12 -0700] cupsdCloseClient: 16
D [22/Apr/2012:10:26:23 -0700] cupsdAcceptClient: 16 from localhost (Domain)
D [22/Apr/2012:10:26:23 -0700] cupsdReadClient: 16 POST / HTTP/1.1
D [22/Apr/2012:10:26:23 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:23 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:23 -0700] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [22/Apr/2012:10:26:23 -0700] CUPS-Get-Printers
D [22/Apr/2012:10:26:23 -0700] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [22/Apr/2012:10:26:23 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:23 -0700] cupsdReadClient: 16 POST / HTTP/1.1
D [22/Apr/2012:10:26:23 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:23 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:23 -0700] cupsdReadClient: 16 1.1 CUPS-Get-Default 1
D [22/Apr/2012:10:26:23 -0700] CUPS-Get-Default
D [22/Apr/2012:10:26:23 -0700] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [22/Apr/2012:10:26:23 -0700] cupsdSetBusyState: Printing jobs and dirty files
I [22/Apr/2012:10:26:30 -0700] Generating printcap /var/run/cups/printcap...
I [22/Apr/2012:10:26:30 -0700] Saving job cache file "/var/cache/cups/job.cache"...
I [22/Apr/2012:10:26:30 -0700] Saving subscriptions.conf...
D [22/Apr/2012:10:26:30 -0700] cupsdSetBusyState: Printing jobs
I [22/Apr/2012:10:26:30 -0700] [Job 1] printer not responding; will retry in 30 seconds...
D [22/Apr/2012:10:26:30 -0700] cupsdMarkDirty(-----S)
D [22/Apr/2012:10:26:30 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:31 -0700] cupsdNetIFUpdate: "lo" = localhost:631
D [22/Apr/2012:10:26:31 -0700] cupsdNetIFUpdate: "eth0" = 192.168.0.5:631
D [22/Apr/2012:10:26:31 -0700] cupsdNetIFUpdate: "lo" = localhost:631
D [22/Apr/2012:10:26:31 -0700] cupsdNetIFUpdate: "eth0" = fe80::21f:bcff:fe07:bd61%eth0:631
D [22/Apr/2012:10:26:43 -0700] cupsdReadClient: 13 POST / HTTP/1.1
D [22/Apr/2012:10:26:43 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:43 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:43 -0700] cupsdReadClient: 13 1.1 Get-Job-Attributes 1
D [22/Apr/2012:10:26:43 -0700] Get-Job-Attributes ipp://localhost/jobs/2
D [22/Apr/2012:10:26:43 -0700] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/2) from localhost
D [22/Apr/2012:10:26:43 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:43 -0700] cupsdReadClient: 13 POST / HTTP/1.1
D [22/Apr/2012:10:26:43 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:43 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:43 -0700] cupsdReadClient: 13 1.1 Cancel-Subscription 1
D [22/Apr/2012:10:26:43 -0700] Cancel-Subscription /
D [22/Apr/2012:10:26:43 -0700] cupsdIsAuthorized: requesting-user-name="ljohnson"
D [22/Apr/2012:10:26:43 -0700] cupsdMarkDirty(-----S)
D [22/Apr/2012:10:26:43 -0700] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
D [22/Apr/2012:10:26:43 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:43 -0700] cupsdReadClient: 13 PUT /admin/conf/cupsd.conf HTTP/1.1
D [22/Apr/2012:10:26:43 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:43 -0700] cupsdAuthorize: No authentication data provided.
D [22/Apr/2012:10:26:43 -0700] cupsdIsAuthorized: username=""
D [22/Apr/2012:10:26:43 -0700] cupsdSendHeader: 13 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [22/Apr/2012:10:26:43 -0700] cupsdCloseClient: 13
D [22/Apr/2012:10:26:43 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:43 -0700] cupsdAcceptClient: 13 from localhost (Domain)
D [22/Apr/2012:10:26:43 -0700] cupsdReadClient: 13 PUT /admin/conf/cupsd.conf HTTP/1.1
D [22/Apr/2012:10:26:43 -0700] cupsdSetBusyState: Active clients, printing jobs, and dirty files
D [22/Apr/2012:10:26:43 -0700] cupsdAuthorize: Authorized as ljohnson using PeerCred
D [22/Apr/2012:10:26:43 -0700] cupsdIsAuthorized: username="ljohnson"
I [22/Apr/2012:10:26:43 -0700] Installing config file "/etc/cups/cupsd.conf"...
D [22/Apr/2012:10:26:43 -0700] cupsdSetBusyState: Printing jobs and dirty files
D [22/Apr/2012:10:26:43 -0700] cupsdCloseClient: 14
D [22/Apr/2012:10:26:43 -0700] cupsdCloseClient: 16
D [22/Apr/2012:10:26:43 -0700] cupsdCloseClient: 13
D [22/Apr/2012:10:26:43 -0700] cupsdDeregisterPrinter(p=0x7ff756595700(MG5300LAN), removeit=1)
D [22/Apr/2012:10:26:43 -0700] cupsdDeregisterPrinter(p=0x7ff75669a1f0(MG5300USB), removeit=1)
I [22/Apr/2012:10:26:43 -0700] Saving subscriptions.conf...
D [22/Apr/2012:10:26:43 -0700] cupsdSetBusyState: Printing jobs
E [22/Apr/2012:10:31:49 -0700] [Job 3] No %%BoundingBox: comment in header!
E [22/Apr/2012:10:33:26 -0700] [Job 1] Unable to queue job for destination "MG5300LAN"!
E [22/Apr/2012:10:33:26 -0700] [Job 2] Unable to queue job for destination "MG5300LAN"!
E [22/Apr/2012:10:34:09 -0700] [Job 2] Unable to open job control file "/var/spool/cups/c00002" - No such file or directory!