E [03/Jun/2009:22:38:03 +0800] [Job 93] Empty print file!
E [03/Jun/2009:22:38:03 +0800] PID 28739 (/usr/lib/cups/filter/cpdftocps) stopped with status 1!
E [03/Jun/2009:22:38:08 +0800] [Job 93] Job stopped due to filter errors.
E [03/Jun/2009:22:39:16 +0800] [Job 94] Empty print file!
E [03/Jun/2009:22:39:16 +0800] PID 28795 (/usr/lib/cups/filter/cpdftocps) stopped with status 1!
E [03/Jun/2009:22:39:21 +0800] [Job 94] Job stopped due to filter errors.
E [03/Jun/2009:22:40:50 +0800] [Job 95] Empty print file!
E [03/Jun/2009:22:40:50 +0800] PID 29057 (/usr/lib/cups/filter/cpdftocps) stopped with status 1!
E [03/Jun/2009:22:40:55 +0800] [Job 95] Job stopped due to filter errors.
E [03/Jun/2009:22:44:59 +0800] [Job 97] Empty print file!
E [03/Jun/2009:22:44:59 +0800] PID 29143 (/usr/lib/cups/filter/cpdftocps) stopped with status 1!
E [03/Jun/2009:22:45:04 +0800] [Job 97] Job stopped due to filter errors.
E [03/Jun/2009:22:47:58 +0800] [Job 98] Empty print file!
E [03/Jun/2009:22:47:58 +0800] PID 29217 (/usr/lib/cups/filter/cpdftocps) stopped with status 1!
E [03/Jun/2009:22:48:03 +0800] [Job 98] Job stopped due to filter errors.
I [03/Jun/2009:23:35:20 +0800] Listening to 0.0.0.0:631 (IPv4)
I [03/Jun/2009:23:35:20 +0800] Listening to :::631 (IPv6)
I [03/Jun/2009:23:35:20 +0800] Listening to /var/run/cups/cups.sock (Domain)
I [03/Jun/2009:23:35:20 +0800] Loaded configuration file "/etc/cups/cupsd.conf"
I [03/Jun/2009:23:35:20 +0800] Using default TempDir of /var/spool/cups/tmp...
I [03/Jun/2009:23:35:20 +0800] Configured for up to 100 clients.
I [03/Jun/2009:23:35:20 +0800] Allowing up to 100 client connections per host.
I [03/Jun/2009:23:35:20 +0800] Using policy "default" as the default!
W [03/Jun/2009:23:35:20 +0800] DNS-SD registration of "HP-LaserJet-2200" failed with -65537
I [03/Jun/2009:23:35:20 +0800] Partial reload complete.
I [03/Jun/2009:23:35:20 +0800] Listening to 0.0.0.0:631 on fd 0...
I [03/Jun/2009:23:35:20 +0800] Listening to :::631 on fd 3...
I [03/Jun/2009:23:35:20 +0800] Listening to /var/run/cups/cups.sock on fd 4...
I [03/Jun/2009:23:35:20 +0800] Resuming new connection processing...
D [03/Jun/2009:23:35:20 +0800] Discarding unused server-restarted event...
D [03/Jun/2009:23:35:20 +0800] Report: clients=0
D [03/Jun/2009:23:35:20 +0800] Report: jobs=7
D [03/Jun/2009:23:35:20 +0800] Report: jobs-active=1
D [03/Jun/2009:23:35:20 +0800] Report: printers=1
D [03/Jun/2009:23:35:20 +0800] Report: printers-implicit=0
D [03/Jun/2009:23:35:20 +0800] Report: stringpool-string-count=1047
D [03/Jun/2009:23:35:20 +0800] Report: stringpool-alloc-bytes=10488
D [03/Jun/2009:23:35:20 +0800] Report: stringpool-total-bytes=23600
D [03/Jun/2009:23:35:21 +0800] cupsdAcceptClient: 13 from localhost (Domain)
D [03/Jun/2009:23:35:21 +0800] cupsdReadClient: 13 POST / HTTP/1.1
D [03/Jun/2009:23:35:21 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:21 +0800] CUPS-Get-Printers
D [03/Jun/2009:23:35:21 +0800] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:21 +0800] cupsdReadClient: 13 POST / HTTP/1.1
D [03/Jun/2009:23:35:21 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:21 +0800] CUPS-Get-Classes
D [03/Jun/2009:23:35:21 +0800] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:21 +0800] cupsdReadClient: 13 POST / HTTP/1.1
D [03/Jun/2009:23:35:21 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:21 +0800] CUPS-Get-Default
D [03/Jun/2009:23:35:21 +0800] cupsdProcessIPPRequest: 13 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:49 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:35:49 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:49 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:49 +0800] CUPS-Get-Printers
D [03/Jun/2009:23:35:49 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:49 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:49 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:49 +0800] CUPS-Get-Classes
D [03/Jun/2009:23:35:49 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:49 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:49 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:49 +0800] CUPS-Get-Default
D [03/Jun/2009:23:35:49 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:49 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:49 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:49 +0800] Get-Printer-Attributes ipp://localhost/printers/HP-LaserJet-2200
D [03/Jun/2009:23:35:49 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:49 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:49 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:49 +0800] Get-Jobs ipp://localhost/printers/HP-LaserJet-2200
D [03/Jun/2009:23:35:49 +0800] [Job 98] Loading attributes...
D [03/Jun/2009:23:35:49 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:49 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:35:53 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:35:53 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:53 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:53 +0800] CUPS-Get-Printers
D [03/Jun/2009:23:35:53 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:53 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:53 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:53 +0800] CUPS-Get-Classes
D [03/Jun/2009:23:35:53 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:53 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:53 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:53 +0800] CUPS-Get-Default
D [03/Jun/2009:23:35:53 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:53 +0800] cupsdAcceptClient: 17 from localhost (Domain)
D [03/Jun/2009:23:35:53 +0800] cupsdReadClient: 17 POST /jobs/ HTTP/1.1
D [03/Jun/2009:23:35:53 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:53 +0800] Cancel-Job ipp://localhost/printers/HP-LaserJet-2200
D [03/Jun/2009:23:35:53 +0800] cupsdIsAuthorized: requesting-user-name="chandra"
I [03/Jun/2009:23:35:53 +0800] Saving subscriptions.conf...
I [03/Jun/2009:23:35:53 +0800] [Job 98] Canceled by "chandra".
D [03/Jun/2009:23:35:53 +0800] cupsdProcessIPPRequest: 17 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:53 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:35:53 +0800] cupsdCloseClient: 17
D [03/Jun/2009:23:35:55 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:35:55 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:55 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:55 +0800] CUPS-Get-Printers
D [03/Jun/2009:23:35:55 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:55 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:55 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:55 +0800] CUPS-Get-Classes
D [03/Jun/2009:23:35:55 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:55 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:55 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:55 +0800] CUPS-Get-Default
D [03/Jun/2009:23:35:55 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:55 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:55 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:55 +0800] Get-Printer-Attributes ipp://localhost/printers/HP-LaserJet-2200
D [03/Jun/2009:23:35:55 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:55 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:35:55 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:35:55 +0800] Get-Jobs ipp://localhost/printers/HP-LaserJet-2200
D [03/Jun/2009:23:35:55 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:35:55 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:36:07 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:36:07 +0800] cupsdNetIFUpdate: "eth0" = 10.1.1.5:631
D [03/Jun/2009:23:36:07 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:36:07 +0800] cupsdNetIFUpdate: "eth0" = fe80::21f:d0ff:fe97:588%eth0:631
D [03/Jun/2009:23:36:39 +0800] Report: clients=1
D [03/Jun/2009:23:36:39 +0800] Report: jobs=7
D [03/Jun/2009:23:36:39 +0800] Report: jobs-active=0
D [03/Jun/2009:23:36:39 +0800] Report: printers=1
D [03/Jun/2009:23:36:39 +0800] Report: printers-implicit=0
D [03/Jun/2009:23:36:39 +0800] Report: stringpool-string-count=1110
D [03/Jun/2009:23:36:39 +0800] Report: stringpool-alloc-bytes=11536
D [03/Jun/2009:23:36:39 +0800] Report: stringpool-total-bytes=24816
D [03/Jun/2009:23:37:10 +0800] [Job 98] Unloading...
D [03/Jun/2009:23:37:10 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:37:10 +0800] cupsdNetIFUpdate: "eth0" = 10.1.1.5:631
D [03/Jun/2009:23:37:10 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:37:10 +0800] cupsdNetIFUpdate: "eth0" = fe80::21f:d0ff:fe97:588%eth0:631
D [03/Jun/2009:23:37:41 +0800] Report: clients=1
D [03/Jun/2009:23:37:41 +0800] Report: jobs=7
D [03/Jun/2009:23:37:41 +0800] Report: jobs-active=0
D [03/Jun/2009:23:37:41 +0800] Report: printers=1
D [03/Jun/2009:23:37:41 +0800] Report: printers-implicit=0
D [03/Jun/2009:23:37:41 +0800] Report: stringpool-string-count=1065
D [03/Jun/2009:23:37:41 +0800] Report: stringpool-alloc-bytes=10488
D [03/Jun/2009:23:37:41 +0800] Report: stringpool-total-bytes=23944
D [03/Jun/2009:23:38:03 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:03 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:03 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:03 +0800] CUPS-Get-Printers
D [03/Jun/2009:23:38:03 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:03 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:03 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:03 +0800] CUPS-Get-Classes
D [03/Jun/2009:23:38:03 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:03 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:03 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:03 +0800] CUPS-Get-Default
D [03/Jun/2009:23:38:03 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:03 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:38:03 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:03 +0800] cupsdReadClient: 16 POST /printers/HP-LaserJet-2200 HTTP/1.1
D [03/Jun/2009:23:38:03 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:03 +0800] Print-Job ipp://localhost/printers/HP-LaserJet-2200
D [03/Jun/2009:23:38:03 +0800] [Job ???] Auto-typing file...
I [03/Jun/2009:23:38:03 +0800] [Job ???] Request file type is application/pdf.
D [03/Jun/2009:23:38:03 +0800] add_job: requesting-user-name="chandra"
I [03/Jun/2009:23:38:03 +0800] [Job 99] Adding start banner page "none".
I [03/Jun/2009:23:38:03 +0800] Saving subscriptions.conf...
I [03/Jun/2009:23:38:03 +0800] [Job 99] Adding end banner page "none".
I [03/Jun/2009:23:38:03 +0800] [Job 99] File of type application/pdf queued by "chandra".
D [03/Jun/2009:23:38:03 +0800] [Job 99] hold_until=0
I [03/Jun/2009:23:38:03 +0800] [Job 99] Queued on "HP-LaserJet-2200" by "chandra".
I [03/Jun/2009:23:38:03 +0800] Saving subscriptions.conf...
D [03/Jun/2009:23:38:03 +0800] [Job 99] job-sheets=none,none
D [03/Jun/2009:23:38:03 +0800] [Job 99] banner_page = 0
D [03/Jun/2009:23:38:03 +0800] [Job 99] argv[0]="HP-LaserJet-2200"
D [03/Jun/2009:23:38:03 +0800] [Job 99] argv[1]="99"
D [03/Jun/2009:23:38:03 +0800] [Job 99] argv[2]="chandra"
D [03/Jun/2009:23:38:03 +0800] [Job 99] argv[3]="Referees.pdf"
D [03/Jun/2009:23:38:03 +0800] [Job 99] argv[4]="1"
D [03/Jun/2009:23:38:03 +0800] [Job 99] argv[5]="sides=two-sided-long-edge media=A4 finishings=3 fitplot number-up=1 job-uuid=urn:uuid:4d8b3409-caf6-3fd2-5f7f-6fd76e2df086"
D [03/Jun/2009:23:38:03 +0800] [Job 99] argv[6]="/var/spool/cups/d00099-001"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[8]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[9]="SERVER_ADMIN=root@saraswati.homedomain.lan"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[10]="SOFTWARE=CUPS/1.3.9"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[11]="TMPDIR=/var/spool/cups/tmp"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[12]="TZ=Australia/Perth"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[13]="USER=root"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[16]="IPP_PORT=631"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[17]="CHARSET=utf-8"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[18]="LANG=en_AU.UTF8"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[19]="PPD=/etc/cups/ppd/HP-LaserJet-2200.ppd"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[20]="RIP_MAX_CACHE=8m"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[21]="CONTENT_TYPE=application/pdf"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[22]="DEVICE_URI=socket://10.1.1.4:9100"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[23]="PRINTER=HP-LaserJet-2200"
D [03/Jun/2009:23:38:03 +0800] [Job 99] envp[24]="FINAL_CONTENT_TYPE=application/vnd.cups-postscript"
I [03/Jun/2009:23:38:03 +0800] [Job 99] Started filter /usr/lib/cups/filter/pdftopdf (PID 30589)
I [03/Jun/2009:23:38:03 +0800] [Job 99] Started filter /usr/lib/cups/filter/cpdftocps (PID 30590)
I [03/Jun/2009:23:38:03 +0800] [Job 99] Started backend /usr/lib/cups/backend/socket (PID 30591)
I [03/Jun/2009:23:38:03 +0800] Saving subscriptions.conf...
D [03/Jun/2009:23:38:03 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:03 +0800] cupsdCloseClient: 16
I [03/Jun/2009:23:38:03 +0800] Saving subscriptions.conf...
I [03/Jun/2009:23:38:03 +0800] Saving subscriptions.conf...
I [03/Jun/2009:23:38:03 +0800] Saving subscriptions.conf...
I [03/Jun/2009:23:38:03 +0800] Saving subscriptions.conf...
D [03/Jun/2009:23:38:03 +0800] [Job 99] Connected to 10.1.1.4:9100 (IPv4)...
D [03/Jun/2009:23:38:03 +0800] [Job 99] backendRunLoop(print_fd=0, device_fd=5, use_bc=1, side_cb=0x7feebcd4f520)
D [03/Jun/2009:23:38:03 +0800] PID 30589 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [03/Jun/2009:23:38:03 +0800] [Job 99] Device copies: 1; device collate: 
D [03/Jun/2009:23:38:03 +0800] [Job 99] pdftops argv[5] = 99 chandra Referees.pdf 1 sides=two-sided-long-edge media=A4 finishings=3 fitplot number-up=1 job-uuid=urn:uuid:4d8b3409-caf6-3fd2-5f7f-6fd76e2df086
D [03/Jun/2009:23:38:03 +0800] [Job 99] PPD: /etc/cups/ppd/HP-LaserJet-2200.ppd
D [03/Jun/2009:23:38:03 +0800] [Job 99] /usr/bin/pdftops supports '-origpagesizes': no
D [03/Jun/2009:23:38:03 +0800] [Job 99] PostScript Level: 2
D [03/Jun/2009:23:38:03 +0800] [Job 99] Resolution: 600
D [03/Jun/2009:23:38:03 +0800] [Job 99] Page size: A4
D [03/Jun/2009:23:38:03 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:03 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:03 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:03 +0800] Get-Notifications /
D [03/Jun/2009:23:38:03 +0800] cupsdIsAuthorized: requesting-user-name="chandra"
D [03/Jun/2009:23:38:03 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:03 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:03 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:03 +0800] Get-Job-Attributes ipp://localhost/jobs/99
D [03/Jun/2009:23:38:03 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:03 +0800] [Job 99] Width: 595, height: 842, absolute margins: 12.00, 12.00, 582.96, 829.44
D [03/Jun/2009:23:38:03 +0800] [Job 99] Relative margins: 12.00, 12.00, 12.04, 12.56
D [03/Jun/2009:23:38:04 +0800] [Job 99] PPD options: -level2 -noembtt -paperw 595 -paperh 842 -expand
D [03/Jun/2009:23:38:04 +0800] [Job 99] PostScript to be injected: 
D [03/Jun/2009:23:38:04 +0800] [Job 99] Running /usr/bin/pdftops  -level2 -noembtt -paperw 595 -paperh 842 -expand /tmp/pdftops.GCXGXc -
D [03/Jun/2009:23:38:04 +0800] [Job 99] /usr/lib/cups/filter/pdftops: 207: /usr/bin/pdftops: Permission denied
E [03/Jun/2009:23:38:04 +0800] [Job 99] Empty print file!
I [03/Jun/2009:23:38:04 +0800] Saving subscriptions.conf...
E [03/Jun/2009:23:38:04 +0800] PID 30590 (/usr/lib/cups/filter/cpdftocps) stopped with status 1!
D [03/Jun/2009:23:38:04 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:38:04 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:04 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:04 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:04 +0800] Get-Notifications /
D [03/Jun/2009:23:38:04 +0800] cupsdIsAuthorized: requesting-user-name="chandra"
D [03/Jun/2009:23:38:04 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:04 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:38:04 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:04 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:04 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:04 +0800] Get-Notifications /
D [03/Jun/2009:23:38:04 +0800] cupsdIsAuthorized: requesting-user-name="chandra"
D [03/Jun/2009:23:38:04 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:04 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:38:04 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:04 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:04 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:04 +0800] Get-Notifications /
D [03/Jun/2009:23:38:04 +0800] cupsdIsAuthorized: requesting-user-name="chandra"
D [03/Jun/2009:23:38:04 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:04 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:38:04 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:04 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:04 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:04 +0800] Get-Notifications /
D [03/Jun/2009:23:38:04 +0800] cupsdIsAuthorized: requesting-user-name="chandra"
D [03/Jun/2009:23:38:04 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:04 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:38:04 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:04 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:04 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:04 +0800] Get-Notifications /
D [03/Jun/2009:23:38:04 +0800] cupsdIsAuthorized: requesting-user-name="chandra"
D [03/Jun/2009:23:38:04 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:04 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:38:04 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:04 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:04 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:04 +0800] Get-Notifications /
D [03/Jun/2009:23:38:04 +0800] cupsdIsAuthorized: requesting-user-name="chandra"
D [03/Jun/2009:23:38:04 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:04 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:38:04 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:04 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:04 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:04 +0800] Get-Notifications /
D [03/Jun/2009:23:38:04 +0800] cupsdIsAuthorized: requesting-user-name="chandra"
D [03/Jun/2009:23:38:04 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:04 +0800] cupsdCloseClient: 16
D [03/Jun/2009:23:38:09 +0800] PID 30591 (/usr/lib/cups/backend/socket) exited with no errors.
D [03/Jun/2009:23:38:09 +0800] [Job 99] File 0 is complete.
E [03/Jun/2009:23:38:09 +0800] [Job 99] Job stopped due to filter errors.
I [03/Jun/2009:23:38:09 +0800] Saving subscriptions.conf...
I [03/Jun/2009:23:38:09 +0800] Saving subscriptions.conf...
D [03/Jun/2009:23:38:09 +0800] cupsdAcceptClient: 16 from localhost (Domain)
D [03/Jun/2009:23:38:09 +0800] cupsdReadClient: 16 POST / HTTP/1.1
D [03/Jun/2009:23:38:09 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:09 +0800] Get-Notifications /
D [03/Jun/2009:23:38:09 +0800] cupsdIsAuthorized: requesting-user-name="chandra"
D [03/Jun/2009:23:38:09 +0800] cupsdProcessIPPRequest: 16 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:10 +0800] [Job 99] Unloading...
D [03/Jun/2009:23:38:10 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:38:10 +0800] cupsdNetIFUpdate: "eth0" = 10.1.1.5:631
D [03/Jun/2009:23:38:10 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:38:10 +0800] cupsdNetIFUpdate: "eth0" = fe80::21f:d0ff:fe97:588%eth0:631
D [03/Jun/2009:23:38:33 +0800] cupsdAcceptClient: 17 from localhost (Domain)
D [03/Jun/2009:23:38:33 +0800] cupsdReadClient: 17 POST / HTTP/1.1
D [03/Jun/2009:23:38:33 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:33 +0800] CUPS-Get-Printers
D [03/Jun/2009:23:38:33 +0800] cupsdProcessIPPRequest: 17 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:33 +0800] cupsdReadClient: 17 POST / HTTP/1.1
D [03/Jun/2009:23:38:33 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:33 +0800] CUPS-Get-Classes
D [03/Jun/2009:23:38:33 +0800] cupsdProcessIPPRequest: 17 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:33 +0800] cupsdReadClient: 17 POST / HTTP/1.1
D [03/Jun/2009:23:38:33 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:33 +0800] CUPS-Get-Default
D [03/Jun/2009:23:38:33 +0800] cupsdProcessIPPRequest: 17 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:33 +0800] cupsdReadClient: 17 POST / HTTP/1.1
D [03/Jun/2009:23:38:33 +0800] cupsdAuthorize: No authentication data provided.
D [03/Jun/2009:23:38:33 +0800] CUPS-Get-Printers
D [03/Jun/2009:23:38:33 +0800] cupsdProcessIPPRequest: 17 status_code=0 (successful-ok)
D [03/Jun/2009:23:38:33 +0800] cupsdCloseClient: 17
D [03/Jun/2009:23:38:41 +0800] Report: clients=2
D [03/Jun/2009:23:38:41 +0800] Report: jobs=8
D [03/Jun/2009:23:38:41 +0800] Report: jobs-active=1
D [03/Jun/2009:23:38:41 +0800] Report: printers=1
D [03/Jun/2009:23:38:41 +0800] Report: printers-implicit=0
D [03/Jun/2009:23:38:41 +0800] Report: stringpool-string-count=1278
D [03/Jun/2009:23:38:41 +0800] Report: stringpool-alloc-bytes=10528
D [03/Jun/2009:23:38:41 +0800] Report: stringpool-total-bytes=28840
D [03/Jun/2009:23:39:12 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:39:12 +0800] cupsdNetIFUpdate: "eth0" = 10.1.1.5:631
D [03/Jun/2009:23:39:12 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:39:12 +0800] cupsdNetIFUpdate: "eth0" = fe80::21f:d0ff:fe97:588%eth0:631
D [03/Jun/2009:23:39:43 +0800] Report: clients=2
D [03/Jun/2009:23:39:43 +0800] Report: jobs=8
D [03/Jun/2009:23:39:43 +0800] Report: jobs-active=1
D [03/Jun/2009:23:39:43 +0800] Report: printers=1
D [03/Jun/2009:23:39:43 +0800] Report: printers-implicit=0
D [03/Jun/2009:23:39:43 +0800] Report: stringpool-string-count=1278
D [03/Jun/2009:23:39:43 +0800] Report: stringpool-alloc-bytes=10528
D [03/Jun/2009:23:39:43 +0800] Report: stringpool-total-bytes=28840
D [03/Jun/2009:23:40:14 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:40:14 +0800] cupsdNetIFUpdate: "eth0" = 10.1.1.5:631
D [03/Jun/2009:23:40:14 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:40:14 +0800] cupsdNetIFUpdate: "eth0" = fe80::21f:d0ff:fe97:588%eth0:631
D [03/Jun/2009:23:40:22 +0800] Closing client 13 after 300 seconds of inactivity...
D [03/Jun/2009:23:40:22 +0800] cupsdCloseClient: 13
D [03/Jun/2009:23:40:45 +0800] Report: clients=1
D [03/Jun/2009:23:40:45 +0800] Report: jobs=8
D [03/Jun/2009:23:40:45 +0800] Report: jobs-active=1
D [03/Jun/2009:23:40:45 +0800] Report: printers=1
D [03/Jun/2009:23:40:45 +0800] Report: printers-implicit=0
D [03/Jun/2009:23:40:45 +0800] Report: stringpool-string-count=1278
D [03/Jun/2009:23:40:45 +0800] Report: stringpool-alloc-bytes=10528
D [03/Jun/2009:23:40:45 +0800] Report: stringpool-total-bytes=28840
D [03/Jun/2009:23:41:16 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:41:16 +0800] cupsdNetIFUpdate: "eth0" = 10.1.1.5:631
D [03/Jun/2009:23:41:16 +0800] cupsdNetIFUpdate: "lo" = localhost:631
D [03/Jun/2009:23:41:16 +0800] cupsdNetIFUpdate: "eth0" = fe80::21f:d0ff:fe97:588%eth0:631