Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 882759 Details for
Bug 1084494
driver for QL-570
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
troubleshooting information
troubleshoot.txt (text/plain), 208.90 KB, created by
David Woodhouse
on 2014-04-04 14:50:02 UTC
(
hide
)
Description:
troubleshooting information
Filename:
MIME Type:
Creator:
David Woodhouse
Created:
2014-04-04 14:50:02 UTC
Size:
208.90 KB
patch
obsolete
>Page 1 (Scheduler not running?): >{'cups_connection_failure': False} >Page 2 (Is local server publishing?): >{'local_server_exporting_printers': False} >Page 3 (Choose printer): >{'cups_dest': <cups.Dest Brother_QL-570>, > 'cups_instance': None, > 'cups_queue': u'Brother_QL-570', > 'cups_queue_listed': True} >Page 4 (Check printer sanity): >{'cups_device_uri_scheme': u'usb', > 'cups_printer_dict': {'device-uri': u'usb://Brother/QL-570?serial=E3Z445441', > 'printer-info': u'Brother QL-570', > 'printer-is-shared': False, > 'printer-location': u'', > 'printer-make-and-model': u'Brother QL-550 Foomatic/ptouch (recommended)', > 'printer-state': 3, > 'printer-state-message': u'Rendering completed', > 'printer-state-reasons': [u'none'], > 'printer-type': 10522628, > 'printer-uri-supported': u'ipp://localhost:631/printers/Brother_QL-570'}, > 'cups_printer_remote': False, > 'is_cups_class': False, > 'local_cups_queue_attributes': {'charset-configured': u'utf-8', > 'charset-supported': [u'us-ascii', u'utf-8'], > 'color-supported': False, > 'compression-supported': [u'none', u'gzip'], > 'copies-default': 1, > 'copies-supported': (1, 9999), > 'cups-version': u'1.7.1', > 'device-uri': u'usb://Brother/QL-570?serial=E3Z445441', > 'document-format-default': u'application/octet-stream', > 'document-format-supported': [u'application/octet-stream', > u'application/pdf', > u'application/postscript', > u'application/vnd.adobe-reader-postscript', > u'application/vnd.cups-command', > u'application/vnd.cups-pdf', > u'application/vnd.cups-pdf-banner', > u'application/vnd.cups-postscript', > u'application/vnd.cups-raw', > u'application/x-cshell', > u'application/x-csource', > u'application/x-perl', > u'application/x-shell', > u'image/gif', > u'image/jpeg', > u'image/png', > u'image/tiff', > u'image/x-bitmap', > u'image/x-photocd', > u'image/x-portable-anymap', > u'image/x-portable-bitmap', > u'image/x-portable-graymap', > u'image/x-portable-pixmap', > u'image/x-sgi-rgb', > u'image/x-sun-raster', > u'image/x-xbitmap', > u'image/x-xpixmap', > u'image/x-xwindowdump', > u'text/css', > u'text/html', > u'text/plain'], > 'finishings-default': 3, > 'finishings-supported': [3], > 'generated-natural-language-supported': [u'en-us'], > 'ipp-versions-supported': [u'1.0', > u'1.1', > u'2.0', > u'2.1'], > 'ippget-event-life': 15, > 'job-creation-attributes-supported': [u'copies', > u'finishings', > u'ipp-attribute-fidelity', > u'job-hold-until', > u'job-name', > u'job-priority', > u'job-sheets', > u'media', > u'media-col', > u'multiple-document-handling', > u'number-up', > u'output-bin', > u'orientation-requested', > u'page-ranges', > u'print-color-mode', > u'print-quality', > u'printer-resolution', > u'sides'], > 'job-hold-until-default': u'no-hold', > 'job-hold-until-supported': [u'no-hold', > u'indefinite', > u'day-time', > u'evening', > u'night', > u'second-shift', > u'third-shift', > u'weekend'], > 'job-ids-supported': True, > 'job-k-limit': 0, > 'job-k-octets-supported': (0, 358365964), > 'job-page-limit': 0, > 'job-priority-default': 50, > 'job-priority-supported': [100], > 'job-quota-period': 0, > 'job-settable-attributes-supported': [u'copies', > u'finishings', > u'job-hold-until', > u'job-name', > u'job-priority', > u'media', > u'media-col', > u'multiple-document-handling', > u'number-up', > u'output-bin', > u'orientation-requested', > u'page-ranges', > u'print-color-mode', > u'print-quality', > u'printer-resolution', > u'sides'], > 'job-sheets-default': (u'none', u'none'), > 'job-sheets-supported': [u'none', > u'classified', > u'confidential', > u'secret', > u'standard', > u'topsecret', > u'unclassified'], > 'jpeg-k-octets-supported': (0, 358365964), > 'jpeg-x-dimension-supported': (0, 65535), > 'jpeg-y-dimension-supported': (1, 65535), > 'marker-change-time': 0, > 'media-bottom-margin-supported': [1270], > 'media-col-supported': [u'media-bottom-margin', > u'media-left-margin', > u'media-right-margin', > u'media-size', > u'media-source', > u'media-top-margin', > u'media-type'], > 'media-default': u'om_floppy-tape_62.09x50.09mm', > 'media-left-margin-supported': [635], > 'media-right-margin-supported': [635], > 'media-supported': [u'om_binder3cm-spine-tape_28.93x208.84mm', > u'om_binder5cm-spine-tape_62.09x208.84mm', > u'om_cd_57.86x57.86mm', > u'om_compact-flash-tape_11.99x35.98mm', > u'om_file-folder-label_16.93x87.14mm', > u'om_floppy-tape_62.09x50.09mm', > u'om_large-address-label_38.1x89.96mm', > u'om_memory-stick-tape_11.99x33.87mm', > u'om_mo-face-tape_62.09x45.16mm', > u'om_return-address-label_16.93x53.98mm', > u'om_shipping-label_62.09x99.84mm', > u'om_small-address-label_62.09x28.93mm', > u'om_smart-media-tape_11.99x26.11mm', > u'om_standard-address-label_28.93x89.96mm', > u'om_stock-tape_56.8x50.09mm', > u'custom_min_0.5x0.5in', > u'custom_max_35277.78x35277.78mm'], > 'media-top-margin-supported': [1270], > 'multiple-document-handling-supported': [u'separate-documents-uncollated-copies', > u'separate-documents-collated-copies'], > 'multiple-document-jobs-supported': True, > 'multiple-operation-time-out': 300, > 'natural-language-configured': u'en-us', > 'notify-attributes-supported': [u'printer-state-change-time', > u'notify-lease-expiration-time', > u'notify-subscriber-user-name'], > 'notify-events-default': [u'job-completed'], > 'notify-events-supported': [u'job-completed', > u'job-config-changed', > u'job-created', > u'job-progress', > u'job-state-changed', > u'job-stopped', > u'printer-added', > u'printer-changed', > u'printer-config-changed', > u'printer-deleted', > u'printer-finishings-changed', > u'printer-media-changed', > u'printer-modified', > u'printer-restarted', > u'printer-shutdown', > u'printer-state-changed', > u'printer-stopped', > u'server-audit', > u'server-restarted', > u'server-started', > u'server-stopped'], > 'notify-lease-duration-default': 86400, > 'notify-lease-duration-supported': (0, > 2147483647), > 'notify-max-events-supported': [100], > 'notify-pull-method-supported': [u'ippget'], > 'notify-schemes-supported': [u'dbus', > u'mailto', > u'rss'], > 'number-up-default': 1, > 'number-up-supported': [1, 2, 4, 6, 9, 16], > 'operations-supported': [2, > 4, > 5, > 6, > 8, > 9, > 10, > 11, > 12, > 13, > 14, > 16, > 17, > 18, > 19, > 20, > 21, > 22, > 23, > 24, > 25, > 26, > 27, > 28, > 34, > 35, > 37, > 38, > 56, > 57, > 59, > 16385, > 16386, > 16387, > 16388, > 16389, > 16390, > 16391, > 16392, > 16393, > 16394, > 16395, > 16396, > 16397, > 16398, > 16399, > 16423, > 14], > 'orientation-requested-default': None, > 'orientation-requested-supported': [3, > 4, > 5, > 6], > 'output-bin-default': u'face-down', > 'output-bin-supported': [u'face-down'], > 'page-ranges-supported': True, > 'pages-per-minute': 1, > 'pdf-k-octets-supported': (0, 358365964), > 'pdf-versions-supported': [u'adobe-1.2', > u'adobe-1.3', > u'adobe-1.4', > u'adobe-1.5', > u'adobe-1.6', > u'adobe-1.7', > u'iso-19005-1_2005', > u'iso-32000-1_2008', > u'pwg-5102.3'], > 'pdl-override-supported': [u'attempted'], > 'port-monitor': u'none', > 'port-monitor-supported': [u'none'], > 'print-color-mode-default': u'monochrome', > 'print-color-mode-supported': [u'monochrome'], > 'print-quality-default': 4, > 'print-quality-supported': [4], > 'printer-commands': [u'AutoConfigure', > u'Clean', > u'PrintSelfTestPage'], > 'printer-current-time': '(IPP_TAG_DATE)', > 'printer-dns-sd-name': None, > 'printer-error-policy': u'stop-printer', > 'printer-error-policy-supported': [u'abort-job', > u'retry-current-job', > u'retry-job', > u'stop-printer'], > 'printer-icons': u'http://localhost:631/icons/Brother_QL-570.png', > 'printer-info': u'Brother QL-570', > 'printer-is-accepting-jobs': True, > 'printer-is-shared': False, > 'printer-location': u'', > 'printer-make-and-model': u'Brother QL-550 Foomatic/ptouch (recommended)', > 'printer-more-info': u'http://localhost:631/printers/Brother_QL-570', > 'printer-name': u'Brother_QL-570', > 'printer-op-policy': u'default', > 'printer-op-policy-supported': [u'authenticated', > u'default'], > 'printer-resolution-default': (300, 300, 3), > 'printer-resolution-supported': [(300, > 300, > 3)], > 'printer-settable-attributes-supported': [u'printer-info', > u'printer-location'], > 'printer-state': 3, > 'printer-state-change-time': 1396622357, > 'printer-state-message': u'Rendering completed', > 'printer-state-reasons': [u'none'], > 'printer-type': 10522628, > 'printer-up-time': 1396622510, > 'printer-uri-supported': [u'ipp://localhost:631/printers/Brother_QL-570'], > 'printer-uuid': u'urn:uuid:624fb83d-435e-340d-65c8-09d46fee181e', > 'queued-job-count': 0, > 'server-is-sharing-printers': False, > 'sides-default': u'one-sided', > 'sides-supported': [u'one-sided'], > 'uri-authentication-supported': [u'requesting-user-name'], > 'uri-security-supported': [u'none'], > 'which-jobs-supported': [u'completed', > u'not-completed', > u'aborted', > u'all', > u'canceled', > u'pending', > u'pending-held', > u'processing', > u'processing-stopped']}} >Page 5 (Check PPD sanity): >{'cups_printer_ppd_defaults': {u'Finishing': {u'AdvanceDistance': u'0None', > u'AdvanceMedia': u'Off', > u'CutMedia': u'Off'}, > u'General': {u'ConcatPages': u'False', > u'MirrorPrint': u'False', > u'NegativePrint': u'False', > u'PageRegion': u'FloppyTape', > u'PageSize': u'FloppyTape', > u'PrintDensity': u'5VeryDark', > u'PrintQuality': u'Fast', > u'RollFedMedia': u'Roll'}, > u'PrinterSpecifics': {u'Align': u'Right', > u'BytesPerLine': u'90', > u'LabelPreamble': u'True', > u'SoftwareMirror': u'True'}}, > 'cups_printer_ppd_valid': True, > 'missing_pkgs_and_exes': ([], [])} >Page 6 (Local or remote?): >{'printer_is_remote': False} >Page 7 (Choose device): >{'cups_device_dict': {'device-class': u'direct', > 'device-id': u'MFG:Brother;CMD:PT-CBP;MDL:QL-570;CLS:PRINTER;', > 'device-info': u'Brother QL-570', > 'device-location': u'', > 'device-make-and-model': u'Brother QL-570'}} >Page 8 (Printer state reasons): >{'printer-state-message': u'Rendering completed', > 'printer-state-reasons': [u'none']} >Page 9 (Verify packages): >{'packages_verification': {'cups': ['.M....... c /etc/cups/subscriptions.conf'], > 'foomatic': [], > 'gutenprint': [], > 'hpijs': [], > 'hplip': [], > 'system-config-printer': []}} >Page 10 (Error log checkpoint): >{'cups_server_settings': {'BrowseLocalProtocols': 'dnssd', > 'DefaultAuthType': 'Basic', > 'JobPrivateAccess': 'default', > 'JobPrivateValues': 'default', > 'MaxLogSize': '0', > 'SubscriptionPrivateAccess': 'default', > 'SubscriptionPrivateValues': 'default', > 'WebInterface': 'Yes', > '_debug_logging': '0', > '_remote_admin': '0', > '_remote_any': '0', > '_share_printers': '0', > '_user_cancel_any': '0'}, > 'error_log_checkpoint': 15932, > 'error_log_debug_logging_set': True} >Page 11 (Print test page): >{'test_page_job_status': [(True, > 253, > 'Brother_QL-570', > 'labels', > 'Completed', > {'Align': u'Right', > 'BytesPerLine': u'90', > 'ConcatPages': True, > 'CutMedia': u'JobEnd', > 'LabelPreamble': True, > 'PageSize': u'FloppyTape', > 'PrintDensity': u'3Medium', > 'RollFedMedia': u'Roll', > 'attributes-charset': u'utf-8', > 'attributes-natural-language': u'en-gb', > 'document-format': u'application/pdf', > 'job-hold-until': u'no-hold', > 'job-id': 253, > 'job-k-octets': 25, > 'job-media-progress': 0, > 'job-media-sheets-completed': 4, > 'job-more-info': u'http://localhost:631/jobs/253', > 'job-preserved': True, > 'job-printer-state-message': u'', > 'job-printer-state-reasons': [u'none'], > 'job-printer-up-time': 1396622609, > 'job-printer-uri': u'ipp://localhost:631/printers/Brother_QL-570', > 'job-priority': 50, > 'job-sheets': [u'none', u'none'], > 'job-state': 9, > 'job-state-reasons': u'processing-to-stop-point', > 'job-uri': u'ipp://localhost:631/jobs/253', > 'job-uuid': u'urn:uuid:8102ca8b-df8a-3e5d-6b95-d4525f1b6880', > 'number-of-documents': 1, > 'printer-uri': u'ipp://localhost:631/printers/Brother_QL-570', > 'time-at-completed': 1396622608, > 'time-at-creation': 1396622605, > 'time-at-processing': 1396622605})], > 'test_page_successful': True} >Page 12 (Error log fetch): >{'error_log': ['D [04/Apr/2014:15:43:09 +0100] [Client 14] Sending file.', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=15932, response=(nil)(), pipe_pid=0, file=16', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=13884, response=(nil)(), pipe_pid=0, file=16', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=11836, response=(nil)(), pipe_pid=0, file=16', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=9788, response=(nil)(), pipe_pid=0, file=16', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7740, response=(nil)(), pipe_pid=0, file=16', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=5692, response=(nil)(), pipe_pid=0, file=16', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3644, response=(nil)(), pipe_pid=0, file=16', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1596, response=(nil)(), pipe_pid=0, file=16', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:09 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:09 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] No authentication data provided.', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] 1.1 Get-Jobs 22', > 'D [04/Apr/2014:15:43:09 +0100] Get-Jobs ipp://localhost/printers/', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] Content-Length: 75', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f522ddb3440(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:09 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:09 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] No authentication data provided.', > 'D [04/Apr/2014:15:43:09 +0100] [Client 14] 1.1 Get-Jobs 23', > 'D [04/Apr/2014:15:43:09 +0100] Get-Jobs ipp://localhost/printers/', > 'D [04/Apr/2014:15:43:09 +0100] [Job 66] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 66] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 67] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 67] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 68] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 68] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 69] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 69] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 70] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 70] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 71] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 71] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 72] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 72] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 73] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 73] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 74] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 74] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 75] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 75] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 76] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 76] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 77] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 77] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 78] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 78] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 79] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 79] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 80] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 80] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 81] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 81] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 82] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 82] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 83] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 83] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 84] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 84] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 85] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 85] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 86] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 86] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 87] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 87] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 88] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 88] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 89] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 89] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 90] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 90] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 91] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 91] Adding missing job-state-reasons attribute to control file.', > 'D [04/Apr/2014:15:43:09 +0100] [Job 92] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 93] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 94] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 95] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 96] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 97] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 98] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 99] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 100] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 101] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 102] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 103] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 104] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 105] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 106] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 107] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 108] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 109] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 110] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 111] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 123] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 124] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 125] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 126] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 127] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 128] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 129] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 130] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 131] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 132] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 133] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 134] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 135] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 137] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 138] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 139] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 140] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 141] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 142] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 143] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 144] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 145] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 146] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 147] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 148] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 149] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 150] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 151] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 152] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 153] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 154] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 155] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 156] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 157] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 158] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 159] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 160] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 161] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 162] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 163] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 164] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 165] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 166] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 167] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 168] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 169] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 170] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 171] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 172] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 173] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 174] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 175] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 176] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 177] Loading attributes...', > 'D [04/Apr/2014:15:43:09 +0100] [Job 178] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 179] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 180] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 181] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 182] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 183] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 184] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 185] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 186] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 187] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 188] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 189] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 190] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 191] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 192] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 193] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 194] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 195] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 196] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 197] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 198] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 199] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 200] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 201] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 202] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 203] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 204] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 205] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 206] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 207] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 208] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 209] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 210] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 211] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 212] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 213] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 214] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 215] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 216] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 217] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 218] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 219] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 220] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 221] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 222] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 223] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 224] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 225] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 226] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 227] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 228] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 229] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 230] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 231] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 232] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 233] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 234] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 235] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 236] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 237] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 238] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 239] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 240] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 241] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 242] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 243] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 244] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 245] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 246] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 247] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 248] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 249] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 250] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 251] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Job 252] Loading attributes...', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] Content-Length: 16902', > 'D [04/Apr/2014:15:43:10 +0100] [Job 66] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 67] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 68] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 69] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 70] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 71] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 72] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 73] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 74] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 75] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 76] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 77] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 78] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 79] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 80] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 81] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 82] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 83] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 84] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 85] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 86] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 87] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 88] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 89] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 90] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 91] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 92] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 93] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 94] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 95] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 96] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 97] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 98] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 99] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 100] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 101] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 102] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 103] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 104] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 105] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 106] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 107] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 108] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 109] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 110] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 111] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 123] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 124] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 125] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 126] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 127] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 128] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 129] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 130] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 131] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 132] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 133] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 134] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 135] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 137] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 138] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 139] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 140] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 141] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 142] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 143] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 144] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 145] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 146] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 147] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 148] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 149] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 150] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 151] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 152] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 153] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 154] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 155] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 156] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 157] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 158] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 159] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 160] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 161] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 162] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 163] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 164] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 165] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 166] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 167] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 168] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 169] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 170] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 171] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 172] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 173] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 174] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 175] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 176] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 177] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 178] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 179] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 180] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 181] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 182] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 183] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 184] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 185] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 186] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 187] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 188] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 189] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 190] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 191] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 192] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 193] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 194] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 195] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 196] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 197] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 198] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 199] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 200] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 201] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 202] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 203] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 204] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 205] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 206] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 207] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Job 208] Removing document files.', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=16902, response=0x7f522db3af50(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:10 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:10 +0100] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] No authentication data provided.', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] 1.1 Create-Printer-Subscription 24', > 'D [04/Apr/2014:15:43:10 +0100] Create-Printer-Subscription /', > 'D [04/Apr/2014:15:43:10 +0100] cupsdCreateSubscription(con=0x7f522df32760(14), uri="/")', > 'D [04/Apr/2014:15:43:10 +0100] pullmethod="ippget"', > 'D [04/Apr/2014:15:43:10 +0100] notify-lease-duration=86400', > 'D [04/Apr/2014:15:43:10 +0100] notify-time-interval=0', > 'D [04/Apr/2014:15:43:10 +0100] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")', > 'D [04/Apr/2014:15:43:10 +0100] Added subscription #111 for server.', > 'D [04/Apr/2014:15:43:10 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:10 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] Content-Length: 107', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x7f522ddcf160(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:10 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:10 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:11 +0100] [Client 14] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:11 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:11 +0100] [Client 14] No authentication data provided.', > 'D [04/Apr/2014:15:43:11 +0100] [Client 14] 1.1 Get-Notifications 25', > 'D [04/Apr/2014:15:43:11 +0100] Get-Notifications /', > 'D [04/Apr/2014:15:43:11 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [04/Apr/2014:15:43:11 +0100] [Client 14] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [04/Apr/2014:15:43:11 +0100] [Client 14] Content-Length: 127', > 'D [04/Apr/2014:15:43:11 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f522db3af50(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:11 +0100] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:11 +0100] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:11 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:11 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:20 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] Waiting for request.', > 'D [04/Apr/2014:15:43:20 +0100] Report: clients=3', > 'D [04/Apr/2014:15:43:20 +0100] Report: jobs=175', > 'D [04/Apr/2014:15:43:20 +0100] Report: jobs-active=0', > 'D [04/Apr/2014:15:43:20 +0100] Report: printers=3', > 'D [04/Apr/2014:15:43:20 +0100] Report: stringpool-string-count=70552', > 'D [04/Apr/2014:15:43:20 +0100] Report: stringpool-alloc-bytes=31768', > 'D [04/Apr/2014:15:43:20 +0100] Report: stringpool-total-bytes=1269208', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:20 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] No authentication data provided.', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] 1.1 Get-Notifications 26', > 'D [04/Apr/2014:15:43:20 +0100] Get-Notifications /', > 'D [04/Apr/2014:15:43:20 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] Content-Length: 127', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f522df37050(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] Waiting for request.', > 'D [04/Apr/2014:15:43:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [04/Apr/2014:15:43:20 +0100] [Client 16] Closing connection.', > 'D [04/Apr/2014:15:43:20 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:21 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:21 +0100] [Client 16] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:21 +0100] [Client 16] Waiting for request.', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:21 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] No authentication data provided.', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] 1.1 CUPS-Get-Printers 5', > 'D [04/Apr/2014:15:43:21 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] Content-Length: 3944', > 'D [04/Apr/2014:15:43:21 +0100] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [04/Apr/2014:15:43:21 +0100] [Client 16] Closing connection.', > 'D [04/Apr/2014:15:43:21 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522ddca420(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] Waiting for request.', > 'D [04/Apr/2014:15:43:21 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:21 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] No authentication data provided.', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] 1.1 CUPS-Get-Default 6', > 'D [04/Apr/2014:15:43:21 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] Content-Length: 7766', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522dde85b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:21 +0100] [Client 15] Waiting for request.', > 'D [04/Apr/2014:15:43:21 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] POST /printers/Brother_QL-570 HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] 1.1 Create-Job 7', > 'D [04/Apr/2014:15:43:25 +0100] Create-Job ipp://localhost:631/printers/Brother_QL-570', > 'D [04/Apr/2014:15:43:25 +0100] add_job: setting context of job to UNKNOWN SL', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] add_job: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] Adding default job-sheets values "none,none"...', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Adding start banner page "none".', > 'D [04/Apr/2014:15:43:25 +0100] Notifier dbus started - PID = 31990', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Queued on "Brother_QL-570" by "dwoodhou".', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] Returning IPP successful-ok for Create-Job (ipp://localhost:631/printers/Brother_QL-570) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] Content-Length: 183', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=183, response=0x7f522ddca420(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] POST /printers/Brother_QL-570 HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] 1.1 Send-Document 8', > 'D [04/Apr/2014:15:43:25 +0100] Send-Document ipp://localhost:631/printers/Brother_QL-570', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Auto-typing file...', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Request file type is application/pdf.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] File of type application/pdf queued by "dwoodhou".', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Adding end banner page "none".', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] time-at-processing=1396622605', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] 2 filters for job:', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] foomatic-rip (application/vnd.cups-pdf to printer/Brother_QL-570, cost 0)', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] job-sheets=none,none', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] argv[0]="Brother_QL-570"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] argv[1]="253"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] argv[2]="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] argv[3]="labels"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] argv[4]="1"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] argv[5]="Align=Right BytesPerLine=90 ConcatPages CutMedia=JobEnd LabelPreamble PageSize=FloppyTape PrintDensity=3Medium RollFedMedia=Roll job-uuid=urn:uuid:8102ca8b-df8a-3e5d-6b95-d4525f1b6880 job-originating-host-name=localhost time-at-creation=1396622605 time-at-processing=1396622605"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] argv[6]="/var/spool/cups/d00253-001"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[1]="CUPS_DATADIR=/usr/share/cups"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[6]="CUPS_SERVERROOT=/etc/cups"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[7]="CUPS_STATEDIR=/var/run/cups"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[8]="HOME=/var/spool/cups/tmp"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[10]="SERVER_ADMIN=root@i7.infradead.org"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[11]="SOFTWARE=CUPS/1.7.1"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[12]="TMPDIR=/var/spool/cups/tmp"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[13]="USER=root"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[14]="CUPS_MAX_MESSAGE=2047"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[16]="CUPS_ENCRYPTION=IfRequested"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[17]="IPP_PORT=631"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[18]="CHARSET=utf-8"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[19]="LANG=en_GB.UTF-8"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[20]="PPD=/etc/cups/ppd/Brother_QL-570.ppd"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[21]="RIP_MAX_CACHE=128m"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[22]="CONTENT_TYPE=application/pdf"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[23]="DEVICE_URI=usb://Brother/QL-570?serial=E3Z445441"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[24]="PRINTER_INFO=Brother QL-570"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[25]="PRINTER_LOCATION="', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[26]="PRINTER=Brother_QL-570"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[27]="PRINTER_STATE_REASONS=none"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[28]="CUPS_FILETYPE=document"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[29]="FINAL_CONTENT_TYPE=printer/Brother_QL-570"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] envp[30]="AUTH_I****"', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Started filter /usr/lib/cups/filter/pdftopdf (PID 31991)', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Started filter /usr/lib/cups/filter/foomatic-rip (PID 31992)', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Started backend /usr/lib/cups/backend/usb (PID 31993)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] Returning IPP successful-ok for Send-Document (ipp://localhost:631/printers/Brother_QL-570) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] Content-Length: 175', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=175, response=0x7f522dde9900(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 15] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Getting input from file', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] foomatic-rip version 4.0.17.256 running...', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Parsing PPD file ...', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option ColorSpace', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option PageSize', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option ImageableArea', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option PaperDimension', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option Resolution', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option PrintQuality', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option ConcatPages', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option MirrorPrint', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option NegativePrint', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option PrintDensity', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option RollFedMedia', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option AdvanceDistance', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option AdvanceMedia', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option CutMedia', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option Align', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option BytesPerLine', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option PixelTransfer', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option LabelPreamble', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option SoftwareMirror', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Added option Font', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Parameter Summary', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] -----------------', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Spooler: cups', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Printer: Brother_QL-570', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Shell: /bin/bash', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PPD file: /etc/cups/ppd/Brother_QL-570.ppd', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] ATTR file:', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Printer model: Brother QL-550 Foomatic/ptouch (recommended)', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Job title: labels', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] File(s) to be printed:', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] <STDIN>', > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Ghostscript extra search path ('GS_LIB'): /usr/share/cups/fonts", > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Printing system options:', > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'job-uuid=urn:uuid:8102ca8b-df8a-3e5d-6b95-d4525f1b6880'", > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Unknown option job-uuid=urn:uuid:8102ca8b-df8a-3e5d-6b95-d4525f1b6880.', > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'job-originating-host-name=localhost'", > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Unknown option job-originating-host-name=localhost.', > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'time-at-creation=1396622605'", > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Unknown option time-at-creation=1396622605.', > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'time-at-processing=1396622605'", > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Unknown option time-at-processing=1396622605.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Options from the PPD file:', > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'Align=Right'", > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'BytesPerLine=90'", > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'ConcatPages'", > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'CutMedia=JobEnd'", > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'LabelPreamble'", > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'PageSize=FloppyTape'", > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'PrintDensity=3Medium'", > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Pondering option 'RollFedMedia=Roll'", > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] ================================================', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] File: <STDIN>', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] ================================================', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Loading USB quirks from "/usr/share/cups/usb".', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Loaded 68 quirks.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Printing on printer with URI: usb://Brother/QL-570?serial=E3Z445441', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] Connected to D-BUS', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] JobCreated', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] libusb_get_device_list=12', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] STATE: +connecting-to-device', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] JobState', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2702', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 2084', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2084, response=0x7f522ddda200(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] 1.1 Get-Job-Attributes 2703', > 'D [04/Apr/2014:15:43:25 +0100] Get-Job-Attributes ipp://localhost/jobs/253', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/253) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] Content-Length: 114', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=114, response=0x7f522ddce3a0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 21] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] 1.1 Get-Job-Attributes 2704', > 'D [04/Apr/2014:15:43:25 +0100] Get-Job-Attributes ipp://localhost/jobs/253', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/253) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] Content-Length: 114', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=114, response=0x7f522ddcc750(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 22] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] STATE: -connecting-to-device', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Device protocol: 2', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Sending data to printer.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Sending data to printer.", current level=INFO', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Read 64 bytes of back-channel data...', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Filetype: PDF', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PostScript option found: PageSize=Binder3cmSpineTape: "<</PageSize[82 592]/ImagingBBox null/MediaType (roll)>>setpagedevice"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Driver does not understand PDF input, converting to PostScript', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Storing temporary files in /var/spool/cups/tmp', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PID 31991 (/usr/lib/cups/filter/pdftopdf) exited with no errors.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Starting process "pdf-to-ps" (generation 1)', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Printers 2705', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 3944', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522ddc3b10(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Default 2706', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522dda7690(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2707', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 989', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=989, response=0x7f522ddf6ce0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Printers 2708', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 3944', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522dda7690(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Default 2709', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522dd9ec90(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Printers 2710', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 3944', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522dd9ae50(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Default 2711', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522dd9b710(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2712', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 127', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f522dd98190(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2713', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 127', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f522dd9b710(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2714', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 127', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f522dd98190(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2715', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 127', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f522dd9b710(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Switching to Poppler's pdftops instead of Ghostscript for Brother, Minolta, and Konica Minolta to work around bugs in the printer's PS interpreters", > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Using image rendering resolution 300 dpi', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Started filter pdftops (PID 32024)', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Started filter pstops (PID 32025)', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Page = 176x142; 18,36 to 158,106', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] slow_collate=0, slow_duplex=0, slow_order=0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Before copy_comments - %!PS-Adobe-3.0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] %!PS-Adobe-3.0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] %Produced by poppler pdftops version: 0.24.3 (http://poppler.freedesktop.org)', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] %%Creator: LibreOffice 4.2.2.1', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] %%LanguageLevel: 3', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] %%DocumentSuppliedResources: (atend)', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] %%DocumentMedia: 176x142 176 142 0 () ()', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] %%BoundingBox: 0 0 176 142', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] %%Pages: 2', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] %%EndComments', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Before copy_prolog - %%BeginProlog', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Filetype: PostScript', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Reading PostScript input ...', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] --> This document is DSC-conforming!', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Before copy_setup - %%BeginSetup', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found %RBINumCopies: 1', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] -----------', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginProlog', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Inserting option code into "Prolog" section.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%EndProlog', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] -----------', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginSetup', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *Align Right', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: Align=Right', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %% FoomaticRIPOptionSetting: Align=Right', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: Align=Right', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *BytesPerLine 90', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: BytesPerLine=90', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %% FoomaticRIPOptionSetting: BytesPerLine=90', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: BytesPerLine=90', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *PageSize FloppyTape', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: PageSize=FloppyTape', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] --> Option will be set by PostScript interpreter', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *AdvanceMedia Off', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: AdvanceMedia=Off', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] --> Option will be set by PostScript interpreter', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *ConcatPages True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: ConcatPages=True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %% FoomaticRIPOptionSetting: ConcatPages=True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: ConcatPages=True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *CutMedia JobEnd', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: CutMedia=JobEnd', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] --> Option will be set by PostScript interpreter', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *LabelPreamble True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: LabelPreamble=True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %% FoomaticRIPOptionSetting: LabelPreamble=True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: LabelPreamble=True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *MirrorPrint False', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: MirrorPrint=False', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] --> Option will be set by PostScript interpreter', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *NegativePrint False', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: NegativePrint=False', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] --> Option will be set by PostScript interpreter', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *AdvanceDistance 0None', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: AdvanceDistance=0None', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] --> Option will be set by PostScript interpreter', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *PrintDensity 3Medium', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: PrintDensity=3Medium', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %% FoomaticRIPOptionSetting: PrintDensity=3Medium', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: PrintDensity=3Medium', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *PrintQuality Fast', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: PrintQuality=Fast', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %% FoomaticRIPOptionSetting: PrintQuality=Fast', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: PrintQuality=Fast', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *RollFedMedia Roll', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: RollFedMedia=Roll', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] --> Option will be set by PostScript interpreter', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginFeature: *SoftwareMirror True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: SoftwareMirror=True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %% FoomaticRIPOptionSetting: SoftwareMirror=True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Option: SoftwareMirror=True', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Setting option', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Before page loop - %%Page: 1 1', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Copying page 1...', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] pagew = 140.0, pagel = 70.0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] bboxx = 0, bboxy = 0, bboxw = 176, bboxl = 142', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PageLeft = 18.0, PageRight = 158.0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PageTop = 106.0, PageBottom = 36.0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PageWidth = 176.0, PageLength = 142.0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%EndSetup', > "D [04/Apr/2014:15:43:25 +0100] [Job 253] Inserting PostScript code for CUPS' page accounting", > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Inserting option code into "Setup" section.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] -----------', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] New page: %%Page: 1 1', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginPageSetup', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Inserting option code into "PageSetup" section.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Copying page 2...', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] pagew = 140.0, pagel = 70.0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] bboxx = 0, bboxy = 0, bboxw = 176, bboxl = 142', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PageLeft = 18.0, PageRight = 158.0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PageTop = 106.0, PageBottom = 36.0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PageWidth = 176.0, PageLength = 142.0', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Wrote 2 pages...', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] New page found but previous not printed, print it now.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%Page: 2 2', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] --> Output goes directly to the renderer now.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PID 32024 (pdftops) exited with no errors.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PID 32025 (pstops) exited with no errors.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Starting renderer with command: "gs -sstdout=%stderr -dBATCH -dPARANOIDSAFER -dQUIET -dNOPAUSE -sDEVICE=cups -r300x300 -sOutputFile=%stdout -_ | rastertoptch dummyjob dummyuser dummytitle 1 \' PixelXfer=ULP BytesPerLine=90 Align=Right SoftwareMirror LabelPreamble PrintDensity=3 ConcatPages PrintQuality=Fast \'"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Starting process "kid3" (generation 1)', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Starting process "kid4" (generation 2)', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Starting process "renderer" (generation 2)', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] JCL: \x1b%-12345X@PJL', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] <job data>', > 'D [04/Apr/2014:15:43:25 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] 1.1 Get-Notifications 27', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Content-Length: 1881', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1881, response=0x7f522ddc4cb0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] HTTP_STATE_WAITING Closing on EOF', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Closing connection.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 14] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 14] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 14] 1.1 Get-Notifications 28', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 14] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 14] Content-Length: 1726', > 'D [04/Apr/2014:15:43:25 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1726, response=0x7f522ddc4cb0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Start rendering...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Start rendering...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Processing page 1...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Processing page 1...", current level=INFO', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2716', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 558', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=558, response=0x7f522ddb1660(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Printers 2717', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 3944', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522ddb1f20(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Default 2718', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522dded0b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] -----------', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] New page: %%Page: 2 2', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%Page: 2 2', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] --> Output goes to the FIFO buffer now.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Found: %%BeginPageSetup', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Inserting option code into "PageSetup" section.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Flushing FIFO.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Closing renderer', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PAGE: 1 1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 0% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 0% complete...", current level=INFO', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 10% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 10% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 21% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 21% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 32% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 32% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 43% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 43% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Processing page 2...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Processing page 2...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 54% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 54% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 65% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 65% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 76% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 76% complete...", current level=INFO', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 87% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 87% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 98% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 98% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 1, 100% complete.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 1, 100% complete.", current level=INFO', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2719', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 1420', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1420, response=0x7f522ddaf480(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Printers 2720', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 3944', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522dd989e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Default 2721', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522dda0f50(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Printers 2722', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 3944', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522ddb0760(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Default 2723', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522ddaaa30(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Printers 2724', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 3944', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522ddc1d10(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Default 2725', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522ddafdb0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2726', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 127', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f522df6a690(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PAGE: 2 1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PAGE: 1 1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 0% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 0% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 10% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 10% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 21% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 21% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 32% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 32% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 43% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 43% complete...", current level=INFO', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2727', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 558', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 54% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 54% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 65% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 65% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 76% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 76% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 87% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 87% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Processing page 3...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Processing page 3...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 98% complete...', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 98% complete...", current level=INFO', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Printing page 2, 100% complete.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Printing page 2, 100% complete.", current level=INFO', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=558, response=0x7f522ddb0760(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Printers 2728', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 3944', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522ddae630(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Default 2729', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522df66a10(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2730', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 558', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=558, response=0x7f522ddebdb0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Printers 2731', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 3944', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522df66a10(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Default 2732', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522ddeb430(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2733', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 127', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f522dddfb10(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'I [04/Apr/2014:15:43:25 +0100] [Job 253] Rendering completed', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Set job-printer-state-message to "Rendering completed", current level=INFO', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:25 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 Get-Notifications 2734', > 'D [04/Apr/2014:15:43:25 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:25 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 558', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=558, response=0x7f522ddd00a0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Printers 2735', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 3944', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3944, response=0x7f522dde2470(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] 1.1 CUPS-Get-Default 2736', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522df66a10(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Read 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] PAGE: 2 1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] renderer exited with status 0', > 'D [04/Apr/2014:15:43:25 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Wrote 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:25 +0100] [Job 253] Read 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] 1.1 CUPS-Get-Printers 29', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Content-Length: 1481', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1481, response=0x7f522dddfc30(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] 1.1 CUPS-Get-Classes 30', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Classes', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Content-Length: 75', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f522dd9f560(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] No authentication data provided.', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] 1.1 CUPS-Get-Default 31', > 'D [04/Apr/2014:15:43:25 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Content-Length: 7766', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522dddfc30(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:25 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:25 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] Waiting for request.', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] No authentication data provided.', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] 1.1 Get-Notifications 32', > 'D [04/Apr/2014:15:43:26 +0100] Get-Notifications /', > 'D [04/Apr/2014:15:43:26 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] Content-Length: 3144', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3144, response=0x7f522dda90c0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] Waiting for request.', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] HTTP_STATE_WAITING Closing on EOF', > 'D [04/Apr/2014:15:43:26 +0100] [Client 24] Closing connection.', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 14] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 14] No authentication data provided.', > 'D [04/Apr/2014:15:43:26 +0100] [Client 14] 1.1 Get-Notifications 33', > 'D [04/Apr/2014:15:43:26 +0100] Get-Notifications /', > 'D [04/Apr/2014:15:43:26 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 14] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [04/Apr/2014:15:43:26 +0100] [Client 14] Content-Length: 6005', > 'D [04/Apr/2014:15:43:26 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6005, response=0x7f522dda6ec0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:26 +0100] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:26 +0100] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:26 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] No authentication data provided.', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] 1.1 CUPS-Get-Printers 34', > 'D [04/Apr/2014:15:43:26 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Content-Length: 1481', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1481, response=0x7f522dd98a20(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] No authentication data provided.', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] 1.1 CUPS-Get-Classes 35', > 'D [04/Apr/2014:15:43:26 +0100] CUPS-Get-Classes', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Content-Length: 75', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f522ddaf480(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] No authentication data provided.', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] 1.1 CUPS-Get-Default 36', > 'D [04/Apr/2014:15:43:26 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Content-Length: 7766', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522dd98a20(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:26 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:26 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [04/Apr/2014:15:43:26 +0100] [Job 253] Read 32 bytes of back-channel data...', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] Wrote 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] Read 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] kid4 exited with status 0', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] kid3 finished', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] kid3 exited with status 0', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] pdf-to-ps exited with status 0', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] Closing foomatic-rip.', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] PID 31992 (/usr/lib/cups/filter/foomatic-rip) exited with no errors.', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] Wrote 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] Read 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] Wrote 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:27 +0100] [Job 253] Read 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Wrote 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Read 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Wrote 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Read 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Wrote 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Read 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Wrote 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Read 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Wrote 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Read 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Wrote 8192 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Read 320 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Wrote 320 bytes of print data...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Sent 82240 bytes...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] Waiting for read thread to exit...', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] PID 31993 (/usr/lib/cups/backend/usb) exited with no errors.', > 'D [04/Apr/2014:15:43:28 +0100] [Job 253] time-at-completed=1396622608', > 'D [04/Apr/2014:15:43:28 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [04/Apr/2014:15:43:28 +0100] [Job 253] Job completed.', > 'D [04/Apr/2014:15:43:28 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] cupsdMarkDirty(---J-)', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:28 +0100] [Notifier] JobCompleted', > 'D [04/Apr/2014:15:43:28 +0100] [Notifier] state=3', > 'D [04/Apr/2014:15:43:28 +0100] [Notifier] PrinterStateChanged', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] 1.1 Get-Notifications 2737', > 'D [04/Apr/2014:15:43:28 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:28 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] Content-Length: 1105', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1105, response=0x7f522ddc8d00(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] Waiting for request.', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] No authentication data provided.', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] 1.1 Get-Job-Attributes 2738', > 'D [04/Apr/2014:15:43:28 +0100] Get-Job-Attributes ipp://localhost/jobs/253', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/253) from localhost', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] Content-Length: 114', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=114, response=0x7f522dde6aa0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:28 +0100] [Client 18] Waiting for request.', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] No authentication data provided.', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] 1.1 Get-Notifications 2739', > 'D [04/Apr/2014:15:43:28 +0100] Get-Notifications /printers/', > 'D [04/Apr/2014:15:43:28 +0100] cupsdIsAuthorized: requesting-user-name="dwoodhou"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] Returning IPP successful-ok for Get-Notifications (/printers/) from localhost', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] Content-Length: 127', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f522ddf56e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:28 +0100] [Client 20] Waiting for request.', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] Waiting for request.', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] No authentication data provided.', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] 1.1 Get-Notifications 37', > 'D [04/Apr/2014:15:43:28 +0100] Get-Notifications /', > 'D [04/Apr/2014:15:43:28 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] Content-Length: 566', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=566, response=0x7f522dda4c20(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] Waiting for request.', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] HTTP_STATE_WAITING Closing on EOF', > 'D [04/Apr/2014:15:43:28 +0100] [Client 24] Closing connection.', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 14] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 14] No authentication data provided.', > 'D [04/Apr/2014:15:43:28 +0100] [Client 14] 1.1 Get-Notifications 38', > 'D [04/Apr/2014:15:43:28 +0100] Get-Notifications /', > 'D [04/Apr/2014:15:43:28 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [04/Apr/2014:15:43:28 +0100] [Client 14] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [04/Apr/2014:15:43:28 +0100] [Client 14] Content-Length: 666', > 'D [04/Apr/2014:15:43:28 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=666, response=0x7f522dda90c0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:28 +0100] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:28 +0100] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:28 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:28 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] No authentication data provided.', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] 1.1 CUPS-Get-Printers 39', > 'D [04/Apr/2014:15:43:29 +0100] CUPS-Get-Printers', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Content-Length: 1481', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1481, response=0x7f522ddf56e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] No authentication data provided.', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] 1.1 CUPS-Get-Classes 40', > 'D [04/Apr/2014:15:43:29 +0100] CUPS-Get-Classes', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Content-Length: 75', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f522dda54e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] No authentication data provided.', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] 1.1 CUPS-Get-Default 41', > 'D [04/Apr/2014:15:43:29 +0100] CUPS-Get-Default', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Content-Length: 7766', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7766, response=0x7f522ddf56e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Waiting for request.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] No authentication data provided.', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] 1.1 Get-Job-Attributes 42', > 'D [04/Apr/2014:15:43:29 +0100] Get-Job-Attributes ipp://localhost/jobs/253', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/253) from localhost', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Content-Length: 989', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=989, response=0x7f522dd94de0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] POST / HTTP/1.1', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] No authentication data provided.', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] 1.1 Cancel-Subscription 43', > 'D [04/Apr/2014:15:43:29 +0100] Cancel-Subscription /', > 'D [04/Apr/2014:15:43:29 +0100] cupsdIsAuthorized: requesting-user-name="root"', > 'D [04/Apr/2014:15:43:29 +0100] cupsdMarkDirty(----S)', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Returning IPP successful-ok for Cancel-Subscription (/) from localhost', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Content-Length: 75', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f522ddf56e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] PUT /admin/conf/cupsd.conf HTTP/1.1', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] No authentication data provided.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdIsAuthorized: username=""', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] WWW-Authenticate: Basic realm="CUPS", trc="y"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Closing connection.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:29 +0100] [Client 24] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:29 +0100] [Client 24] Waiting for request.', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] HTTP_STATE_WAITING Closing on EOF', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Closing connection.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] cupsdAcceptClient: skipping getpeercon()', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Accepted from localhost (Domain)', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Waiting for request.', > 'D [04/Apr/2014:15:43:29 +0100] [Client 24] HTTP_STATE_WAITING Closing on EOF', > 'D [04/Apr/2014:15:43:29 +0100] [Client 24] Closing connection.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] PUT /admin/conf/cupsd.conf HTTP/1.1', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Authorized as root using PeerCred', > 'D [04/Apr/2014:15:43:29 +0100] cupsdIsAuthorized: username="root"', > 'I [04/Apr/2014:15:43:29 +0100] Installing config file "/etc/cups/cupsd.conf"...', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 15] Closing connection.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 20] Closing connection.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 21] Closing connection.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 22] Closing connection.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 23] Closing connection.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 18] Closing connection.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [04/Apr/2014:15:43:29 +0100] [Client 14] Closing connection.', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'I [04/Apr/2014:15:43:29 +0100] Saving job.cache...', > 'I [04/Apr/2014:15:43:29 +0100] Saving subscriptions.conf...', > 'D [04/Apr/2014:15:43:29 +0100] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"', > 'E [04/Apr/2014:15:43:29 +0100] Unknown directive JobPrivateAccess on line 84 of /etc/cups/cupsd.conf.', > 'E [04/Apr/2014:15:43:29 +0100] Unknown directive JobPrivateValues on line 85 of /etc/cups/cupsd.conf.', > 'E [04/Apr/2014:15:43:29 +0100] Unknown directive SubscriptionPrivateAccess on line 86 of /etc/cups/cupsd.conf.', > 'E [04/Apr/2014:15:43:29 +0100] Unknown directive SubscriptionPrivateValues on line 87 of /etc/cups/cupsd.conf.', > "W [04/Apr/2014:15:43:29 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Brother_QL-570-Gray..' already exists", > "W [04/Apr/2014:15:43:29 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP-Color-LaserJet-CP5520-Series-Gray..' already exists", > "W [04/Apr/2014:15:43:29 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP-Color-LaserJet-CP5520-Series-CMYK..' already exists", > "W [04/Apr/2014:15:43:29 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Lexmark-C543-Gray..' already exists", > "W [04/Apr/2014:15:43:29 +0100] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Lexmark-C543-RGB..' already exists"], > 'error_log_debug_logging_unset': True} >Page 13 (Locale issues): >{'job_page_size': u'FloppyTape', > 'printer_page_size': u'FloppyTape', > 'system_locale_lang': 'en_US', > 'user_locale_ctype': 'en_GB', > 'user_locale_messages': 'en_GB'}
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1084494
: 882759 |
882781
|
883572