I [27/Dec/2008:07:38:07 +0800] Listening to :::631 (IPv6)
I [27/Dec/2008:07:38:07 +0800] Listening to 0.0.0.0:631 (IPv4)
I [27/Dec/2008:07:38:07 +0800] Listening to /var/run/cups/cups.sock (Domain)
I [27/Dec/2008:07:38:07 +0800] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Dec/2008:07:38:07 +0800] Using default TempDir of /var/spool/cups/tmp...
I [27/Dec/2008:07:38:07 +0800] Configured for up to 100 clients.
I [27/Dec/2008:07:38:07 +0800] Allowing up to 100 client connections per host.
I [27/Dec/2008:07:38:07 +0800] Using policy "default" as the default!
I [27/Dec/2008:07:38:07 +0800] Full reload is required.
I [27/Dec/2008:07:38:07 +0800] Saving job cache file "/var/cache/cups/job.cache"...
I [27/Dec/2008:07:38:07 +0800] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 38 types, 66 filters...
I [27/Dec/2008:07:38:07 +0800] Loading job cache file "/var/cache/cups/job.cache"...
I [27/Dec/2008:07:38:07 +0800] Full reload complete.
I [27/Dec/2008:07:38:07 +0800] Listening to :::631 on fd 3...
I [27/Dec/2008:07:38:07 +0800] Listening to 0.0.0.0:631 on fd 4...
I [27/Dec/2008:07:38:07 +0800] Listening to /var/run/cups/cups.sock on fd 5...
I [27/Dec/2008:07:38:07 +0800] Resuming new connection processing...
I [27/Dec/2008:08:46:50 +0800] Saving subscriptions.conf...
I [27/Dec/2008:08:49:35 +0800] Installing config file "/etc/cups/cupsd.conf"...
I [27/Dec/2008:08:49:35 +0800] Listening to :::631 (IPv6)
I [27/Dec/2008:08:49:35 +0800] Listening to 0.0.0.0:631 (IPv4)
I [27/Dec/2008:08:49:35 +0800] Listening to /var/run/cups/cups.sock (Domain)
I [27/Dec/2008:08:49:35 +0800] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Dec/2008:08:49:35 +0800] Using default TempDir of /var/spool/cups/tmp...
I [27/Dec/2008:08:49:35 +0800] Configured for up to 100 clients.
I [27/Dec/2008:08:49:35 +0800] Allowing up to 100 client connections per host.
I [27/Dec/2008:08:49:35 +0800] Using policy "default" as the default!
I [27/Dec/2008:08:49:35 +0800] Partial reload complete.
I [27/Dec/2008:08:49:35 +0800] Listening to :::631 on fd 2...
I [27/Dec/2008:08:49:35 +0800] Listening to 0.0.0.0:631 on fd 4...
I [27/Dec/2008:08:49:35 +0800] Listening to /var/run/cups/cups.sock on fd 5...
I [27/Dec/2008:08:49:35 +0800] Resuming new connection processing...
D [27/Dec/2008:08:49:35 +0800] Discarding unused server-restarted event...
D [27/Dec/2008:08:49:36 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:49:36 +0800] Report: clients=1
D [27/Dec/2008:08:49:36 +0800] Report: jobs=80
D [27/Dec/2008:08:49:36 +0800] Report: jobs-active=0
D [27/Dec/2008:08:49:36 +0800] Report: printers=1
D [27/Dec/2008:08:49:36 +0800] Report: printers-implicit=0
D [27/Dec/2008:08:49:36 +0800] Report: stringpool-string-count=484
D [27/Dec/2008:08:49:36 +0800] Report: stringpool-alloc-bytes=7312
D [27/Dec/2008:08:49:36 +0800] Report: stringpool-total-bytes=9248
D [27/Dec/2008:08:49:51 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:49:51 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:49:51 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:49:51 +0800] Get-Notifications /
D [27/Dec/2008:08:49:51 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:49:51 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:49:51 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:50:51 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:50:51 +0800] Report: clients=2
D [27/Dec/2008:08:50:51 +0800] Report: jobs=80
D [27/Dec/2008:08:50:51 +0800] Report: jobs-active=0
D [27/Dec/2008:08:50:51 +0800] Report: printers=1
D [27/Dec/2008:08:50:51 +0800] Report: printers-implicit=0
D [27/Dec/2008:08:50:51 +0800] Report: stringpool-string-count=484
D [27/Dec/2008:08:50:51 +0800] Report: stringpool-alloc-bytes=7312
D [27/Dec/2008:08:50:51 +0800] Report: stringpool-total-bytes=9248
D [27/Dec/2008:08:50:51 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:50:51 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:50:51 +0800] Get-Notifications /
D [27/Dec/2008:08:50:51 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:50:51 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:50:51 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:51:51 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:51:51 +0800] Report: clients=2
D [27/Dec/2008:08:51:51 +0800] Report: jobs=80
D [27/Dec/2008:08:51:51 +0800] Report: jobs-active=0
D [27/Dec/2008:08:51:51 +0800] Report: printers=1
D [27/Dec/2008:08:51:51 +0800] Report: printers-implicit=0
D [27/Dec/2008:08:51:51 +0800] Report: stringpool-string-count=484
D [27/Dec/2008:08:51:51 +0800] Report: stringpool-alloc-bytes=7312
D [27/Dec/2008:08:51:51 +0800] Report: stringpool-total-bytes=9248
D [27/Dec/2008:08:51:51 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:51:51 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:51:51 +0800] Get-Notifications /
D [27/Dec/2008:08:51:51 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:51:51 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:51:51 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:52:10 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:52:10 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:52:10 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:52:10 +0800] Cancel-Subscription /
D [27/Dec/2008:08:52:10 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
I [27/Dec/2008:08:52:10 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:52:10 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:52:10 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:52:10 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:26 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:26 +0800] Report: clients=1
D [27/Dec/2008:08:53:26 +0800] Report: jobs=80
D [27/Dec/2008:08:53:26 +0800] Report: jobs-active=0
D [27/Dec/2008:08:53:26 +0800] Report: printers=1
D [27/Dec/2008:08:53:26 +0800] Report: printers-implicit=0
D [27/Dec/2008:08:53:26 +0800] Report: stringpool-string-count=483
D [27/Dec/2008:08:53:26 +0800] Report: stringpool-alloc-bytes=7312
D [27/Dec/2008:08:53:26 +0800] Report: stringpool-total-bytes=9232
D [27/Dec/2008:08:53:26 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:26 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:26 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:53:26 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:53:26 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:53:26 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:53:26 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:29 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:29 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:29 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:29 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:53:29 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:53:29 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:53:29 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:53:32 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:35 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:35 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:35 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:35 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:53:35 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:53:35 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:53:35 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:53:35 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:41 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:41 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:41 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:41 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:53:41 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:53:41 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:53:41 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:53:41 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:53:41 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:41 +0800] cupsdReadClient: 10 GET /printers/MP140_series.ppd HTTP/1.1
D [27/Dec/2008:08:53:41 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:53:41 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:53:45 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:45 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:45 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:45 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:53:45 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:53:45 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:53:45 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:53:45 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:48 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:48 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:48 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:48 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:53:48 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:53:51 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:53:51 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:53:54 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:57 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:57 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:53:57 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:53:57 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:53:57 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:53:57 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:53:57 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:53:57 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:00 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:00 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:54:00 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:00 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:54:00 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:00 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:54:00 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:00 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:54:03 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:03 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:03 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:03 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:03 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:03 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:54:03 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:03 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:06 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:06 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:06 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:06 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:06 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:06 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:54:06 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:06 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:09 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:09 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:09 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:09 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:09 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:09 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:54:09 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:09 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:25 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:25 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:25 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:25 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:25 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:25 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:54:25 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:25 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:54:25 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:25 +0800] cupsdReadClient: 10 GET /printers/MP140_series.ppd HTTP/1.1
D [27/Dec/2008:08:54:25 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:25 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:54:26 +0800] Report: clients=0
D [27/Dec/2008:08:54:26 +0800] Report: jobs=80
D [27/Dec/2008:08:54:26 +0800] Report: jobs-active=0
D [27/Dec/2008:08:54:26 +0800] Report: printers=1
D [27/Dec/2008:08:54:26 +0800] Report: printers-implicit=0
D [27/Dec/2008:08:54:26 +0800] Report: stringpool-string-count=483
D [27/Dec/2008:08:54:26 +0800] Report: stringpool-alloc-bytes=7312
D [27/Dec/2008:08:54:26 +0800] Report: stringpool-total-bytes=9232
D [27/Dec/2008:08:54:28 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:28 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:28 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:28 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:28 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:28 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:54:28 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:28 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:30 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:31 +0800] cupsdReadClient: 9 POST /printers/MP140_series HTTP/1.1
D [27/Dec/2008:08:54:31 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:32 +0800] Print-Job ipp://localhost:631/printers/MP140_series
D [27/Dec/2008:08:54:32 +0800] [Job ???] Auto-typing file...
I [27/Dec/2008:08:54:32 +0800] [Job ???] Request file type is application/pdf.
D [27/Dec/2008:08:54:32 +0800] add_job: requesting-user-name="mggraham"
I [27/Dec/2008:08:54:32 +0800] [Job 144] Adding start banner page "none".
D [27/Dec/2008:08:54:32 +0800] Discarding unused job-created event...
I [27/Dec/2008:08:54:32 +0800] [Job 144] Adding end banner page "none".
I [27/Dec/2008:08:54:32 +0800] [Job 144] File of type application/pdf queued by "mggraham".
D [27/Dec/2008:08:54:32 +0800] [Job 144] hold_until=0
I [27/Dec/2008:08:54:32 +0800] [Job 144] Queued on "MP140_series" by "mggraham".
D [27/Dec/2008:08:54:32 +0800] Discarding unused printer-state-changed event...
D [27/Dec/2008:08:54:32 +0800] [Job 144] job-sheets=none,none
D [27/Dec/2008:08:54:32 +0800] [Job 144] banner_page = 0
D [27/Dec/2008:08:54:32 +0800] [Job 144] argv[0]="MP140_series"
D [27/Dec/2008:08:54:32 +0800] [Job 144] argv[1]="144"
D [27/Dec/2008:08:54:32 +0800] [Job 144] argv[2]="mggraham"
D [27/Dec/2008:08:54:32 +0800] [Job 144] argv[3]="100_0210.jpg"
D [27/Dec/2008:08:54:32 +0800] [Job 144] argv[4]="1"
D [27/Dec/2008:08:54:32 +0800] [Job 144] argv[5]="PageSize=4X6 MediaType=plain number-up=1 job-uuid=urn:uuid:70c08d7d-5fff-360a-7985-1fcf9ed28e88 fitplot page-bottom=0 page-left=0 page-top=0 page-right=0"
D [27/Dec/2008:08:54:32 +0800] [Job 144] argv[6]="/var/spool/cups/d00144-001"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[9]="SERVER_ADMIN=root@Office"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[10]="SOFTWARE=CUPS/1.3.9"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[12]="TZ=Asia/Manila"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[13]="USER=root"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[16]="IPP_PORT=631"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[17]="CHARSET=utf-8"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[18]="LANG=en_PH.UTF8"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[19]="PPD=/etc/cups/ppd/MP140_series.ppd"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[20]="RIP_MAX_CACHE=8m"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[21]="CONTENT_TYPE=application/pdf"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[22]="DEVICE_URI=usb://Canon/MP140%20series"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[23]="PRINTER=MP140_series"
D [27/Dec/2008:08:54:32 +0800] [Job 144] envp[24]="FINAL_CONTENT_TYPE=printer/MP140_series"
I [27/Dec/2008:08:54:32 +0800] [Job 144] Started filter /usr/lib/cups/filter/pdftopdf (PID 8964)
I [27/Dec/2008:08:54:32 +0800] [Job 144] Started filter /usr/lib/cups/filter/cpdftocps (PID 8965)
I [27/Dec/2008:08:54:32 +0800] [Job 144] Started filter /usr/lib/cups/filter/pstocanonij (PID 8966)
I [27/Dec/2008:08:54:32 +0800] [Job 144] Started backend /usr/lib/cups/backend/usb (PID 8967)
D [27/Dec/2008:08:54:32 +0800] Discarding unused job-state-changed event...
D [27/Dec/2008:08:54:32 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:32 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:32 +0800] [Job 144] pstocanonij start.
D [27/Dec/2008:08:54:32 +0800] Discarding unused printer-state-changed event...
D [27/Dec/2008:08:54:32 +0800] [Job 144] Printer using device file "/dev/usblp0"...
D [27/Dec/2008:08:54:32 +0800] [Job 144] backendRunLoop(print_fd=0, device_fd=5, use_bc=0, side_cb=0xb7f95a80)
D [27/Dec/2008:08:54:32 +0800] Discarding unused printer-state-changed event...
D [27/Dec/2008:08:54:32 +0800] PID 8964 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [27/Dec/2008:08:54:32 +0800] [Job 144] pdftops - copying to temp print file "/tmp/49557cc832d82"
D [27/Dec/2008:08:54:32 +0800] [Job 144] Page = 288x432; 10,14 to 278,424
D [27/Dec/2008:08:54:32 +0800] [Job 144] slow_collate=0, slow_duplex=0, slow_order=0
D [27/Dec/2008:08:54:32 +0800] [Job 144] Before copy_comments - %!PS-Adobe-3.0
D [27/Dec/2008:08:54:32 +0800] [Job 144] %!PS-Adobe-3.0
D [27/Dec/2008:08:54:32 +0800] [Job 144] %%LanguageLevel: 3
D [27/Dec/2008:08:54:32 +0800] [Job 144] %%DocumentSuppliedResources: (atend)
D [27/Dec/2008:08:54:32 +0800] [Job 144] %%DocumentMedia: plain 288 432 0 () ()
D [27/Dec/2008:08:54:32 +0800] [Job 144] %%BoundingBox: 0 0 288 432
D [27/Dec/2008:08:54:32 +0800] [Job 144] %%Pages: 1
D [27/Dec/2008:08:54:32 +0800] [Job 144] %%EndComments
D [27/Dec/2008:08:54:32 +0800] [Job 144] Before copy_prolog - %%BeginDefaults
D [27/Dec/2008:08:54:32 +0800] [Job 144] Before copy_setup - %%BeginSetup
D [27/Dec/2008:08:54:32 +0800] [Job 144] Before page loop - %%Page: 1 1
D [27/Dec/2008:08:54:32 +0800] [Job 144] Copying page 1...
D [27/Dec/2008:08:54:32 +0800] [Job 144] pagew = 268.7, pagel = 409.3
D [27/Dec/2008:08:54:32 +0800] [Job 144] bboxx = 0, bboxy = 0, bboxw = 288, bboxl = 432
D [27/Dec/2008:08:54:32 +0800] [Job 144] PageLeft = 9.6, PageRight = 278.4
D [27/Dec/2008:08:54:32 +0800] [Job 144] PageTop = 423.5, PageBottom = 14.2
D [27/Dec/2008:08:54:32 +0800] [Job 144] PageWidth = 288.0, PageLength = 432.0
D [27/Dec/2008:08:54:32 +0800] [Job 144] Wrote 1 pages...
D [27/Dec/2008:08:54:32 +0800] [Job 144] pstocanonij: /usr/bin/gs -r600 -g2400x3600 -q -dNOPROMPT -dSAFER -sDEVICE=ppmraw -sOutputFile=- -| /usr/bin/cifmp140 --imageres 600 --papersize 4X6 --media plain --paperload asf --bbox 9,14,278,424 
D [27/Dec/2008:08:54:32 +0800] PID 8965 (/usr/lib/cups/filter/cpdftocps) exited with no errors.
D [27/Dec/2008:08:54:32 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:32 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:32 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:32 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:08:54:32 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:32 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:32 +0800] [Job 144] Read 72 bytes of print data...
D [27/Dec/2008:08:54:32 +0800] [Job 144] Wrote 72 bytes of print data...
D [27/Dec/2008:08:54:32 +0800] Discarding unused printer-state-changed event...
D [27/Dec/2008:08:54:32 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:32 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:32 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:32 +0800] Create-Printer-Subscription /
D [27/Dec/2008:08:54:32 +0800] cupsdCreateSubscription(con=0xb947a448(9), uri="/")
D [27/Dec/2008:08:54:32 +0800] pullmethod="ippget"
D [27/Dec/2008:08:54:32 +0800] notify-lease-duration=86400
D [27/Dec/2008:08:54:32 +0800] notify-time-interval=0
D [27/Dec/2008:08:54:32 +0800] cupsdAddSubscription(mask=1798f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [27/Dec/2008:08:54:32 +0800] Added subscription 109 for server
I [27/Dec/2008:08:54:32 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:54:32 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:32 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:32 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:32 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:08:54:32 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:32 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:32 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:32 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:54:32 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:32 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:32 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:32 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:54:32 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:32 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:32 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:32 +0800] CUPS-Get-Classes
D [27/Dec/2008:08:54:32 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:32 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:32 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:32 +0800] CUPS-Get-Default
D [27/Dec/2008:08:54:32 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:32 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:54:32 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:54:32 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:32 +0800] Get-Printer-Attributes ipp://Office:631/printers/MP140_series
D [27/Dec/2008:08:54:32 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:32 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:54:32 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:54:32 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:54:32 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:32 +0800] Get-Job-Attributes ipp://localhost/jobs/144
D [27/Dec/2008:08:54:32 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:32 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:54:32 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:33 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:33 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:33 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:08:54:33 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:33 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:33 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:33 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:33 +0800] Get-Notifications /
D [27/Dec/2008:08:54:33 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:54:33 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:33 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:33 +0800] [Job 144] Read 146 bytes of print data...
D [27/Dec/2008:08:54:33 +0800] [Job 144] Wrote 146 bytes of print data...
D [27/Dec/2008:08:54:33 +0800] [Job 144] Read 8 bytes of print data...
D [27/Dec/2008:08:54:33 +0800] [Job 144] Wrote 8 bytes of print data...
D [27/Dec/2008:08:54:33 +0800] [Job 144] Read 14 bytes of print data...
D [27/Dec/2008:08:54:33 +0800] [Job 144] Wrote 14 bytes of print data...
D [27/Dec/2008:08:54:33 +0800] PID 8966 (/usr/lib/cups/filter/pstocanonij) exited with no errors.
D [27/Dec/2008:08:54:33 +0800] PID 8967 (/usr/lib/cups/backend/usb) exited with no errors.
D [27/Dec/2008:08:54:33 +0800] [Job 144] File 0 is complete.
I [27/Dec/2008:08:54:33 +0800] [Job 144] Completed successfully.
I [27/Dec/2008:08:54:33 +0800] Saving subscriptions.conf...
I [27/Dec/2008:08:54:33 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:54:33 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:54:33 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:54:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:33 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:08:54:33 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:33 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:54:33 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:54:33 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:54:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:54:33 +0800] Get-Notifications /
D [27/Dec/2008:08:54:33 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:54:33 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:54:33 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:55:44 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:55:44 +0800] Report: clients=1
D [27/Dec/2008:08:55:44 +0800] Report: jobs=80
D [27/Dec/2008:08:55:44 +0800] Report: jobs-active=0
D [27/Dec/2008:08:55:44 +0800] Report: printers=1
D [27/Dec/2008:08:55:44 +0800] Report: printers-implicit=0
D [27/Dec/2008:08:55:44 +0800] Report: stringpool-string-count=601
D [27/Dec/2008:08:55:44 +0800] Report: stringpool-alloc-bytes=7896
D [27/Dec/2008:08:55:44 +0800] Report: stringpool-total-bytes=11832
D [27/Dec/2008:08:55:44 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:55:44 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:55:44 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:55:44 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:55:44 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:55:44 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:55:44 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:55:44 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:55:44 +0800] cupsdReadClient: 10 GET /printers/MP140_series.ppd HTTP/1.1
D [27/Dec/2008:08:55:44 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:55:44 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:55:48 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:55:48 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:55:48 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:55:48 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:55:48 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:55:48 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:55:48 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:55:48 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:55:49 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:55:50 +0800] cupsdReadClient: 9 POST /printers/MP140_series HTTP/1.1
D [27/Dec/2008:08:55:50 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:55:51 +0800] Print-Job ipp://localhost:631/printers/MP140_series
D [27/Dec/2008:08:55:51 +0800] [Job ???] Auto-typing file...
I [27/Dec/2008:08:55:51 +0800] [Job ???] Request file type is application/pdf.
D [27/Dec/2008:08:55:51 +0800] add_job: requesting-user-name="mggraham"
I [27/Dec/2008:08:55:51 +0800] [Job 145] Adding start banner page "none".
I [27/Dec/2008:08:55:51 +0800] Saving subscriptions.conf...
I [27/Dec/2008:08:55:51 +0800] [Job 145] Adding end banner page "none".
I [27/Dec/2008:08:55:51 +0800] [Job 145] File of type application/pdf queued by "mggraham".
D [27/Dec/2008:08:55:51 +0800] [Job 145] hold_until=0
I [27/Dec/2008:08:55:51 +0800] [Job 145] Queued on "MP140_series" by "mggraham".
I [27/Dec/2008:08:55:51 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:55:51 +0800] [Job 145] job-sheets=none,none
D [27/Dec/2008:08:55:51 +0800] [Job 145] banner_page = 0
D [27/Dec/2008:08:55:51 +0800] [Job 145] argv[0]="MP140_series"
D [27/Dec/2008:08:55:51 +0800] [Job 145] argv[1]="145"
D [27/Dec/2008:08:55:51 +0800] [Job 145] argv[2]="mggraham"
D [27/Dec/2008:08:55:51 +0800] [Job 145] argv[3]="100_0210.jpg"
D [27/Dec/2008:08:55:51 +0800] [Job 145] argv[4]="1"
D [27/Dec/2008:08:55:51 +0800] [Job 145] argv[5]="PageSize=4X6 MediaType=plain number-up=1 job-uuid=urn:uuid:66053099-2073-36d6-57e6-a0274c7ff2dd fitplot page-bottom=0 page-left=0 page-top=0 page-right=0"
D [27/Dec/2008:08:55:51 +0800] [Job 145] argv[6]="/var/spool/cups/d00145-001"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[9]="SERVER_ADMIN=root@Office"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[10]="SOFTWARE=CUPS/1.3.9"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[12]="TZ=Asia/Manila"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[13]="USER=root"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[16]="IPP_PORT=631"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[17]="CHARSET=utf-8"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[18]="LANG=en_PH.UTF8"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[19]="PPD=/etc/cups/ppd/MP140_series.ppd"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[20]="RIP_MAX_CACHE=8m"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[21]="CONTENT_TYPE=application/pdf"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[22]="DEVICE_URI=usb://Canon/MP140%20series"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[23]="PRINTER=MP140_series"
D [27/Dec/2008:08:55:51 +0800] [Job 145] envp[24]="FINAL_CONTENT_TYPE=printer/MP140_series"
I [27/Dec/2008:08:55:51 +0800] [Job 145] Started filter /usr/lib/cups/filter/pdftopdf (PID 8996)
I [27/Dec/2008:08:55:51 +0800] [Job 145] Started filter /usr/lib/cups/filter/cpdftocps (PID 8997)
I [27/Dec/2008:08:55:51 +0800] [Job 145] Started filter /usr/lib/cups/filter/pstocanonij (PID 9004)
I [27/Dec/2008:08:55:51 +0800] [Job 145] Started backend /usr/lib/cups/backend/usb (PID 9005)
I [27/Dec/2008:08:55:51 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:55:51 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:55:51 +0800] PID 8996 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [27/Dec/2008:08:55:51 +0800] [Job 145] pdftops - copying to temp print file "/tmp/49557d171b2d7"
D [27/Dec/2008:08:55:51 +0800] [Job 145] pstocanonij start.
D [27/Dec/2008:08:55:51 +0800] [Job 145] Printer using device file "/dev/usblp0"...
D [27/Dec/2008:08:55:51 +0800] [Job 145] backendRunLoop(print_fd=0, device_fd=5, use_bc=0, side_cb=0xb8094a80)
I [27/Dec/2008:08:55:51 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:55:51 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:55:51 +0800] [Job 145] Page = 288x432; 10,14 to 278,424
D [27/Dec/2008:08:55:51 +0800] [Job 145] slow_collate=0, slow_duplex=0, slow_order=0
D [27/Dec/2008:08:55:51 +0800] [Job 145] Before copy_comments - %!PS-Adobe-3.0
D [27/Dec/2008:08:55:51 +0800] PID 8997 (/usr/lib/cups/filter/cpdftocps) exited with no errors.
D [27/Dec/2008:08:55:51 +0800] [Job 145] %!PS-Adobe-3.0
D [27/Dec/2008:08:55:51 +0800] [Job 145] %%LanguageLevel: 3
D [27/Dec/2008:08:55:51 +0800] [Job 145] %%DocumentSuppliedResources: (atend)
D [27/Dec/2008:08:55:51 +0800] [Job 145] %%DocumentMedia: plain 288 432 0 () ()
D [27/Dec/2008:08:55:51 +0800] [Job 145] %%BoundingBox: 0 0 288 432
D [27/Dec/2008:08:55:51 +0800] [Job 145] %%Pages: 1
D [27/Dec/2008:08:55:51 +0800] [Job 145] %%EndComments
D [27/Dec/2008:08:55:51 +0800] [Job 145] Before copy_prolog - %%BeginDefaults
D [27/Dec/2008:08:55:51 +0800] [Job 145] Before copy_setup - %%BeginSetup
D [27/Dec/2008:08:55:51 +0800] [Job 145] Before page loop - %%Page: 1 1
D [27/Dec/2008:08:55:51 +0800] [Job 145] Copying page 1...
D [27/Dec/2008:08:55:51 +0800] [Job 145] pagew = 268.7, pagel = 409.3
D [27/Dec/2008:08:55:51 +0800] [Job 145] bboxx = 0, bboxy = 0, bboxw = 288, bboxl = 432
D [27/Dec/2008:08:55:51 +0800] [Job 145] PageLeft = 9.6, PageRight = 278.4
D [27/Dec/2008:08:55:51 +0800] [Job 145] PageTop = 423.5, PageBottom = 14.2
D [27/Dec/2008:08:55:51 +0800] [Job 145] PageWidth = 288.0, PageLength = 432.0
D [27/Dec/2008:08:55:51 +0800] [Job 145] Wrote 1 pages...
D [27/Dec/2008:08:55:51 +0800] [Job 145] pstocanonij: /usr/bin/gs -r600 -g2400x3600 -q -dNOPROMPT -dSAFER -sDEVICE=ppmraw -sOutputFile=- -| /usr/bin/cifmp140 --imageres 600 --papersize 4X6 --media plain --paperload asf --bbox 9,14,278,424 
D [27/Dec/2008:08:55:51 +0800] [Job 145] Read 72 bytes of print data...
D [27/Dec/2008:08:55:51 +0800] [Job 145] Wrote 72 bytes of print data...
I [27/Dec/2008:08:55:51 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:55:51 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:55:51 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:55:51 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:55:51 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:08:55:51 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:55:51 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:55:51 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:55:51 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:55:51 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:55:51 +0800] Get-Notifications /
D [27/Dec/2008:08:55:51 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:55:51 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:55:51 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:55:51 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:55:51 +0800] Get-Job-Attributes ipp://localhost/jobs/145
D [27/Dec/2008:08:55:51 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:55:51 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:55:51 +0800] [Job 145] Read 146 bytes of print data...
D [27/Dec/2008:08:55:51 +0800] [Job 145] Wrote 146 bytes of print data...
D [27/Dec/2008:08:55:51 +0800] [Job 145] Read 8 bytes of print data...
D [27/Dec/2008:08:55:51 +0800] [Job 145] Wrote 8 bytes of print data...
D [27/Dec/2008:08:55:51 +0800] [Job 145] Read 14 bytes of print data...
D [27/Dec/2008:08:55:51 +0800] [Job 145] Wrote 14 bytes of print data...
D [27/Dec/2008:08:55:51 +0800] PID 9004 (/usr/lib/cups/filter/pstocanonij) exited with no errors.
D [27/Dec/2008:08:55:51 +0800] PID 9005 (/usr/lib/cups/backend/usb) exited with no errors.
D [27/Dec/2008:08:55:51 +0800] [Job 145] File 0 is complete.
I [27/Dec/2008:08:55:51 +0800] [Job 145] Completed successfully.
I [27/Dec/2008:08:55:51 +0800] Saving subscriptions.conf...
I [27/Dec/2008:08:55:51 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:55:52 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:55:52 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:55:52 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:55:52 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:08:55:52 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:55:52 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:55:52 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:55:52 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:55:52 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:55:52 +0800] Get-Notifications /
D [27/Dec/2008:08:55:52 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:55:52 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:55:52 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:56:30 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:56:30 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:56:30 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:56:31 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:56:31 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:56:31 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:56:31 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:56:31 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:56:31 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:56:31 +0800] cupsdReadClient: 10 GET /printers/MP140_series.ppd HTTP/1.1
D [27/Dec/2008:08:56:31 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:56:31 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:56:34 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:56:34 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:56:34 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:56:36 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:56:36 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:56:36 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:56:36 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:56:36 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:56:37 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:56:37 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:56:37 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:56:39 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:56:39 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:56:39 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:56:39 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:56:39 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:56:40 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:56:40 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:56:40 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:56:40 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:56:40 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:56:40 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:56:40 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:56:40 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:57:19 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:57:19 +0800] Report: clients=1
D [27/Dec/2008:08:57:19 +0800] Report: jobs=80
D [27/Dec/2008:08:57:19 +0800] Report: jobs-active=0
D [27/Dec/2008:08:57:19 +0800] Report: printers=1
D [27/Dec/2008:08:57:19 +0800] Report: printers-implicit=0
D [27/Dec/2008:08:57:19 +0800] Report: stringpool-string-count=764
D [27/Dec/2008:08:57:19 +0800] Report: stringpool-alloc-bytes=7976
D [27/Dec/2008:08:57:19 +0800] Report: stringpool-total-bytes=15240
D [27/Dec/2008:08:57:19 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:57:19 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:57:19 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:57:19 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:57:20 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:57:20 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:57:23 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:57:23 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:57:23 +0800] cupsdReadClient: 10 POST /printers/MP140_series HTTP/1.1
D [27/Dec/2008:08:57:23 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:57:23 +0800] Print-Job ipp://localhost:631/printers/MP140_series
D [27/Dec/2008:08:57:23 +0800] [Job ???] Auto-typing file...
I [27/Dec/2008:08:57:23 +0800] [Job ???] Request file type is application/pdf.
D [27/Dec/2008:08:57:23 +0800] add_job: requesting-user-name="mggraham"
I [27/Dec/2008:08:57:23 +0800] [Job 146] Adding start banner page "none".
I [27/Dec/2008:08:57:23 +0800] Saving subscriptions.conf...
I [27/Dec/2008:08:57:23 +0800] [Job 146] Adding end banner page "none".
I [27/Dec/2008:08:57:23 +0800] [Job 146] File of type application/pdf queued by "mggraham".
D [27/Dec/2008:08:57:23 +0800] [Job 146] hold_until=0
I [27/Dec/2008:08:57:23 +0800] [Job 146] Queued on "MP140_series" by "mggraham".
I [27/Dec/2008:08:57:23 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:57:23 +0800] [Job 146] job-sheets=none,none
D [27/Dec/2008:08:57:23 +0800] [Job 146] banner_page = 0
D [27/Dec/2008:08:57:23 +0800] [Job 146] argv[0]="MP140_series"
D [27/Dec/2008:08:57:23 +0800] [Job 146] argv[1]="146"
D [27/Dec/2008:08:57:23 +0800] [Job 146] argv[2]="mggraham"
D [27/Dec/2008:08:57:23 +0800] [Job 146] argv[3]="preview1D6UMU.pdf"
D [27/Dec/2008:08:57:23 +0800] [Job 146] argv[4]="1"
D [27/Dec/2008:08:57:23 +0800] [Job 146] argv[5]="PageSize=A4 MediaType=plain number-up=1 job-uuid=urn:uuid:f86c9850-1d87-3997-434e-8d05963acb6e fitplot page-bottom=0 page-left=0 page-top=0 page-right=0"
D [27/Dec/2008:08:57:23 +0800] [Job 146] argv[6]="/var/spool/cups/d00146-001"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[9]="SERVER_ADMIN=root@Office"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[10]="SOFTWARE=CUPS/1.3.9"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[12]="TZ=Asia/Manila"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[13]="USER=root"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[16]="IPP_PORT=631"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[17]="CHARSET=utf-8"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[18]="LANG=en_PH.UTF8"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[19]="PPD=/etc/cups/ppd/MP140_series.ppd"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[20]="RIP_MAX_CACHE=8m"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[21]="CONTENT_TYPE=application/pdf"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[22]="DEVICE_URI=usb://Canon/MP140%20series"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[23]="PRINTER=MP140_series"
D [27/Dec/2008:08:57:23 +0800] [Job 146] envp[24]="FINAL_CONTENT_TYPE=printer/MP140_series"
I [27/Dec/2008:08:57:23 +0800] [Job 146] Started filter /usr/lib/cups/filter/pdftopdf (PID 9033)
I [27/Dec/2008:08:57:23 +0800] [Job 146] Started filter /usr/lib/cups/filter/cpdftocps (PID 9034)
I [27/Dec/2008:08:57:23 +0800] [Job 146] Started filter /usr/lib/cups/filter/pstocanonij (PID 9036)
I [27/Dec/2008:08:57:23 +0800] [Job 146] Started backend /usr/lib/cups/backend/usb (PID 9039)
I [27/Dec/2008:08:57:23 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:57:23 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:57:23 +0800] [Job 146] pstocanonij start.
D [27/Dec/2008:08:57:23 +0800] [Job 146] Printer using device file "/dev/usblp0"...
D [27/Dec/2008:08:57:23 +0800] [Job 146] backendRunLoop(print_fd=0, device_fd=5, use_bc=0, side_cb=0xb7f41a80)
I [27/Dec/2008:08:57:23 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:57:23 +0800] [Job 146] pdftops - copying to temp print file "/tmp/49557d7323c21"
D [27/Dec/2008:08:57:23 +0800] PID 9033 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [27/Dec/2008:08:57:23 +0800] [Job 146] Page = 595x842; 10,14 to 586,833
D [27/Dec/2008:08:57:23 +0800] [Job 146] slow_collate=0, slow_duplex=0, slow_order=0
D [27/Dec/2008:08:57:23 +0800] [Job 146] Before copy_comments - %!PS-Adobe-3.0
D [27/Dec/2008:08:57:23 +0800] [Job 146] %!PS-Adobe-3.0
D [27/Dec/2008:08:57:23 +0800] [Job 146] %%LanguageLevel: 3
D [27/Dec/2008:08:57:23 +0800] [Job 146] %%DocumentSuppliedResources: (atend)
D [27/Dec/2008:08:57:23 +0800] [Job 146] %%DocumentMedia: plain 595 842 0 () ()
D [27/Dec/2008:08:57:23 +0800] [Job 146] %%BoundingBox: 0 0 595 842
D [27/Dec/2008:08:57:23 +0800] [Job 146] %%Pages: 1
D [27/Dec/2008:08:57:23 +0800] [Job 146] %%EndComments
D [27/Dec/2008:08:57:23 +0800] [Job 146] Before copy_prolog - %%BeginDefaults
D [27/Dec/2008:08:57:23 +0800] [Job 146] Before copy_setup - %%BeginSetup
D [27/Dec/2008:08:57:23 +0800] [Job 146] Before page loop - %%Page: 1 1
D [27/Dec/2008:08:57:23 +0800] [Job 146] Copying page 1...
D [27/Dec/2008:08:57:23 +0800] [Job 146] pagew = 576.0, pagel = 819.2
D [27/Dec/2008:08:57:23 +0800] [Job 146] bboxx = 0, bboxy = 0, bboxw = 595, bboxl = 842
D [27/Dec/2008:08:57:23 +0800] [Job 146] PageLeft = 9.6, PageRight = 585.6
D [27/Dec/2008:08:57:23 +0800] [Job 146] PageTop = 833.4, PageBottom = 14.2
D [27/Dec/2008:08:57:23 +0800] [Job 146] PageWidth = 595.0, PageLength = 842.0
D [27/Dec/2008:08:57:23 +0800] [Job 146] Wrote 1 pages...
D [27/Dec/2008:08:57:23 +0800] PID 9034 (/usr/lib/cups/filter/cpdftocps) exited with no errors.
D [27/Dec/2008:08:57:23 +0800] [Job 146] pstocanonij: /usr/bin/gs -r600 -g4958x7016 -q -dNOPROMPT -dSAFER -sDEVICE=ppmraw -sOutputFile=- -| /usr/bin/cifmp140 --imageres 600 --papersize a4 --media plain --paperload asf --bbox 9,14,585,834 
D [27/Dec/2008:08:57:23 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:57:23 +0800] [Job 146] Read 72 bytes of print data...
I [27/Dec/2008:08:57:23 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:57:23 +0800] [Job 146] Wrote 72 bytes of print data...
I [27/Dec/2008:08:57:23 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:57:23 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:57:23 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:57:23 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:57:23 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:08:57:23 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:57:23 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:57:23 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:57:23 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:57:23 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:57:23 +0800] Get-Notifications /
D [27/Dec/2008:08:57:23 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:57:23 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:57:23 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:57:23 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:57:23 +0800] Get-Job-Attributes ipp://localhost/jobs/146
D [27/Dec/2008:08:57:23 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:57:23 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:57:23 +0800] [Job 146] Read 146 bytes of print data...
D [27/Dec/2008:08:57:23 +0800] [Job 146] Wrote 146 bytes of print data...
D [27/Dec/2008:08:57:24 +0800] [Job 146] Read 8 bytes of print data...
D [27/Dec/2008:08:57:24 +0800] [Job 146] Wrote 8 bytes of print data...
D [27/Dec/2008:08:57:24 +0800] [Job 146] Read 14 bytes of print data...
D [27/Dec/2008:08:57:24 +0800] [Job 146] Wrote 14 bytes of print data...
D [27/Dec/2008:08:57:24 +0800] PID 9036 (/usr/lib/cups/filter/pstocanonij) exited with no errors.
D [27/Dec/2008:08:57:24 +0800] PID 9039 (/usr/lib/cups/backend/usb) exited with no errors.
D [27/Dec/2008:08:57:24 +0800] [Job 146] File 0 is complete.
I [27/Dec/2008:08:57:24 +0800] [Job 146] Completed successfully.
I [27/Dec/2008:08:57:24 +0800] Saving subscriptions.conf...
I [27/Dec/2008:08:57:24 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:57:25 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:57:25 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:57:25 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:57:25 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:08:57:25 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:57:25 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:57:25 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:57:25 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:57:25 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:57:25 +0800] Get-Notifications /
D [27/Dec/2008:08:57:25 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:57:25 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:57:25 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:58:05 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:58:05 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:58:05 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:58:05 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:58:05 +0800] Create-Printer-Subscription /
D [27/Dec/2008:08:58:05 +0800] cupsdCreateSubscription(con=0xb947dbb8(10), uri="/")
D [27/Dec/2008:08:58:05 +0800] pullmethod="ippget"
D [27/Dec/2008:08:58:05 +0800] notify-lease-duration=86400
D [27/Dec/2008:08:58:05 +0800] notify-time-interval=0
D [27/Dec/2008:08:58:05 +0800] cupsdAddSubscription(mask=18f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [27/Dec/2008:08:58:05 +0800] Added subscription 110 for server
I [27/Dec/2008:08:58:05 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:58:05 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:58:05 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:58:05 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:58:05 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:58:05 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:58:05 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:58:05 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:58:05 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:58:05 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:58:05 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:58:05 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:58:05 +0800] CUPS-Get-Classes
D [27/Dec/2008:08:58:05 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:58:05 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:58:05 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:58:05 +0800] CUPS-Get-Default
D [27/Dec/2008:08:58:05 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:58:05 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:58:05 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:58:05 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:58:05 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:58:05 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:58:05 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:58:05 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:58:05 +0800] CUPS-Get-Classes
D [27/Dec/2008:08:58:05 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:58:05 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:58:05 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:58:05 +0800] CUPS-Get-Default
D [27/Dec/2008:08:58:05 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:58:06 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:58:06 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:58:06 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:58:06 +0800] Get-Notifications /
D [27/Dec/2008:08:58:06 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:58:06 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:58:06 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:58:25 +0800] cupsdReadClient: 9 GET /admin/conf/cupsd.conf HTTP/1.1
D [27/Dec/2008:08:58:25 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:58:25 +0800] cupsdIsAuthorized: username=""
D [27/Dec/2008:08:58:25 +0800] cupsdSendError: 9 code=401 (Unauthorized)
D [27/Dec/2008:08:58:25 +0800] cupsdSendHeader: WWW-Authenticate: Basic realm="CUPS"
D [27/Dec/2008:08:58:25 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:58:25 +0800] Report: clients=0
D [27/Dec/2008:08:58:25 +0800] Report: jobs=80
D [27/Dec/2008:08:58:25 +0800] Report: jobs-active=0
D [27/Dec/2008:08:58:25 +0800] Report: printers=1
D [27/Dec/2008:08:58:25 +0800] Report: printers-implicit=0
D [27/Dec/2008:08:58:25 +0800] Report: stringpool-string-count=938
D [27/Dec/2008:08:58:25 +0800] Report: stringpool-alloc-bytes=8040
D [27/Dec/2008:08:58:25 +0800] Report: stringpool-total-bytes=18880
D [27/Dec/2008:08:58:25 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:58:25 +0800] cupsdReadClient: 9 GET /admin/conf/cupsd.conf HTTP/1.1
D [27/Dec/2008:08:58:25 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:08:58:25 +0800] cupsdIsAuthorized: username="root"
D [27/Dec/2008:08:58:47 +0800] cupsdReadClient: 9 PUT /admin/conf/cupsd.conf HTTP/1.1
D [27/Dec/2008:08:58:47 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:08:58:47 +0800] cupsdIsAuthorized: username="root"
I [27/Dec/2008:08:58:47 +0800] Installing config file "/etc/cups/cupsd.conf"...
D [27/Dec/2008:08:58:47 +0800] cupsdSendError: 9 code=201 (Created)
D [27/Dec/2008:08:58:47 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:58:47 +0800] Saving remote.cache...
I [27/Dec/2008:08:58:47 +0800] Listening to :::631 (IPv6)
I [27/Dec/2008:08:58:47 +0800] Listening to 0.0.0.0:631 (IPv4)
I [27/Dec/2008:08:58:47 +0800] Listening to /var/run/cups/cups.sock (Domain)
I [27/Dec/2008:08:58:47 +0800] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Dec/2008:08:58:47 +0800] Using default TempDir of /var/spool/cups/tmp...
I [27/Dec/2008:08:58:47 +0800] Configured for up to 100 clients.
I [27/Dec/2008:08:58:47 +0800] Allowing up to 100 client connections per host.
I [27/Dec/2008:08:58:47 +0800] Using policy "default" as the default!
I [27/Dec/2008:08:58:47 +0800] Partial reload complete.
I [27/Dec/2008:08:58:47 +0800] Listening to :::631 on fd 2...
I [27/Dec/2008:08:58:47 +0800] Listening to 0.0.0.0:631 on fd 4...
I [27/Dec/2008:08:58:47 +0800] Listening to /var/run/cups/cups.sock on fd 5...
I [27/Dec/2008:08:58:47 +0800] Resuming new connection processing...
D [27/Dec/2008:08:58:47 +0800] Discarding unused server-restarted event...
D [27/Dec/2008:08:58:48 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:59:06 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:59:06 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:59:06 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:06 +0800] Get-Notifications /
D [27/Dec/2008:08:59:06 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:59:06 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:06 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:59:08 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:59:08 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:59:08 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:59:08 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:59:08 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:08 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:59:08 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:08 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:59:08 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:08 +0800] CUPS-Get-Classes
D [27/Dec/2008:08:59:08 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:08 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:59:08 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:08 +0800] CUPS-Get-Default
D [27/Dec/2008:08:59:08 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:13 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:59:13 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:13 +0800] Get-Printer-Attributes ipp://localhost/printers/MP140_series
D [27/Dec/2008:08:59:13 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:13 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:59:13 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:13 +0800] Get-Printer-Attributes ipp://localhost/printers/MP140_series
D [27/Dec/2008:08:59:13 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:13 +0800] cupsdReadClient: 10 GET /printers/MP140_series.ppd HTTP/1.1
D [27/Dec/2008:08:59:13 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:37 +0800] cupsdReadClient: 10 POST /admin/ HTTP/1.1
D [27/Dec/2008:08:59:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:37 +0800] CUPS-Add-Modify-Printer ipp://localhost/printers/MP140_series
D [27/Dec/2008:08:59:37 +0800] cupsdIsAuthorized: username=""
E [27/Dec/2008:08:59:37 +0800] CUPS-Add-Modify-Printer: Unauthorized
D [27/Dec/2008:08:59:37 +0800] cupsdSendError: 10 code=401 (Unauthorized)
D [27/Dec/2008:08:59:37 +0800] cupsdSendHeader: WWW-Authenticate: Basic realm="CUPS"
D [27/Dec/2008:08:59:37 +0800] Report: clients=2
D [27/Dec/2008:08:59:37 +0800] Report: jobs=80
D [27/Dec/2008:08:59:37 +0800] Report: jobs-active=0
D [27/Dec/2008:08:59:37 +0800] Report: printers=1
D [27/Dec/2008:08:59:37 +0800] Report: printers-implicit=0
D [27/Dec/2008:08:59:37 +0800] Report: stringpool-string-count=944
D [27/Dec/2008:08:59:37 +0800] Report: stringpool-alloc-bytes=8200
D [27/Dec/2008:08:59:37 +0800] Report: stringpool-total-bytes=19008
D [27/Dec/2008:08:59:37 +0800] cupsdAcceptClient: 12 from localhost (Domain)
D [27/Dec/2008:08:59:37 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:59:37 +0800] cupsdReadClient: 12 POST /admin/ HTTP/1.1
D [27/Dec/2008:08:59:37 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:08:59:37 +0800] CUPS-Add-Modify-Printer ipp://localhost/printers/MP140_series
D [27/Dec/2008:08:59:37 +0800] cupsdIsAuthorized: username="root"
D [27/Dec/2008:08:59:37 +0800] Copied PPD file successfully!
I [27/Dec/2008:08:59:37 +0800] Saving printers.conf...
D [27/Dec/2008:08:59:37 +0800] Discarding unused printer-modified event...
I [27/Dec/2008:08:59:37 +0800] Printer "MP140_series" modified by "root".
D [27/Dec/2008:08:59:37 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:37 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:08:59:37 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:08:59:37 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:59:37 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:37 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:08:59:37 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:08:59:37 +0800] CUPS-Get-Classes
D [27/Dec/2008:08:59:37 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:37 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:08:59:37 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:08:59:37 +0800] Get-Printer-Attributes ipp://localhost/printers/MP140_series
D [27/Dec/2008:08:59:37 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:37 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:08:59:37 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:08:59:37 +0800] Get-Printer-Attributes ipp://localhost/printers/MP140_series
D [27/Dec/2008:08:59:37 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:37 +0800] cupsdReadClient: 12 GET /printers/MP140_series.ppd HTTP/1.1
D [27/Dec/2008:08:59:37 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:08:59:37 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:59:37 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:59:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:37 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:08:59:37 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:37 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:59:37 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:59:37 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:59:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:37 +0800] Get-Notifications /
D [27/Dec/2008:08:59:37 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:59:37 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:37 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:59:37 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:59:37 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:59:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:37 +0800] Get-Notifications /
D [27/Dec/2008:08:59:37 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:08:59:37 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:37 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:59:38 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:08:59:38 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:08:59:38 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:59:38 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:38 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:08:59:38 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:08:59:38 +0800] CUPS-Get-Classes
D [27/Dec/2008:08:59:38 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:40 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:08:59:40 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:08:59:40 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:40 +0800] Cancel-Subscription /
D [27/Dec/2008:08:59:40 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
I [27/Dec/2008:08:59:40 +0800] Saving subscriptions.conf...
D [27/Dec/2008:08:59:40 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:40 +0800] cupsdCloseClient: 10
D [27/Dec/2008:08:59:40 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:59:40 +0800] cupsdCloseClient: 12
D [27/Dec/2008:08:59:59 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:59:59 +0800] cupsdCloseClient: 9
D [27/Dec/2008:08:59:59 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:08:59:59 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:08:59:59 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:08:59:59 +0800] CUPS-Get-Printers
D [27/Dec/2008:08:59:59 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:08:59:59 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:03 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:03 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:03 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:03 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:00:03 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:03 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:00:03 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:03 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:08 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:08 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:08 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:09 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:00:09 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:09 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:00:09 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:09 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:00:09 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:09 +0800] cupsdReadClient: 10 GET /printers/MP140_series.ppd HTTP/1.1
D [27/Dec/2008:09:00:09 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:09 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:00:12 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:12 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:00:12 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:14 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:00:14 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:14 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:00:14 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:14 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:00:15 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:15 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:15 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:15 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:00:15 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:15 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:00:15 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:15 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:20 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:20 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:20 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:20 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:00:20 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:20 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:00:20 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:20 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:23 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:23 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:23 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:23 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:00:23 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:23 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:00:23 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:23 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:23 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:23 +0800] cupsdReadClient: 9 GET /printers/MP140_series.ppd HTTP/1.1
D [27/Dec/2008:09:00:23 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:23 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:26 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:26 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:00:26 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:26 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:00:26 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:26 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:00:26 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:26 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:00:33 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:33 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:33 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:33 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:00:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:33 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:00:33 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:33 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:00:33 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:33 +0800] cupsdReadClient: 10 GET /printers/MP140_series.ppd HTTP/1.1
D [27/Dec/2008:09:00:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:34 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:00:41 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:41 +0800] Report: clients=1
D [27/Dec/2008:09:00:41 +0800] Report: jobs=80
D [27/Dec/2008:09:00:41 +0800] Report: jobs-active=0
D [27/Dec/2008:09:00:41 +0800] Report: printers=1
D [27/Dec/2008:09:00:41 +0800] Report: printers-implicit=0
D [27/Dec/2008:09:00:41 +0800] Report: stringpool-string-count=937
D [27/Dec/2008:09:00:41 +0800] Report: stringpool-alloc-bytes=8040
D [27/Dec/2008:09:00:41 +0800] Report: stringpool-total-bytes=18864
D [27/Dec/2008:09:00:42 +0800] cupsdReadClient: 9 POST /printers/MP140_series HTTP/1.1
D [27/Dec/2008:09:00:42 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:43 +0800] Print-Job ipp://localhost:631/printers/MP140_series
D [27/Dec/2008:09:00:43 +0800] [Job ???] Auto-typing file...
I [27/Dec/2008:09:00:43 +0800] [Job ???] Request file type is application/pdf.
D [27/Dec/2008:09:00:43 +0800] add_job: requesting-user-name="mggraham"
I [27/Dec/2008:09:00:43 +0800] [Job 147] Adding start banner page "none".
I [27/Dec/2008:09:00:43 +0800] Saving subscriptions.conf...
I [27/Dec/2008:09:00:43 +0800] [Job 147] Adding end banner page "none".
I [27/Dec/2008:09:00:43 +0800] [Job 147] File of type application/pdf queued by "mggraham".
D [27/Dec/2008:09:00:43 +0800] [Job 147] hold_until=0
I [27/Dec/2008:09:00:43 +0800] [Job 147] Queued on "MP140_series" by "mggraham".
I [27/Dec/2008:09:00:43 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:00:43 +0800] [Job 147] job-sheets=none,none
D [27/Dec/2008:09:00:43 +0800] [Job 147] banner_page = 0
D [27/Dec/2008:09:00:43 +0800] [Job 147] argv[0]="MP140_series"
D [27/Dec/2008:09:00:43 +0800] [Job 147] argv[1]="147"
D [27/Dec/2008:09:00:43 +0800] [Job 147] argv[2]="mggraham"
D [27/Dec/2008:09:00:43 +0800] [Job 147] argv[3]="100_0210.jpg"
D [27/Dec/2008:09:00:43 +0800] [Job 147] argv[4]="1"
D [27/Dec/2008:09:00:43 +0800] [Job 147] argv[5]="PageSize=4X6 MediaType=plain number-up=1 job-uuid=urn:uuid:3fde2f14-9212-3289-730c-69b4ac855281 fitplot page-bottom=0 page-left=0 page-top=0 page-right=0"
D [27/Dec/2008:09:00:43 +0800] [Job 147] argv[6]="/var/spool/cups/d00147-001"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[9]="SERVER_ADMIN=root@Office"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[10]="SOFTWARE=CUPS/1.3.9"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[12]="TZ=Asia/Manila"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[13]="USER=root"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[16]="IPP_PORT=631"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[17]="CHARSET=utf-8"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[18]="LANG=en_PH.UTF8"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[19]="PPD=/etc/cups/ppd/MP140_series.ppd"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[20]="RIP_MAX_CACHE=8m"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[21]="CONTENT_TYPE=application/pdf"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[22]="DEVICE_URI=usb://Canon/MP140%20series"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[23]="PRINTER=MP140_series"
D [27/Dec/2008:09:00:43 +0800] [Job 147] envp[24]="FINAL_CONTENT_TYPE=printer/MP140_series"
I [27/Dec/2008:09:00:43 +0800] [Job 147] Started filter /usr/lib/cups/filter/pdftopdf (PID 9184)
I [27/Dec/2008:09:00:43 +0800] [Job 147] Started filter /usr/lib/cups/filter/cpdftocps (PID 9185)
I [27/Dec/2008:09:00:43 +0800] [Job 147] Started filter /usr/lib/cups/filter/pstocanonij (PID 9187)
I [27/Dec/2008:09:00:43 +0800] [Job 147] Started backend /usr/lib/cups/backend/usb (PID 9188)
I [27/Dec/2008:09:00:43 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:00:43 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:43 +0800] [Job 147] pstocanonij start.
D [27/Dec/2008:09:00:43 +0800] [Job 147] Printer using device file "/dev/usblp0"...
D [27/Dec/2008:09:00:43 +0800] [Job 147] backendRunLoop(print_fd=0, device_fd=5, use_bc=0, side_cb=0xb8081a80)
I [27/Dec/2008:09:00:43 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:00:43 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:43 +0800] [Job 147] pdftops - copying to temp print file "/tmp/49557e3b604c0"
D [27/Dec/2008:09:00:43 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:43 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:00:43 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:43 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:09:00:43 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:43 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:00:43 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:43 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:00:43 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:43 +0800] Get-Notifications /
D [27/Dec/2008:09:00:43 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:00:43 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:43 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:00:43 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:43 +0800] Get-Job-Attributes ipp://localhost/jobs/147
D [27/Dec/2008:09:00:43 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:43 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:00:46 +0800] PID 9184 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [27/Dec/2008:09:00:46 +0800] [Job 147] Page = 288x432; 10,14 to 278,424
D [27/Dec/2008:09:00:46 +0800] [Job 147] slow_collate=0, slow_duplex=0, slow_order=0
D [27/Dec/2008:09:00:46 +0800] [Job 147] Before copy_comments - %!PS-Adobe-3.0
D [27/Dec/2008:09:00:46 +0800] [Job 147] %!PS-Adobe-3.0
D [27/Dec/2008:09:00:46 +0800] [Job 147] %%LanguageLevel: 3
D [27/Dec/2008:09:00:46 +0800] [Job 147] %%DocumentSuppliedResources: (atend)
D [27/Dec/2008:09:00:46 +0800] [Job 147] %%DocumentMedia: plain 288 432 0 () ()
D [27/Dec/2008:09:00:46 +0800] [Job 147] %%BoundingBox: 0 0 288 432
D [27/Dec/2008:09:00:46 +0800] [Job 147] %%Pages: 1
D [27/Dec/2008:09:00:46 +0800] [Job 147] %%EndComments
D [27/Dec/2008:09:00:46 +0800] [Job 147] Before copy_prolog - %%BeginDefaults
D [27/Dec/2008:09:00:47 +0800] [Job 147] Before copy_setup - %%BeginSetup
D [27/Dec/2008:09:00:52 +0800] [Job 147] Before page loop - %%Page: 1 1
D [27/Dec/2008:09:00:52 +0800] [Job 147] Copying page 1...
D [27/Dec/2008:09:00:52 +0800] [Job 147] pagew = 268.7, pagel = 409.3
D [27/Dec/2008:09:00:52 +0800] [Job 147] bboxx = 0, bboxy = 0, bboxw = 288, bboxl = 432
D [27/Dec/2008:09:00:52 +0800] [Job 147] PageLeft = 9.6, PageRight = 278.4
D [27/Dec/2008:09:00:52 +0800] [Job 147] PageTop = 423.5, PageBottom = 14.2
D [27/Dec/2008:09:00:52 +0800] [Job 147] PageWidth = 288.0, PageLength = 432.0
D [27/Dec/2008:09:00:52 +0800] [Job 147] pstocanonij: /usr/bin/gs -r600 -g2400x3600 -q -dNOPROMPT -dSAFER -sDEVICE=ppmraw -sOutputFile=- -| /usr/bin/cifmp140 --imageres 600 --papersize 4X6 --media plain --paperload asf --bbox 9,14,278,424 
D [27/Dec/2008:09:00:52 +0800] [Job 147] Wrote 1 pages...
D [27/Dec/2008:09:00:52 +0800] PID 9185 (/usr/lib/cups/filter/cpdftocps) exited with no errors.
D [27/Dec/2008:09:00:52 +0800] [Job 147] Read 72 bytes of print data...
D [27/Dec/2008:09:00:52 +0800] [Job 147] Wrote 72 bytes of print data...
I [27/Dec/2008:09:00:52 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:00:52 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:52 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:00:52 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:52 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:09:00:52 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:52 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:52 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:52 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:00:52 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:52 +0800] Get-Notifications /
D [27/Dec/2008:09:00:52 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:00:52 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:52 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:52 +0800] [Job 147] CIF COMMAND ERROR :file access Error!
D [27/Dec/2008:09:00:52 +0800] [Job 147] not support this image format
D [27/Dec/2008:09:00:52 +0800] [Job 147] Read 14 bytes of print data...
D [27/Dec/2008:09:00:52 +0800] [Job 147] Wrote 14 bytes of print data...
D [27/Dec/2008:09:00:52 +0800] [Job 147] GPL Ghostscript 8.63: Unrecoverable error, exit code 1
E [27/Dec/2008:09:00:52 +0800] [Job 147] pstocanonij write error,32.
I [27/Dec/2008:09:00:52 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:00:52 +0800] PID 9188 (/usr/lib/cups/backend/usb) exited with no errors.
E [27/Dec/2008:09:00:52 +0800] PID 9187 (/usr/lib/cups/filter/pstocanonij) stopped with status 1!
D [27/Dec/2008:09:00:52 +0800] [Job 147] File 0 is complete.
E [27/Dec/2008:09:00:52 +0800] [Job 147] Job stopped due to filter errors.
I [27/Dec/2008:09:00:52 +0800] Saving subscriptions.conf...
I [27/Dec/2008:09:00:52 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:00:52 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:00:52 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:00:52 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:52 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:09:00:52 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:52 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:00:52 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:00:52 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:00:52 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:00:52 +0800] Get-Notifications /
D [27/Dec/2008:09:00:52 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:00:52 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:00:52 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:00:54 +0800] [Job 147] Unloading...
D [27/Dec/2008:09:01:13 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:01:13 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:13 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:13 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:13 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:13 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:13 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:01:13 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:13 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:13 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:13 +0800] Get-Printer-Attributes ipp://localhost/printers/MP140_series
D [27/Dec/2008:09:01:13 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:13 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:13 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:13 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:13 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:13 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:01:13 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:13 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:13 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:13 +0800] CUPS-Get-Classes
D [27/Dec/2008:09:01:13 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:13 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:13 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:13 +0800] CUPS-Get-Default
D [27/Dec/2008:09:01:13 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:13 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:13 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:13 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:01:13 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:13 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:18 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:18 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:18 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:18 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:01:18 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:18 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:18 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:18 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:18 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:18 +0800] Get-Printer-Attributes ipp://localhost/printers/MP140_series
D [27/Dec/2008:09:01:18 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:18 +0800] cupsdReadClient: 10 GET /printers/MP140_series.ppd HTTP/1.1
D [27/Dec/2008:09:01:18 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:18 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:18 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:18 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:18 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:18 +0800] CUPS-Get-Devices
D [27/Dec/2008:09:01:18 +0800] [CGI] /usr/lib/cups/daemon/cups-deviced started - PID = 9206
I [27/Dec/2008:09:01:18 +0800] Started "/usr/lib/cups/daemon/cups-deviced" (pid=9206)
D [27/Dec/2008:09:01:18 +0800] cupsdSendCommand: 10 file=12
D [27/Dec/2008:09:01:19 +0800] cupsdAcceptClient: 13 from localhost (Domain)
D [27/Dec/2008:09:01:19 +0800] cupsdReadClient: 13 POST / HTTP/1.1
D [27/Dec/2008:09:01:19 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:19 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:01:19 +0800] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:19 +0800] cupsdReadClient: 13 POST / HTTP/1.1
D [27/Dec/2008:09:01:19 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:19 +0800] CUPS-Get-Classes
D [27/Dec/2008:09:01:19 +0800] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:19 +0800] cupsdReadClient: 13 POST / HTTP/1.1
D [27/Dec/2008:09:01:19 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:19 +0800] CUPS-Get-Default
D [27/Dec/2008:09:01:19 +0800] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:19 +0800] cupsdReadClient: 13 POST / HTTP/1.1
D [27/Dec/2008:09:01:19 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:19 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:01:19 +0800] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:19 +0800] cupsdReadClient: 13 POST / HTTP/1.1
D [27/Dec/2008:09:01:19 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:19 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:01:19 +0800] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:19 +0800] cupsdCloseClient: 13
D [27/Dec/2008:09:01:19 +0800] [cups-deviced] Added device "serial:/dev/ttyS0?baud=115200"...
D [27/Dec/2008:09:01:19 +0800] [cups-deviced] Added device "scsi"...
D [27/Dec/2008:09:01:19 +0800] [cups-deviced] Added device "ipp"...
D [27/Dec/2008:09:01:19 +0800] [cups-deviced] Added device "parallel:/dev/lp0"...
D [27/Dec/2008:09:01:19 +0800] [cups-deviced] Added device "cnij_usb:/dev/usb/lp0"...
D [27/Dec/2008:09:01:19 +0800] [cups-deviced] Added device "beh"...
D [27/Dec/2008:09:01:19 +0800] [cups-deviced] Added device "lpd"...
D [27/Dec/2008:09:01:20 +0800] [cups-deviced] Added device "smb"...
D [27/Dec/2008:09:01:20 +0800] [cups-deviced] Added device "socket"...
D [27/Dec/2008:09:01:20 +0800] [cups-deviced] Added device "hp"...
D [27/Dec/2008:09:01:21 +0800] [cups-deviced] Added device "usb://Canon/MP140%20series"...
D [27/Dec/2008:09:01:21 +0800] [cups-deviced] Added device "http"...
D [27/Dec/2008:09:01:21 +0800] [cups-deviced] Added device "hpfax"...
D [27/Dec/2008:09:01:21 +0800] [cups-deviced] Added device "hal:///org/freedesktop/Hal/devices/usb_device_4a9_172b_512BEA_if1_printer_noserial"...
D [27/Dec/2008:09:01:21 +0800] PID 9206 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
D [27/Dec/2008:09:01:21 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:21 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:21 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:21 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:21 +0800] Get-Printer-Attributes ipp://localhost/printers/MP140_series
D [27/Dec/2008:09:01:21 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:21 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:28 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:28 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:28 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:28 +0800] cupsdReadClient: 10 GET /admin/log/error_log HTTP/1.1
D [27/Dec/2008:09:01:28 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:28 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:28 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:28 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:28 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:28 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:09:01:28 +0800] [Job 147] Loading attributes...
D [27/Dec/2008:09:01:28 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:28 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:28 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:28 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:28 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:28 +0800] Create-Printer-Subscription /
D [27/Dec/2008:09:01:28 +0800] cupsdCreateSubscription(con=0xb94860c8(10), uri="/")
D [27/Dec/2008:09:01:28 +0800] pullmethod="ippget"
D [27/Dec/2008:09:01:28 +0800] notify-lease-duration=86400
D [27/Dec/2008:09:01:28 +0800] notify-time-interval=0
D [27/Dec/2008:09:01:28 +0800] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")
D [27/Dec/2008:09:01:28 +0800] Added subscription 111 for server
I [27/Dec/2008:09:01:28 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:01:28 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:28 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:29 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:29 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:29 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:29 +0800] Get-Notifications /
D [27/Dec/2008:09:01:29 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:01:29 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:29 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:37 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:37 +0800] cupsdReadClient: 10 POST /printers/MP140_series HTTP/1.1
D [27/Dec/2008:09:01:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:37 +0800] Print-Job ipp://localhost/printers/MP140_series
D [27/Dec/2008:09:01:37 +0800] add_job: requesting-user-name="mggraham"
D [27/Dec/2008:09:01:37 +0800] Adding default job-sheets values "none,none"...
I [27/Dec/2008:09:01:37 +0800] [Job 148] Adding start banner page "none".
I [27/Dec/2008:09:01:37 +0800] Saving subscriptions.conf...
I [27/Dec/2008:09:01:37 +0800] [Job 148] Adding end banner page "none".
I [27/Dec/2008:09:01:37 +0800] [Job 148] File of type application/postscript queued by "mggraham".
D [27/Dec/2008:09:01:37 +0800] [Job 148] hold_until=0
I [27/Dec/2008:09:01:37 +0800] [Job 148] Queued on "MP140_series" by "mggraham".
I [27/Dec/2008:09:01:37 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:01:37 +0800] [Job 148] job-sheets=none,none
D [27/Dec/2008:09:01:37 +0800] [Job 148] banner_page = 0
D [27/Dec/2008:09:01:37 +0800] [Job 148] argv[0]="MP140_series"
D [27/Dec/2008:09:01:37 +0800] [Job 148] argv[1]="148"
D [27/Dec/2008:09:01:37 +0800] [Job 148] argv[2]="mggraham"
D [27/Dec/2008:09:01:37 +0800] [Job 148] argv[3]="Test Page"
D [27/Dec/2008:09:01:37 +0800] [Job 148] argv[4]="1"
D [27/Dec/2008:09:01:37 +0800] [Job 148] argv[5]="job-uuid=urn:uuid:acb80830-d159-3704-7b76-bbb10da3bf5d fitplot page-bottom=0 page-left=0 page-top=0 page-right=0"
D [27/Dec/2008:09:01:37 +0800] [Job 148] argv[6]="/var/spool/cups/d00148-001"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[9]="SERVER_ADMIN=root@Office"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[10]="SOFTWARE=CUPS/1.3.9"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[12]="TZ=Asia/Manila"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[13]="USER=root"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[16]="IPP_PORT=631"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[17]="CHARSET=utf-8"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[18]="LANG=en_PH.UTF8"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[19]="PPD=/etc/cups/ppd/MP140_series.ppd"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[20]="RIP_MAX_CACHE=8m"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[21]="CONTENT_TYPE=application/postscript"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[22]="DEVICE_URI=usb://Canon/MP140%20series"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[23]="PRINTER=MP140_series"
D [27/Dec/2008:09:01:37 +0800] [Job 148] envp[24]="FINAL_CONTENT_TYPE=printer/MP140_series"
I [27/Dec/2008:09:01:37 +0800] [Job 148] Started filter /usr/lib/cups/filter/pstopdf (PID 9234)
I [27/Dec/2008:09:01:37 +0800] [Job 148] Started filter /usr/lib/cups/filter/pdftopdf (PID 9235)
I [27/Dec/2008:09:01:37 +0800] [Job 148] Started filter /usr/lib/cups/filter/cpdftocps (PID 9236)
I [27/Dec/2008:09:01:37 +0800] [Job 148] Started filter /usr/lib/cups/filter/pstocanonij (PID 9237)
I [27/Dec/2008:09:01:37 +0800] [Job 148] Started backend /usr/lib/cups/backend/usb (PID 9238)
I [27/Dec/2008:09:01:37 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:01:37 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:37 +0800] [Job 148] pstocanonij start.
D [27/Dec/2008:09:01:37 +0800] [Job 148] Printer using device file "/dev/usblp0"...
D [27/Dec/2008:09:01:37 +0800] [Job 148] backendRunLoop(print_fd=0, device_fd=5, use_bc=0, side_cb=0xb809ba80)
I [27/Dec/2008:09:01:37 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:01:37 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:37 +0800] [Job 148] pstopdf argv[6] = 148 mggraham Test Page 1 job-uuid=urn:uuid:acb80830-d159-3704-7b76-bbb10da3bf5d fitplot page-bottom=0 page-left=0 page-top=0 page-right=0 /var/spool/cups/d00148-001
D [27/Dec/2008:09:01:37 +0800] [Job 148] PPD: /etc/cups/ppd/MP140_series.ppd
D [27/Dec/2008:09:01:37 +0800] [Job 148] pdftops - copying to temp print file "/tmp/49557e7116f9e"
D [27/Dec/2008:09:01:37 +0800] [Job 148] Resolution: 600
D [27/Dec/2008:09:01:37 +0800] [Job 148] Page size: 4X6
D [27/Dec/2008:09:01:37 +0800] [Job 148] Width: , height: , absolute margins: , , , 
D [27/Dec/2008:09:01:37 +0800] [Job 148] Relative margins: , , , 
D [27/Dec/2008:09:01:37 +0800] [Job 148] PPD options: -r600
D [27/Dec/2008:09:01:37 +0800] [Job 148] PostScript to be injected: 
D [27/Dec/2008:09:01:37 +0800] [Job 148] Running cat | /usr/bin/ps2pdf13 -dAutoRotatePages=/None -dAutoFilterColorImages=false                -dNOPLATFONTS -dPARANOIDSAFER -sstdout=%stderr -dColorImageFilter=/FlateEncode                -dPDFSETTINGS=/printer -r600 - -
D [27/Dec/2008:09:01:37 +0800] [Job 148] GPL Ghostscript 8.63: Set UseCIEColor for UseDeviceIndependentColor to work properly.
D [27/Dec/2008:09:01:37 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:37 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:37 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:09:01:37 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:37 +0800] cupsdAcceptClient: 12 from localhost (Domain)
D [27/Dec/2008:09:01:37 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:37 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:01:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:37 +0800] Get-Notifications /
D [27/Dec/2008:09:01:37 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:01:37 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:37 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:01:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:37 +0800] Get-Job-Attributes ipp://localhost/jobs/148
D [27/Dec/2008:09:01:37 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:37 +0800] cupsdCloseClient: 12
D [27/Dec/2008:09:01:37 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:37 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:37 +0800] Get-Notifications /
D [27/Dec/2008:09:01:37 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:01:37 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:37 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:37 +0800] PID 9234 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [27/Dec/2008:09:01:37 +0800] PID 9235 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [27/Dec/2008:09:01:37 +0800] [Job 148] Page = 288x432; 10,14 to 278,424
D [27/Dec/2008:09:01:37 +0800] [Job 148] slow_collate=0, slow_duplex=0, slow_order=0
D [27/Dec/2008:09:01:37 +0800] [Job 148] Before copy_comments - %!PS-Adobe-3.0
D [27/Dec/2008:09:01:37 +0800] [Job 148] %!PS-Adobe-3.0
D [27/Dec/2008:09:01:37 +0800] [Job 148] %%LanguageLevel: 3
D [27/Dec/2008:09:01:37 +0800] [Job 148] %%DocumentSuppliedResources: (atend)
D [27/Dec/2008:09:01:37 +0800] [Job 148] %%DocumentMedia: plain 288 432 0 () ()
D [27/Dec/2008:09:01:37 +0800] [Job 148] %%BoundingBox: 0 0 288 432
D [27/Dec/2008:09:01:37 +0800] [Job 148] %%Pages: 1
D [27/Dec/2008:09:01:37 +0800] [Job 148] %%EndComments
D [27/Dec/2008:09:01:37 +0800] [Job 148] Before copy_prolog - %%BeginDefaults
D [27/Dec/2008:09:01:37 +0800] [Job 148] Before copy_setup - %%BeginSetup
D [27/Dec/2008:09:01:37 +0800] [Job 148] Before page loop - %%Page: 1 1
D [27/Dec/2008:09:01:37 +0800] [Job 148] Copying page 1...
D [27/Dec/2008:09:01:37 +0800] [Job 148] pagew = 268.7, pagel = 409.3
D [27/Dec/2008:09:01:37 +0800] [Job 148] bboxx = 0, bboxy = 0, bboxw = 288, bboxl = 432
D [27/Dec/2008:09:01:37 +0800] [Job 148] PageLeft = 9.6, PageRight = 278.4
D [27/Dec/2008:09:01:37 +0800] [Job 148] PageTop = 423.5, PageBottom = 14.2
D [27/Dec/2008:09:01:37 +0800] [Job 148] PageWidth = 288.0, PageLength = 432.0
D [27/Dec/2008:09:01:37 +0800] [Job 148] pstocanonij: /usr/bin/gs -r600 -g2400x3600 -q -dNOPROMPT -dSAFER -sDEVICE=ppmraw -sOutputFile=- -| /usr/bin/cifmp140 --imageres 600 --papersize 4X6 --media plain --paperload asf --bbox 9,14,278,424 
D [27/Dec/2008:09:01:37 +0800] [Job 148] Read 72 bytes of print data...
I [27/Dec/2008:09:01:37 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:01:37 +0800] [Job 148] Wrote 72 bytes of print data...
I [27/Dec/2008:09:01:37 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:01:37 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:37 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:37 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:09:01:37 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:37 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:37 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:37 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:37 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:37 +0800] Get-Notifications /
D [27/Dec/2008:09:01:37 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:01:37 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:38 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:38 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:38 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:38 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:38 +0800] Get-Notifications /
D [27/Dec/2008:09:01:38 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:01:38 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:38 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1 pages...
D [27/Dec/2008:09:01:38 +0800] PID 9236 (/usr/lib/cups/filter/cpdftocps) exited with no errors.
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 146 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 146 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 18 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 18 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 891 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 891 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 4371 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 4371 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 4103 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 4103 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2761 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2761 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2761 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2761 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2361 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2361 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2942 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2942 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 865 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 865 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 77 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 77 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 55 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 55 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 412 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 412 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 939 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 939 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2122 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2122 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2226 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2226 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2094 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2094 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 1862 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1862 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 1853 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1853 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 250 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 250 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 198 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 198 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 407 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 407 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 104 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 104 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 256 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 256 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 287 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 287 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 344 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 344 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 252 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 252 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 110 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 110 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 412 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 412 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 1736 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1736 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2538 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2538 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 3061 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 3061 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 3523 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 3523 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2485 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2485 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 1325 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1325 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 833 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 833 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 3539 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 3539 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 1169 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1169 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 3758 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 3758 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 1489 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1489 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 4024 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 4024 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 1724 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1724 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 4097 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 4097 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 1882 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1882 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 4093 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 4093 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 1920 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1920 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 4138 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 4138 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2021 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2021 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 4262 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 4262 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2082 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2082 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 4511 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 4511 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 6817 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 6817 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 5242 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 5242 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 1793 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 1793 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 2301 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 2301 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 4790 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 4790 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 7170 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 7170 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:38 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:39 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] Report: clients=1
D [27/Dec/2008:09:01:49 +0800] Report: jobs=82
D [27/Dec/2008:09:01:49 +0800] Report: jobs-active=2
D [27/Dec/2008:09:01:49 +0800] Report: printers=1
D [27/Dec/2008:09:01:49 +0800] Report: printers-implicit=0
D [27/Dec/2008:09:01:49 +0800] Report: stringpool-string-count=1384
D [27/Dec/2008:09:01:49 +0800] Report: stringpool-alloc-bytes=9280
D [27/Dec/2008:09:01:49 +0800] Report: stringpool-total-bytes=28064
D [27/Dec/2008:09:01:49 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:49 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:50 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:51 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:51 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:51 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:51 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:51 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:51 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:52 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:52 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:53 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:53 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:53 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:53 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:53 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:53 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:53 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:53 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:53 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:53 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:54 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:54 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:54 +0800] PID 9237 (/usr/lib/cups/filter/pstocanonij) exited with no errors.
D [27/Dec/2008:09:01:54 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:54 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:55 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:55 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:55 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:55 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:55 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:55 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:55 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:55 +0800] [Job 148] Read 8192 bytes of print data...
D [27/Dec/2008:09:01:56 +0800] [Job 148] Wrote 8192 bytes of print data...
D [27/Dec/2008:09:01:56 +0800] [Job 148] Read 6939 bytes of print data...
D [27/Dec/2008:09:01:56 +0800] [Job 148] Wrote 6939 bytes of print data...
D [27/Dec/2008:09:01:56 +0800] PID 9238 (/usr/lib/cups/backend/usb) exited with no errors.
D [27/Dec/2008:09:01:56 +0800] [Job 148] File 0 is complete.
I [27/Dec/2008:09:01:56 +0800] [Job 148] Completed successfully.
I [27/Dec/2008:09:01:56 +0800] Saving subscriptions.conf...
I [27/Dec/2008:09:01:56 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:01:56 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:56 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:56 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:56 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:09:01:56 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:56 +0800] cupsdAcceptClient: 12 from localhost (Domain)
D [27/Dec/2008:09:01:56 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:01:56 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:01:56 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:56 +0800] Get-Notifications /
D [27/Dec/2008:09:01:56 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:01:56 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:56 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:01:56 +0800] cupsdCloseClient: 12
D [27/Dec/2008:09:01:56 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:01:56 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:01:56 +0800] Get-Notifications /
D [27/Dec/2008:09:01:56 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:01:56 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:01:56 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:02:10 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:02:10 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:02:10 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:02:10 +0800] Cancel-Subscription /
D [27/Dec/2008:09:02:10 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
I [27/Dec/2008:09:02:10 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:02:10 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:02:10 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:02:17 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:02:17 +0800] cupsdReadClient: 10 POST /jobs/ HTTP/1.1
D [27/Dec/2008:09:02:17 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:02:17 +0800] Cancel-Job ipp://localhost/jobs/147
D [27/Dec/2008:09:02:17 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
I [27/Dec/2008:09:02:17 +0800] Saving subscriptions.conf...
I [27/Dec/2008:09:02:17 +0800] [Job 147] Canceled by "mggraham".
D [27/Dec/2008:09:02:17 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:02:17 +0800] cupsdAcceptClient: 12 from localhost (Domain)
D [27/Dec/2008:09:02:17 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:02:17 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:02:17 +0800] Cancel-Subscription /
D [27/Dec/2008:09:02:17 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
I [27/Dec/2008:09:02:17 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:02:17 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:02:17 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:02:17 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:02:17 +0800] Create-Printer-Subscription /
D [27/Dec/2008:09:02:17 +0800] cupsdCreateSubscription(con=0xb9489838(12), uri="/")
D [27/Dec/2008:09:02:17 +0800] pullmethod="ippget"
D [27/Dec/2008:09:02:17 +0800] notify-lease-duration=86400
D [27/Dec/2008:09:02:17 +0800] notify-time-interval=0
D [27/Dec/2008:09:02:17 +0800] cupsdAddSubscription(mask=1798f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [27/Dec/2008:09:02:17 +0800] Added subscription 112 for server
I [27/Dec/2008:09:02:17 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:02:17 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:02:17 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:02:17 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:02:17 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:09:02:17 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:02:17 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:02:17 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:02:17 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:02:17 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:02:17 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:02:17 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:02:17 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:02:17 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:02:17 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:02:17 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:02:17 +0800] CUPS-Get-Classes
D [27/Dec/2008:09:02:17 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:02:17 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:02:17 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:02:17 +0800] CUPS-Get-Default
D [27/Dec/2008:09:02:17 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:02:17 +0800] cupsdCloseClient: 12
D [27/Dec/2008:09:02:18 +0800] cupsdAcceptClient: 12 from localhost (Domain)
D [27/Dec/2008:09:02:18 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:09:02:18 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:02:18 +0800] Get-Notifications /
D [27/Dec/2008:09:02:18 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:02:18 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:09:02:18 +0800] cupsdCloseClient: 12
D [27/Dec/2008:09:06:14 +0800] Closing client 9 after 300 seconds of inactivity...
D [27/Dec/2008:09:06:14 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:06:14 +0800] Report: clients=1
D [27/Dec/2008:09:06:14 +0800] Report: jobs=80
D [27/Dec/2008:09:06:14 +0800] Report: jobs-active=0
D [27/Dec/2008:09:06:14 +0800] Report: printers=1
D [27/Dec/2008:09:06:14 +0800] Report: printers-implicit=0
D [27/Dec/2008:09:06:14 +0800] Report: stringpool-string-count=565
D [27/Dec/2008:09:06:14 +0800] Report: stringpool-alloc-bytes=7416
D [27/Dec/2008:09:06:14 +0800] Report: stringpool-total-bytes=11144
D [27/Dec/2008:09:07:18 +0800] Closing client 10 after 300 seconds of inactivity...
D [27/Dec/2008:09:07:18 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:07:18 +0800] Report: clients=0
D [27/Dec/2008:09:07:18 +0800] Report: jobs=80
D [27/Dec/2008:09:07:18 +0800] Report: jobs-active=0
D [27/Dec/2008:09:07:18 +0800] Report: printers=1
D [27/Dec/2008:09:07:18 +0800] Report: printers-implicit=0
D [27/Dec/2008:09:07:18 +0800] Report: stringpool-string-count=565
D [27/Dec/2008:09:07:18 +0800] Report: stringpool-alloc-bytes=7416
D [27/Dec/2008:09:07:18 +0800] Report: stringpool-total-bytes=11144
D [27/Dec/2008:09:20:33 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:20:33 +0800] Report: clients=1
D [27/Dec/2008:09:20:33 +0800] Report: jobs=80
D [27/Dec/2008:09:20:33 +0800] Report: jobs-active=0
D [27/Dec/2008:09:20:33 +0800] Report: printers=1
D [27/Dec/2008:09:20:33 +0800] Report: printers-implicit=0
D [27/Dec/2008:09:20:33 +0800] Report: stringpool-string-count=565
D [27/Dec/2008:09:20:33 +0800] Report: stringpool-alloc-bytes=7416
D [27/Dec/2008:09:20:33 +0800] Report: stringpool-total-bytes=11144
D [27/Dec/2008:09:20:33 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:20:33 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:20:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:20:33 +0800] Create-Printer-Subscription /
D [27/Dec/2008:09:20:33 +0800] cupsdCreateSubscription(con=0xb94860c8(10), uri="/")
D [27/Dec/2008:09:20:33 +0800] pullmethod="ippget"
D [27/Dec/2008:09:20:33 +0800] notify-lease-duration=86400
D [27/Dec/2008:09:20:33 +0800] notify-time-interval=0
D [27/Dec/2008:09:20:33 +0800] cupsdAddSubscription(mask=18f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [27/Dec/2008:09:20:33 +0800] Added subscription 113 for server
I [27/Dec/2008:09:20:33 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:20:33 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:20:33 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:20:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:20:33 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:20:33 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:20:33 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:20:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:20:33 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:20:33 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:20:33 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:20:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:20:33 +0800] CUPS-Get-Classes
D [27/Dec/2008:09:20:33 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:20:33 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:20:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:20:33 +0800] CUPS-Get-Default
D [27/Dec/2008:09:20:33 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:20:33 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:20:33 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:20:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:20:33 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:20:33 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:20:33 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:20:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:20:33 +0800] CUPS-Get-Classes
D [27/Dec/2008:09:20:33 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:20:33 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:20:33 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:20:33 +0800] CUPS-Get-Default
D [27/Dec/2008:09:20:33 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:20:34 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:20:34 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:20:34 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:20:34 +0800] Get-Notifications /
D [27/Dec/2008:09:20:34 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:20:34 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:20:34 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:20:39 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:20:39 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:20:39 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:20:39 +0800] Cancel-Subscription /
D [27/Dec/2008:09:20:39 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
I [27/Dec/2008:09:20:39 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:20:39 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:20:39 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:20:39 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:23:11 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:23:11 +0800] Report: clients=1
D [27/Dec/2008:09:23:11 +0800] Report: jobs=80
D [27/Dec/2008:09:23:11 +0800] Report: jobs-active=0
D [27/Dec/2008:09:23:11 +0800] Report: printers=1
D [27/Dec/2008:09:23:11 +0800] Report: printers-implicit=0
D [27/Dec/2008:09:23:11 +0800] Report: stringpool-string-count=565
D [27/Dec/2008:09:23:11 +0800] Report: stringpool-alloc-bytes=7416
D [27/Dec/2008:09:23:11 +0800] Report: stringpool-total-bytes=11144
D [27/Dec/2008:09:23:11 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:23:11 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:23:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:23:11 +0800] Create-Printer-Subscription /
D [27/Dec/2008:09:23:11 +0800] cupsdCreateSubscription(con=0xb94860c8(10), uri="/")
D [27/Dec/2008:09:23:11 +0800] pullmethod="ippget"
D [27/Dec/2008:09:23:11 +0800] notify-lease-duration=86400
D [27/Dec/2008:09:23:11 +0800] notify-time-interval=0
D [27/Dec/2008:09:23:11 +0800] cupsdAddSubscription(mask=18f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [27/Dec/2008:09:23:11 +0800] Added subscription 114 for server
I [27/Dec/2008:09:23:11 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:23:11 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:23:11 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:23:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:23:11 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:23:11 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:23:11 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:23:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:23:11 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:23:11 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:23:11 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:23:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:23:11 +0800] CUPS-Get-Classes
D [27/Dec/2008:09:23:11 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:23:11 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:23:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:23:11 +0800] CUPS-Get-Default
D [27/Dec/2008:09:23:11 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:23:11 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:23:11 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:23:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:23:11 +0800] CUPS-Get-Printers
D [27/Dec/2008:09:23:11 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:23:11 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:23:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:23:11 +0800] CUPS-Get-Classes
D [27/Dec/2008:09:23:11 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:23:11 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:09:23:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:23:11 +0800] CUPS-Get-Default
D [27/Dec/2008:09:23:11 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:09:23:12 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:23:12 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:23:12 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:23:12 +0800] Get-Notifications /
D [27/Dec/2008:09:23:12 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:09:23:12 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:23:12 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:23:32 +0800] cupsdReadClient: 9 GET /admin/conf/cupsd.conf HTTP/1.1
D [27/Dec/2008:09:23:32 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:23:32 +0800] cupsdIsAuthorized: username=""
D [27/Dec/2008:09:23:32 +0800] cupsdSendError: 9 code=401 (Unauthorized)
D [27/Dec/2008:09:23:32 +0800] cupsdSendHeader: WWW-Authenticate: Basic realm="CUPS"
D [27/Dec/2008:09:23:32 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:23:32 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:23:32 +0800] cupsdReadClient: 9 GET /admin/conf/cupsd.conf HTTP/1.1
D [27/Dec/2008:09:23:32 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:09:23:32 +0800] cupsdIsAuthorized: username="root"
D [27/Dec/2008:09:23:53 +0800] cupsdReadClient: 9 GET /admin/conf/cupsd.conf HTTP/1.1
D [27/Dec/2008:09:23:53 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:09:23:53 +0800] cupsdIsAuthorized: username="root"
D [27/Dec/2008:09:23:53 +0800] cupsdReadClient: 9 PUT /admin/conf/cupsd.conf HTTP/1.1
D [27/Dec/2008:09:23:53 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:09:23:53 +0800] cupsdIsAuthorized: username="root"
I [27/Dec/2008:09:23:53 +0800] Installing config file "/etc/cups/cupsd.conf"...
D [27/Dec/2008:09:23:53 +0800] cupsdSendError: 9 code=201 (Created)
D [27/Dec/2008:09:23:53 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:23:53 +0800] Saving remote.cache...
I [27/Dec/2008:09:23:53 +0800] Listening to :::631 (IPv6)
I [27/Dec/2008:09:23:53 +0800] Listening to 0.0.0.0:631 (IPv4)
I [27/Dec/2008:09:23:53 +0800] Listening to /var/run/cups/cups.sock (Domain)
I [27/Dec/2008:09:23:53 +0800] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Dec/2008:09:23:53 +0800] Using default TempDir of /var/spool/cups/tmp...
I [27/Dec/2008:09:23:53 +0800] Configured for up to 100 clients.
I [27/Dec/2008:09:23:53 +0800] Allowing up to 100 client connections per host.
I [27/Dec/2008:09:23:53 +0800] Using policy "default" as the default!
I [27/Dec/2008:09:23:53 +0800] Partial reload complete.
I [27/Dec/2008:09:23:53 +0800] Listening to :::631 on fd 2...
I [27/Dec/2008:09:23:53 +0800] Listening to 0.0.0.0:631 on fd 4...
I [27/Dec/2008:09:23:53 +0800] Listening to /var/run/cups/cups.sock on fd 5...
I [27/Dec/2008:09:23:53 +0800] Resuming new connection processing...
D [27/Dec/2008:09:23:53 +0800] Discarding unused server-restarted event...
D [27/Dec/2008:09:23:54 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:24:01 +0800] cupsdReadClient: 9 PUT /admin/conf/cupsd.conf HTTP/1.1
D [27/Dec/2008:09:24:01 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:24:01 +0800] cupsdIsAuthorized: username=""
D [27/Dec/2008:09:24:01 +0800] cupsdSendError: 9 code=401 (Unauthorized)
D [27/Dec/2008:09:24:01 +0800] cupsdSendHeader: WWW-Authenticate: Basic realm="CUPS"
D [27/Dec/2008:09:24:01 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:24:01 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:24:01 +0800] cupsdReadClient: 9 PUT /admin/conf/cupsd.conf HTTP/1.1
D [27/Dec/2008:09:24:01 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:09:24:01 +0800] cupsdIsAuthorized: username="root"
I [27/Dec/2008:09:24:01 +0800] Installing config file "/etc/cups/cupsd.conf"...
D [27/Dec/2008:09:24:01 +0800] cupsdSendError: 9 code=201 (Created)
D [27/Dec/2008:09:24:01 +0800] cupsdCloseClient: 9
D [27/Dec/2008:09:24:01 +0800] Saving remote.cache...
I [27/Dec/2008:09:24:01 +0800] Listening to :::631 (IPv6)
I [27/Dec/2008:09:24:01 +0800] Listening to 0.0.0.0:631 (IPv4)
I [27/Dec/2008:09:24:01 +0800] Listening to /var/run/cups/cups.sock (Domain)
I [27/Dec/2008:09:24:01 +0800] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Dec/2008:09:24:01 +0800] Using default TempDir of /var/spool/cups/tmp...
I [27/Dec/2008:09:24:01 +0800] Configured for up to 100 clients.
I [27/Dec/2008:09:24:01 +0800] Allowing up to 100 client connections per host.
I [27/Dec/2008:09:24:01 +0800] Using policy "default" as the default!
I [27/Dec/2008:09:24:01 +0800] Partial reload complete.
I [27/Dec/2008:09:24:01 +0800] Listening to :::631 on fd 2...
I [27/Dec/2008:09:24:01 +0800] Listening to 0.0.0.0:631 on fd 4...
I [27/Dec/2008:09:24:01 +0800] Listening to /var/run/cups/cups.sock on fd 5...
I [27/Dec/2008:09:24:01 +0800] Resuming new connection processing...
D [27/Dec/2008:09:24:01 +0800] Discarding unused server-restarted event...
D [27/Dec/2008:09:24:02 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:09:24:08 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:09:24:08 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:09:24:08 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:09:24:08 +0800] Cancel-Subscription /
D [27/Dec/2008:09:24:08 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
I [27/Dec/2008:09:24:08 +0800] Saving subscriptions.conf...
D [27/Dec/2008:09:24:08 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:09:24:08 +0800] cupsdCloseClient: 10
D [27/Dec/2008:09:24:08 +0800] cupsdCloseClient: 9
D [27/Dec/2008:11:04:42 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:11:04:42 +0800] Report: clients=1
D [27/Dec/2008:11:04:42 +0800] Report: jobs=80
D [27/Dec/2008:11:04:42 +0800] Report: jobs-active=0
D [27/Dec/2008:11:04:42 +0800] Report: printers=1
D [27/Dec/2008:11:04:42 +0800] Report: printers-implicit=0
D [27/Dec/2008:11:04:42 +0800] Report: stringpool-string-count=565
D [27/Dec/2008:11:04:42 +0800] Report: stringpool-alloc-bytes=7416
D [27/Dec/2008:11:04:42 +0800] Report: stringpool-total-bytes=11144
D [27/Dec/2008:11:04:42 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:11:04:42 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:04:42 +0800] CUPS-Get-Printers
D [27/Dec/2008:11:04:42 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:11:04:42 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:11:04:42 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:04:42 +0800] CUPS-Get-Classes
D [27/Dec/2008:11:04:42 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:11:04:42 +0800] cupsdReadClient: 9 POST /admin/ HTTP/1.1
D [27/Dec/2008:11:04:42 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:04:42 +0800] Pause-Printer ipp://localhost/printers/MP140_series
D [27/Dec/2008:11:04:42 +0800] cupsdIsAuthorized: username=""
E [27/Dec/2008:11:04:42 +0800] Pause-Printer: Unauthorized
D [27/Dec/2008:11:04:42 +0800] cupsdSendError: 9 code=401 (Unauthorized)
D [27/Dec/2008:11:04:42 +0800] cupsdSendHeader: WWW-Authenticate: Basic realm="CUPS"
D [27/Dec/2008:11:04:42 +0800] cupsdCloseClient: 9
D [27/Dec/2008:11:04:42 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:11:04:42 +0800] cupsdReadClient: 9 POST /admin/ HTTP/1.1
D [27/Dec/2008:11:04:42 +0800] cupsdAuthorize: Authorized as root using Local
D [27/Dec/2008:11:04:42 +0800] Pause-Printer ipp://localhost/printers/MP140_series
D [27/Dec/2008:11:04:42 +0800] cupsdIsAuthorized: username="root"
I [27/Dec/2008:11:04:42 +0800] Saving subscriptions.conf...
I [27/Dec/2008:11:04:42 +0800] Saving printers.conf...
I [27/Dec/2008:11:04:42 +0800] Printer "MP140_series" stopped by "root".
D [27/Dec/2008:11:04:42 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:11:04:42 +0800] cupsdCloseClient: 9
D [27/Dec/2008:11:04:42 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:11:04:42 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:11:04:42 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:04:42 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:11:04:42 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:11:04:42 +0800] cupsdAcceptClient: 10 from localhost (Domain)
D [27/Dec/2008:11:04:42 +0800] cupsdCloseClient: 9
D [27/Dec/2008:11:04:42 +0800] cupsdReadClient: 10 POST / HTTP/1.1
D [27/Dec/2008:11:04:42 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:04:42 +0800] Get-Notifications /
D [27/Dec/2008:11:04:42 +0800] cupsdIsAuthorized: requesting-user-name="mggraham"
D [27/Dec/2008:11:04:42 +0800] cupsdProcessIPPRequest: 10 status_code=0 (successful-ok)
D [27/Dec/2008:11:04:42 +0800] cupsdCloseClient: 10
D [27/Dec/2008:11:04:44 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:11:04:44 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:11:04:44 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:04:44 +0800] CUPS-Get-Printers
D [27/Dec/2008:11:04:44 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:11:04:44 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:11:04:44 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:04:44 +0800] CUPS-Get-Classes
D [27/Dec/2008:11:04:44 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:11:04:44 +0800] cupsdCloseClient: 9
I [27/Dec/2008:11:08:32 +0800] Scheduler shutting down normally.
D [27/Dec/2008:11:08:32 +0800] Discarding unused server-stopped event...
D [27/Dec/2008:11:08:32 +0800] Saving remote.cache...
I [27/Dec/2008:11:08:32 +0800] Saving job cache file "/var/cache/cups/job.cache"...
I [27/Dec/2008:11:09:50 +0800] Listening to :::631 (IPv6)
I [27/Dec/2008:11:09:50 +0800] Listening to 0.0.0.0:631 (IPv4)
I [27/Dec/2008:11:09:50 +0800] Listening to /var/run/cups/cups.sock (Domain)
I [27/Dec/2008:11:09:50 +0800] Loaded configuration file "/etc/cups/cupsd.conf"
D [27/Dec/2008:11:09:51 +0800] Repairing ownership of "/var/run/cups"
D [27/Dec/2008:11:09:51 +0800] Creating missing directory "/var/run/cups/certs"
D [27/Dec/2008:11:09:51 +0800] Repairing ownership of "/var/run/cups/certs"
D [27/Dec/2008:11:09:51 +0800] Repairing access permissions of "/var/run/cups/certs"
I [27/Dec/2008:11:09:51 +0800] Using default TempDir of /var/spool/cups/tmp...
I [27/Dec/2008:11:09:51 +0800] Configured for up to 100 clients.
I [27/Dec/2008:11:09:51 +0800] Allowing up to 100 client connections per host.
I [27/Dec/2008:11:09:51 +0800] Using policy "default" as the default!
I [27/Dec/2008:11:09:51 +0800] Full reload is required.
I [27/Dec/2008:11:09:51 +0800] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 38 types, 66 filters...
D [27/Dec/2008:11:09:51 +0800] Loading printer MP140_series...
I [27/Dec/2008:11:09:51 +0800] Loading job cache file "/var/cache/cups/job.cache"...
D [27/Dec/2008:11:09:51 +0800] [Job 1] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 2] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 3] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 4] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 5] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 6] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 7] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 8] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 9] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 10] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 11] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 12] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 13] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 14] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 15] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 16] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 17] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 18] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 19] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 20] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 21] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 22] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 23] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 24] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 25] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 26] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 27] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 28] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 29] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 30] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 31] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 32] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 33] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 34] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 35] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 36] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 37] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 38] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 39] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 40] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 41] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 42] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 43] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 44] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 45] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 46] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 47] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 48] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 49] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 50] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 51] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 52] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 53] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 54] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 55] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 56] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 57] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 58] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 59] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 60] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 61] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 62] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 63] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 64] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 65] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 66] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 67] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 68] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 69] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 70] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 71] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 72] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 73] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 74] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 75] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 76] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 77] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 78] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 79] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] [Job 80] Loading from cache...
D [27/Dec/2008:11:09:51 +0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [27/Dec/2008:11:09:51 +0800] Full reload complete.
I [27/Dec/2008:11:09:51 +0800] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [27/Dec/2008:11:09:51 +0800] Listening to :::631 on fd 3...
I [27/Dec/2008:11:09:51 +0800] Listening to 0.0.0.0:631 on fd 4...
I [27/Dec/2008:11:09:51 +0800] Listening to /var/run/cups/cups.sock on fd 5...
I [27/Dec/2008:11:09:51 +0800] Resuming new connection processing...
D [27/Dec/2008:11:09:51 +0800] Discarding unused server-started event...
D [27/Dec/2008:11:09:52 +0800] Report: clients=0
D [27/Dec/2008:11:09:52 +0800] Report: jobs=80
D [27/Dec/2008:11:09:52 +0800] Report: jobs-active=0
D [27/Dec/2008:11:09:52 +0800] Report: printers=1
D [27/Dec/2008:11:09:52 +0800] Report: printers-implicit=0
D [27/Dec/2008:11:09:52 +0800] Report: stringpool-string-count=484
D [27/Dec/2008:11:09:52 +0800] Report: stringpool-alloc-bytes=7344
D [27/Dec/2008:11:09:52 +0800] Report: stringpool-total-bytes=9264
D [27/Dec/2008:11:11:04 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:11:11:04 +0800] Report: clients=1
D [27/Dec/2008:11:11:04 +0800] Report: jobs=80
D [27/Dec/2008:11:11:04 +0800] Report: jobs-active=0
D [27/Dec/2008:11:11:04 +0800] Report: printers=1
D [27/Dec/2008:11:11:04 +0800] Report: printers-implicit=0
D [27/Dec/2008:11:11:04 +0800] Report: stringpool-string-count=484
D [27/Dec/2008:11:11:04 +0800] Report: stringpool-alloc-bytes=7344
D [27/Dec/2008:11:11:04 +0800] Report: stringpool-total-bytes=9264
D [27/Dec/2008:11:11:04 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:11:11:04 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:11:04 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:11:11:04 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:11:11:05 +0800] cupsdCloseClient: 9
D [27/Dec/2008:11:12:10 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:11:12:10 +0800] Report: clients=1
D [27/Dec/2008:11:12:10 +0800] Report: jobs=80
D [27/Dec/2008:11:12:10 +0800] Report: jobs-active=0
D [27/Dec/2008:11:12:10 +0800] Report: printers=1
D [27/Dec/2008:11:12:10 +0800] Report: printers-implicit=0
D [27/Dec/2008:11:12:10 +0800] Report: stringpool-string-count=484
D [27/Dec/2008:11:12:10 +0800] Report: stringpool-alloc-bytes=7344
D [27/Dec/2008:11:12:10 +0800] Report: stringpool-total-bytes=9264
D [27/Dec/2008:11:12:10 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:11:12:10 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:12:10 +0800] CUPS-Get-Devices
D [27/Dec/2008:11:12:10 +0800] [CGI] /usr/lib/cups/daemon/cups-deviced started - PID = 5898
I [27/Dec/2008:11:12:10 +0800] Started "/usr/lib/cups/daemon/cups-deviced" (pid=5898)
D [27/Dec/2008:11:12:10 +0800] cupsdSendCommand: 9 file=11
D [27/Dec/2008:11:12:11 +0800] cupsdAcceptClient: 12 from localhost (Domain)
D [27/Dec/2008:11:12:11 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:11:12:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:12:11 +0800] CUPS-Get-Printers
D [27/Dec/2008:11:12:11 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:11:12:11 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:11:12:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:12:11 +0800] CUPS-Get-Classes
D [27/Dec/2008:11:12:11 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:11:12:11 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:11:12:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:12:11 +0800] CUPS-Get-Default
D [27/Dec/2008:11:12:11 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:11:12:11 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:11:12:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:12:11 +0800] CUPS-Get-Printers
D [27/Dec/2008:11:12:11 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:11:12:11 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:11:12:11 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:11:12:11 +0800] CUPS-Get-Printers
D [27/Dec/2008:11:12:11 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:11:12:11 +0800] cupsdCloseClient: 12
D [27/Dec/2008:11:12:11 +0800] [cups-deviced] Added device "serial:/dev/ttyS0?baud=115200"...
D [27/Dec/2008:11:12:11 +0800] [cups-deviced] Added device "scsi"...
D [27/Dec/2008:11:12:11 +0800] [cups-deviced] Added device "ipp"...
D [27/Dec/2008:11:12:11 +0800] [cups-deviced] Added device "parallel:/dev/lp0"...
D [27/Dec/2008:11:12:11 +0800] [cups-deviced] Added device "beh"...
D [27/Dec/2008:11:12:11 +0800] [cups-deviced] Added device "lpd"...
D [27/Dec/2008:11:12:11 +0800] [cups-deviced] Added device "smb"...
D [27/Dec/2008:11:12:11 +0800] [cups-deviced] Added device "socket"...
D [27/Dec/2008:11:12:12 +0800] [cups-deviced] Added device "hp"...
D [27/Dec/2008:11:12:13 +0800] [cups-deviced] Added device "http"...
D [27/Dec/2008:11:12:13 +0800] [cups-deviced] Added device "hpfax"...
D [27/Dec/2008:11:12:13 +0800] [cups-deviced] Added device "hal"...
D [27/Dec/2008:11:12:13 +0800] cupsdCloseClient: 9
D [27/Dec/2008:11:12:13 +0800] PID 5898 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
I [27/Dec/2008:12:59:39 +0800] Scheduler shutting down normally.
D [27/Dec/2008:12:59:39 +0800] Discarding unused server-stopped event...
D [27/Dec/2008:12:59:39 +0800] Saving remote.cache...
I [27/Dec/2008:12:59:39 +0800] Saving job cache file "/var/cache/cups/job.cache"...
I [27/Dec/2008:18:45:46 +0800] Listening to :::631 (IPv6)
I [27/Dec/2008:18:45:46 +0800] Listening to 0.0.0.0:631 (IPv4)
I [27/Dec/2008:18:45:46 +0800] Listening to /var/run/cups/cups.sock (Domain)
I [27/Dec/2008:18:45:46 +0800] Loaded configuration file "/etc/cups/cupsd.conf"
D [27/Dec/2008:18:45:47 +0800] Repairing ownership of "/var/run/cups"
D [27/Dec/2008:18:45:47 +0800] Creating missing directory "/var/run/cups/certs"
D [27/Dec/2008:18:45:47 +0800] Repairing ownership of "/var/run/cups/certs"
D [27/Dec/2008:18:45:47 +0800] Repairing access permissions of "/var/run/cups/certs"
I [27/Dec/2008:18:45:47 +0800] Using default TempDir of /var/spool/cups/tmp...
I [27/Dec/2008:18:45:47 +0800] Configured for up to 100 clients.
I [27/Dec/2008:18:45:47 +0800] Allowing up to 100 client connections per host.
I [27/Dec/2008:18:45:47 +0800] Using policy "default" as the default!
I [27/Dec/2008:18:45:47 +0800] Full reload is required.
I [27/Dec/2008:18:45:47 +0800] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 38 types, 66 filters...
D [27/Dec/2008:18:45:47 +0800] Loading printer MP140_series...
I [27/Dec/2008:18:45:47 +0800] Loading job cache file "/var/cache/cups/job.cache"...
D [27/Dec/2008:18:45:47 +0800] [Job 1] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 2] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 3] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 4] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 5] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 6] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 7] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 8] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 9] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 10] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 11] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 12] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 13] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 14] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 15] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 16] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 17] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 18] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 19] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 20] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 21] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 22] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 23] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 24] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 25] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 26] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 27] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 28] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 29] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 30] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 31] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 32] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 33] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 34] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 35] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 36] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 37] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 38] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 39] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 40] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 41] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 42] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 43] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 44] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 45] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 46] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 47] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 48] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 49] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 50] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 51] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 52] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 53] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 54] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 55] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 56] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 57] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 58] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 59] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 60] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 61] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 62] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 63] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 64] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 65] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 66] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 67] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 68] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 69] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 70] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 71] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 72] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 73] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 74] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 75] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 76] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 77] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 78] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 79] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] [Job 80] Loading from cache...
D [27/Dec/2008:18:45:47 +0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [27/Dec/2008:18:45:47 +0800] Full reload complete.
I [27/Dec/2008:18:45:47 +0800] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [27/Dec/2008:18:45:47 +0800] Listening to :::631 on fd 3...
I [27/Dec/2008:18:45:47 +0800] Listening to 0.0.0.0:631 on fd 4...
I [27/Dec/2008:18:45:47 +0800] Listening to /var/run/cups/cups.sock on fd 5...
I [27/Dec/2008:18:45:47 +0800] Resuming new connection processing...
D [27/Dec/2008:18:45:47 +0800] Discarding unused server-started event...
D [27/Dec/2008:18:45:48 +0800] Report: clients=0
D [27/Dec/2008:18:45:48 +0800] Report: jobs=80
D [27/Dec/2008:18:45:48 +0800] Report: jobs-active=0
D [27/Dec/2008:18:45:48 +0800] Report: printers=1
D [27/Dec/2008:18:45:48 +0800] Report: printers-implicit=0
D [27/Dec/2008:18:45:48 +0800] Report: stringpool-string-count=484
D [27/Dec/2008:18:45:48 +0800] Report: stringpool-alloc-bytes=7344
D [27/Dec/2008:18:45:48 +0800] Report: stringpool-total-bytes=9264
D [27/Dec/2008:18:46:40 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:18:46:40 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:18:46:40 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:18:46:40 +0800] Get-Jobs ipp://localhost/jobs/
D [27/Dec/2008:18:46:40 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [27/Dec/2008:18:46:40 +0800] cupsdCloseClient: 9
D [27/Dec/2008:18:47:43 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [27/Dec/2008:18:47:43 +0800] Report: clients=1
D [27/Dec/2008:18:47:43 +0800] Report: jobs=80
D [27/Dec/2008:18:47:43 +0800] Report: jobs-active=0
D [27/Dec/2008:18:47:43 +0800] Report: printers=1
D [27/Dec/2008:18:47:43 +0800] Report: printers-implicit=0
D [27/Dec/2008:18:47:43 +0800] Report: stringpool-string-count=484
D [27/Dec/2008:18:47:43 +0800] Report: stringpool-alloc-bytes=7344
D [27/Dec/2008:18:47:43 +0800] Report: stringpool-total-bytes=9264
D [27/Dec/2008:18:47:43 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [27/Dec/2008:18:47:43 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:18:47:43 +0800] CUPS-Get-Devices
D [27/Dec/2008:18:47:43 +0800] [CGI] /usr/lib/cups/daemon/cups-deviced started - PID = 5859
I [27/Dec/2008:18:47:43 +0800] Started "/usr/lib/cups/daemon/cups-deviced" (pid=5859)
D [27/Dec/2008:18:47:43 +0800] cupsdSendCommand: 9 file=11
D [27/Dec/2008:18:47:44 +0800] cupsdAcceptClient: 12 from localhost (Domain)
D [27/Dec/2008:18:47:44 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:18:47:44 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:18:47:44 +0800] CUPS-Get-Printers
D [27/Dec/2008:18:47:44 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:18:47:44 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:18:47:44 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:18:47:44 +0800] CUPS-Get-Classes
D [27/Dec/2008:18:47:44 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:18:47:44 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:18:47:44 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:18:47:44 +0800] CUPS-Get-Default
D [27/Dec/2008:18:47:44 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:18:47:44 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:18:47:44 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:18:47:44 +0800] CUPS-Get-Printers
D [27/Dec/2008:18:47:44 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:18:47:44 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [27/Dec/2008:18:47:44 +0800] cupsdAuthorize: No authentication data provided.
D [27/Dec/2008:18:47:44 +0800] CUPS-Get-Printers
D [27/Dec/2008:18:47:44 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [27/Dec/2008:18:47:44 +0800] cupsdCloseClient: 12
D [27/Dec/2008:18:47:44 +0800] [cups-deviced] Added device "serial:/dev/ttyS0?baud=115200"...
D [27/Dec/2008:18:47:44 +0800] [cups-deviced] Added device "scsi"...
D [27/Dec/2008:18:47:44 +0800] [cups-deviced] Added device "ipp"...
D [27/Dec/2008:18:47:45 +0800] [cups-deviced] Added device "parallel:/dev/lp0"...
D [27/Dec/2008:18:47:45 +0800] [cups-deviced] Added device "beh"...
D [27/Dec/2008:18:47:45 +0800] [cups-deviced] Added device "lpd"...
D [27/Dec/2008:18:47:45 +0800] [cups-deviced] Added device "smb"...
D [27/Dec/2008:18:47:45 +0800] [cups-deviced] Added device "socket"...
D [27/Dec/2008:18:47:45 +0800] [cups-deviced] Added device "hp"...
D [27/Dec/2008:18:47:46 +0800] [cups-deviced] Added device "http"...
D [27/Dec/2008:18:47:47 +0800] [cups-deviced] Added device "hpfax"...
D [27/Dec/2008:18:47:47 +0800] [cups-deviced] Added device "hal"...
D [27/Dec/2008:18:47:47 +0800] PID 5859 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
D [27/Dec/2008:18:47:47 +0800] cupsdCloseClient: 9
I [27/Dec/2008:19:06:54 +0800] Scheduler shutting down normally.
D [27/Dec/2008:19:06:54 +0800] Discarding unused server-stopped event...
D [27/Dec/2008:19:06:54 +0800] Saving remote.cache...
I [27/Dec/2008:19:06:54 +0800] Saving job cache file "/var/cache/cups/job.cache"...
I [28/Dec/2008:06:11:34 +0800] Listening to :::631 (IPv6)
I [28/Dec/2008:06:11:34 +0800] Listening to 0.0.0.0:631 (IPv4)
I [28/Dec/2008:06:11:34 +0800] Listening to /var/run/cups/cups.sock (Domain)
I [28/Dec/2008:06:11:34 +0800] Loaded configuration file "/etc/cups/cupsd.conf"
D [28/Dec/2008:06:11:34 +0800] Repairing ownership of "/var/run/cups"
D [28/Dec/2008:06:11:34 +0800] Creating missing directory "/var/run/cups/certs"
D [28/Dec/2008:06:11:34 +0800] Repairing ownership of "/var/run/cups/certs"
D [28/Dec/2008:06:11:34 +0800] Repairing access permissions of "/var/run/cups/certs"
I [28/Dec/2008:06:11:34 +0800] Using default TempDir of /var/spool/cups/tmp...
I [28/Dec/2008:06:11:34 +0800] Configured for up to 100 clients.
I [28/Dec/2008:06:11:34 +0800] Allowing up to 100 client connections per host.
I [28/Dec/2008:06:11:34 +0800] Using policy "default" as the default!
I [28/Dec/2008:06:11:34 +0800] Full reload is required.
I [28/Dec/2008:06:11:34 +0800] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 38 types, 66 filters...
D [28/Dec/2008:06:11:34 +0800] Loading printer MP140_series...
I [28/Dec/2008:06:11:34 +0800] Loading job cache file "/var/cache/cups/job.cache"...
D [28/Dec/2008:06:11:34 +0800] [Job 1] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 2] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 3] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 4] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 5] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 6] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 7] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 8] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 9] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 10] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 11] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 12] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 13] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 14] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 15] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 16] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 17] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 18] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 19] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 20] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 21] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 22] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 23] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 24] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 25] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 26] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 27] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 28] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 29] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 30] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 31] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 32] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 33] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 34] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 35] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 36] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 37] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 38] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 39] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 40] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 41] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 42] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 43] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 44] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 45] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 46] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 47] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 48] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 49] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 50] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 51] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 52] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 53] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 54] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 55] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 56] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 57] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 58] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 59] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 60] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 61] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 62] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 63] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 64] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 65] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 66] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 67] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 68] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 69] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 70] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 71] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 72] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 73] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 74] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 75] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 76] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 77] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 78] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 79] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] [Job 80] Loading from cache...
D [28/Dec/2008:06:11:34 +0800] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [28/Dec/2008:06:11:34 +0800] Full reload complete.
I [28/Dec/2008:06:11:34 +0800] Cleaning out old temporary files in "/var/spool/cups/tmp"...
I [28/Dec/2008:06:11:34 +0800] Listening to :::631 on fd 3...
I [28/Dec/2008:06:11:34 +0800] Listening to 0.0.0.0:631 on fd 4...
I [28/Dec/2008:06:11:34 +0800] Listening to /var/run/cups/cups.sock on fd 5...
I [28/Dec/2008:06:11:34 +0800] Resuming new connection processing...
D [28/Dec/2008:06:11:34 +0800] Discarding unused server-started event...
D [28/Dec/2008:06:11:35 +0800] Report: clients=0
D [28/Dec/2008:06:11:35 +0800] Report: jobs=80
D [28/Dec/2008:06:11:35 +0800] Report: jobs-active=0
D [28/Dec/2008:06:11:35 +0800] Report: printers=1
D [28/Dec/2008:06:11:35 +0800] Report: printers-implicit=0
D [28/Dec/2008:06:11:35 +0800] Report: stringpool-string-count=484
D [28/Dec/2008:06:11:35 +0800] Report: stringpool-alloc-bytes=7344
D [28/Dec/2008:06:11:35 +0800] Report: stringpool-total-bytes=9264
D [28/Dec/2008:06:12:21 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [28/Dec/2008:06:12:21 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [28/Dec/2008:06:12:21 +0800] cupsdAuthorize: No authentication data provided.
D [28/Dec/2008:06:12:21 +0800] Get-Jobs ipp://localhost/jobs/
D [28/Dec/2008:06:12:21 +0800] cupsdProcessIPPRequest: 9 status_code=0 (successful-ok)
D [28/Dec/2008:06:12:21 +0800] cupsdCloseClient: 9
D [28/Dec/2008:06:13:25 +0800] cupsdAcceptClient: 9 from localhost (Domain)
D [28/Dec/2008:06:13:25 +0800] Report: clients=1
D [28/Dec/2008:06:13:25 +0800] Report: jobs=80
D [28/Dec/2008:06:13:25 +0800] Report: jobs-active=0
D [28/Dec/2008:06:13:25 +0800] Report: printers=1
D [28/Dec/2008:06:13:25 +0800] Report: printers-implicit=0
D [28/Dec/2008:06:13:25 +0800] Report: stringpool-string-count=484
D [28/Dec/2008:06:13:25 +0800] Report: stringpool-alloc-bytes=7344
D [28/Dec/2008:06:13:25 +0800] Report: stringpool-total-bytes=9264
D [28/Dec/2008:06:13:25 +0800] cupsdReadClient: 9 POST / HTTP/1.1
D [28/Dec/2008:06:13:25 +0800] cupsdAuthorize: No authentication data provided.
D [28/Dec/2008:06:13:25 +0800] CUPS-Get-Devices
D [28/Dec/2008:06:13:25 +0800] [CGI] /usr/lib/cups/daemon/cups-deviced started - PID = 5936
I [28/Dec/2008:06:13:25 +0800] Started "/usr/lib/cups/daemon/cups-deviced" (pid=5936)
D [28/Dec/2008:06:13:25 +0800] cupsdSendCommand: 9 file=11
D [28/Dec/2008:06:13:26 +0800] cupsdAcceptClient: 12 from localhost (Domain)
D [28/Dec/2008:06:13:26 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [28/Dec/2008:06:13:26 +0800] cupsdAuthorize: No authentication data provided.
D [28/Dec/2008:06:13:26 +0800] CUPS-Get-Printers
D [28/Dec/2008:06:13:26 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [28/Dec/2008:06:13:26 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [28/Dec/2008:06:13:26 +0800] cupsdAuthorize: No authentication data provided.
D [28/Dec/2008:06:13:26 +0800] CUPS-Get-Classes
D [28/Dec/2008:06:13:26 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [28/Dec/2008:06:13:26 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [28/Dec/2008:06:13:26 +0800] cupsdAuthorize: No authentication data provided.
D [28/Dec/2008:06:13:26 +0800] CUPS-Get-Default
D [28/Dec/2008:06:13:26 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [28/Dec/2008:06:13:26 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [28/Dec/2008:06:13:26 +0800] cupsdAuthorize: No authentication data provided.
D [28/Dec/2008:06:13:26 +0800] CUPS-Get-Printers
D [28/Dec/2008:06:13:26 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [28/Dec/2008:06:13:26 +0800] cupsdReadClient: 12 POST / HTTP/1.1
D [28/Dec/2008:06:13:26 +0800] cupsdAuthorize: No authentication data provided.
D [28/Dec/2008:06:13:26 +0800] CUPS-Get-Printers
D [28/Dec/2008:06:13:26 +0800] cupsdProcessIPPRequest: 12 status_code=0 (successful-ok)
D [28/Dec/2008:06:13:26 +0800] cupsdCloseClient: 12
D [28/Dec/2008:06:13:26 +0800] [cups-deviced] Added device "serial:/dev/ttyS0?baud=115200"...
D [28/Dec/2008:06:13:26 +0800] [cups-deviced] Added device "scsi"...
D [28/Dec/2008:06:13:26 +0800] [cups-deviced] Added device "ipp"...
D [28/Dec/2008:06:13:26 +0800] [cups-deviced] Added device "parallel:/dev/lp0"...
D [28/Dec/2008:06:13:26 +0800] [cups-deviced] Added device "beh"...
D [28/Dec/2008:06:13:26 +0800] [cups-deviced] Added device "lpd"...
D [28/Dec/2008:06:13:26 +0800] [cups-deviced] Added device "smb"...
D [28/Dec/2008:06:13:26 +0800] [cups-deviced] Added device "socket"...
D [28/Dec/2008:06:13:26 +0800] [cups-deviced] Added device "hp"...
D [28/Dec/2008:06:13:28 +0800] [cups-deviced] Added device "http"...
D [28/Dec/2008:06:13:28 +0800] [cups-deviced] Added device "hpfax"...
D [28/Dec/2008:06:13:28 +0800] [cups-deviced] Added device "hal"...
D [28/Dec/2008:06:13:28 +0800] cupsdCloseClient: 9
D [28/Dec/2008:06:13:28 +0800] PID 5936 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.