Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 865681 Details for
Bug 1067681
printing directly to printer fails, to file and then printing works
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
output of the print management tools
troubleshoot.txt (text/plain), 197.45 KB, created by
Ray Holme
on 2014-02-20 20:45:46 UTC
(
hide
)
Description:
output of the print management tools
Filename:
MIME Type:
Creator:
Ray Holme
Created:
2014-02-20 20:45:46 UTC
Size:
197.45 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 HP_LaserJet_2100 (default)>, > 'cups_instance': None, > 'cups_queue': u'HP_LaserJet_2100', > 'cups_queue_listed': True} >Page 4 (Check printer sanity): >{'cups_device_uri_scheme': u'hp', > 'cups_printer_dict': {'device-uri': u'hp:/net/HP_LaserJet_2100_Series?ip=192.168.101.200', > 'printer-info': u'HP_LaserJet_2100', > 'printer-is-shared': True, > 'printer-location': u'old office', > 'printer-make-and-model': u'HP LaserJet 2100 Series Postscript (recommended)', > 'printer-state': 3, > 'printer-state-message': u'ready to print', > 'printer-state-reasons': [u'none'], > 'printer-type': 8556612, > 'printer-uri-supported': u'ipp://localhost:631/printers/HP_LaserJet_2100'}, > 'cups_printer_remote': False, > 'hplip_output': (['', > '\x1b[01mHP Linux Imaging and Printing System (ver. 3.13.11)\x1b[0m', > '\x1b[01mDevice Information Utility ver. 5.2\x1b[0m', > '', > 'Copyright (c) 2001-13 Hewlett-Packard Development Company, LP', > 'This software comes with ABSOLUTELY NO WARRANTY.', > 'This is free software, and you are welcome to distribute it', > 'under certain conditions. See COPYING file for more details.', > '', > '', > '\x1b[01mHP Linux Imaging and Printing System (ver. 3.13.11)\x1b[0m', > '\x1b[01mSystem Tray Status Service ver. 2.0\x1b[0m', > '', > 'Copyright (c) 2001-13 Hewlett-Packard Development Company, LP', > 'This software comes with ABSOLUTELY NO WARRANTY.', > 'This is free software, and you are welcome to distribute it', > 'under certain conditions. See COPYING file for more details.', > '', > '', > '\x1b[01mhp:/net/HP_LaserJet_2100_Series?ip=192.168.101.200\x1b[0m', > '', > '\x1b[01mDevice Parameters (dynamic data):\x1b[0m', > '\x1b[01m Parameter Value(s) \x1b[0m', > ' ---------------------------- ----------------------------------------------------------', > ' back-end hp ', > " cups-printers ['HP_LaserJet_2100'] ", > ' cups-uri hp:/net/HP_LaserJet_2100_Series?ip=192.168.101.200 ', > ' dev-file ', > ' device-state -1 ', > ' device-uri hp:/net/HP_LaserJet_2100_Series?ip=192.168.101.200 ', > ' deviceid ', > ' error-state 101 ', > ' host 192.168.101.200 ', > ' is-hp True ', > ' panel 0 ', > ' panel-line1 ', > ' panel-line2 ', > ' port 1 ', > ' serial ', > ' status-code 5002 ', > ' status-desc ', > '\x1b[01m', > 'Model Parameters (static data):\x1b[0m', > '\x1b[01m Parameter Value(s) \x1b[0m', > ' ---------------------------- ----------------------------------------------------------', > ' align-type 0 ', > ' clean-type 0 ', > ' color-cal-type 0 ', > ' copy-type 0 ', > ' embedded-server-type 1 ', > ' fax-type 0 ', > ' fw-download False ', > ' icon hp_LaserJet_2100.png ', > ' io-mfp-mode 6 ', > ' io-mode 1 ', > ' io-support 3 ', > ' job-storage 0 ', > ' linefeed-cal-type 0 ', > ' model HP_LaserJet_2100_Series ', > ' model-ui HP LaserJet 2100 Series ', > ' model1 HP LaserJet 2100 Series Printer ', > ' monitor-type 0 ', > ' panel-check-type 1 ', > ' pcard-type 0 ', > ' plugin 0 ', > ' plugin-reason 0 ', > ' power-settings 0 ', > ' pq-diag-type 0 ', > ' r-type 0 ', > ' r0-agent1-kind 4 ', > ' r0-agent1-sku C4096A ', > ' r0-agent1-type 1 ', > ' scan-src 0 ', > ' scan-type 0 ', > ' status-battery-check 0 ', > ' status-dynamic-counters 0 ', > ' status-type 3 ', > ' support-released True ', > ' support-subtype 13847 ', > ' support-type 2 ', > ' support-ver 0.9.5 ', > " tech-class ['LJMono', 'Postscript'] ", > " tech-subclass ['Normal'] ", > ' tech-type 3 ', > ' usb-pid 0 ', > ' usb-vid 1008 ', > ' wifi-config 0 ', > '', > 'Done.', > ''], > ['\x1b[35;01mwarning: No display found.\x1b[0m', > '\x1b[31;01merror: hp-info -u/--gui requires Qt4 GUI support. Entering interactive mode.\x1b[0m', > '\x1b[35;01mwarning: No display found.\x1b[0m', > '\x1b[31;01merror: hp-systray requires Qt4 GUI and DBus support. Exiting.\x1b[0m', > '\x1b[35;01mwarning: Unable to connect to dbus. Is hp-systray running?\x1b[0m', > '\x1b[31;01merror: Unable to communicate with device (code=12): hp:/net/HP_LaserJet_2100_Series?ip=192.168.101.200\x1b[0m', > '\x1b[31;01merror: Error opening device (Device not found).\x1b[0m', > ''], > 0), > '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.0', > 'device-uri': u'hp:/net/HP_LaserJet_2100_Series?ip=192.168.101.200', > '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/urf', > 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, 51475068), > '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, 51475068), > 'jpeg-x-dimension-supported': (0, 65535), > 'jpeg-y-dimension-supported': (1, 65535), > 'marker-change-time': 0, > 'media-bottom-margin-supported': [423], > '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'na_letter_8.5x11in', > 'media-left-margin-supported': [423], > 'media-right-margin-supported': [432, > 425, > 449, > 442, > 440, > 452, > 431, > 436, > 428], > 'media-source-supported': [u'auto', > u'top', > u'middle', > u'bottom'], > 'media-supported': [u'na_letter_8.5x11in', > u'na_executive_7.25x10.5in', > u'na_legal_8.5x14in', > u'iso_a4_210x297mm', > u'iso_a5_148x210mm', > u'iso_b5_176x250mm', > u'jis_b5_182x257mm', > u'oe_w612h936_8.5x13in', > u'jpn_hagaki_100x148mm', > u'om_double-postcard_147.99x200.03mm', > u'oe_w558h774_7.75x10.75in', > u'na_number-10_4.125x9.5in', > u'na_monarch_3.875x7.5in', > u'iso_dl_110x220mm', > u'iso_c5_162x229mm', > u'custom_min_3x5in', > u'custom_max_8.5x14in'], > 'media-top-margin-supported': [432, > 443, > 425, > 450, > 453, > 413, > 436, > 449, > 433], > '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': 10, > 'pdf-k-octets-supported': (0, 51475068), > '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', u'tbcp'], > '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/HP_LaserJet_2100.png', > 'printer-info': u'HP_LaserJet_2100', > 'printer-is-accepting-jobs': True, > 'printer-is-shared': True, > 'printer-location': u'old office', > 'printer-make-and-model': u'HP LaserJet 2100 Series Postscript (recommended)', > 'printer-more-info': u'http://localhost:631/printers/HP_LaserJet_2100', > 'printer-name': u'HP_LaserJet_2100', > '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), > (600, > 600, > 3), > (1200, > 1200, > 3)], > 'printer-settable-attributes-supported': [u'printer-info', > u'printer-location'], > 'printer-state': 3, > 'printer-state-change-time': 1392826576, > 'printer-state-message': u'ready to print', > 'printer-state-reasons': [u'none'], > 'printer-type': 8556612, > 'printer-up-time': 1392844528, > 'printer-uri-supported': [u'ipp://localhost:631/printers/HP_LaserJet_2100'], > 'printer-uuid': u'urn:uuid:aedf5d24-6534-3545-47e8-63a0d981d7a4', > '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'General': {u'HPEconoMode': u'True', > u'HPHalftone': u'PrinterDefault', > u'HPStraightPaperPath': u'False', > u'InputSlot': u'Auto', > u'ManualFeed': u'False', > u'PageRegion': u'Letter', > u'PageSize': u'Letter', > u'Resolution': u'300x300dpi', > u'Smoothing': u'Off'}, > u'InstallableOptions': {u'HPOption_Tray3': u'False', > u'InstalledMemory': u'6MB'}}, > 'cups_printer_ppd_valid': True, > 'missing_pkgs_and_exes': ([], [])} >Page 6 (Local or remote?): >{'printer_is_remote': False} >Page 7 (Printer state reasons): >{'printer-state-message': u'ready to print', > 'printer-state-reasons': [u'none']} >Page 8 (Verify packages): >{'packages_verification': {'cups': ['..?...... /etc/cups/cupsd.conf.default', > '.M....... c /etc/cups/subscriptions.conf', > '..?...... /usr/lib/cups/backend/dnssd', > '..?...... /usr/lib/cups/backend/ipp', > '..?...... /usr/lib/cups/backend/lpd', > 'missing /var/spool/cups/tmp (Permission denied)'], > 'foomatic': [], > 'gutenprint': [], > 'hpijs': [], > 'hplip': ['..?...... /usr/lib/cups/backend/hpfax'], > 'system-config-printer': []}} >Page 9 (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': 6586, > 'error_log_debug_logging_set': True} >Page 10 (Print test page): >{'test_page_job_status': [(True, > 8, > 'HP_LaserJet_2100', > 'Rabies Certificate', > 'Completed', > {'HPEconoMode': True, > 'HPHalftone': u'PrinterDefault', > 'HPStraightPaperPath': False, > 'InputSlot': u'Auto', > 'ManualFeed': False, > 'PageSize': u'Letter', > 'Resolution': u'300x300dpi', > 'Smoothing': u'Off', > 'attributes-charset': u'utf-8', > 'attributes-natural-language': u'en-us', > 'document-format': u'application/pdf', > 'job-hold-until': u'no-hold', > 'job-id': 8, > 'job-k-octets': 94, > 'job-media-progress': 0, > 'job-media-sheets-completed': 1, > 'job-more-info': u'http://localhost:631/jobs/8', > 'job-preserved': True, > 'job-printer-state-message': u'', > 'job-printer-state-reasons': [u'none'], > 'job-printer-up-time': 1392844712, > 'job-printer-uri': u'ipp://localhost:631/printers/HP_LaserJet_2100', > '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/8', > 'job-uuid': u'urn:uuid:58f7232c-4434-36a0-4a58-29c00a800fec', > 'number-of-documents': 1, > 'number-up': 1, > 'printer-uri': u'ipp://localhost:631/printers/HP_LaserJet_2100', > 'time-at-completed': 1392844670, > 'time-at-creation': 1392844647, > 'time-at-processing': 1392844647})], > 'test_page_successful': False} >Page 11 (Error log fetch): >{'error_log': ['D [19/Feb/2014:16:16:41 -0500] [Client 14] Sending file.', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6586, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=4538, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2490, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=442, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-852, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-1051, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-1251, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-1451, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-1651, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-1851, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-2051, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-2251, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-2451, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-2651, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-2851, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-3051, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-3251, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-3451, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-3651, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-3851, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-4051, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-4251, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-4451, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-4651, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-4851, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-5051, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-5251, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-5451, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-5651, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-5851, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-6051, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-6251, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-6451, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-6651, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-6851, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-7051, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-7251, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-7451, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-7651, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-7851, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-8051, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-8251, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-8451, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-8651, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-8851, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-9051, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-9251, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-9451, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-9651, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-9851, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-10051, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-10252, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-10453, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-10654, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-10855, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-11056, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-11257, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-11458, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-11659, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-11860, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-12061, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-12262, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-12463, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-12664, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-12865, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-13066, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-13267, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-13468, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-13669, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-13870, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-14071, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-14272, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-14473, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-14674, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-14875, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-15076, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-15277, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-15478, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-15679, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-15880, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-16081, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-16282, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-16483, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-16684, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-16885, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-17086, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-17287, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-17488, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-17689, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-17890, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-18091, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-18292, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-18493, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-18694, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-18895, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-19096, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-19297, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-19498, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-19699, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-19900, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-20101, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-20302, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-20503, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-20704, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-20905, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-21106, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-21307, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-21508, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-21709, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-21910, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-22111, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-22312, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-22513, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-22714, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-22915, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-23116, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-23317, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-23518, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-23719, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-23920, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-24121, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-24322, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-24523, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-24724, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-24925, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-25126, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-25327, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=-25528, response=(nil)(), pipe_pid=0, file=16', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] POST / HTTP/1.1', > 'D [19/Feb/2014:16:16:41 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] No authentication data provided.', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] 1.1 Get-Jobs 34', > 'D [19/Feb/2014:16:16:41 -0500] Get-Jobs ipp://localhost/printers/', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Content-Length: 75', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Waiting for request.', > 'D [19/Feb/2014:16:16:41 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] POST / HTTP/1.1', > 'D [19/Feb/2014:16:16:41 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] No authentication data provided.', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] 1.1 Get-Jobs 35', > 'D [19/Feb/2014:16:16:41 -0500] Get-Jobs ipp://localhost/printers/', > 'D [19/Feb/2014:16:16:41 -0500] [Job 1] Loading attributes...', > 'D [19/Feb/2014:16:16:41 -0500] [Job 2] Loading attributes...', > 'D [19/Feb/2014:16:16:41 -0500] [Job 3] Loading attributes...', > 'D [19/Feb/2014:16:16:41 -0500] [Job 4] Loading attributes...', > 'D [19/Feb/2014:16:16:41 -0500] [Job 5] Loading attributes...', > 'D [19/Feb/2014:16:16:41 -0500] [Job 6] Loading attributes...', > 'D [19/Feb/2014:16:16:41 -0500] [Job 7] Loading attributes...', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Returning IPP successful-ok for Get-Jobs (ipp://localhost/printers/) from localhost', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Content-Length: 766', > 'D [19/Feb/2014:16:16:41 -0500] [Job 1] Removing document files.', > 'D [19/Feb/2014:16:16:41 -0500] [Job 2] Removing document files.', > 'D [19/Feb/2014:16:16:41 -0500] [Job 3] Removing document files.', > 'D [19/Feb/2014:16:16:41 -0500] [Job 4] Removing document files.', > 'D [19/Feb/2014:16:16:41 -0500] [Job 5] Removing document files.', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=766, response=0x7f814fedf5c0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Waiting for request.', > 'D [19/Feb/2014:16:16:41 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] POST / HTTP/1.1', > 'D [19/Feb/2014:16:16:41 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] No authentication data provided.', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] 1.1 Create-Printer-Subscription 36', > 'D [19/Feb/2014:16:16:41 -0500] Create-Printer-Subscription /', > 'D [19/Feb/2014:16:16:41 -0500] cupsdCreateSubscription(con=0x7f814ff57920(15), uri="/")', > 'D [19/Feb/2014:16:16:41 -0500] pullmethod="ippget"', > 'D [19/Feb/2014:16:16:41 -0500] notify-lease-duration=86400', > 'D [19/Feb/2014:16:16:41 -0500] notify-time-interval=0', > 'D [19/Feb/2014:16:16:41 -0500] cupsdAddSubscription(mask=17800, dest=(nil)(), job=(nil)(0), uri="(null)")', > 'D [19/Feb/2014:16:16:41 -0500] Added subscription #15 for server.', > 'D [19/Feb/2014:16:16:41 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:16:41 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Returning IPP successful-ok for Create-Printer-Subscription (/) from localhost', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Content-Length: 107', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=107, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:16:41 -0500] [Client 15] Waiting for request.', > 'D [19/Feb/2014:16:16:41 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:16:42 -0500] [Client 15] POST / HTTP/1.1', > 'D [19/Feb/2014:16:16:42 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:16:42 -0500] [Client 15] No authentication data provided.', > 'D [19/Feb/2014:16:16:42 -0500] [Client 15] 1.1 Get-Notifications 37', > 'D [19/Feb/2014:16:16:42 -0500] Get-Notifications /', > 'D [19/Feb/2014:16:16:42 -0500] cupsdIsAuthorized: requesting-user-name="ray"', > 'D [19/Feb/2014:16:16:42 -0500] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [19/Feb/2014:16:16:42 -0500] [Client 15] Content-Length: 127', > 'D [19/Feb/2014:16:16:42 -0500] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=127, response=0x7f814fedf5c0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:16:42 -0500] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:16:42 -0500] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:16:42 -0500] [Client 15] Waiting for request.', > 'D [19/Feb/2014:16:16:42 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'I [19/Feb/2014:16:17:08 -0500] Generating printcap /etc/printcap...', > 'I [19/Feb/2014:16:17:08 -0500] Saving subscriptions.conf...', > 'D [19/Feb/2014:16:17:08 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:17:11 -0500] [Client 14] Closing on EOF', > 'D [19/Feb/2014:16:17:11 -0500] [Client 14] Closing connection.', > 'D [19/Feb/2014:16:17:11 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] No authentication data provided.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] 1.1 CUPS-Get-Printers 13', > 'D [19/Feb/2014:16:17:22 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Content-Length: 3158', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3158, response=0x7f814ff4d260(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] No authentication data provided.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] 1.1 CUPS-Get-Default 14', > 'D [19/Feb/2014:16:17:22 -0500] CUPS-Get-Default', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Content-Length: 7441', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7441, response=0x7f814fee18c0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] No authentication data provided.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] 1.1 CUPS-Get-Printers 15', > 'D [19/Feb/2014:16:17:22 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Content-Length: 3158', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=3158, response=0x7f814ff4d260(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] No authentication data provided.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] 1.1 CUPS-Get-Default 16', > 'D [19/Feb/2014:16:17:22 -0500] CUPS-Get-Default', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Content-Length: 7441', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7441, response=0x7f814fee18c0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 14] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:22 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] Content-Length: 2401', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 17] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] GET /printers/HP_LaserJet_2100.ppd HTTP/1.1', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Sending file.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=108629, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=106581, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=104533, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=102485, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=100437, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=98389, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=96341, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=94293, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=92245, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=90197, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=88149, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=86101, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=84053, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=82005, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=79957, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=77909, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75861, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=73813, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=71765, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=69717, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=67669, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=65621, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=63573, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=61525, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=59477, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=57429, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=55381, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=53333, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=51285, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=49237, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=47189, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=45141, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=43093, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=41045, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=38997, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=36949, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=34901, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=32853, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=30805, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=28757, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=26709, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=24661, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=22613, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=20565, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=18517, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=16469, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=14421, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=12373, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=10325, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=8277, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=6229, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=4181, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2133, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_GET_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=85, response=(nil)(), pipe_pid=0, file=17', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:22 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:22 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:22 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:22 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:22 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:22 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:23 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:23 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:23 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:23 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:23 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:23 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:23 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:24 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:24 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:24 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:24 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:24 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:24 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:24 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:25 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:25 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:25 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:25 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:25 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:25 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:25 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:26 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:26 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:26 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d320(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] No authentication data provided.', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] 1.1 CUPS-Get-Printers 1', > 'D [19/Feb/2014:16:17:26 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Content-Length: 2401', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=2401, response=0x7f814ff4d1b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:26 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:17:26 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:17:27 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:27 -0500] [Client 16] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:27 -0500] [Client 16] Waiting for request.', > 'D [19/Feb/2014:16:17:27 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] POST /printers/HP_LaserJet_2100 HTTP/1.1', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] 2.1 Print-Job 1', > 'D [19/Feb/2014:16:17:27 -0500] Print-Job ipp://localhost:631/printers/HP_LaserJet_2100', > 'D [19/Feb/2014:16:17:27 -0500] [Job ???] Auto-typing file...', > 'I [19/Feb/2014:16:17:27 -0500] [Job ???] Request file type is application/pdf.', > 'D [19/Feb/2014:16:17:27 -0500] add_job: setting context of job to UNKNOWN SL', > 'D [19/Feb/2014:16:17:27 -0500] cupsdMarkDirty(---J-)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"', > 'D [19/Feb/2014:16:17:27 -0500] add_job: requesting-user-name="ray"', > 'I [19/Feb/2014:16:17:27 -0500] [Job 8] Adding start banner page "none".', > 'D [19/Feb/2014:16:17:27 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] cupsdMarkDirty(---J-)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'I [19/Feb/2014:16:17:27 -0500] [Job 8] Adding end banner page "none".', > 'I [19/Feb/2014:16:17:27 -0500] [Job 8] File of type application/pdf queued by "ray".', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] hold_until=0', > 'I [19/Feb/2014:16:17:27 -0500] [Job 8] Queued on "HP_LaserJet_2100" by "ray".', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] time-at-processing=1392844647', > 'D [19/Feb/2014:16:17:27 -0500] cupsdMarkDirty(---J-)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] 3 filters for job:', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] pdftopdf (application/pdf to application/vnd.cups-pdf, cost 66)', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] pdftops (application/vnd.cups-pdf to application/vnd.cups-postscript, cost 100)', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] - (application/vnd.cups-postscript to printer/HP_LaserJet_2100, cost 0)', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] job-sheets=none,none', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] argv[0]="HP_LaserJet_2100"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] argv[1]="8"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] argv[2]="ray"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] argv[3]="Rabies Certificate"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] argv[4]="1"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] argv[5]="InputSlot=Auto HPEconoMode noManualFeed HPHalftone=PrinterDefault Smoothing=Off PageSize=Letter Resolution=300x300dpi noHPStraightPaperPath number-up=1 job-uuid=urn:uuid:58f7232c-4434-36a0-4a58-29c00a800fec job-originating-host-name=localhost time-at-creation=1392844647 time-at-processing=1392844647"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] argv[6]="/var/spool/cups/d00008-001"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[0]="CUPS_CACHEDIR=/var/cache/cups"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[1]="CUPS_DATADIR=/usr/share/cups"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[2]="CUPS_DOCROOT=/usr/share/cups/www"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[3]="CUPS_FONTPATH=/usr/share/cups/fonts"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[4]="CUPS_REQUESTROOT=/var/spool/cups"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[5]="CUPS_SERVERBIN=/usr/lib/cups"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[6]="CUPS_SERVERROOT=/etc/cups"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[7]="CUPS_STATEDIR=/var/run/cups"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[8]="HOME=/var/spool/cups/tmp"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[9]="PATH=/usr/lib/cups/filter:/usr/lib64/cups/filter:/usr/bin:/usr/sbin:/bin:/usr/bin"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[10]="SERVER_ADMIN=root@rainbow"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[11]="SOFTWARE=CUPS/1.7.0"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[12]="TMPDIR=/var/spool/cups/tmp"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[13]="USER=root"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[14]="CUPS_MAX_MESSAGE=2047"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[15]="CUPS_SERVER=/var/run/cups/cups.sock"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[16]="CUPS_ENCRYPTION=IfRequested"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[17]="IPP_PORT=631"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[18]="CHARSET=utf-8"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[19]="LANG=en_US.UTF-8"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[20]="PPD=/etc/cups/ppd/HP_LaserJet_2100.ppd"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[21]="RIP_MAX_CACHE=128m"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[22]="CONTENT_TYPE=application/pdf"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[23]="DEVICE_URI=hp:/net/HP_LaserJet_2100_Series?ip=192.168.101.200"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[24]="PRINTER_INFO=HP_LaserJet_2100"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[25]="PRINTER_LOCATION=old office"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[26]="PRINTER=HP_LaserJet_2100"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[27]="PRINTER_STATE_REASONS=none"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[28]="CUPS_FILETYPE=document"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[29]="FINAL_CONTENT_TYPE=printer/HP_LaserJet_2100"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] envp[30]="AUTH_I****"', > 'I [19/Feb/2014:16:17:27 -0500] [Job 8] Started filter /usr/lib/cups/filter/pdftopdf (PID 9167)', > 'I [19/Feb/2014:16:17:27 -0500] [Job 8] Started filter /usr/lib/cups/filter/pdftops (PID 9168)', > 'I [19/Feb/2014:16:17:27 -0500] [Job 8] Started backend /usr/lib/cups/backend/hp (PID 9169)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Returning IPP successful-ok for Print-Job (ipp://localhost:631/printers/HP_LaserJet_2100) from localhost', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Content-Length: 173', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=173, response=0x7f814ff4d2b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Closing connection.', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] pdftops - copying to temp print file "/var/spool/cups/tmp/cups7YGgVe"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] PID 9167 (/usr/lib/cups/filter/pdftopdf) exited with no errors.', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] Using image rendering resolution 300 dpi', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] Started filter gs (PID 9170)', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] Started filter pstops (PID 9171)', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] Page = 612x792; 12,12 to 600,780', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] slow_collate=0, slow_duplex=0, slow_order=0', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] Before copy_comments - %!PS-Adobe-3.0', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] %!PS-Adobe-3.0', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] %%BoundingBox: 0 0 612 792', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] %%HiResBoundingBox: 0 0 612.00 792.00', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] %%Creator: GPL Ghostscript 910 (ps2write)', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] %%LanguageLevel: 2', > "D [19/Feb/2014:16:17:27 -0500] [Job 8] %%CreationDate: D:20140219161727-05'00'", > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] %%Pages: 1', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] %%EndComments', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] Before copy_prolog - %%BeginProlog', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] STATE: +connecting-to-device', > 'D [19/Feb/2014:16:17:27 -0500] cupsdMarkDirty(---J-)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] STATE: -connecting-to-device', > 'D [19/Feb/2014:16:17:27 -0500] cupsdMarkDirty(---J-)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] [Job 8] STATE: -media-empty-error,media-jam-error,hplip.plugin-error,cover-open-error,toner-empty-error,other', > 'D [19/Feb/2014:16:17:27 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] 1.1 Get-Notifications 38', > 'D [19/Feb/2014:16:17:27 -0500] Get-Notifications /', > 'D [19/Feb/2014:16:17:27 -0500] cupsdIsAuthorized: requesting-user-name="ray"', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Content-Length: 1441', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1441, response=0x7f814fee37b0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:27 -0500] [Client 19] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:27 -0500] [Client 19] Waiting for request.', > 'D [19/Feb/2014:16:17:27 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:27 -0500] [Client 20] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:27 -0500] [Client 20] Waiting for request.', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:27 -0500] [Client 17] Closing connection.', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] [Client 15] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:27 -0500] [Client 15] No authentication data provided.', > 'D [19/Feb/2014:16:17:27 -0500] [Client 15] 1.1 Get-Notifications 39', > 'D [19/Feb/2014:16:17:27 -0500] Get-Notifications /', > 'D [19/Feb/2014:16:17:27 -0500] cupsdIsAuthorized: requesting-user-name="ray"', > 'D [19/Feb/2014:16:17:27 -0500] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [19/Feb/2014:16:17:27 -0500] [Client 15] Content-Length: 1184', > 'D [19/Feb/2014:16:17:27 -0500] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1184, response=0x7f814fee37e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:27 -0500] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:27 -0500] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:27 -0500] [Client 15] Waiting for request.', > 'D [19/Feb/2014:16:17:27 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:28 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:28 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] 1.1 CUPS-Get-Printers 40', > 'D [19/Feb/2014:16:17:28 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Content-Length: 1294', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1294, response=0x7f814fee3790(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:28 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:28 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] 1.1 CUPS-Get-Classes 41', > 'D [19/Feb/2014:16:17:28 -0500] CUPS-Get-Classes', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Content-Length: 75', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f814fee3650(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:28 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:28 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] 1.1 CUPS-Get-Default 42', > 'D [19/Feb/2014:16:17:28 -0500] CUPS-Get-Default', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Content-Length: 7427', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7427, response=0x7f814fee3790(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:28 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:28 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:36 -0500] [Job 8] PID 9170 (gs) exited with no errors.', > 'D [19/Feb/2014:16:17:37 -0500] Report: clients=6', > 'D [19/Feb/2014:16:17:37 -0500] Report: jobs=8', > 'D [19/Feb/2014:16:17:37 -0500] Report: jobs-active=1', > 'D [19/Feb/2014:16:17:37 -0500] Report: printers=3', > 'D [19/Feb/2014:16:17:37 -0500] Report: stringpool-string-count=11254', > 'D [19/Feb/2014:16:17:37 -0500] Report: stringpool-alloc-bytes=13480', > 'D [19/Feb/2014:16:17:37 -0500] Report: stringpool-total-bytes=130680', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] Before copy_setup - %%Page: 1 1', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] Before page loop - %%Page: 1 1', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] Copying page 1...', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] PAGE: 1 1', > 'D [19/Feb/2014:16:17:41 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:17:41 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] pagew = 587.8, pagel = 767.8', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] bboxx = 0, bboxy = 0, bboxw = 612, bboxl = 792', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] PageLeft = 12.0, PageRight = 599.8', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] PageTop = 779.8, PageBottom = 12.0', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] PageWidth = 612.0, PageLength = 792.0', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] Wrote 1 pages...', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] PID 9171 (pstops) exited with no errors.', > 'D [19/Feb/2014:16:17:41 -0500] [Job 8] PID 9168 (/usr/lib/cups/filter/pdftops) exited with no errors.', > 'I [19/Feb/2014:16:17:49 -0500] [Job 8] ready to print', > 'D [19/Feb/2014:16:17:49 -0500] cupsdMarkDirty(---J-)', > 'D [19/Feb/2014:16:17:49 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:49 -0500] [Job 8] Set job-printer-state-message to "ready to print", current level=INFO', > 'D [19/Feb/2014:16:17:49 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:17:49 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:49 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:17:49 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:49 -0500] [Job 1] Unloading...', > 'D [19/Feb/2014:16:17:49 -0500] [Job 2] Unloading...', > 'D [19/Feb/2014:16:17:49 -0500] [Job 3] Unloading...', > 'D [19/Feb/2014:16:17:49 -0500] [Job 4] Unloading...', > 'D [19/Feb/2014:16:17:49 -0500] [Job 5] Unloading...', > 'D [19/Feb/2014:16:17:49 -0500] [Job 6] Unloading...', > 'D [19/Feb/2014:16:17:49 -0500] [Job 7] Unloading...', > 'D [19/Feb/2014:16:17:49 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] Waiting for request.', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:49 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] No authentication data provided.', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] 1.1 Get-Notifications 43', > 'D [19/Feb/2014:16:17:49 -0500] Get-Notifications /', > 'D [19/Feb/2014:16:17:49 -0500] cupsdIsAuthorized: requesting-user-name="ray"', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] Content-Length: 555', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=555, response=0x7f814ff4da10(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] Waiting for request.', > 'D [19/Feb/2014:16:17:49 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:49 -0500] [Client 21] Closing connection.', > 'D [19/Feb/2014:16:17:49 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:49 -0500] [Client 15] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:49 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:49 -0500] [Client 15] No authentication data provided.', > 'D [19/Feb/2014:16:17:49 -0500] [Client 15] 1.1 Get-Notifications 44', > 'D [19/Feb/2014:16:17:49 -0500] Get-Notifications /', > 'D [19/Feb/2014:16:17:49 -0500] cupsdIsAuthorized: requesting-user-name="ray"', > 'D [19/Feb/2014:16:17:49 -0500] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [19/Feb/2014:16:17:49 -0500] [Client 15] Content-Length: 1193', > 'D [19/Feb/2014:16:17:49 -0500] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1193, response=0x7f814ff4d4e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:49 -0500] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:49 -0500] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:49 -0500] [Client 15] Waiting for request.', > 'D [19/Feb/2014:16:17:49 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] 1.1 CUPS-Get-Printers 45', > 'D [19/Feb/2014:16:17:50 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Content-Length: 1308', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1308, response=0x7f814ff40880(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] 1.1 CUPS-Get-Classes 46', > 'D [19/Feb/2014:16:17:50 -0500] CUPS-Get-Classes', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Content-Length: 75', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f814ff4d4e0(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Active clients, printing jobs, and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] 1.1 CUPS-Get-Default 47', > 'D [19/Feb/2014:16:17:50 -0500] CUPS-Get-Default', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Content-Length: 7441', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7441, response=0x7f814ff40880(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:50 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Active clients, printing jobs, and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Job 8] PID 9169 (/usr/lib/cups/backend/hp) exited with no errors.', > 'D [19/Feb/2014:16:17:50 -0500] [Job 8] time-at-completed=1392844670', > 'D [19/Feb/2014:16:17:50 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'I [19/Feb/2014:16:17:50 -0500] [Job 8] Job completed.', > 'D [19/Feb/2014:16:17:50 -0500] cupsdMarkDirty(---J-)', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] cupsdMarkDirty(---J-)', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Printing jobs and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] Waiting for request.', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Printing jobs and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] No authentication data provided.', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] 1.1 Get-Notifications 48', > 'D [19/Feb/2014:16:17:50 -0500] Get-Notifications /', > 'D [19/Feb/2014:16:17:50 -0500] cupsdIsAuthorized: requesting-user-name="ray"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] Content-Length: 563', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=563, response=0x7f814ff4da10(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] Waiting for request.', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:50 -0500] [Client 18] Closing connection.', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 15] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 15] No authentication data provided.', > 'D [19/Feb/2014:16:17:50 -0500] [Client 15] 1.1 Get-Notifications 49', > 'D [19/Feb/2014:16:17:50 -0500] Get-Notifications /', > 'D [19/Feb/2014:16:17:50 -0500] cupsdIsAuthorized: requesting-user-name="ray"', > 'D [19/Feb/2014:16:17:50 -0500] [Client 15] Returning IPP successful-ok for Get-Notifications (/) from localhost', > 'D [19/Feb/2014:16:17:50 -0500] [Client 15] Content-Length: 673', > 'D [19/Feb/2014:16:17:50 -0500] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=673, response=0x7f814ff4da10(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:50 -0500] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:50 -0500] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:50 -0500] [Client 15] Waiting for request.', > 'D [19/Feb/2014:16:17:50 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:51 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] 1.1 CUPS-Get-Printers 50', > 'D [19/Feb/2014:16:17:51 -0500] CUPS-Get-Printers', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Returning IPP successful-ok for CUPS-Get-Printers (no URI) from localhost', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Content-Length: 1308', > 'D [19/Feb/2014:16:17:51 -0500] [Job 8] Unloading...', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1308, response=0x7f814ff45b70(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:51 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:51 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] 1.1 CUPS-Get-Classes 51', > 'D [19/Feb/2014:16:17:51 -0500] CUPS-Get-Classes', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Returning IPP successful-ok for CUPS-Get-Classes (no URI) from localhost', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Content-Length: 75', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f814ff4da10(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:51 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] POST / HTTP/1.1', > 'D [19/Feb/2014:16:17:51 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] No authentication data provided.', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] 1.1 CUPS-Get-Default 52', > 'D [19/Feb/2014:16:17:51 -0500] CUPS-Get-Default', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Returning IPP successful-ok for CUPS-Get-Default (no URI) from localhost', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Content-Length: 7441', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=7441, response=0x7f814ff45b70(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:17:51 -0500] [Client 17] Waiting for request.', > 'D [19/Feb/2014:16:17:51 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'I [19/Feb/2014:16:17:58 -0500] Saving job.cache...', > 'I [19/Feb/2014:16:17:58 -0500] Saving subscriptions.conf...', > 'D [19/Feb/2014:16:17:58 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"', > 'D [19/Feb/2014:16:17:58 -0500] [Client 20] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:17:58 -0500] [Client 20] Closing connection.', > 'D [19/Feb/2014:16:17:58 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Not busy"', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] POST / HTTP/1.1', > 'D [19/Feb/2014:16:18:32 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] No authentication data provided.', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] 1.1 Get-Job-Attributes 53', > 'D [19/Feb/2014:16:18:32 -0500] Get-Job-Attributes ipp://localhost/jobs/8', > 'D [19/Feb/2014:16:18:32 -0500] [Job 8] Loading attributes...', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] Returning IPP successful-ok for Get-Job-Attributes (ipp://localhost/jobs/8) from localhost', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] Content-Length: 1016', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=1016, response=0x7f814ff4d230(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] Waiting for request.', > 'D [19/Feb/2014:16:18:32 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Active clients"', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] POST / HTTP/1.1', > 'D [19/Feb/2014:16:18:32 -0500] cupsdSetBusyState: newbusy="Active clients", busy="Not busy"', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] No authentication data provided.', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] 1.1 Cancel-Subscription 54', > 'D [19/Feb/2014:16:18:32 -0500] Cancel-Subscription /', > 'D [19/Feb/2014:16:18:32 -0500] cupsdIsAuthorized: requesting-user-name="ray"', > 'D [19/Feb/2014:16:18:32 -0500] cupsdMarkDirty(----S)', > 'D [19/Feb/2014:16:18:32 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Active clients"', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] Returning IPP successful-ok for Cancel-Subscription (/) from localhost', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] Content-Length: 75', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] cupsdWriteClient error=0, used=0, state=HTTP_STATE_POST_SEND, data_encoding=HTTP_ENCODING_LENGTH, data_remaining=75, response=0x7f814ff47200(IPP_IDLE), pipe_pid=0, file=-1', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] Writing IPP response, ipp_state=DATA, old wused=0, new wused=0', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] bytes=0, http_state=0, data_remaining=0', > 'D [19/Feb/2014:16:18:32 -0500] [Client 15] Waiting for request.', > 'D [19/Feb/2014:16:18:32 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:18:32 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:18:32 -0500] [Client 18] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:18:32 -0500] [Client 18] Waiting for request.', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] PUT /admin/conf/cupsd.conf HTTP/1.1', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] No authentication data provided.', > 'D [19/Feb/2014:16:18:33 -0500] cupsdIsAuthorized: username=""', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] WWW-Authenticate: Basic realm="CUPS", trc="y"', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Closing connection.', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Waiting for request.', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Closing connection.', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Waiting for request.', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] HTTP_STATE_WAITING Closing on EOF', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Closing connection.', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] cupsdAcceptClient: skipping getpeercon()', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Accepted from localhost (Domain)', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Waiting for request.', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] PUT /admin/conf/cupsd.conf HTTP/1.1', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Active clients and dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Authorized as root using PeerCred', > 'D [19/Feb/2014:16:18:33 -0500] cupsdIsAuthorized: username="root"', > 'I [19/Feb/2014:16:18:33 -0500] Installing config file "/etc/cups/cupsd.conf"...', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Active clients and dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] [Client 15] Closing connection.', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] [Client 14] Closing connection.', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] [Client 16] Closing connection.', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] [Client 19] Closing connection.', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] [Client 17] Closing connection.', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'D [19/Feb/2014:16:18:33 -0500] [Client 18] Closing connection.', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Dirty files", busy="Dirty files"', > 'I [19/Feb/2014:16:18:33 -0500] Saving subscriptions.conf...', > 'D [19/Feb/2014:16:18:33 -0500] cupsdSetBusyState: newbusy="Not busy", busy="Dirty files"', > 'E [19/Feb/2014:16:18:33 -0500] Unknown directive JobPrivateAccess on line 84 of /etc/cups/cupsd.conf.', > 'E [19/Feb/2014:16:18:33 -0500] Unknown directive JobPrivateValues on line 85 of /etc/cups/cupsd.conf.', > 'E [19/Feb/2014:16:18:33 -0500] Unknown directive SubscriptionPrivateAccess on line 86 of /etc/cups/cupsd.conf.', > 'E [19/Feb/2014:16:18:33 -0500] Unknown directive SubscriptionPrivateValues on line 87 of /etc/cups/cupsd.conf.', > "W [19/Feb/2014:16:18:33 -0500] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'HP_LaserJet_2100-Gray..' already exists", > "W [19/Feb/2014:16:18:33 -0500] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet_6600-Gray..' already exists", > "W [19/Feb/2014:16:18:33 -0500] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet_6600-RGB..' already exists", > "W [19/Feb/2014:16:18:33 -0500] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet_6600_fax-Gray..' already exists", > "W [19/Feb/2014:16:18:33 -0500] CreateProfile failed: org.freedesktop.ColorManager.AlreadyExists:profile id 'Officejet_6600_fax-RGB..' already exists"], > 'error_log_debug_logging_unset': True} >Page 12 (Locale issues): >{'job_page_size': u'Letter', > 'printer_page_size': u'Letter', > 'system_locale_lang': 'en_US', > 'user_locale_ctype': 'en_US', > 'user_locale_messages': 'en_US'}
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1067681
: 865681