W [27/Jun/2012:07:59:58 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'ML-4500-Gray..' already exists
W [27/Jun/2012:07:59:58 +0100] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-ML-4500' already exists
W [28/Jun/2012:00:29:56 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'ML-4500-Gray..' already exists
W [28/Jun/2012:00:29:56 +0100] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-ML-4500' already exists
W [28/Jun/2012:00:29:56 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'ML-4500-Gray..' already exists
W [28/Jun/2012:00:29:56 +0100] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-ML-4500' already exists
I [28/Jun/2012:00:34:03 +0100] Remote access is disabled.
D [28/Jun/2012:00:34:03 +0100] Added auto ServerAlias daniel-MS-7693
I [28/Jun/2012:00:34:03 +0100] Loaded configuration file "/etc/cups/cupsd.conf"
I [28/Jun/2012:00:34:03 +0100] Using default TempDir of /var/spool/cups/tmp...
I [28/Jun/2012:00:34:03 +0100] Configured for up to 100 clients.
I [28/Jun/2012:00:34:03 +0100] Allowing up to 100 client connections per host.
I [28/Jun/2012:00:34:03 +0100] Using policy "default" as the default.
D [28/Jun/2012:00:34:03 +0100] load_ppd: Loading /var/cache/cups/ML-4500.data...
D [28/Jun/2012:00:34:03 +0100] Calling DeleteDevice(cups-ML-4500)
D [28/Jun/2012:00:34:03 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [28/Jun/2012:00:34:03 +0100] Using profile id of ML-4500-Gray..
D [28/Jun/2012:00:34:03 +0100] Calling CreateProfile(ML-4500-Gray..,temp)
W [28/Jun/2012:00:34:03 +0100] failed to CreateProfile: org.freedesktop.ColorManager.AlreadyExists:profile id 'ML-4500-Gray..' already exists
I [28/Jun/2012:00:34:03 +0100] Registering ICC color profiles for "ML-4500"
D [28/Jun/2012:00:34:03 +0100] Calling CreateDevice(cups-ML-4500,temp)
W [28/Jun/2012:00:34:03 +0100] failed to CreateDevice: org.freedesktop.ColorManager.AlreadyExists:device id 'cups-ML-4500' already exists
D [28/Jun/2012:00:34:03 +0100] cupsdRegisterPrinter(p=0x7ff62e268ae0(ML-4500))
D [28/Jun/2012:00:34:03 +0100] cupsdMarkDirty(---p--)
D [28/Jun/2012:00:34:03 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Not busy"
I [28/Jun/2012:00:34:03 +0100] Partial reload complete.
I [28/Jun/2012:00:34:03 +0100] Listening to [v1.::1]:631 on fd 9...
I [28/Jun/2012:00:34:03 +0100] Listening to 127.0.0.1:631 on fd 10...
I [28/Jun/2012:00:34:03 +0100] Listening to /var/run/cups/cups.sock:631 on fd 11...
I [28/Jun/2012:00:34:03 +0100] Resuming new connection processing...
D [28/Jun/2012:00:34:03 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:03 +0100] Notifier dbus started - PID = 25674
D [28/Jun/2012:00:34:03 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:34:03 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:03 +0100] Report: clients=0
D [28/Jun/2012:00:34:03 +0100] Report: jobs=1
D [28/Jun/2012:00:34:03 +0100] Report: jobs-active=0
D [28/Jun/2012:00:34:03 +0100] Report: printers=1
D [28/Jun/2012:00:34:03 +0100] Report: printers-implicit=0
D [28/Jun/2012:00:34:03 +0100] Report: stringpool-string-count=3196
D [28/Jun/2012:00:34:03 +0100] Report: stringpool-alloc-bytes=10000
D [28/Jun/2012:00:34:03 +0100] Report: stringpool-total-bytes=61416
D [28/Jun/2012:00:34:03 +0100] PID 25088 (/usr/lib/cups/notifier/dbus) was terminated normally with signal 15.
D [28/Jun/2012:00:34:03 +0100] [Notifier] state=3
D [28/Jun/2012:00:34:03 +0100] [Notifier] Connected to D-BUS
D [28/Jun/2012:00:34:13 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Devices 1
D [28/Jun/2012:00:34:13 +0100] CUPS-Get-Devices
D [28/Jun/2012:00:34:13 +0100] cupsdIsAuthorized: username=""
D [28/Jun/2012:00:34:13 +0100] Returning HTTP Unauthorized for CUPS-Get-Devices (no URI) from localhost
D [28/Jun/2012:00:34:13 +0100] cupsdSendHeader: 17 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jun/2012:00:34:13 +0100] cupsdCloseClient: 17
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jun/2012:00:34:13 +0100] cupsdAcceptClient: 19 from localhost (Domain)
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jun/2012:00:34:13 +0100] cupsdCloseClient: 17
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 19 POST / HTTP/1.1
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdAuthorize: Authorized as root using PeerCred
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 19 1.1 CUPS-Get-Devices 1
D [28/Jun/2012:00:34:13 +0100] CUPS-Get-Devices
D [28/Jun/2012:00:34:13 +0100] cupsdIsAuthorized: username="root"
D [28/Jun/2012:00:34:13 +0100] [CGI] argv[0] = "/usr/lib/cups/daemon/cups-deviced"
D [28/Jun/2012:00:34:13 +0100] [CGI] argv[1] = "1"
D [28/Jun/2012:00:34:13 +0100] [CGI] argv[2] = "0"
D [28/Jun/2012:00:34:13 +0100] [CGI] argv[3] = "2"
D [28/Jun/2012:00:34:13 +0100] [CGI] argv[4] = "7"
D [28/Jun/2012:00:34:13 +0100] [CGI] argv[5] = "requested-attributes=all exclude-schemes='beh','cups-pdf','bluetooth','dnssd','http','https','ipp','lpd','ncp','parallel','scsi','smb','snmp','socket'"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[0] = "CUPS_CACHEDIR=/var/cache/cups"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[1] = "CUPS_DATADIR=/usr/share/cups"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[2] = "CUPS_DOCROOT=/usr/share/cups/doc-root"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[3] = "CUPS_FONTPATH=/usr/share/cups/fonts"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[4] = "CUPS_REQUESTROOT=/var/spool/cups"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[5] = "CUPS_SERVERBIN=/usr/lib/cups"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[6] = "CUPS_SERVERROOT=/etc/cups"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[7] = "CUPS_STATEDIR=/var/run/cups"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[8] = "HOME=/var/spool/cups/tmp"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[9] = "PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[10] = "SERVER_ADMIN=root@daniel-MS-7693"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[11] = "SOFTWARE=CUPS/1.5.3"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[12] = "TMPDIR=/var/spool/cups/tmp"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[13] = "USER=root"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[14] = "CUPS_SERVER=/var/run/cups/cups.sock"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[15] = "CUPS_ENCRYPTION=IfRequested"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[16] = "IPP_PORT=631"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[17] = "AUTH_TYPE=PeerCred"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[18] = "LANG=en_US.UTF8"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[19] = "REDIRECT_STATUS=1"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[20] = "GATEWAY_INTERFACE=CGI/1.1"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[21] = "SERVER_NAME=localhost"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[22] = "SERVER_PORT=631"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[23] = "REMOTE_ADDR=localhost"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[24] = "REMOTE_HOST=localhost"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[25] = "SCRIPT_NAME=/"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[26] = "SCRIPT_FILENAME=/usr/share/cups/doc-root/"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[27] = "REMOTE_USER=root"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[28] = "SERVER_PROTOCOL=HTTP/1.1"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[29] = "HTTP_USER_AGENT=CUPS/1.5.3"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[30] = "REQUEST_METHOD=POST"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[31] = "CONTENT_LENGTH=244"
D [28/Jun/2012:00:34:13 +0100] [CGI] envp[32] = "CONTENT_TYPE=application/ipp"
D [28/Jun/2012:00:34:13 +0100] [CGI] Started /usr/lib/cups/daemon/cups-deviced (PID 25686)
I [28/Jun/2012:00:34:13 +0100] Started "/usr/lib/cups/daemon/cups-deviced" (pid=25686)
D [28/Jun/2012:00:34:13 +0100] cupsdSendCommand: 19 file=17
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/hp (PID 25687)
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/hpfax (PID 25688)
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/serial (PID 25689)
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/ipps (PID 25690)
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/ipp14 (PID 25691)
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/usb (PID 25692)
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Started backend /usr/lib/cups/backend/mdns (PID 25693)
D [28/Jun/2012:00:34:13 +0100] [CGI] Flushed attributes...
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Found device "hp"...
D [28/Jun/2012:00:34:13 +0100] Script header: Content-Type: application/ipp
D [28/Jun/2012:00:34:13 +0100] Script header: 
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] PID 25687 (hp) exited with no errors.
D [28/Jun/2012:00:34:13 +0100] [CGI] Flushed attributes...
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Found device "ipps"...
D [28/Jun/2012:00:34:13 +0100] [CGI] Flushed attributes...
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Found device "ipp14"...
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] PID 25690 (ipps) exited with no errors.
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] PID 25691 (ipp14) exited with no errors.
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] PID 25693 (mdns) exited with no errors.
D [28/Jun/2012:00:34:13 +0100] [CGI] list_devices
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] PID 25689 (serial) exited with no errors.
D [28/Jun/2012:00:34:13 +0100] [CGI] libusb_get_device_list=12
D [28/Jun/2012:00:34:13 +0100] [CGI] Flushed attributes...
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Found device "usb://Samsung/ML-4500"...
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] PID 25692 (usb) exited with no errors.
D [28/Jun/2012:00:34:13 +0100] [CGI] Flushed attributes...
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] Found device "hpfax"...
D [28/Jun/2012:00:34:13 +0100] [cups-deviced] PID 25688 (hpfax) exited with no errors.
D [28/Jun/2012:00:34:13 +0100] PID 25686 (/usr/lib/cups/daemon/cups-deviced) exited with no errors.
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 19 WAITING Closing on EOF
D [28/Jun/2012:00:34:13 +0100] cupsdCloseClient: 19
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [28/Jun/2012:00:34:13 +0100] CUPS-Get-Printers
D [28/Jun/2012:00:34:13 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jun/2012:00:34:13 +0100] cupsdCloseClient: 17
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 POST /admin/ HTTP/1.1
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 1.1 Resume-Printer 1
D [28/Jun/2012:00:34:13 +0100] Resume-Printer ipp://localhost:631/printers/ML-4500
D [28/Jun/2012:00:34:13 +0100] cupsdIsAuthorized: username=""
D [28/Jun/2012:00:34:13 +0100] Returning HTTP Unauthorized for Resume-Printer (ipp://localhost:631/printers/ML-4500) from localhost
D [28/Jun/2012:00:34:13 +0100] cupsdSendHeader: 17 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jun/2012:00:34:13 +0100] cupsdCloseClient: 17
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jun/2012:00:34:13 +0100] cupsdAcceptClient: 19 from localhost (Domain)
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jun/2012:00:34:13 +0100] cupsdCloseClient: 17
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 19 POST /admin/ HTTP/1.1
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdAuthorize: Authorized as root using PeerCred
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 19 1.1 Resume-Printer 1
D [28/Jun/2012:00:34:13 +0100] Resume-Printer ipp://localhost:631/printers/ML-4500
D [28/Jun/2012:00:34:13 +0100] cupsdIsAuthorized: username="root"
D [28/Jun/2012:00:34:13 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdMarkDirty(P-----)
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdRegisterPrinter(p=0x7ff62e268ae0(ML-4500))
D [28/Jun/2012:00:34:13 +0100] cupsdMarkDirty(P-----)
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [28/Jun/2012:00:34:13 +0100] Printer "ML-4500" started by "root".
D [28/Jun/2012:00:34:13 +0100] Returning IPP successful-ok for Resume-Printer (ipp://localhost:631/printers/ML-4500) from localhost
D [28/Jun/2012:00:34:13 +0100] [Notifier] state=3
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:13 +0100] cupsdReadClient: 19 WAITING Closing on EOF
D [28/Jun/2012:00:34:13 +0100] cupsdCloseClient: 19
D [28/Jun/2012:00:34:13 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1
D [28/Jun/2012:00:34:14 +0100] Get-Notifications /
D [28/Jun/2012:00:34:14 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:34:14 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jun/2012:00:34:14 +0100] cupsdCloseClient: 17
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1
D [28/Jun/2012:00:34:14 +0100] Get-Notifications /
D [28/Jun/2012:00:34:14 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:34:14 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jun/2012:00:34:14 +0100] cupsdCloseClient: 17
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 1.1 Get-Notifications 1
D [28/Jun/2012:00:34:14 +0100] Get-Notifications /
D [28/Jun/2012:00:34:14 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:34:14 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 WAITING Closing on EOF
D [28/Jun/2012:00:34:14 +0100] cupsdCloseClient: 17
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdAcceptClient: 17 from localhost (Domain)
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [28/Jun/2012:00:34:14 +0100] CUPS-Get-Printers
D [28/Jun/2012:00:34:14 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Classes 1
D [28/Jun/2012:00:34:14 +0100] CUPS-Get-Classes
D [28/Jun/2012:00:34:14 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:14 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:14 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
D [28/Jun/2012:00:34:14 +0100] CUPS-Get-Default
D [28/Jun/2012:00:34:14 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [28/Jun/2012:00:34:14 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:23 +0100] cupsdAcceptClient: 19 from localhost (Domain)
D [28/Jun/2012:00:34:23 +0100] cupsdReadClient: 19 POST / HTTP/1.1
D [28/Jun/2012:00:34:23 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:23 +0100] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
D [28/Jun/2012:00:34:23 +0100] Get-Printer-Attributes ipp://localhost/printers/ML-4500
D [28/Jun/2012:00:34:23 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ML-4500) from localhost
D [28/Jun/2012:00:34:23 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 20 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 20 POST /printers/ML-4500 HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 20 1.1 Print-Job 1
D [28/Jun/2012:00:34:26 +0100] Print-Job ipp://localhost/printers/ML-4500
D [28/Jun/2012:00:34:26 +0100] [Job ???] Auto-typing file...
I [28/Jun/2012:00:34:26 +0100] [Job ???] Request file type is application/vnd.cups-pdf-banner.
D [28/Jun/2012:00:34:26 +0100] cupsdMarkDirty(----J-)
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:26 +0100] add_job: requesting-user-name="daniel"
D [28/Jun/2012:00:34:26 +0100] Adding default job-sheets values "none,none"...
I [28/Jun/2012:00:34:26 +0100] [Job 3] Adding start banner page "none".
D [28/Jun/2012:00:34:26 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdMarkDirty(----J-)
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [28/Jun/2012:00:34:26 +0100] [Job 3] Adding end banner page "none".
I [28/Jun/2012:00:34:26 +0100] [Job 3] File of type application/vnd.cups-pdf-banner queued by "daniel".
D [28/Jun/2012:00:34:26 +0100] [Job 3] hold_until=0
I [28/Jun/2012:00:34:26 +0100] [Job 3] Queued on "ML-4500" by "daniel".
D [28/Jun/2012:00:34:26 +0100] cupsdMarkDirty(----J-)
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:26 +0100] [Job 3] job-sheets=none,none
D [28/Jun/2012:00:34:26 +0100] [Job 3] argv[0]="ML-4500"
D [28/Jun/2012:00:34:26 +0100] [Job 3] argv[1]="3"
D [28/Jun/2012:00:34:26 +0100] [Job 3] argv[2]="daniel"
D [28/Jun/2012:00:34:26 +0100] [Job 3] argv[3]="Test Page"
D [28/Jun/2012:00:34:26 +0100] [Job 3] argv[4]="1"
D [28/Jun/2012:00:34:26 +0100] [Job 3] argv[5]="job-uuid=urn:uuid:84094aa1-debc-3b9a-42c5-94f39201cc3f usb-unidir job-originating-host-name=localhost time-at-creation=1340840066 time-at-processing=1340840066"
D [28/Jun/2012:00:34:26 +0100] [Job 3] argv[6]="/var/spool/cups/d00003-001"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[8]="HOME=/var/spool/cups/tmp"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[10]="SERVER_ADMIN=root@daniel-MS-7693"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[11]="SOFTWARE=CUPS/1.5.3"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[13]="USER=root"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[14]="CUPS_SERVER=/var/run/cups/cups.sock"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[15]="CUPS_ENCRYPTION=IfRequested"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[16]="IPP_PORT=631"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[17]="CHARSET=utf-8"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[18]="LANG=en_GB.UTF-8"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[19]="PPD=/etc/cups/ppd/ML-4500.ppd"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[20]="RIP_MAX_CACHE=128m"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[21]="CONTENT_TYPE=application/vnd.cups-pdf-banner"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[22]="DEVICE_URI=usb://Samsung/ML-4500"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[23]="PRINTER_INFO=Samsung ML-4500"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[24]="PRINTER_LOCATION=daniel-MS-7693"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[25]="PRINTER=ML-4500"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[26]="PRINTER_STATE_REASONS=none"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[27]="CUPS_FILETYPE=document"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[28]="FINAL_CONTENT_TYPE=printer/ML-4500"
D [28/Jun/2012:00:34:26 +0100] [Job 3] envp[29]="AUTH_I****"
I [28/Jun/2012:00:34:26 +0100] [Job 3] Started filter /usr/lib/cups/filter/bannertopdf (PID 25699)
I [28/Jun/2012:00:34:26 +0100] [Job 3] Started filter /usr/lib/cups/filter/pdftopdf (PID 25700)
I [28/Jun/2012:00:34:26 +0100] [Job 3] Started filter /usr/lib/cups/filter/foomatic-rip (PID 25701)
I [28/Jun/2012:00:34:26 +0100] [Job 3] Started backend /usr/lib/cups/backend/usb (PID 25702)
D [28/Jun/2012:00:34:26 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Print-Job (ipp://localhost/printers/ML-4500) from localhost
D [28/Jun/2012:00:34:26 +0100] [Notifier] state=3
D [28/Jun/2012:00:34:26 +0100] [Notifier] state=3
D [28/Jun/2012:00:34:26 +0100] [Notifier] state=3
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] [Job 3] Getting input from file 
D [28/Jun/2012:00:34:26 +0100] [Job 3] foomatic-rip version 4.0.15.251 running...
D [28/Jun/2012:00:34:26 +0100] [Job 3] Parsing PPD file ...
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option ColorSpace
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option Manualfeed
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option Resolution
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option Economode
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option MediaType
D [28/Jun/2012:00:34:26 +0100] [Job 3] Printing on printer with URI: usb://Samsung/ML-4500
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option RET
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option Copies
D [28/Jun/2012:00:34:26 +0100] [Job 3] libusb_get_device_list=12
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option PageSize
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option ImageableArea
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option PaperDimension
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option Density
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option JamRecovery
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option AllowReprint
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option Altitude
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option PageTimeout
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option PowerSaving
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option PowerSaveTime
D [28/Jun/2012:00:34:26 +0100] PID 25699 (/usr/lib/cups/filter/bannertopdf) exited with no errors.
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option PageSizeJCL
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option PageSizePS
D [28/Jun/2012:00:34:26 +0100] [Job 3] Added option Font
D [28/Jun/2012:00:34:26 +0100] [Job 3] 
D [28/Jun/2012:00:34:26 +0100] [Job 3] Parameter Summary
D [28/Jun/2012:00:34:26 +0100] [Job 3] -----------------
D [28/Jun/2012:00:34:26 +0100] [Job 3] 
D [28/Jun/2012:00:34:26 +0100] [Job 3] Spooler: cups
D [28/Jun/2012:00:34:26 +0100] [Job 3] Printer: ML-4500
D [28/Jun/2012:00:34:26 +0100] [Job 3] Shell: /bin/bash
D [28/Jun/2012:00:34:26 +0100] [Job 3] PPD file: /etc/cups/ppd/ML-4500.ppd
D [28/Jun/2012:00:34:26 +0100] [Job 3] ATTR file: 
D [28/Jun/2012:00:34:26 +0100] [Job 3] Printer model: Samsung ML-4500 Foomatic/gdi (recommended)
D [28/Jun/2012:00:34:26 +0100] [Job 3] Job title: Test Page
D [28/Jun/2012:00:34:26 +0100] [Job 3] File(s) to be printed:
D [28/Jun/2012:00:34:26 +0100] [Job 3] <STDIN>
D [28/Jun/2012:00:34:26 +0100] [Job 3] 
D [28/Jun/2012:00:34:26 +0100] [Job 3] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts
D [28/Jun/2012:00:34:26 +0100] [Job 3] Printing system options:
D [28/Jun/2012:00:34:26 +0100] [Job 3] Pondering option 'job-uuid=urn:uuid:84094aa1-debc-3b9a-42c5-94f39201cc3f'
D [28/Jun/2012:00:34:26 +0100] [Job 3] Unknown option job-uuid=urn:uuid:84094aa1-debc-3b9a-42c5-94f39201cc3f.
D [28/Jun/2012:00:34:26 +0100] [Job 3] Pondering option 'usb-unidir'
D [28/Jun/2012:00:34:26 +0100] [Job 3] Unknown boolean option "usb-unidir".
D [28/Jun/2012:00:34:26 +0100] [Job 3] Pondering option 'job-originating-host-name=localhost'
D [28/Jun/2012:00:34:26 +0100] [Job 3] Unknown option job-originating-host-name=localhost.
D [28/Jun/2012:00:34:26 +0100] [Job 3] Pondering option 'time-at-creation=1340840066'
D [28/Jun/2012:00:34:26 +0100] [Job 3] Unknown option time-at-creation=1340840066.
D [28/Jun/2012:00:34:26 +0100] [Job 3] Pondering option 'time-at-processing=1340840066'
D [28/Jun/2012:00:34:26 +0100] [Job 3] Unknown option time-at-processing=1340840066.
D [28/Jun/2012:00:34:26 +0100] [Job 3] Options from the PPD file:
D [28/Jun/2012:00:34:26 +0100] [Job 3] 
D [28/Jun/2012:00:34:26 +0100] [Job 3] ================================================
D [28/Jun/2012:00:34:26 +0100] [Job 3] 
D [28/Jun/2012:00:34:26 +0100] [Job 3] File: <STDIN>
D [28/Jun/2012:00:34:26 +0100] [Job 3] 
D [28/Jun/2012:00:34:26 +0100] [Job 3] ================================================
D [28/Jun/2012:00:34:26 +0100] [Job 3] 
D [28/Jun/2012:00:34:26 +0100] [Job 3] Filetype: PDF
D [28/Jun/2012:00:34:26 +0100] [Job 3] Storing temporary files in /var/spool/cups/tmp
D [28/Jun/2012:00:34:26 +0100] PID 25700 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [28/Jun/2012:00:34:26 +0100] [Job 3] File contains 1 pages
D [28/Jun/2012:00:34:26 +0100] [Job 3] Starting renderer with command: gs -dFirstPage=1  -q -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -dNOINTERPOLATE -sDEVICE=gdi -dDEVICEWIDTHPOINTS=595 -dDEVICEHEIGHTPOINTS=842 -r600x600 -sOutputFile=- -f    /var/spool/cups/tmp/foomatic-IQprVh | perl -p -e 's/PJL PAGE LETTER/PJL PAGE A4/; s/PJL PAGE (\S*) AUTO/PJL PAGE $1 AUTO/; s/PJL SET TONERSAVE = OFF/PJL SET TONERSAVE = OFF\r\n\@PJL SET ECONOMODE = OFF/; s/PJL SET PAPERTYPE = NORMAL/PJL SET PAPERTYPE = NORMAL/; s/PJL SET DENSITY = 1/PJL SET DENSITY = 3/; s/(\@PJL ENTER LANGUAGE)/\@PJL SET RET = OFF\r\n$1/; s/(\@PJL ENTER LANGUAGE)/\@PJL SET JAMRECOVERY = ON\r\n$1/; s/(\@PJL ENTER LANGUAGE)/\@PJL SET REPRINT = ON\r\n$1/; s/(\@PJL ENTER LANGUAGE)/\@PJL SET ALTITUDE = OFF\r\n$1/; s/PJL COPIES = 1/PJL COPIES = 1/; s/(\@PJL ENTER LANGUAGE)/\@PJL DEFAULT TIMEOUT = 15\r\n$1/; s/(\@PJL ENTER LANGUAGE)/\@PJL DEFAULT POWERSAVE = ON\r\n$1/; s/(\@PJL ENTER LANGUAGE)/\@PJL DEFAULT POWERSAVETIME = 5\r\n$1/; '
D [28/Jun/2012:00:34:26 +0100] [Job 3] Starting process "kid3" (generation 1)
D [28/Jun/2012:00:34:26 +0100] [Job 3] Starting process "kid4" (generation 2)
D [28/Jun/2012:00:34:26 +0100] [Job 3] Starting process "renderer" (generation 2)
D [28/Jun/2012:00:34:26 +0100] [Job 3] JCL: %-12345X@PJL
D [28/Jun/2012:00:34:26 +0100] [Job 3] <job data> 
D [28/Jun/2012:00:34:26 +0100] [Job 3] 
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 1.1 Get-Notifications 1
D [28/Jun/2012:00:34:26 +0100] Get-Notifications /
D [28/Jun/2012:00:34:26 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 19 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Printer-Attributes ipp://localhost/printers/ML-4500
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ML-4500) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 1.1 Get-Notifications 1
D [28/Jun/2012:00:34:26 +0100] Get-Notifications /
D [28/Jun/2012:00:34:26 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/3
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 1.1 Get-Job-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/3
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 23
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 1.1 Get-Job-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/3
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 23
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 1.1 Get-Job-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/3
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 23
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 1.1 Get-Notifications 1
D [28/Jun/2012:00:34:26 +0100] Get-Notifications /
D [28/Jun/2012:00:34:26 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 1.1 Get-Job-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/3
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 1.1 Get-Printer-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Printer-Attributes 
D [28/Jun/2012:00:34:26 +0100] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [28/Jun/2012:00:34:26 +0100] Returning IPP client-error-not-found for Get-Printer-Attributes () from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 23
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 1.1 Get-Job-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/3
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 23
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 1.1 Get-Job-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/3
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 23
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] [Job 3] STATE: +connecting-to-device
D [28/Jun/2012:00:34:26 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] [Notifier] state=3
D [28/Jun/2012:00:34:26 +0100] [Job 3] STATE: -connecting-to-device
D [28/Jun/2012:00:34:26 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"
D [28/Jun/2012:00:34:26 +0100] [Notifier] state=3
D [28/Jun/2012:00:34:26 +0100] [Job 3] Device protocol: 2
D [28/Jun/2012:00:34:26 +0100] [Job 3] Printer reports bi-di support but in reality works only uni-directionally
D [28/Jun/2012:00:34:26 +0100] [Job 3] Uni-directional USB communication only!
D [28/Jun/2012:00:34:26 +0100] [Job 3] Printer does not like usblp kernel module to be re-attached after job
D [28/Jun/2012:00:34:26 +0100] [Job 3] Forced uni-directional communication via "usb-unidir" option.
D [28/Jun/2012:00:34:26 +0100] [Job 3] Uni-directional device/mode, back channel deactivated.
I [28/Jun/2012:00:34:26 +0100] [Job 3] Sending data to printer.
D [28/Jun/2012:00:34:26 +0100] [Job 3] Set job-printer-state-message to "Sending data to printer.", current level=INFO
D [28/Jun/2012:00:34:26 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] [Notifier] state=3
D [28/Jun/2012:00:34:26 +0100] [Notifier] state=3
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [28/Jun/2012:00:34:26 +0100] CUPS-Get-Printers
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Classes 1
D [28/Jun/2012:00:34:26 +0100] CUPS-Get-Classes
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
D [28/Jun/2012:00:34:26 +0100] CUPS-Get-Default
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 1.1 Get-Notifications 1
D [28/Jun/2012:00:34:26 +0100] Get-Notifications /
D [28/Jun/2012:00:34:26 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 19 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Printer-Attributes ipp://localhost/printers/ML-4500
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ML-4500) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] [Job 3] Read 8192 bytes of print data...
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 19 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Printer-Attributes ipp://localhost/printers/ML-4500
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ML-4500) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 19 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Printer-Attributes ipp://localhost/printers/ML-4500
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ML-4500) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 1.1 Get-Notifications 1
D [28/Jun/2012:00:34:26 +0100] Get-Notifications /
D [28/Jun/2012:00:34:26 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:34:26 +0100] [Job 3] Wrote 8192 bytes of print data...
D [28/Jun/2012:00:34:26 +0100] [Job 3] Read 8192 bytes of print data...
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 1.1 Get-Job-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/3
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 23
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 1.1 Get-Notifications 1
D [28/Jun/2012:00:34:26 +0100] Get-Notifications /
D [28/Jun/2012:00:34:26 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 1.1 Get-Job-Attributes 1
D [28/Jun/2012:00:34:26 +0100] Get-Job-Attributes ipp://localhost/jobs/3
D [28/Jun/2012:00:34:26 +0100] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/3) from localhost
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 23 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 23
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:26 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:34:26 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:34:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:27 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:27 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:27 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [28/Jun/2012:00:34:27 +0100] CUPS-Get-Printers
D [28/Jun/2012:00:34:27 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jun/2012:00:34:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:27 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:27 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:27 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Classes 1
D [28/Jun/2012:00:34:27 +0100] CUPS-Get-Classes
D [28/Jun/2012:00:34:27 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Jun/2012:00:34:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:34:27 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:34:27 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:34:27 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:34:27 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
D [28/Jun/2012:00:34:27 +0100] CUPS-Get-Default
D [28/Jun/2012:00:34:27 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [28/Jun/2012:00:34:27 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
I [28/Jun/2012:00:34:34 +0100] Saving printers.conf...
I [28/Jun/2012:00:34:34 +0100] Generating printcap /var/run/cups/printcap...
I [28/Jun/2012:00:34:34 +0100] Saving job.cache...
I [28/Jun/2012:00:34:34 +0100] Saving subscriptions.conf...
D [28/Jun/2012:00:34:34 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:35:26 +0100] [Job 3] Got USB transaction timeout during write.
D [28/Jun/2012:00:35:26 +0100] [Job 3] Wrote 256 bytes of print data...
D [28/Jun/2012:00:35:26 +0100] Report: clients=3
D [28/Jun/2012:00:35:26 +0100] Report: jobs=2
D [28/Jun/2012:00:35:26 +0100] Report: jobs-active=1
D [28/Jun/2012:00:35:26 +0100] Report: printers=1
D [28/Jun/2012:00:35:26 +0100] Report: printers-implicit=0
D [28/Jun/2012:00:35:26 +0100] Report: stringpool-string-count=3847
D [28/Jun/2012:00:35:26 +0100] Report: stringpool-alloc-bytes=10664
D [28/Jun/2012:00:35:26 +0100] Report: stringpool-total-bytes=74656
D [28/Jun/2012:00:36:26 +0100] [Job 3] Got USB transaction timeout during write.
D [28/Jun/2012:00:36:26 +0100] Report: clients=3
D [28/Jun/2012:00:36:26 +0100] Report: jobs=2
D [28/Jun/2012:00:36:26 +0100] Report: jobs-active=1
D [28/Jun/2012:00:36:26 +0100] Report: printers=1
D [28/Jun/2012:00:36:26 +0100] Report: printers-implicit=0
D [28/Jun/2012:00:36:26 +0100] Report: stringpool-string-count=3847
D [28/Jun/2012:00:36:26 +0100] Report: stringpool-alloc-bytes=10664
D [28/Jun/2012:00:36:26 +0100] Report: stringpool-total-bytes=74656
D [28/Jun/2012:00:36:43 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:36:43 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:36:43 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:36:43 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:36:43 +0100] cupsdReadClient: 22 1.1 Get-Jobs 1
D [28/Jun/2012:00:36:43 +0100] Get-Jobs ipp://localhost/
D [28/Jun/2012:00:36:43 +0100] Returning IPP successful-ok for Get-Jobs (ipp://localhost/) from localhost
D [28/Jun/2012:00:36:43 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
D [28/Jun/2012:00:36:43 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:36:43 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:36:43 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:36:46 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:36:46 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:36:46 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:36:46 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:36:46 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Printers 1
D [28/Jun/2012:00:36:46 +0100] CUPS-Get-Printers
D [28/Jun/2012:00:36:46 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jun/2012:00:36:46 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
D [28/Jun/2012:00:36:46 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:36:46 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:36:46 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:36:46 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Default 1
D [28/Jun/2012:00:36:46 +0100] CUPS-Get-Default
D [28/Jun/2012:00:36:46 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [28/Jun/2012:00:36:46 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
D [28/Jun/2012:00:36:46 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:36:46 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:36:46 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:36:46 +0100] cupsdReadClient: 22 1.1 CUPS-Get-Printers 1
D [28/Jun/2012:00:36:46 +0100] CUPS-Get-Printers
D [28/Jun/2012:00:36:46 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jun/2012:00:36:46 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
D [28/Jun/2012:00:36:46 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:36:46 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:36:46 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:37:23 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 1.1 Get-Printer-Attributes 1
D [28/Jun/2012:00:37:23 +0100] Get-Printer-Attributes ipp://localhost:631/printers/ML-4500-3
D [28/Jun/2012:00:37:23 +0100] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [28/Jun/2012:00:37:23 +0100] Returning IPP client-error-not-found for Get-Printer-Attributes (ipp://localhost:631/printers/ML-4500-3) from localhost
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
D [28/Jun/2012:00:37:23 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 23 1.1 Get-Printer-Attributes 1
D [28/Jun/2012:00:37:23 +0100] Get-Printer-Attributes ipp://localhost/printers/ML-4500-3
D [28/Jun/2012:00:37:23 +0100] Get-Printer-Attributes client-error-not-found: The printer or class does not exist.
D [28/Jun/2012:00:37:23 +0100] Returning IPP client-error-not-found for Get-Printer-Attributes (ipp://localhost/printers/ML-4500-3) from localhost
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 POST /admin/ HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 1.1 CUPS-Add-Modify-Printer 1
D [28/Jun/2012:00:37:23 +0100] CUPS-Add-Modify-Printer ipp://localhost:631/printers/ML-4500-3
D [28/Jun/2012:00:37:23 +0100] cupsdIsAuthorized: username=""
D [28/Jun/2012:00:37:23 +0100] Returning HTTP Unauthorized for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/ML-4500-3) from localhost
D [28/Jun/2012:00:37:23 +0100] cupsdSendHeader: 22 WWW-Authenticate: Basic realm="CUPS", trc="y"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:37:23 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Active clients and printing jobs"
D [28/Jun/2012:00:37:23 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:37:23 +0100] cupsdAcceptClient: 24 from localhost (Domain)
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:37:23 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 24 POST /admin/ HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: Authorized as daniel using PeerCred
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 24 1.1 CUPS-Add-Modify-Printer 1
D [28/Jun/2012:00:37:23 +0100] CUPS-Add-Modify-Printer ipp://localhost:631/printers/ML-4500-3
D [28/Jun/2012:00:37:23 +0100] cupsdIsAuthorized: username="daniel"
D [28/Jun/2012:00:37:23 +0100] Setting usb-unidir-default to true...
D [28/Jun/2012:00:37:23 +0100] cupsdMarkDirty(P-----)
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and printing jobs"
D [28/Jun/2012:00:37:23 +0100] load_ppd: Loading /etc/cups/ppd/ML-4500-3.ppd...
D [28/Jun/2012:00:37:23 +0100] Calling DeleteDevice(cups-ML-4500-3)
D [28/Jun/2012:00:37:23 +0100] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [28/Jun/2012:00:37:23 +0100] cannot open /etc/cups/ppd/ML-4500-3.ppd
D [28/Jun/2012:00:37:23 +0100] cupsdRegisterPrinter(p=0x7ff62e2c7f30(ML-4500-3))
D [28/Jun/2012:00:37:23 +0100] cupsdMarkDirty(P-----)
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdMarkDirty(---p--)
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
I [28/Jun/2012:00:37:23 +0100] New printer "ML-4500-3" added by "daniel".
D [28/Jun/2012:00:37:23 +0100] Returning IPP successful-ok for CUPS-Add-Modify-Printer (ipp://localhost:631/printers/ML-4500-3) from localhost
D [28/Jun/2012:00:37:23 +0100] [Notifier] state=3
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 24 WAITING Closing on EOF
D [28/Jun/2012:00:37:23 +0100] cupsdCloseClient: 24
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 23 WAITING Closing on EOF
D [28/Jun/2012:00:37:23 +0100] cupsdCloseClient: 23
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 1.1 Get-Notifications 1
D [28/Jun/2012:00:37:23 +0100] Get-Notifications /
D [28/Jun/2012:00:37:23 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:37:23 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:37:23 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 1.1 Get-Notifications 1
D [28/Jun/2012:00:37:23 +0100] Get-Notifications /
D [28/Jun/2012:00:37:23 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:37:23 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAcceptClient: 23 from localhost (Domain)
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 23 POST / HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 23 1.1 Get-Printer-Attributes 1
D [28/Jun/2012:00:37:23 +0100] Get-Printer-Attributes ipp://localhost/printers/ML-4500-3
D [28/Jun/2012:00:37:23 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ML-4500-3) from localhost
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 23 WAITING Closing on EOF
D [28/Jun/2012:00:37:23 +0100] cupsdCloseClient: 23
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:37:23 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 POST / HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 1.1 Get-Notifications 1
D [28/Jun/2012:00:37:23 +0100] Get-Notifications /
D [28/Jun/2012:00:37:23 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:37:23 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:37:23 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [28/Jun/2012:00:37:23 +0100] CUPS-Get-Printers
D [28/Jun/2012:00:37:23 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Classes 1
D [28/Jun/2012:00:37:23 +0100] CUPS-Get-Classes
D [28/Jun/2012:00:37:23 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:37:23 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:37:23 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
D [28/Jun/2012:00:37:23 +0100] CUPS-Get-Default
D [28/Jun/2012:00:37:23 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [28/Jun/2012:00:37:23 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:37:27 +0100] [Job 3] Got USB transaction timeout during write.
D [28/Jun/2012:00:37:27 +0100] Report: clients=3
D [28/Jun/2012:00:37:27 +0100] Report: jobs=2
D [28/Jun/2012:00:37:27 +0100] Report: jobs-active=1
D [28/Jun/2012:00:37:27 +0100] Report: printers=2
D [28/Jun/2012:00:37:27 +0100] Report: printers-implicit=0
D [28/Jun/2012:00:37:27 +0100] Report: stringpool-string-count=3976
D [28/Jun/2012:00:37:27 +0100] Report: stringpool-alloc-bytes=10936
D [28/Jun/2012:00:37:27 +0100] Report: stringpool-total-bytes=77352
I [28/Jun/2012:00:37:54 +0100] Saving printers.conf...
I [28/Jun/2012:00:37:54 +0100] Generating printcap /var/run/cups/printcap...
I [28/Jun/2012:00:37:54 +0100] Saving subscriptions.conf...
D [28/Jun/2012:00:37:54 +0100] cupsdSetBusyState: newbusy="Printing jobs", busy="Printing jobs and dirty files"
D [28/Jun/2012:00:38:27 +0100] [Job 3] Got USB transaction timeout during write.
D [28/Jun/2012:00:38:27 +0100] Report: clients=3
D [28/Jun/2012:00:38:27 +0100] Report: jobs=2
D [28/Jun/2012:00:38:27 +0100] Report: jobs-active=1
D [28/Jun/2012:00:38:27 +0100] Report: printers=2
D [28/Jun/2012:00:38:27 +0100] Report: printers-implicit=0
D [28/Jun/2012:00:38:27 +0100] Report: stringpool-string-count=3976
D [28/Jun/2012:00:38:27 +0100] Report: stringpool-alloc-bytes=10936
D [28/Jun/2012:00:38:27 +0100] Report: stringpool-total-bytes=77352
D [28/Jun/2012:00:38:33 +0100] cupsdAcceptClient: 22 from localhost (Domain)
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 22 POST /admin/ HTTP/1.1
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients and printing jobs", busy="Printing jobs"
D [28/Jun/2012:00:38:33 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 22 1.1 Purge-Jobs 1
D [28/Jun/2012:00:38:33 +0100] Purge-Jobs ipp://localhost/printers/
D [28/Jun/2012:00:38:33 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:38:33 +0100] [Job 2] Loading attributes...
D [28/Jun/2012:00:38:33 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and printing jobs"
I [28/Jun/2012:00:38:33 +0100] [Job 2] Job purged by user.
D [28/Jun/2012:00:38:33 +0100] [Job 2] Unloading...
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
I [28/Jun/2012:00:38:33 +0100] [Job 3] Job purged by user.
D [28/Jun/2012:00:38:33 +0100] cupsdMarkDirty(-----S)
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients, printing jobs, and dirty files"
I [28/Jun/2012:00:38:33 +0100] All jobs were purged by "daniel".
D [28/Jun/2012:00:38:33 +0100] Returning IPP successful-ok for Purge-Jobs (ipp://localhost/printers/) from localhost
D [28/Jun/2012:00:38:33 +0100] [Notifier] state=3
D [28/Jun/2012:00:38:33 +0100] [Notifier] state=3
D [28/Jun/2012:00:38:33 +0100] [Notifier] state=3
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 22 WAITING Closing on EOF
D [28/Jun/2012:00:38:33 +0100] cupsdCloseClient: 22
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] PID 25702 (/usr/lib/cups/backend/usb) was terminated normally with signal 9.
D [28/Jun/2012:00:38:33 +0100] PID 25701 (/usr/lib/cups/filter/foomatic-rip) was terminated normally with signal 9.
D [28/Jun/2012:00:38:33 +0100] cupsdAcceptClient: 21 from localhost (Domain)
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 21 POST / HTTP/1.1
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 21 1.1 Get-Notifications 1
D [28/Jun/2012:00:38:33 +0100] Get-Notifications /
D [28/Jun/2012:00:38:33 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:38:33 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 19 POST / HTTP/1.1
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 19 1.1 Get-Printer-Attributes 1
D [28/Jun/2012:00:38:33 +0100] Get-Printer-Attributes ipp://localhost/printers/ML-4500
D [28/Jun/2012:00:38:33 +0100] Returning IPP successful-ok for Get-Printer-Attributes (ipp://localhost/printers/ML-4500) from localhost
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 21 WAITING Closing on EOF
D [28/Jun/2012:00:38:33 +0100] cupsdCloseClient: 21
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdAcceptClient: 21 from localhost (Domain)
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 21 POST / HTTP/1.1
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 21 1.1 Get-Notifications 1
D [28/Jun/2012:00:38:33 +0100] Get-Notifications /
D [28/Jun/2012:00:38:33 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:38:33 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 21 WAITING Closing on EOF
D [28/Jun/2012:00:38:33 +0100] cupsdCloseClient: 21
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdAcceptClient: 21 from localhost (Domain)
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 21 POST / HTTP/1.1
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 21 1.1 Get-Notifications 1
D [28/Jun/2012:00:38:33 +0100] Get-Notifications /
D [28/Jun/2012:00:38:33 +0100] cupsdIsAuthorized: requesting-user-name="daniel"
D [28/Jun/2012:00:38:33 +0100] Returning IPP successful-ok for Get-Notifications (/) from localhost
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 21 WAITING Closing on EOF
D [28/Jun/2012:00:38:33 +0100] cupsdCloseClient: 21
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Printers 1
D [28/Jun/2012:00:38:33 +0100] CUPS-Get-Printers
D [28/Jun/2012:00:38:33 +0100] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Classes 1
D [28/Jun/2012:00:38:33 +0100] CUPS-Get-Classes
D [28/Jun/2012:00:38:33 +0100] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 17 POST / HTTP/1.1
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [28/Jun/2012:00:38:33 +0100] cupsdAuthorize: No authentication data provided.
D [28/Jun/2012:00:38:33 +0100] cupsdReadClient: 17 1.1 CUPS-Get-Default 1
D [28/Jun/2012:00:38:33 +0100] CUPS-Get-Default
D [28/Jun/2012:00:38:33 +0100] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [28/Jun/2012:00:38:33 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [28/Jun/2012:00:38:34 +0100] [Job 3] Unloading...
I [28/Jun/2012:00:39:04 +0100] Saving subscriptions.conf...