I [27/Jan/2013:19:06:42 +0000] Saving job.cache...
I [27/Jan/2013:19:06:42 +0000] Listening to 0.0.0.0:631 (IPv4)
I [27/Jan/2013:19:06:42 +0000] Listening to [v1.::]:631 (IPv6)
I [27/Jan/2013:19:06:42 +0000] Listening to /var/run/cups/cups.sock (Domain)
I [27/Jan/2013:19:06:42 +0000] Remote access is enabled.
D [27/Jan/2013:19:06:42 +0000] Added auto ServerAlias raspberrypi
I [27/Jan/2013:19:06:42 +0000] Loaded configuration file "/etc/cups/cupsd.conf"
I [27/Jan/2013:19:06:42 +0000] Using default TempDir of /var/spool/cups/tmp...
I [27/Jan/2013:19:06:43 +0000] Configured for up to 100 clients.
I [27/Jan/2013:19:06:43 +0000] Allowing up to 100 client connections per host.
I [27/Jan/2013:19:06:43 +0000] Using policy "default" as the default.
I [27/Jan/2013:19:06:43 +0000] Full reload is required.
I [27/Jan/2013:19:06:43 +0000] Loaded MIME database from "/usr/share/cups/mime" and "/etc/cups": 38 types, 55 filters...
D [27/Jan/2013:19:06:43 +0000] Loading printer GEBE-TINI...
D [27/Jan/2013:19:06:43 +0000] load_ppd: Loading /var/cache/cups/GEBE-TINI.data...
D [27/Jan/2013:19:06:43 +0000] Calling DeleteDevice(cups-GEBE-TINI)
D [27/Jan/2013:19:06:43 +0000] failed to DeleteDevice: org.freedesktop.DBus.Error.InvalidArgs:Type of message, `(s)', does not match expected type `(o)'
D [27/Jan/2013:19:06:43 +0000] Using profile id of GEBE-TINI-Gray..
D [27/Jan/2013:19:06:43 +0000] Calling CreateProfile(GEBE-TINI-Gray..,temp)
D [27/Jan/2013:19:06:43 +0000] created profile /org/freedesktop/ColorManager/profiles/GEBE_TINI_Gray__
I [27/Jan/2013:19:06:43 +0000] Registering ICC color profiles for "GEBE-TINI"
D [27/Jan/2013:19:06:43 +0000] Calling CreateDevice(cups-GEBE-TINI,temp)
D [27/Jan/2013:19:06:43 +0000] created device /org/freedesktop/ColorManager/devices/cups_GEBE_TINI
D [27/Jan/2013:19:06:43 +0000] Calling /org/freedesktop/ColorManager/devices/cups_GEBE_TINI:AddProfile(/org/freedesktop/ColorManager/profiles/GEBE_TINI_Gray__) [soft]
D [27/Jan/2013:19:06:43 +0000] cupsdRegisterPrinter(p=0x41e21e28(GEBE-TINI))
I [27/Jan/2013:19:06:43 +0000] Loading job cache file "/var/cache/cups/job.cache"...
D [27/Jan/2013:19:06:43 +0000] [Job 6] Loading from cache...
I [27/Jan/2013:19:06:43 +0000] Full reload complete.
D [27/Jan/2013:19:06:43 +0000] cupsdCleanFiles(path="/var/spool/cups/tmp", pattern="(null)")
I [27/Jan/2013:19:06:43 +0000] Cleaning out old files in "/var/spool/cups/tmp"...
D [27/Jan/2013:19:06:43 +0000] cupsdCleanFiles(path="/var/cache/cups", pattern="*.ipp")
I [27/Jan/2013:19:06:43 +0000] Cleaning out old files in "/var/cache/cups"...
I [27/Jan/2013:19:06:43 +0000] Listening to 0.0.0.0:631 on fd 9...
E [27/Jan/2013:19:06:43 +0000] Unable to open listen socket for address [v1.::]:631 - Address family not supported by protocol.
I [27/Jan/2013:19:06:43 +0000] Listening to /var/run/cups/cups.sock:631 on fd 10...
I [27/Jan/2013:19:06:43 +0000] Resuming new connection processing...
D [27/Jan/2013:19:06:43 +0000] Avahi client started
D [27/Jan/2013:19:06:43 +0000] cupsdRegisterPrinter(p=0x41e21e28(GEBE-TINI))
D [27/Jan/2013:19:06:43 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"
D [27/Jan/2013:19:06:43 +0000] Discarding unused server-started event...
D [27/Jan/2013:19:06:44 +0000] Report: clients=0
D [27/Jan/2013:19:06:44 +0000] Report: jobs=1
D [27/Jan/2013:19:06:44 +0000] Report: jobs-active=0
D [27/Jan/2013:19:06:44 +0000] Report: printers=1
D [27/Jan/2013:19:06:44 +0000] Report: printers-implicit=0
D [27/Jan/2013:19:06:44 +0000] Report: stringpool-string-count=498
D [27/Jan/2013:19:06:44 +0000] Report: stringpool-alloc-bytes=7768
D [27/Jan/2013:19:06:44 +0000] Report: stringpool-total-bytes=9472
D [27/Jan/2013:19:07:18 +0000] cupsdAcceptClient: 15 from localhost (Domain)
D [27/Jan/2013:19:07:18 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [27/Jan/2013:19:07:18 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Jan/2013:19:07:18 +0000] cupsdAuthorize: No authentication data provided.
D [27/Jan/2013:19:07:18 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Default 1
D [27/Jan/2013:19:07:18 +0000] CUPS-Get-Default
D [27/Jan/2013:19:07:18 +0000] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost
D [27/Jan/2013:19:07:18 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"
D [27/Jan/2013:19:07:18 +0000] cupsdReadClient: 15 POST /printers/GEBE-TINI HTTP/1.1
D [27/Jan/2013:19:07:18 +0000] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"
D [27/Jan/2013:19:07:18 +0000] cupsdAuthorize: No authentication data provided.
D [27/Jan/2013:19:07:18 +0000] cupsdReadClient: 15 1.1 Create-Job 1
D [27/Jan/2013:19:07:18 +0000] Create-Job ipp://localhost:631/printers/GEBE-TINI
D [27/Jan/2013:19:07:18 +0000] cupsdMarkDirty(----J-)
D [27/Jan/2013:19:07:18 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"
D [27/Jan/2013:19:07:18 +0000] add_job: requesting-user-name="pi"
I [27/Jan/2013:19:07:18 +0000] [Job 7] Adding start banner page "none".
D [27/Jan/2013:19:07:18 +0000] Discarding unused job-created event...
I [27/Jan/2013:19:07:18 +0000] [Job 7] Queued on "GEBE-TINI" by "pi".
D [27/Jan/2013:19:07:18 +0000] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/GEBE-TINI) from localhost
D [27/Jan/2013:19:07:18 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
D [27/Jan/2013:19:07:18 +0000] cupsdReadClient: 15 POST /printers/GEBE-TINI HTTP/1.1
D [27/Jan/2013:19:07:18 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"
D [27/Jan/2013:19:07:18 +0000] cupsdAuthorize: No authentication data provided.
D [27/Jan/2013:19:07:18 +0000] cupsdReadClient: 15 1.1 Send-Document 1
D [27/Jan/2013:19:07:18 +0000] Send-Document ipp://localhost:631/printers/GEBE-TINI
D [27/Jan/2013:19:07:18 +0000] cupsdIsAuthorized: requesting-user-name="pi"
D [27/Jan/2013:19:07:18 +0000] [Job 7] Auto-typing file...
D [27/Jan/2013:19:07:18 +0000] [Job 7] Request file type is text/plain.
D [27/Jan/2013:19:07:18 +0000] cupsdMarkDirty(----J-)
D [27/Jan/2013:19:07:18 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
I [27/Jan/2013:19:07:18 +0000] [Job 7] File of type text/plain queued by "pi".
I [27/Jan/2013:19:07:18 +0000] [Job 7] Adding end banner page "none".
D [27/Jan/2013:19:07:18 +0000] cupsdMarkDirty(----J-)
D [27/Jan/2013:19:07:18 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Jan/2013:19:07:18 +0000] cupsdMarkDirty(----J-)
D [27/Jan/2013:19:07:18 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Jan/2013:19:07:18 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"
D [27/Jan/2013:19:07:18 +0000] Discarding unused printer-state-changed event...
D [27/Jan/2013:19:07:18 +0000] [Job 7] job-sheets=none,none
D [27/Jan/2013:19:07:18 +0000] [Job 7] argv[0]="GEBE-TINI"
D [27/Jan/2013:19:07:18 +0000] [Job 7] argv[1]="7"
D [27/Jan/2013:19:07:18 +0000] [Job 7] argv[2]="pi"
D [27/Jan/2013:19:07:18 +0000] [Job 7] argv[3]="test.txt"
D [27/Jan/2013:19:07:18 +0000] [Job 7] argv[4]="1"
D [27/Jan/2013:19:07:18 +0000] [Job 7] argv[5]="finishings=3 number-up=1 job-uuid=urn:uuid:e504a192-e527-3759-4f72-bfbe7e067bec job-originating-host-name=localhost time-at-creation=1359313638 time-at-processing=1359313638"
D [27/Jan/2013:19:07:18 +0000] [Job 7] argv[6]="/var/spool/cups/d00007-001"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[8]="HOME=/var/spool/cups/tmp"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[10]="SERVER_ADMIN=root@raspberrypi"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[11]="SOFTWARE=CUPS/1.5.3"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[13]="TZ=Etc/UTC"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[14]="USER=root"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[17]="IPP_PORT=631"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[18]="CHARSET=utf-8"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[19]="LANG=de_DE.UTF-8"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[20]="PPD=/etc/cups/ppd/GEBE-TINI.ppd"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[21]="RIP_MAX_CACHE=128m"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[22]="CONTENT_TYPE=text/plain"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[23]="DEVICE_URI=usb://GeBE/GEBE-TINI"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[24]="PRINTER_INFO=Bondrucker"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[25]="PRINTER_LOCATION=Raspberry"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[26]="PRINTER=GEBE-TINI"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[27]="PRINTER_STATE_REASONS=none"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[28]="CUPS_FILETYPE=document"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[29]="FINAL_CONTENT_TYPE=printer/GEBE-TINI"
D [27/Jan/2013:19:07:18 +0000] [Job 7] envp[30]="AUTH_I****"
I [27/Jan/2013:19:07:18 +0000] [Job 7] Started filter /usr/lib/cups/filter/texttopdf (PID 3712)
I [27/Jan/2013:19:07:18 +0000] [Job 7] Started filter /usr/lib/cups/filter/pdftopdf (PID 3713)
I [27/Jan/2013:19:07:18 +0000] [Job 7] Started filter /usr/lib/cups/filter/gstoraster (PID 3714)
I [27/Jan/2013:19:07:18 +0000] [Job 7] Started filter /usr/lib/cups/filter/rastertopclx (PID 3715)
I [27/Jan/2013:19:07:18 +0000] [Job 7] Started backend /usr/lib/cups/backend/usb (PID 3716)
D [27/Jan/2013:19:07:19 +0000] Discarding unused job-state-changed event...
D [27/Jan/2013:19:07:19 +0000] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/GEBE-TINI) from localhost
D [27/Jan/2013:19:07:19 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients and dirty files"
D [27/Jan/2013:19:07:19 +0000] cupsdReadClient: 15 WAITING Closing on EOF
D [27/Jan/2013:19:07:19 +0000] cupsdCloseClient: 15
D [27/Jan/2013:19:07:19 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Jan/2013:19:07:19 +0000] [Job 7] Page = 232x513; 0,0 to 232,513
D [27/Jan/2013:19:07:19 +0000] [Job 7] Printing on printer with URI: usb://GeBE/GEBE-TINI
D [27/Jan/2013:19:07:19 +0000] [Job 7] libusb_get_device_list=6
D [27/Jan/2013:19:07:19 +0000] [Job 7] STATE: +connecting-to-device
D [27/Jan/2013:19:07:19 +0000] Discarding unused printer-state-changed event...
D [27/Jan/2013:19:07:19 +0000] [Job 7] STATE: -connecting-to-device
D [27/Jan/2013:19:07:19 +0000] Discarding unused printer-state-changed event...
D [27/Jan/2013:19:07:19 +0000] [Job 7] Device protocol: 2
I [27/Jan/2013:19:07:19 +0000] [Job 7] Sending data to printer.
D [27/Jan/2013:19:07:19 +0000] [Job 7] Set job-printer-state-message to "Sending data to printer.", current level=INFO
D [27/Jan/2013:19:07:19 +0000] Discarding unused job-progress event...
D [27/Jan/2013:19:07:19 +0000] Discarding unused printer-state-changed event...
D [27/Jan/2013:19:07:19 +0000] PID 3712 (/usr/lib/cups/filter/texttopdf) exited with no errors.
D [27/Jan/2013:19:07:19 +0000] [Job 7] PPD uses qualifier 'Gray..203dpi'
D [27/Jan/2013:19:07:19 +0000] PID 3713 (/usr/lib/cups/filter/pdftopdf) exited with no errors.
D [27/Jan/2013:19:07:19 +0000] [Job 7] Calling FindDeviceById(GEBE-TINI)
D [27/Jan/2013:19:07:19 +0000] [Job 7] Failed to send: org.freedesktop.ColorManager.Failed:device id 'GEBE-TINI' does not exists
D [27/Jan/2013:19:07:19 +0000] [Job 7] Failed to get profile filename!
I [27/Jan/2013:19:07:19 +0000] [Job 7] no profiles specified in PPD
D [27/Jan/2013:19:07:19 +0000] [Job 7] Set job-printer-state-message to "no profiles specified in PPD", current level=INFO
D [27/Jan/2013:19:07:19 +0000] Discarding unused job-progress event...
D [27/Jan/2013:19:07:19 +0000] Discarding unused printer-state-changed event...
D [27/Jan/2013:19:07:19 +0000] [Job 7] Ghostscript command line: /usr/bin/gs -dQUIET -dPARANOIDSAFER -dNOPAUSE -dBATCH -dNOINTERPOLATE -sDEVICE=cups -sstdout=%stderr -sOutputFile=%stdout -r203x203 -dDEVICEWIDTHPOINTS=232 -dDEVICEHEIGHTPOINTS=513 -dcupsBitsPerColor=1 -dcupsColorOrder=0 -dcupsColorSpace=3 -dcupsCompression=3 -scupsPageSizeName=82x181mm -I/usr/share/cups/fonts -c -f -_
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[0]="CUPS_CACHEDIR=/var/cache/cups"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[1]="CUPS_DATADIR=/usr/share/cups"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[2]="CUPS_DOCROOT=/usr/share/cups/doc-root"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[6]="CUPS_SERVERROOT=/etc/cups"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[7]="CUPS_STATEDIR=/var/run/cups"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[8]="HOME=/var/spool/cups/tmp"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[9]="PATH=/usr/lib/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[10]="SERVER_ADMIN=root@raspberrypi"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[11]="SOFTWARE=CUPS/1.5.3"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[12]="TMPDIR=/var/spool/cups/tmp"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[13]="TZ=Etc/UTC"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[14]="USER=root"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[16]="CUPS_ENCRYPTION=IfRequested"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[17]="IPP_PORT=631"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[18]="CHARSET=utf-8"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[19]="LANG=de_DE.UTF-8"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[20]="PPD=/etc/cups/ppd/GEBE-TINI.ppd"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[21]="RIP_MAX_CACHE=128m"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[22]="CONTENT_TYPE=text/plain"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[23]="DEVICE_URI=usb://GeBE/GEBE-TINI"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[24]="PRINTER_INFO=Bondrucker"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[25]="PRINTER_LOCATION=Raspberry"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[26]="PRINTER=GEBE-TINI"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[27]="PRINTER_STATE_REASONS=none"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[28]="CUPS_FILETYPE=document"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[29]="FINAL_CONTENT_TYPE=printer/GEBE-TINI"
D [27/Jan/2013:19:07:19 +0000] [Job 7] envp[30]="AUTH_INFO_REQUIRED=none"
I [27/Jan/2013:19:07:20 +0000] [Job 7] Start rendering...
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Start rendering...", current level=INFO
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
D [27/Jan/2013:19:07:20 +0000] Discarding unused printer-state-changed event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Processing page 1...
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Processing page 1...", current level=INFO
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
D [27/Jan/2013:19:07:20 +0000] Discarding unused printer-state-changed event...
D [27/Jan/2013:19:07:20 +0000] [Job 7] PAGE: 1 1
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Starting page 1.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Starting page 1.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] StartPage...
D [27/Jan/2013:19:07:20 +0000] [Job 7] MediaClass = ""
D [27/Jan/2013:19:07:20 +0000] [Job 7] MediaColor = ""
D [27/Jan/2013:19:07:20 +0000] [Job 7] MediaType = ""
D [27/Jan/2013:19:07:20 +0000] [Job 7] OutputType = ""
D [27/Jan/2013:19:07:20 +0000] [Job 7] AdvanceDistance = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] AdvanceMedia = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] Collate = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] CutMedia = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] Duplex = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] HWResolution = [ 203 203 ]
D [27/Jan/2013:19:07:20 +0000] [Job 7] ImagingBoundingBox = [ 0 0 232 513 ]
D [27/Jan/2013:19:07:20 +0000] [Job 7] InsertSheet = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] Jog = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] LeadingEdge = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] Margins = [ 0 0 ]
D [27/Jan/2013:19:07:20 +0000] [Job 7] ManualFeed = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] MediaPosition = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] MediaWeight = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] MirrorPrint = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] NegativePrint = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] NumCopies = 1
D [27/Jan/2013:19:07:20 +0000] [Job 7] Orientation = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] OutputFaceUp = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] PageSize = [ 232 513 ]
D [27/Jan/2013:19:07:20 +0000] [Job 7] Separations = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] TraySwitch = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] Tumble = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] cupsWidth = 655
D [27/Jan/2013:19:07:20 +0000] [Job 7] cupsHeight = 1447
D [27/Jan/2013:19:07:20 +0000] [Job 7] cupsMediaType = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] cupsBitsPerColor = 1
D [27/Jan/2013:19:07:20 +0000] [Job 7] cupsBitsPerPixel = 1
D [27/Jan/2013:19:07:20 +0000] [Job 7] cupsBytesPerLine = 82
D [27/Jan/2013:19:07:20 +0000] [Job 7] cupsColorOrder = 0
D [27/Jan/2013:19:07:20 +0000] [Job 7] cupsColorSpace = 3
D [27/Jan/2013:19:07:20 +0000] [Job 7] cupsCompression = 3
D [27/Jan/2013:19:07:20 +0000] [Job 7] PrinterPlanes = 1
D [27/Jan/2013:19:07:20 +0000] [Job 7] BlankValue=0
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 0% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 0% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=0
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
D [27/Jan/2013:19:07:20 +0000] Discarding unused printer-state-changed event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 8% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 8% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=8
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 17% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 17% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=17
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 26% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 26% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=26
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 35% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 35% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=35
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 44% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 44% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=44
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 53% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 53% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=53
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Processing page 2...
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Processing page 2...", current level=INFO
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 61% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 61% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=61
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 70% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 70% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=70
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 79% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 79% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=79
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 88% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 88% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=88
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Printing page 1, 97% complete.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Printing page 1, 97% complete.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] [Job 7] ATTR: job-media-progress=97
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Finished page 1.
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Finished page 1.", current level=INFO
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
D [27/Jan/2013:19:07:20 +0000] Discarding unused printer-state-changed event...
I [27/Jan/2013:19:07:20 +0000] [Job 7] Rendering completed
D [27/Jan/2013:19:07:20 +0000] [Job 7] Set job-printer-state-message to "Rendering completed", current level=INFO
D [27/Jan/2013:19:07:20 +0000] Discarding unused job-progress event...
D [27/Jan/2013:19:07:20 +0000] Discarding unused printer-state-changed event...
D [27/Jan/2013:19:07:20 +0000] [Job 7] Read 1348 bytes of print data...
D [27/Jan/2013:19:07:20 +0000] PID 3715 (/usr/lib/cups/filter/rastertopclx) exited with no errors.
D [27/Jan/2013:19:07:20 +0000] PID 3714 (/usr/lib/cups/filter/gstoraster) exited with no errors.
D [27/Jan/2013:19:07:30 +0000] [Job 7] Read 1 bytes of back-channel data...
D [27/Jan/2013:19:07:40 +0000] [Job 7] Got USB pipe stalled during write.
E [27/Jan/2013:19:07:40 +0000] [Job 7] Unable to send data to printer.
D [27/Jan/2013:19:07:40 +0000] [Job 7] Set job-printer-state-message to "Unable to send data to printer.", current level=ERROR
D [27/Jan/2013:19:07:40 +0000] [Job 7] libusb write operation returned fffffff7.
D [27/Jan/2013:19:07:40 +0000] [Job 7] Sent 0 bytes...
D [27/Jan/2013:19:07:40 +0000] [Job 7] Waiting for read thread to exit...
D [27/Jan/2013:19:07:40 +0000] Discarding unused job-progress event...
D [27/Jan/2013:19:07:40 +0000] Discarding unused printer-state-changed event...
D [27/Jan/2013:19:07:40 +0000] PID 3716 (/usr/lib/cups/backend/usb) stopped with status 1.
I [27/Jan/2013:19:07:40 +0000] [Job 7] Backend returned status 1 (failed)
D [27/Jan/2013:19:07:40 +0000] Discarding unused job-state-changed event...
I [27/Jan/2013:19:07:40 +0000] [Job 7] Printer stopped due to backend errors; please consult the error_log file for details.
D [27/Jan/2013:19:07:40 +0000] cupsdMarkDirty(----J-)
D [27/Jan/2013:19:07:40 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Jan/2013:19:07:40 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Jan/2013:19:07:41 +0000] Discarding unused printer-stopped event...
D [27/Jan/2013:19:07:41 +0000] cupsdMarkDirty(P-----)
D [27/Jan/2013:19:07:41 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Jan/2013:19:07:41 +0000] cupsdRegisterPrinter(p=0x41e21e28(GEBE-TINI))
D [27/Jan/2013:19:07:41 +0000] cupsdMarkDirty(P-----)
D [27/Jan/2013:19:07:41 +0000] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"
D [27/Jan/2013:19:07:44 +0000] Report: clients=0
D [27/Jan/2013:19:07:44 +0000] Report: jobs=2
D [27/Jan/2013:19:07:44 +0000] Report: jobs-active=1
D [27/Jan/2013:19:07:44 +0000] Report: printers=1
D [27/Jan/2013:19:07:44 +0000] Report: printers-implicit=0
D [27/Jan/2013:19:07:44 +0000] Report: stringpool-string-count=540
D [27/Jan/2013:19:07:44 +0000] Report: stringpool-alloc-bytes=8616
D [27/Jan/2013:19:07:44 +0000] Report: stringpool-total-bytes=10304
D [27/Jan/2013:19:07:47 +0000] cupsdAcceptClient: 15 from localhost (Domain)
D [27/Jan/2013:19:07:47 +0000] cupsdReadClient: 15 POST / HTTP/1.1
D [27/Jan/2013:19:07:47 +0000] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"
D [27/Jan/2013:19:07:47 +0000] cupsdAuthorize: No authentication data provided.
D [27/Jan/2013:19:07:47 +0000] cupsdReadClient: 15 1.1 CUPS-Get-Printers 1
D [27/Jan/2013:19:07:47 +0000] CUPS-Get-Printers
D [27/Jan/2013:19:07:47 +0000] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost
D [27/Jan/2013:19:07:47 +0000] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"
I [27/Jan/2013:19:07:48 +0000] Saving printers.conf...
I [27/Jan/2013:19:07:48 +0000] Saving job.cache...
D [27/Jan/2013:19:07:48 +0000] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"
D [27/Jan/2013:19:08:54 +0000] Report: clients=1
D [27/Jan/2013:19:08:54 +0000] Report: jobs=2
D [27/Jan/2013:19:08:54 +0000] Report: jobs-active=1
D [27/Jan/2013:19:08:54 +0000] Report: printers=1
D [27/Jan/2013:19:08:54 +0000] Report: printers-implicit=0
D [27/Jan/2013:19:08:54 +0000] Report: stringpool-string-count=540
D [27/Jan/2013:19:08:54 +0000] Report: stringpool-alloc-bytes=8616
D [27/Jan/2013:19:08:54 +0000] Report: stringpool-total-bytes=10304
D [27/Jan/2013:19:10:00 +0000] Report: clients=1
D [27/Jan/2013:19:10:00 +0000] Report: jobs=2
D [27/Jan/2013:19:10:00 +0000] Report: jobs-active=1
D [27/Jan/2013:19:10:00 +0000] Report: printers=1
D [27/Jan/2013:19:10:00 +0000] Report: printers-implicit=0
D [27/Jan/2013:19:10:00 +0000] Report: stringpool-string-count=540
D [27/Jan/2013:19:10:00 +0000] Report: stringpool-alloc-bytes=8616
D [27/Jan/2013:19:10:00 +0000] Report: stringpool-total-bytes=10304
D [27/Jan/2013:19:11:06 +0000] Report: clients=1
D [27/Jan/2013:19:11:06 +0000] Report: jobs=2
D [27/Jan/2013:19:11:06 +0000] Report: jobs-active=1
D [27/Jan/2013:19:11:06 +0000] Report: printers=1
D [27/Jan/2013:19:11:06 +0000] Report: printers-implicit=0
D [27/Jan/2013:19:11:06 +0000] Report: stringpool-string-count=540
D [27/Jan/2013:19:11:06 +0000] Report: stringpool-alloc-bytes=8616
D [27/Jan/2013:19:11:06 +0000] Report: stringpool-total-bytes=10304