Attachment 882759 Details for Bug 1084494 – troubleshooting information

troubleshooting information

troubleshoot.txt (text/plain), 208.90 KB, created by David Woodhouse on 2014-04-04 14:50:02 UTC

(hide)

Description:

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'}

Actions: View

Attachments on bug 1084494: 882759 | 882781 | 883572