W [25/Oct/2011:07:47:46 +1000] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/Officejet_Pro_K550_Gray__' has already been added
W [25/Oct/2011:07:47:46 +1000] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/Officejet_Pro_K550_RGB__' has already been added
W [25/Oct/2011:07:47:46 +1000] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/PDF_Gray__' has already been added
W [25/Oct/2011:07:47:46 +1000] failed to AddProfile: org.freedesktop.ColorManager.Failed:profile object path '/org/freedesktop/ColorManager/profiles/PDF_RGB__' has already been added
E [25/Oct/2011:11:57:12 +1000] Unknown directive hpLogLevel on line 10.
I [25/Oct/2011:11:57:12 +1000] Listening to [v1.::1]:631 (IPv6)
I [25/Oct/2011:11:57:12 +1000] Listening to 127.0.0.1:631 (IPv4)
I [25/Oct/2011:11:57:12 +1000] Listening to /var/run/cups/cups.sock (Domain)
I [25/Oct/2011:11:57:12 +1000] Remote access is disabled.
D [25/Oct/2011:11:57:12 +1000] Added auto ServerAlias robert-HP-ProBook-4520s
I [25/Oct/2011:11:57:12 +1000] Loaded configuration file "/etc/cups/cupsd.conf"
I [25/Oct/2011:11:57:12 +1000] Using default TempDir of /var/spool/cups/tmp...
I [25/Oct/2011:11:57:12 +1000] Configured for up to 100 clients.
I [25/Oct/2011:11:57:12 +1000] Allowing up to 100 client connections per host.
I [25/Oct/2011:11:57:12 +1000] Using policy "default" as the default.
I [25/Oct/2011:11:57:12 +1000] Full reload is required.
I [25/Oct/2011:11:57:12 +1000] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 38 types, 78 filters...
D [25/Oct/2011:11:57:12 +1000] Loading printer Officejet_Pro_K550...
D [25/Oct/2011:11:57:12 +1000] load_ppd: Loading /var/cache/cups/Officejet_Pro_K550.data...
D [25/Oct/2011:11:57:12 +1000] Calling DeleteDevice(cups-Officejet_Pro_K550)
D [25/Oct/2011:11:57:12 +1000] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [25/Oct/2011:11:57:12 +1000] Using profile id of Officejet_Pro_K550-Gray..
D [25/Oct/2011:11:57:12 +1000] Calling CreateProfile(Officejet_Pro_K550-Gray..,temp)
D [25/Oct/2011:11:57:12 +1000] created profile /org/freedesktop/ColorManager/profiles/Officejet_Pro_K550_Gray__
D [25/Oct/2011:11:57:12 +1000] Using profile id of Officejet_Pro_K550-RGB..
D [25/Oct/2011:11:57:12 +1000] Calling CreateProfile(Officejet_Pro_K550-RGB..,temp)
D [25/Oct/2011:11:57:12 +1000] created profile /org/freedesktop/ColorManager/profiles/Officejet_Pro_K550_RGB__
I [25/Oct/2011:11:57:12 +1000] Registering ICC color profiles for "Officejet_Pro_K550"
D [25/Oct/2011:11:57:12 +1000] Calling CreateDevice(cups-Officejet_Pro_K550,temp)
D [25/Oct/2011:11:57:12 +1000] created device /org/freedesktop/ColorManager/devices/cups_Officejet_Pro_K550
D [25/Oct/2011:11:57:12 +1000] Calling /org/freedesktop/ColorManager/devices/cups_Officejet_Pro_K550:AddProfile(/org/freedesktop/ColorManager/profiles/Officejet_Pro_K550_Gray__) [soft]
D [25/Oct/2011:11:57:12 +1000] Calling /org/freedesktop/ColorManager/devices/cups_Officejet_Pro_K550:AddProfile(/org/freedesktop/ColorManager/profiles/Officejet_Pro_K550_RGB__) [soft]
D [25/Oct/2011:11:57:12 +1000] cupsdRegisterPrinter(p=0x226c1a78(Officejet_Pro_K550))
D [25/Oct/2011:11:57:12 +1000] Loading printer PDF...
D [25/Oct/2011:11:57:12 +1000] load_ppd: Loading /var/cache/cups/PDF.data...
D [25/Oct/2011:11:57:12 +1000] Calling DeleteDevice(cups-PDF)
D [25/Oct/2011:11:57:12 +1000] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [25/Oct/2011:11:57:12 +1000] Using profile id of PDF-Gray..
D [25/Oct/2011:11:57:12 +1000] Calling CreateProfile(PDF-Gray..,temp)
D [25/Oct/2011:11:57:12 +1000] created profile /org/freedesktop/ColorManager/profiles/PDF_Gray__
D [25/Oct/2011:11:57:12 +1000] Using profile id of PDF-RGB..
D [25/Oct/2011:11:57:12 +1000] Calling CreateProfile(PDF-RGB..,temp)
D [25/Oct/2011:11:57:12 +1000] created profile /org/freedesktop/ColorManager/profiles/PDF_RGB__
I [25/Oct/2011:11:57:12 +1000] Registering ICC color profiles for "PDF"
D [25/Oct/2011:11:57:12 +1000] Calling CreateDevice(cups-PDF,temp)
D [25/Oct/2011:11:57:12 +1000] created device /org/freedesktop/ColorManager/devices/cups_PDF
D [25/Oct/2011:11:57:12 +1000] Calling /org/freedesktop/ColorManager/devices/cups_PDF:AddProfile(/org/freedesktop/ColorManager/profiles/PDF_Gray__) [soft]
D [25/Oct/2011:11:57:12 +1000] Calling /org/freedesktop/ColorManager/devices/cups_PDF:AddProfile(/org/freedesktop/ColorManager/profiles/PDF_RGB__) [soft]
D [25/Oct/2011:11:57:12 +1000] cupsdRegisterPrinter(p=0x227ae798(PDF))
D [25/Oct/2011:11:57:12 +1000] cupsdLoadRemoteCache: Not loading remote cache.
I [25/Oct/2011:11:57:12 +1000] Loading job cache file "/var/cache/cups/job.cache"...
D [25/Oct/2011:11:57:12 +1000] [Job 22] Loading from cache...
D [25/Oct/2011:11:57:12 +1000] [Job 59] Loading from cache...
D [25/Oct/2011:11:57:12 +1000] [Job 60] Loading from cache...
D [25/Oct/2011:11:57:12 +1000] [Job 64] Loading from cache...
D [25/Oct/2011:11:57:12 +1000] cupsdAddSubscription(mask=0, dest=(nil)(), job=(nil)(0), uri="(null)")
I [25/Oct/2011:11:57:12 +1000] Full reload complete.
D [25/Oct/2011:11:57:12 +1000] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [25/Oct/2011:11:57:12 +1000] Cleaning out old files in "/var/spool/cups/tmp"...
D [25/Oct/2011:11:57:12 +1000] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [25/Oct/2011:11:57:12 +1000] Cleaning out old files in "/var/cache/cups"...
I [25/Oct/2011:11:57:12 +1000] Listening to [v1.::1]:631 on fd 9...
I [25/Oct/2011:11:57:12 +1000] Listening to 127.0.0.1:631 on fd 10...
I [25/Oct/2011:11:57:12 +1000] Listening to /var/run/cups/cups.sock:631 on fd 11...
I [25/Oct/2011:11:57:12 +1000] Resuming new connection processing...
D [25/Oct/2011:11:57:12 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [25/Oct/2011:11:57:12 +1000] Discarding unused server-started event...
D [25/Oct/2011:11:57:13 +1000] Report: clients=0
D [25/Oct/2011:11:57:13 +1000] Report: jobs=4
D [25/Oct/2011:11:57:13 +1000] Report: jobs-active=0
D [25/Oct/2011:11:57:13 +1000] Report: printers=2
D [25/Oct/2011:11:57:13 +1000] Report: printers-implicit=0
D [25/Oct/2011:11:57:13 +1000] Report: stringpool-string-count=18753
D [25/Oct/2011:11:57:13 +1000] Report: stringpool-alloc-bytes=13264
D [25/Oct/2011:11:57:13 +1000] Report: stringpool-total-bytes=348856
D [25/Oct/2011:11:59:11 +1000] cupsdAcceptClient: 14 from localhost (Domain)
D [25/Oct/2011:11:59:11 +1000] Avahi client started
D [25/Oct/2011:11:59:11 +1000] Report: clients=1
D [25/Oct/2011:11:59:11 +1000] Report: jobs=4
D [25/Oct/2011:11:59:11 +1000] Report: jobs-active=0
D [25/Oct/2011:11:59:11 +1000] Report: printers=2
D [25/Oct/2011:11:59:11 +1000] Report: printers-implicit=0
D [25/Oct/2011:11:59:11 +1000] Report: stringpool-string-count=18753
D [25/Oct/2011:11:59:11 +1000] Report: stringpool-alloc-bytes=13264
D [25/Oct/2011:11:59:11 +1000] Report: stringpool-total-bytes=348856
D [25/Oct/2011:11:59:11 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:11 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Oct/2011:11:59:11 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:11 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Devices 1
D [25/Oct/2011:11:59:11 +1000] CUPS-Get-Devices
D [25/Oct/2011:11:59:11 +1000] cupsdIsAuthorized: username=""
D [25/Oct/2011:11:59:11 +1000] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
D [25/Oct/2011:11:59:11 +1000] cupsdSendHeader: 14 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [25/Oct/2011:11:59:11 +1000] cupsdReadClient: 14 WAITING Closing on EOF
D [25/Oct/2011:11:59:11 +1000] cupsdCloseClient: 14
D [25/Oct/2011:11:59:11 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Oct/2011:11:59:11 +1000] cupsdAcceptClient: 14 from localhost (Domain)
D [25/Oct/2011:11:59:11 +1000] cupsdAcceptClient: 16 from localhost (Domain)
D [25/Oct/2011:11:59:11 +1000] cupsdReadClient: 14 WAITING Closing on EOF
D [25/Oct/2011:11:59:11 +1000] cupsdCloseClient: 14
D [25/Oct/2011:11:59:11 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [25/Oct/2011:11:59:11 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:11 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Oct/2011:11:59:11 +1000] cupsdAuthorize: Authorized as root using PeerCred
D [25/Oct/2011:11:59:11 +1000] cupsdReadClient: 16 1.1 CUPS-Get-Devices 1
D [25/Oct/2011:11:59:11 +1000] CUPS-Get-Devices
D [25/Oct/2011:11:59:11 +1000] cupsdIsAuthorized: username="root"
D [25/Oct/2011:11:59:11 +1000] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-deviced"
D [25/Oct/2011:11:59:11 +1000] [CGI] argv[1] = "1"
D [25/Oct/2011:11:59:11 +1000] [CGI] argv[2] = "0"
D [25/Oct/2011:11:59:11 +1000] [CGI] argv[3] = "2"
D [25/Oct/2011:11:59:11 +1000] [CGI] argv[4] = "7"
D [25/Oct/2011:11:59:11 +1000] [CGI] argv[5] = "requested-attributes=all exclude-schemes='beh','cups-pdf','bluetooth','dnssd','http','https','ipp','lpd','ncp','parallel','scsi','smb','snmp','socket'"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[10] = "SERVER_ADMIN=root@robert-HP-ProBook-4520s"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.0"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[13] = "USER=root"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[16] = "IPP_PORT=631"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[17] = "AUTH_TYPE=PeerCred"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[18] = "LANG=en_US.UTF8"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[21] = "SERVER_NAME=localhost"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[22] = "SERVER_PORT=631"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[23] = "REMOTE_ADDR="
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[25] = "SCRIPT_NAME=/"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[27] = "REMOTE_USER=root"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[29] = "HTTP_USER_AGENT=CUPS/1.5.0"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[30] = "REQUEST_METHOD=POST"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[31] = "CONTENT_LENGTH=244"
D [25/Oct/2011:11:59:11 +1000] [CGI] envp[32] = "CONTENT_TYPE=application/ipp"
D [25/Oct/2011:11:59:11 +1000] [CGI] Started /usr/lib/cups/daemon/cups-deviced (PID 2683)
I [25/Oct/2011:11:59:11 +1000] Started "/usr/lib/cups/daemon/cups-deviced" (pid=2683)
D [25/Oct/2011:11:59:11 +1000] cupsdSendCommand: 16 file=14
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] Started backend /usr/lib/cups/backend/hp (PID 2684)
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] Started backend /usr/lib/cups/backend/serial (PID 2685)
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] Started backend /usr/lib/cups/backend/hpfax (PID 2686)
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] Started backend /usr/lib/cups/backend/usb (PID 2687)
D [25/Oct/2011:11:59:11 +1000] [CGI] Flushed attributes...
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] Found device "hp:/usb/Officejet_Pro_K550?serial=MY5C9210JF"...
D [25/Oct/2011:11:59:11 +1000] Script header: Content-Type: application/ipp
D [25/Oct/2011:11:59:11 +1000] Script header: 
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] PID 2684 (hp) exited with no errors.
D [25/Oct/2011:11:59:11 +1000] [CGI] list_devices
D [25/Oct/2011:11:59:11 +1000] [CGI] usb_find_busses=2
D [25/Oct/2011:11:59:11 +1000] [CGI] usb_find_devices=8
D [25/Oct/2011:11:59:11 +1000] [CGI] Flushed attributes...
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] Found device "usb://HP/Officejet%20Pro%20K550?serial=MY5C9210JF"...
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] PID 2687 (usb) exited with no errors.
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] PID 2685 (serial) exited with no errors.
D [25/Oct/2011:11:59:11 +1000] [CGI] Flushed attributes...
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] Found device "hpfax"...
D [25/Oct/2011:11:59:11 +1000] [cups-deviced] PID 2686 (hpfax) exited with no errors.
D [25/Oct/2011:11:59:11 +1000] PID 2683 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
D [25/Oct/2011:11:59:11 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Oct/2011:11:59:11 +1000] cupsdReadClient: 16 WAITING Closing on EOF
D [25/Oct/2011:11:59:11 +1000] cupsdCloseClient: 16
D [25/Oct/2011:11:59:11 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [25/Oct/2011:11:59:11 +1000] cupsdAcceptClient: 14 from localhost (Domain)
D [25/Oct/2011:11:59:11 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:11 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Oct/2011:11:59:11 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:11 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:11 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:11 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:11 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Oct/2011:11:59:11 +1000] cupsdReadClient: 14 WAITING Closing on EOF
D [25/Oct/2011:11:59:11 +1000] cupsdCloseClient: 14
D [25/Oct/2011:11:59:11 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [25/Oct/2011:11:59:27 +1000] cupsdAcceptClient: 14 from localhost (Domain)
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Oct/2011:11:59:27 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:27 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:27 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Oct/2011:11:59:27 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Classes 1
D [25/Oct/2011:11:59:27 +1000] CUPS-Get-Classes
D [25/Oct/2011:11:59:27 +1000] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Oct/2011:11:59:27 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [25/Oct/2011:11:59:27 +1000] CUPS-Get-Default
D [25/Oct/2011:11:59:27 +1000] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [25/Oct/2011:11:59:27 +1000] cupsdAcceptClient: 16 from localhost (Domain)
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [25/Oct/2011:11:59:27 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 16 1.1 Create-Printer-Subscription 1
D [25/Oct/2011:11:59:27 +1000] Create-Printer-Subscription /
D [25/Oct/2011:11:59:27 +1000] cupsdCreateSubscription(con=0x227c72d0(16), uri="/")
D [25/Oct/2011:11:59:27 +1000] pullmethod="ippget"
D [25/Oct/2011:11:59:27 +1000] notify-lease-duration=86400
D [25/Oct/2011:11:59:27 +1000] notify-time-interval=0
D [25/Oct/2011:11:59:27 +1000] cupsdAddSubscription(mask=18f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [25/Oct/2011:11:59:27 +1000] Added subscription #87 for server.
D [25/Oct/2011:11:59:27 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [25/Oct/2011:11:59:27 +1000] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:27 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:27 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:27 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:27 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:27 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:27 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:27 +1000] cupsdReadClient: 16 WAITING Closing on EOF
D [25/Oct/2011:11:59:27 +1000] cupsdCloseClient: 16
D [25/Oct/2011:11:59:27 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:28 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:28 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:28 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:28 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:28 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:28 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:28 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:28 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:28 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:28 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:28 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Classes 1
D [25/Oct/2011:11:59:28 +1000] CUPS-Get-Classes
D [25/Oct/2011:11:59:28 +1000] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [25/Oct/2011:11:59:28 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:28 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:28 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:28 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:28 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [25/Oct/2011:11:59:28 +1000] CUPS-Get-Default
D [25/Oct/2011:11:59:28 +1000] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [25/Oct/2011:11:59:28 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:29 +1000] cupsdAcceptClient: 16 from localhost (Domain)
D [25/Oct/2011:11:59:29 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:29 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:29 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:29 +1000] cupsdReadClient: 16 1.1 Get-Notifications 1
D [25/Oct/2011:11:59:29 +1000] Get-Notifications /
D [25/Oct/2011:11:59:29 +1000] cupsdIsAuthorized: requesting-user-name="robert"
D [25/Oct/2011:11:59:29 +1000] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [25/Oct/2011:11:59:29 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:29 +1000] cupsdReadClient: 16 WAITING Closing on EOF
D [25/Oct/2011:11:59:29 +1000] cupsdCloseClient: 16
D [25/Oct/2011:11:59:29 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdAcceptClient: 16 from localhost (Domain)
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 16 1.1 Create-Printer-Subscription 1
D [25/Oct/2011:11:59:32 +1000] Create-Printer-Subscription /
D [25/Oct/2011:11:59:32 +1000] cupsdCreateSubscription(con=0x227c72d0(16), uri="/")
D [25/Oct/2011:11:59:32 +1000] pullmethod="ippget"
D [25/Oct/2011:11:59:32 +1000] notify-lease-duration=86400
D [25/Oct/2011:11:59:32 +1000] notify-time-interval=0
D [25/Oct/2011:11:59:32 +1000] cupsdAddSubscription(mask=1798f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [25/Oct/2011:11:59:32 +1000] Added subscription #88 for server.
D [25/Oct/2011:11:59:32 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:32 +1000] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:32 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:32 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 16 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:32 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:32 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdAcceptClient: 17 from localhost (Domain)
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 17 POST / HTTP/1.1
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:11:59:32 +1000] Get-Printer-Attributes ipp://localhost/printers/PDF
D [25/Oct/2011:11:59:32 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 17 WAITING Closing on EOF
D [25/Oct/2011:11:59:32 +1000] cupsdCloseClient: 17
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdAcceptClient: 17 from localhost (Domain)
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 17 POST / HTTP/1.1
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 17 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:11:59:32 +1000] Get-Printer-Attributes ipp://localhost/printers/Officejet_Pro_K550
D [25/Oct/2011:11:59:32 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Officejet_Pro_K550) from localhost
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 17 WAITING Closing on EOF
D [25/Oct/2011:11:59:32 +1000] cupsdCloseClient: 17
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 16 WAITING Closing on EOF
D [25/Oct/2011:11:59:32 +1000] cupsdCloseClient: 16
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdAcceptClient: 16 from localhost (Domain)
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 16 1.1 Get-Jobs 1
D [25/Oct/2011:11:59:32 +1000] Get-Jobs ipp://localhost/printers/
D [25/Oct/2011:11:59:32 +1000] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:32 +1000] cupsdReadClient: 16 WAITING Closing on EOF
D [25/Oct/2011:11:59:32 +1000] cupsdCloseClient: 16
D [25/Oct/2011:11:59:32 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:33 +1000] cupsdAcceptClient: 16 from localhost (Domain)
D [25/Oct/2011:11:59:33 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:33 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:33 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:33 +1000] cupsdReadClient: 16 1.1 Get-Notifications 1
D [25/Oct/2011:11:59:33 +1000] Get-Notifications /
D [25/Oct/2011:11:59:33 +1000] cupsdIsAuthorized: requesting-user-name="robert"
D [25/Oct/2011:11:59:33 +1000] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [25/Oct/2011:11:59:33 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:33 +1000] cupsdReadClient: 16 WAITING Closing on EOF
D [25/Oct/2011:11:59:33 +1000] cupsdCloseClient: 16
D [25/Oct/2011:11:59:33 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:34 +1000] cupsdAcceptClient: 16 from localhost (Domain)
D [25/Oct/2011:11:59:34 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:34 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:34 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:34 +1000] cupsdReadClient: 16 1.1 Cancel-Subscription 1
D [25/Oct/2011:11:59:34 +1000] Cancel-Subscription /
D [25/Oct/2011:11:59:34 +1000] cupsdIsAuthorized: requesting-user-name="robert"
D [25/Oct/2011:11:59:34 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:34 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:34 +1000] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
D [25/Oct/2011:11:59:34 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:34 +1000] cupsdReadClient: 16 WAITING Closing on EOF
D [25/Oct/2011:11:59:34 +1000] cupsdCloseClient: 16
D [25/Oct/2011:11:59:34 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:36 +1000] cupsdAcceptClient: 16 from localhost (Domain)
D [25/Oct/2011:11:59:36 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:36 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:36 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:36 +1000] cupsdReadClient: 16 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:11:59:36 +1000] Get-Printer-Attributes ipp://localhost/printers/Officejet_Pro_K550
D [25/Oct/2011:11:59:36 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Officejet_Pro_K550) from localhost
D [25/Oct/2011:11:59:36 +1000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAcceptClient: 17 from localhost (Domain)
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 17 POST /printers/Officejet_Pro_K550 HTTP/1.1
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 17 1.1 Print-Job 1
D [25/Oct/2011:11:59:40 +1000] Print-Job ipp://localhost/printers/Officejet_Pro_K550
D [25/Oct/2011:11:59:40 +1000] [Job ???] Auto-typing file...
I [25/Oct/2011:11:59:40 +1000] [Job ???] Request file type is application/postscript.
D [25/Oct/2011:11:59:40 +1000] cupsdMarkDirty(----J-)
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:40 +1000] add_job: requesting-user-name="robert"
D [25/Oct/2011:11:59:40 +1000] Adding default job-sheets values "none,none"...
I [25/Oct/2011:11:59:40 +1000] [Job 65] Adding start banner page "none".
D [25/Oct/2011:11:59:40 +1000] Notifier dbus started - PID = 2709
D [25/Oct/2011:11:59:40 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdMarkDirty(----J-)
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [25/Oct/2011:11:59:40 +1000] [Job 65] Adding end banner page "none".
I [25/Oct/2011:11:59:40 +1000] [Job 65] File of type application/postscript queued by "robert".
D [25/Oct/2011:11:59:40 +1000] [Job 65] hold_until=0
I [25/Oct/2011:11:59:40 +1000] [Job 65] Queued on "Officejet_Pro_K550" by "robert".
D [25/Oct/2011:11:59:40 +1000] cupsdMarkDirty(----J-)
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:40 +1000] [Job 65] job-sheets=none,none
D [25/Oct/2011:11:59:40 +1000] [Job 65] argv[0]="Officejet_Pro_K550"
D [25/Oct/2011:11:59:40 +1000] [Job 65] argv[1]="65"
D [25/Oct/2011:11:59:40 +1000] [Job 65] argv[2]="robert"
D [25/Oct/2011:11:59:40 +1000] [Job 65] argv[3]="Test Page"
D [25/Oct/2011:11:59:40 +1000] [Job 65] argv[4]="1"
D [25/Oct/2011:11:59:40 +1000] [Job 65] argv[5]="PageSize=Letter job-uuid=urn:uuid:92a3581c-231a-31f9-7db8-6a02ab16389c job-originating-host-name=localhost time-at-creation=1319507980 time-at-processing=1319507980 AP_D_InputSlot="
D [25/Oct/2011:11:59:40 +1000] [Job 65] argv[6]="/var/spool/cups/d00065-001"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[8]="HOME=/var/spool/cups/tmp"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[10]="SERVER_ADMIN=root@robert-HP-ProBook-4520s"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[11]="SOFTWARE=CUPS/1.5.0"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[13]="USER=root"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[16]="IPP_PORT=631"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[17]="CHARSET=utf-8"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[18]="LANG=en_AU.UTF-8"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[19]="PPD=/etc/cups/ppd/Officejet_Pro_K550.ppd"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[20]="RIP_MAX_CACHE=128m"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[21]="CONTENT_TYPE=application/postscript"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[22]="DEVICE_URI=hp:/usb/Officejet_Pro_K550?serial=MY5C9210JF"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[23]="PRINTER_INFO=Officejet_Pro_K550"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[24]="PRINTER_LOCATION="
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[25]="PRINTER=Officejet_Pro_K550"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[26]="PRINTER_STATE_REASONS=none"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[27]="CUPS_FILETYPE=document"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[28]="FINAL_CONTENT_TYPE=printer/Officejet_Pro_K550"
D [25/Oct/2011:11:59:40 +1000] [Job 65] envp[29]="AUTH_I****"
I [25/Oct/2011:11:59:40 +1000] [Job 65] Started filter /usr/lib/cups/filter/pstopdf (PID 2710)
I [25/Oct/2011:11:59:40 +1000] [Job 65] Started filter /usr/lib/cups/filter/pdftopdf (PID 2711)
I [25/Oct/2011:11:59:40 +1000] [Job 65] Started filter /usr/lib/cups/filter/gstoraster (PID 2712)
I [25/Oct/2011:11:59:40 +1000] [Job 65] Started filter /usr/lib/cups/filter/hpcups (PID 2713)
I [25/Oct/2011:11:59:40 +1000] [Job 65] Started backend /usr/lib/cups/backend/hp (PID 2714)
D [25/Oct/2011:11:59:40 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [25/Oct/2011:11:59:40 +1000] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/Officejet_Pro_K550) from localhost
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:40 +1000] [Job 65] pstopdf 6 args: 65 robert Test Page 1 PageSize=Letter job-uuid=urn:uuid:92a3581c-231a-31f9-7db8-6a02ab16389c job-originating-host-name=localhost time-at-creation=1319507980 time-at-processing=1319507980 AP_D_InputSlot= /var/spool/cups/d00065-001
D [25/Oct/2011:11:59:40 +1000] [Job 65] PPD: /etc/cups/ppd/Officejet_Pro_K550.ppd
D [25/Oct/2011:11:59:40 +1000] [Notifier] state=3
D [25/Oct/2011:11:59:40 +1000] [Notifier] Connected to D-BUS
D [25/Oct/2011:11:59:40 +1000] [Notifier] JobCreated
D [25/Oct/2011:11:59:40 +1000] [Notifier] state=3
D [25/Oct/2011:11:59:40 +1000] [Notifier] PrinterStateChanged
D [25/Oct/2011:11:59:40 +1000] [Notifier] state=3
D [25/Oct/2011:11:59:40 +1000] [Notifier] JobState
D [25/Oct/2011:11:59:40 +1000] cupsdAcceptClient: 22 from localhost (Domain)
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 22 POST / HTTP/1.1
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [25/Oct/2011:11:59:40 +1000] Get-Job-Attributes ipp://localhost/jobs/65
D [25/Oct/2011:11:59:40 +1000] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/65) from localhost
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAcceptClient: 23 from localhost (Domain)
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 23 POST / HTTP/1.1
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 23 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:40 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:40 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 23 POST / HTTP/1.1
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 23 1.1 CUPS-Get-Default 1
D [25/Oct/2011:11:59:40 +1000] CUPS-Get-Default
D [25/Oct/2011:11:59:40 +1000] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAcceptClient: 24 from localhost (Domain)
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 24 POST / HTTP/1.1
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 24 1.1 Get-Job-Attributes 1
D [25/Oct/2011:11:59:40 +1000] Get-Job-Attributes ipp://localhost/jobs/65
D [25/Oct/2011:11:59:40 +1000] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/65) from localhost
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:40 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 481: argv[0] = Officejet_Pro_K550
D [25/Oct/2011:11:59:40 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 481: argv[1] = 65
D [25/Oct/2011:11:59:40 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 481: argv[2] = robert
D [25/Oct/2011:11:59:40 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 481: argv[3] = Test Page
D [25/Oct/2011:11:59:40 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 481: argv[4] = 1
D [25/Oct/2011:11:59:40 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 481: argv[5] = PageSize=Letter job-uuid=urn:uuid:92a3581c-231a-31f9-7db8-6a02ab16389c job-originating-host-name=localhost time-at-creation=1319507980 time-at-processing=1319507980 AP_D_InputSlot=
D [25/Oct/2011:11:59:40 +1000] cupsdAcceptClient: 25 from localhost (Domain)
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 25 POST / HTTP/1.1
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 25 1.1 Get-Jobs 1
D [25/Oct/2011:11:59:40 +1000] Get-Jobs ipp://localhost/printers/
D [25/Oct/2011:11:59:40 +1000] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 25 WAITING Closing on EOF
D [25/Oct/2011:11:59:40 +1000] cupsdCloseClient: 25
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAcceptClient: 25 from localhost (Domain)
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 25 POST / HTTP/1.1
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 25 1.1 Get-Notifications 1
D [25/Oct/2011:11:59:40 +1000] Get-Notifications /
D [25/Oct/2011:11:59:40 +1000] cupsdIsAuthorized: requesting-user-name="robert"
D [25/Oct/2011:11:59:40 +1000] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 16 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:11:59:40 +1000] Get-Printer-Attributes ipp://localhost/printers/Officejet_Pro_K550
D [25/Oct/2011:11:59:40 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Officejet_Pro_K550) from localhost
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:40 +1000] cupsdReadClient: 25 WAITING Closing on EOF
D [25/Oct/2011:11:59:40 +1000] cupsdCloseClient: 25
D [25/Oct/2011:11:59:40 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:40 +1000] [Job 65] Resolution: 
D [25/Oct/2011:11:59:40 +1000] [Job 65] Page size: Letter
D [25/Oct/2011:11:59:40 +1000] [Job 65] Width: 612, height: 792, absolute margins: 9, 9, 603, 783
D [25/Oct/2011:11:59:40 +1000] [Job 65] Relative margins: 9, 9, 9, 9
D [25/Oct/2011:11:59:40 +1000] [Job 65] PPD options: -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792
D [25/Oct/2011:11:59:40 +1000] [Job 65] PostScript to be injected: 
D [25/Oct/2011:11:59:40 +1000] [Job 65] Running cat | /usr/bin/gs -q -dNOPAUSE -dBATCH -sDEVICE=pdfwrite -dCompatibilityLevel=1.3 -dAutoRotatePages=/None -dAutoFilterColorImages=false                -dNOPLATFONTS -dPARANOIDSAFER -dNOINTERPOLATE -sstdout=%stderr -dColorImageFilter=/FlateEncode                 -dPDFSETTINGS=/printer                 -dColorConversionStrategy=/LeaveColorUnchanged -dDoNumCopies -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -sOutputFile=-  -c .setpdfwrite -f -
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:41 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Classes 1
D [25/Oct/2011:11:59:41 +1000] CUPS-Get-Classes
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [25/Oct/2011:11:59:41 +1000] CUPS-Get-Default
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAcceptClient: 25 from localhost (Domain)
D [25/Oct/2011:11:59:41 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 1.1 Create-Printer-Subscription 1
D [25/Oct/2011:11:59:41 +1000] Create-Printer-Subscription /
D [25/Oct/2011:11:59:41 +1000] cupsdCreateSubscription(con=0x227df5d0(26), uri="/")
D [25/Oct/2011:11:59:41 +1000] pullmethod="ippget"
D [25/Oct/2011:11:59:41 +1000] notify-lease-duration=86400
D [25/Oct/2011:11:59:41 +1000] notify-time-interval=0
D [25/Oct/2011:11:59:41 +1000] cupsdAddSubscription(mask=1798f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [25/Oct/2011:11:59:41 +1000] Added subscription #89 for server.
D [25/Oct/2011:11:59:41 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:41 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:41 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAcceptClient: 27 from localhost (Domain)
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 27 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 27 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:11:59:41 +1000] Get-Printer-Attributes ipp://localhost/printers/PDF
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 27 WAITING Closing on EOF
D [25/Oct/2011:11:59:41 +1000] cupsdCloseClient: 27
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAcceptClient: 27 from localhost (Domain)
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 27 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 27 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:11:59:41 +1000] Get-Printer-Attributes ipp://localhost/printers/Officejet_Pro_K550
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Officejet_Pro_K550) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 27 WAITING Closing on EOF
D [25/Oct/2011:11:59:41 +1000] cupsdCloseClient: 27
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:11:59:41 +1000] cupsdCloseClient: 26
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 1.1 Get-Jobs 1
D [25/Oct/2011:11:59:41 +1000] Get-Jobs ipp://localhost/printers/
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAcceptClient: 27 from localhost (Domain)
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 27 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 27 1.1 Get-Job-Attributes 1
D [25/Oct/2011:11:59:41 +1000] Get-Job-Attributes ipp://localhost/jobs/65
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/65) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 27 WAITING Closing on EOF
D [25/Oct/2011:11:59:41 +1000] cupsdCloseClient: 27
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:11:59:41 +1000] cupsdCloseClient: 26
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 1.1 Get-Jobs 1
D [25/Oct/2011:11:59:41 +1000] Get-Jobs ipp://localhost/printers/
D [25/Oct/2011:11:59:41 +1000] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:41 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:11:59:41 +1000] cupsdCloseClient: 26
D [25/Oct/2011:11:59:41 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] PID 2710 (/usr/lib/cups/filter/pstopdf) exited with no errors.
D [25/Oct/2011:11:59:42 +1000] PID 2711 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [25/Oct/2011:11:59:42 +1000] [Job 65] PPD uses qualifier 'RGB.Automatic.'
D [25/Oct/2011:11:59:42 +1000] [Job 65] Calling FindDeviceById(Officejet_Pro_K550)
D [25/Oct/2011:11:59:42 +1000] [Job 65] Failed to send: org.freedesktop.DBus.Error.AccessDenied:Rejected send message, 1 matched rules; type="method_call", sender=":1.72" (uid=7 pid=2712 comm="Officejet_Pro_K550 65 robert Test Page 1 PageSize=") interface="org.freedesktop.ColorManager" member="FindDeviceById" error name="(unset)" requested_reply="0" destination="org.freedesktop.ColorManager" (uid=114 pid=1189 comm="/usr/lib/i386-linux-gnu/colord/colord ")
D [25/Oct/2011:11:59:42 +1000] [Job 65] Failed to get profile filename!
I [25/Oct/2011:11:59:42 +1000] [Job 65] no profiles specified in PPD
D [25/Oct/2011:11:59:42 +1000] [Job 65] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
D [25/Oct/2011:11:59:42 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] [Job 65] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -sMediaType=Automatic -sOutputType=0 -r600x600 -dMediaPosition=7 -dDEVICEWIDTHPOINTS=612 -dDEVICEHEIGHTPOINTS=792 -dcupsMediaType=-1 -dcupsBitsPerColor=8 -dcupsColorOrder=0 -dcupsColorSpace=1 -dcupsInteger0=2 -scupsPageSizeName=Letter -I/usr/share/cups/fonts -c -f -_
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[8]="HOME=/var/spool/cups/tmp"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[10]="SERVER_ADMIN=root@robert-HP-ProBook-4520s"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[11]="SOFTWARE=CUPS/1.5.0"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[12]="USER=root"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[13]="CUPS_SERVER=/var/run/cups/cups.sock"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[14]="CUPS_ENCRYPTION=IfRequested"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[15]="IPP_PORT=631"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[16]="CHARSET=utf-8"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[17]="LANG=en_AU.UTF-8"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[18]="PPD=/etc/cups/ppd/Officejet_Pro_K550.ppd"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[19]="RIP_MAX_CACHE=128m"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[20]="CONTENT_TYPE=application/postscript"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[21]="DEVICE_URI=hp:/usb/Officejet_Pro_K550?serial=MY5C9210JF"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[22]="PRINTER_INFO=Officejet_Pro_K550"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[23]="PRINTER_LOCATION="
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[24]="PRINTER=Officejet_Pro_K550"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[25]="PRINTER_STATE_REASONS=none"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[26]="CUPS_FILETYPE=document"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[27]="FINAL_CONTENT_TYPE=printer/Officejet_Pro_K550"
D [25/Oct/2011:11:59:42 +1000] [Job 65] envp[28]="AUTH_INFO_REQUIRED=none"
D [25/Oct/2011:11:59:42 +1000] [Notifier] state=3
D [25/Oct/2011:11:59:42 +1000] [Notifier] PrinterStateChanged
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 23 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 23 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:42 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 23 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 23 1.1 CUPS-Get-Default 1
D [25/Oct/2011:11:59:42 +1000] CUPS-Get-Default
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
I [25/Oct/2011:11:59:42 +1000] [Job 65] Start rendering...
D [25/Oct/2011:11:59:42 +1000] [Job 65] Set job-printer-state-message to "Start rendering...", current level=INFO
I [25/Oct/2011:11:59:42 +1000] [Job 65] Processing page 1...
D [25/Oct/2011:11:59:42 +1000] [Job 65] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [25/Oct/2011:11:59:42 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] [Notifier] state=3
D [25/Oct/2011:11:59:42 +1000] [Notifier] PrinterStateChanged
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 23 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 23 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:42 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 23 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 23 1.1 CUPS-Get-Default 1
D [25/Oct/2011:11:59:42 +1000] CUPS-Get-Default
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:11:59:42 +1000] cupsdAcceptClient: 27 from localhost (Domain)
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 27 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 26 1.1 Get-Notifications 1
D [25/Oct/2011:11:59:42 +1000] Get-Notifications /
D [25/Oct/2011:11:59:42 +1000] cupsdIsAuthorized: requesting-user-name="robert"
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 27 1.1 Get-Notifications 1
D [25/Oct/2011:11:59:42 +1000] Get-Notifications /
D [25/Oct/2011:11:59:42 +1000] cupsdIsAuthorized: requesting-user-name="robert"
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAcceptClient: 28 from localhost (Domain)
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 28 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 16 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:11:59:42 +1000] Get-Printer-Attributes ipp://localhost/printers/Officejet_Pro_K550
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Officejet_Pro_K550) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 28 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:11:59:42 +1000] Get-Printer-Attributes ipp://robert-HP-ProBook-4520s/printers/Officejet_Pro_K550
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://robert-HP-ProBook-4520s/printers/Officejet_Pro_K550) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 28 WAITING Closing on EOF
D [25/Oct/2011:11:59:42 +1000] cupsdCloseClient: 28
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAcceptClient: 28 from localhost (Domain)
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 28 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 28 1.1 Get-Job-Attributes 1
D [25/Oct/2011:11:59:42 +1000] Get-Job-Attributes ipp://localhost/jobs/65
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/65) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 28 WAITING Closing on EOF
D [25/Oct/2011:11:59:42 +1000] cupsdCloseClient: 28
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAcceptClient: 28 from localhost (Domain)
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 28 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 28 1.1 Get-Job-Attributes 1
D [25/Oct/2011:11:59:42 +1000] Get-Job-Attributes ipp://localhost/jobs/65
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/65) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 28 WAITING Closing on EOF
D [25/Oct/2011:11:59:42 +1000] cupsdCloseClient: 28
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:11:59:42 +1000] cupsdCloseClient: 26
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 16 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 16 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:11:59:42 +1000] Get-Printer-Attributes ipp://localhost/printers/Officejet_Pro_K550
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Officejet_Pro_K550) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 27 WAITING Closing on EOF
D [25/Oct/2011:11:59:42 +1000] cupsdCloseClient: 27
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:11:59:42 +1000] CUPS-Get-Printers
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Classes 1
D [25/Oct/2011:11:59:42 +1000] CUPS-Get-Classes
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 14 POST / HTTP/1.1
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:11:59:42 +1000] cupsdReadClient: 14 1.1 CUPS-Get-Default 1
D [25/Oct/2011:11:59:42 +1000] CUPS-Get-Default
D [25/Oct/2011:11:59:42 +1000] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 753: DEBUG: startPage...
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 754: DEBUG: MediaClass = ""
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 755: DEBUG: MediaColor = ""
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 756: DEBUG: MediaType = "Automatic"
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 757: DEBUG: OutputType = "0"
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 758: DEBUG: AdvanceDistance = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 759: DEBUG: AdvanceMedia = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 760: DEBUG: Collate = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 761: DEBUG: CutMedia = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 762: DEBUG: Duplex = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 763: DEBUG: HWResolution = [ 600 600 ]
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 766: DEBUG: ImagingBoundingBox = [ 9 9 603 783 ]
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 767: DEBUG: InsertSheet = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 768: DEBUG: Jog = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 769: DEBUG: LeadingEdge = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 770: DEBUG: Margins = [ 9 9 ]
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 771: DEBUG: ManualFeed = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 772: DEBUG: MediaPosition = 7
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 773: DEBUG: MediaWeight = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 774: DEBUG: MirrorPrint = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 775: DEBUG: NegativePrint = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 776: DEBUG: NumCopies = 1
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 777: DEBUG: Orientation = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 778: DEBUG: OutputFaceUp = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 779: DEBUG: PageSize = [ 612 792 ]
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 780: DEBUG: Separations = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 781: DEBUG: TraySwitch = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 782: DEBUG: Tumble = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 783: DEBUG: cupsWidth = 4950
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 784: DEBUG: cupsHeight = 6450
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 785: DEBUG: cupsMediaType = -1
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 786: DEBUG: cupsRowStep = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 787: DEBUG: cupsBitsPerColor = 8
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 788: DEBUG: cupsBitsPerPixel = 24
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 789: DEBUG: cupsBytesPerLine = 14850
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 790: DEBUG: cupsColorOrder = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 791: DEBUG: cupsColorSpace = 1
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 792: DEBUG: cupsCompression = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 793: DEBUG: cupsPageSizeName = Letter
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 794: DEBUG: cupsInteger0 = 2
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 795: DEBUG: cupsInteger1 = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 796: DEBUG: cupsInteger2 = 0
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 797: DEBUG: cupsString0 = 
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 798: DEBUG: cupsReal0 = 0.000000
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 799: DEBUG: cupsReal1 = 0.000000
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 373: HPCUPS: found Printer Language, it is - pcl3gui2STATE: +connecting-to-device
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 425: HPCUPS: returning NO_ERROR from startPageprnt/hpcups/HPCupsFilter.cpp 602: 5......
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 637: 9......
D [25/Oct/2011:11:59:42 +1000] [Job 65] prnt/hpcups/HPCupsFilter.cpp 640: 10......
D [25/Oct/2011:11:59:42 +1000] [Job 65] PAGE: 1 1STATE: -connecting-to-device
D [25/Oct/2011:11:59:42 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:11:59:42 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:11:59:42 +1000] [Job 65] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other
I [25/Oct/2011:11:59:58 +1000] Saving job.cache...
I [25/Oct/2011:11:59:59 +1000] Saving subscriptions.conf...
D [25/Oct/2011:11:59:59 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:12:00:29 +1000] Report: clients=8
D [25/Oct/2011:12:00:29 +1000] Report: jobs=5
D [25/Oct/2011:12:00:29 +1000] Report: jobs-active=1
D [25/Oct/2011:12:00:29 +1000] Report: printers=2
D [25/Oct/2011:12:00:29 +1000] Report: printers-implicit=0
D [25/Oct/2011:12:00:29 +1000] Report: stringpool-string-count=19076
D [25/Oct/2011:12:00:29 +1000] Report: stringpool-alloc-bytes=14848
D [25/Oct/2011:12:00:29 +1000] Report: stringpool-total-bytes=355952
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [25/Oct/2011:12:00:29 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 26 1.1 Create-Printer-Subscription 1
D [25/Oct/2011:12:00:29 +1000] Create-Printer-Subscription /
D [25/Oct/2011:12:00:29 +1000] cupsdCreateSubscription(con=0x227e14b0(26), uri="/")
D [25/Oct/2011:12:00:29 +1000] pullmethod="ippget"
D [25/Oct/2011:12:00:29 +1000] notify-lease-duration=86400
D [25/Oct/2011:12:00:29 +1000] notify-time-interval=0
D [25/Oct/2011:12:00:29 +1000] cupsdAddSubscription(mask=1798f, dest=(nil)(), job=(nil)(0), uri="(null)")
D [25/Oct/2011:12:00:29 +1000] Added subscription #90 for server.
D [25/Oct/2011:12:00:29 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and printing jobs"
D [25/Oct/2011:12:00:29 +1000] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 26 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:12:00:29 +1000] CUPS-Get-Printers
D [25/Oct/2011:12:00:29 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 26 1.1 CUPS-Get-Printers 1
D [25/Oct/2011:12:00:29 +1000] CUPS-Get-Printers
D [25/Oct/2011:12:00:29 +1000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdAcceptClient: 27 from localhost (Domain)
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 27 POST / HTTP/1.1
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 27 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:12:00:29 +1000] Get-Printer-Attributes ipp://localhost/printers/PDF
D [25/Oct/2011:12:00:29 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/PDF) from localhost
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 27 WAITING Closing on EOF
D [25/Oct/2011:12:00:29 +1000] cupsdCloseClient: 27
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdAcceptClient: 27 from localhost (Domain)
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 27 POST / HTTP/1.1
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 27 1.1 Get-Printer-Attributes 1
D [25/Oct/2011:12:00:29 +1000] Get-Printer-Attributes ipp://localhost/printers/Officejet_Pro_K550
D [25/Oct/2011:12:00:29 +1000] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/Officejet_Pro_K550) from localhost
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 27 WAITING Closing on EOF
D [25/Oct/2011:12:00:29 +1000] cupsdCloseClient: 27
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:29 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:12:00:29 +1000] cupsdCloseClient: 26
D [25/Oct/2011:12:00:29 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:30 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:12:00:30 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:12:00:30 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:30 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:00:30 +1000] cupsdReadClient: 26 1.1 Get-Jobs 1
D [25/Oct/2011:12:00:30 +1000] Get-Jobs ipp://localhost/printers/
D [25/Oct/2011:12:00:30 +1000] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [25/Oct/2011:12:00:30 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:00:30 +1000] cupsdAcceptClient: 27 from localhost (Domain)
D [25/Oct/2011:12:00:30 +1000] cupsdReadClient: 27 POST / HTTP/1.1
D [25/Oct/2011:12:00:30 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:30 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:00:30 +1000] cupsdReadClient: 27 1.1 Get-Job-Attributes 1
D [25/Oct/2011:12:00:30 +1000] Get-Job-Attributes ipp://localhost/jobs/65
D [25/Oct/2011:12:00:30 +1000] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/65) from localhost
D [25/Oct/2011:12:00:30 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:00:30 +1000] cupsdReadClient: 27 WAITING Closing on EOF
D [25/Oct/2011:12:00:30 +1000] cupsdCloseClient: 27
D [25/Oct/2011:12:00:30 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:30 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:12:00:30 +1000] cupsdCloseClient: 26
D [25/Oct/2011:12:00:30 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:30 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:12:00:30 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:12:00:30 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:30 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:00:30 +1000] cupsdReadClient: 26 1.1 Get-Jobs 1
D [25/Oct/2011:12:00:30 +1000] Get-Jobs ipp://localhost/printers/
D [25/Oct/2011:12:00:30 +1000] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost
D [25/Oct/2011:12:00:30 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:00:30 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:12:00:30 +1000] cupsdCloseClient: 26
D [25/Oct/2011:12:00:30 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:31 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:12:00:31 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:12:00:31 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:00:31 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:00:31 +1000] cupsdReadClient: 26 1.1 Get-Notifications 1
D [25/Oct/2011:12:00:31 +1000] Get-Notifications /
D [25/Oct/2011:12:00:31 +1000] cupsdIsAuthorized: requesting-user-name="robert"
D [25/Oct/2011:12:00:31 +1000] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [25/Oct/2011:12:00:31 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:00:31 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:12:00:31 +1000] cupsdCloseClient: 26
D [25/Oct/2011:12:00:31 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [25/Oct/2011:12:01:00 +1000] Saving subscriptions.conf...
D [25/Oct/2011:12:01:00 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:01:31 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:12:01:31 +1000] Report: clients=8
D [25/Oct/2011:12:01:31 +1000] Report: jobs=5
D [25/Oct/2011:12:01:31 +1000] Report: jobs-active=1
D [25/Oct/2011:12:01:31 +1000] Report: printers=2
D [25/Oct/2011:12:01:31 +1000] Report: printers-implicit=0
D [25/Oct/2011:12:01:31 +1000] Report: stringpool-string-count=19077
D [25/Oct/2011:12:01:31 +1000] Report: stringpool-alloc-bytes=14848
D [25/Oct/2011:12:01:31 +1000] Report: stringpool-total-bytes=355960
D [25/Oct/2011:12:01:31 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:12:01:31 +1000] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [25/Oct/2011:12:01:31 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:01:31 +1000] cupsdReadClient: 26 1.1 Get-Notifications 1
D [25/Oct/2011:12:01:31 +1000] Get-Notifications /
D [25/Oct/2011:12:01:31 +1000] cupsdIsAuthorized: requesting-user-name="robert"
D [25/Oct/2011:12:01:31 +1000] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [25/Oct/2011:12:01:31 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
D [25/Oct/2011:12:01:31 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:12:01:31 +1000] cupsdCloseClient: 26
D [25/Oct/2011:12:01:31 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"
D [25/Oct/2011:12:02:01 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:12:02:01 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:12:02:01 +1000] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [25/Oct/2011:12:02:01 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:02:01 +1000] cupsdReadClient: 26 1.1 Get-Job-Attributes 1
D [25/Oct/2011:12:02:01 +1000] Get-Job-Attributes ipp://localhost/jobs/65
D [25/Oct/2011:12:02:01 +1000] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/65) from localhost
D [25/Oct/2011:12:02:01 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
D [25/Oct/2011:12:02:01 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:12:02:01 +1000] cupsdCloseClient: 26
D [25/Oct/2011:12:02:01 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"
D [25/Oct/2011:12:02:21 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:12:02:21 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:12:02:21 +1000] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [25/Oct/2011:12:02:21 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:02:21 +1000] cupsdReadClient: 26 1.1 Cancel-Subscription 1
D [25/Oct/2011:12:02:21 +1000] Cancel-Subscription /
D [25/Oct/2011:12:02:21 +1000] cupsdIsAuthorized: requesting-user-name="robert"
D [25/Oct/2011:12:02:21 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:12:02:21 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and printing jobs"
D [25/Oct/2011:12:02:21 +1000] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
D [25/Oct/2011:12:02:21 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:02:21 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:12:02:21 +1000] cupsdCloseClient: 26
D [25/Oct/2011:12:02:21 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:02:23 +1000] cupsdAcceptClient: 26 from localhost (Domain)
D [25/Oct/2011:12:02:23 +1000] cupsdReadClient: 26 POST / HTTP/1.1
D [25/Oct/2011:12:02:23 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:02:23 +1000] cupsdAuthorize: No authentication data provided.
D [25/Oct/2011:12:02:23 +1000] cupsdReadClient: 26 1.1 Cancel-Subscription 1
D [25/Oct/2011:12:02:23 +1000] Cancel-Subscription /
D [25/Oct/2011:12:02:23 +1000] cupsdIsAuthorized: requesting-user-name="robert"
D [25/Oct/2011:12:02:23 +1000] cupsdMarkDirty(-----S)
D [25/Oct/2011:12:02:23 +1000] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:02:23 +1000] Returning IPP successful-ok for Cancel-Subscription (/) from localhost
D [25/Oct/2011:12:02:23 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [25/Oct/2011:12:02:23 +1000] cupsdReadClient: 26 WAITING Closing on EOF
D [25/Oct/2011:12:02:23 +1000] cupsdCloseClient: 26
D [25/Oct/2011:12:02:23 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:02:23 +1000] cupsdReadClient: 17 WAITING Closing on EOF
D [25/Oct/2011:12:02:23 +1000] cupsdCloseClient: 17
D [25/Oct/2011:12:02:23 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:02:24 +1000] cupsdReadClient: 14 WAITING Closing on EOF
D [25/Oct/2011:12:02:24 +1000] cupsdCloseClient: 14
D [25/Oct/2011:12:02:24 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:02:24 +1000] cupsdReadClient: 16 WAITING Closing on EOF
D [25/Oct/2011:12:02:24 +1000] cupsdCloseClient: 16
D [25/Oct/2011:12:02:24 +1000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
I [25/Oct/2011:12:02:52 +1000] Saving subscriptions.conf...
D [25/Oct/2011:12:02:52 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [25/Oct/2011:12:02:52 +1000] Report: clients=4
D [25/Oct/2011:12:02:52 +1000] Report: jobs=5
D [25/Oct/2011:12:02:52 +1000] Report: jobs-active=1
D [25/Oct/2011:12:02:52 +1000] Report: printers=2
D [25/Oct/2011:12:02:52 +1000] Report: printers-implicit=0
D [25/Oct/2011:12:02:52 +1000] Report: stringpool-string-count=19018
D [25/Oct/2011:12:02:52 +1000] Report: stringpool-alloc-bytes=14848
D [25/Oct/2011:12:02:52 +1000] Report: stringpool-total-bytes=354608
D [25/Oct/2011:12:04:41 +1000] Closing client 22 after 300 seconds of inactivity...
D [25/Oct/2011:12:04:41 +1000] cupsdCloseClient: 22
D [25/Oct/2011:12:04:41 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"
D [25/Oct/2011:12:04:41 +1000] Closing client 24 after 300 seconds of inactivity...
D [25/Oct/2011:12:04:41 +1000] cupsdCloseClient: 24
D [25/Oct/2011:12:04:41 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"
D [25/Oct/2011:12:04:41 +1000] Report: clients=2
D [25/Oct/2011:12:04:41 +1000] Report: jobs=5
D [25/Oct/2011:12:04:41 +1000] Report: jobs-active=1
D [25/Oct/2011:12:04:41 +1000] Report: printers=2
D [25/Oct/2011:12:04:41 +1000] Report: printers-implicit=0
D [25/Oct/2011:12:04:41 +1000] Report: stringpool-string-count=19018
D [25/Oct/2011:12:04:41 +1000] Report: stringpool-alloc-bytes=14848
D [25/Oct/2011:12:04:41 +1000] Report: stringpool-total-bytes=354608
D [25/Oct/2011:12:04:42 +1000] Closing client 25 after 300 seconds of inactivity...
D [25/Oct/2011:12:04:42 +1000] cupsdCloseClient: 25
D [25/Oct/2011:12:04:42 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"
D [25/Oct/2011:12:04:43 +1000] Closing client 23 after 300 seconds of inactivity...
D [25/Oct/2011:12:04:43 +1000] cupsdCloseClient: 23
D [25/Oct/2011:12:04:43 +1000] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"