+<newprinter.NewPrinterGUI object at 0x7f1c5674be58 (newprinter+NewPrinterGUI at 0xc0689b6d60)>
Connected as user ant
+<printerproperties.PrinterPropertiesDialog object at 0x7f1c56766ab0 (printerproperties+PrinterPropertiesDialog at 0xc068aaf460)>
<authconn.Connection object at 0x7f1c67739ac8>: Operation += obtaining queue details
Authentication pass: 1
Authentication: password callback set
Authentication pass: 1
Authentication: password callback set
Authentication pass: 1
Authentication: password callback set
<authconn.Connection object at 0x7f1c67739ac8>: Operation ended
+<ppdcache.PPDCache object at 0x7f1c5677d550>
refresh
Created subscription 2258, events=['printer-added', 'printer-modified', 'printer-deleted', 'printer-state-changed']
Next notifications fetch in 1s
update_jobs
get_notifications
update_jobs
Next notifications fetch in 60s
<authconn.Connection object at 0x7f1c67739ac8>: Operation += obtaining queue details
Authentication pass: 1
Authentication: password callback set
Authentication pass: 1
Authentication: password callback set
Authentication pass: 1
Authentication: password callback set
<authconn.Connection object at 0x7f1c67739ac8>: Operation ended
Connected as user ant
Authentication pass: 1
Authentication: password callback set
Authentication pass: 1
Authentication: password callback set

(system-config-printer.py:30612): Gtk-CRITICAL **: gtk_tree_store_insert_after: assertion 'G_NODE (sibling->user_data)->parent == G_NODE (parent->user_data)' failed

(system-config-printer.py:30612): Gtk-CRITICAL **: gtk_tree_store_set_value: assertion 'VALID_ITER (iter, tree_store)' failed

(system-config-printer.py:30612): Gtk-CRITICAL **: gtk_tree_store_set_value: assertion 'VALID_ITER (iter, tree_store)' failed
Fetching devices
Using polkit-1 connection class
+<asyncipp._IPPAuthOperation object at 0x7f1c5676dc18>
New IPPConnection
+<_IPPConnectionThread(Thread-1, initial daemon)>
+<asyncipp.IPPAuthConnection object at 0x7f1c5676dcf8>
Awaiting further instructions
DevicesGet new API: True
+<asyncpk1.PK1Connection object at 0x7f1c5676de10>
+<asyncconn.Connection object at 0x7f1c5676de48>
fetchDevices
+<asyncconn._AsyncMethodCall object at 0x7f1c55ccfcc0>
<asyncconn._AsyncMethodCall object at 0x7f1c55ccfcc0>: calling <bound method PK1Connection.getDevices of <asyncpk1.PK1Connection object at 0x7f1c5676de10>>
Converted ()/{'error_handler': <bound method _AsyncMethodCall.error_handler of <asyncconn._AsyncMethodCall object at 0x7f1c55ccfcc0>>, 'reply_handler': <bound method _AsyncMethodCall.reply_handler of <asyncconn._AsyncMethodCall object at 0x7f1c55ccfcc0>>, 'auth_handler': <bound method _AsyncMethodCall.auth_handler of <asyncconn._AsyncMethodCall object at 0x7f1c55ccfcc0>>, 'exclude_schemes': ['dnssd', 'snmp', 'bluetooth']} to (0, 0, [], ['dnssd', 'snmp', 'bluetooth'])
+_PK1AsyncMethodCall: <asyncpk1._PK1AsyncMethodCall object at 0x7f1c55ccf198>
Calling PK method DevicesGet
<asyncpk1._PK1AsyncMethodCall object at 0x7f1c55ccf198>: calling <dbus.proxies._DeferredMethod object at 0x7f1c55ccfd30>
Skipping firewall adjustment: discovery in progress
<asyncpk1._PK1AsyncMethodCall object at 0x7f1c55ccf198>: no error, calling reply handler <bound method _AsyncMethodCall.reply_handler of <asyncconn._AsyncMethodCall object at 0x7f1c55ccfcc0>>
<asyncconn._AsyncMethodCall object at 0x7f1c55ccfcc0>: to reply_handler at <bound method Connection._subst_reply_handler of <asyncconn.Connection object at 0x7f1c5676de48>>
DESTROY: <asyncconn._AsyncMethodCall object at 0x7f1c55ccfcc0>
<asyncconn.Connection object at 0x7f1c5676de48>: chaining up to <bound method _GetDevicesCall._reply_handler of <cupshelpers.cupshelpers._GetDevicesCall object at 0x7f1c55ccfc50>>
fetchDevices
+<asyncconn._AsyncMethodCall object at 0x7f1c5699c7b8>
<asyncconn._AsyncMethodCall object at 0x7f1c5699c7b8>: calling <bound method PK1Connection.getDevices of <asyncpk1.PK1Connection object at 0x7f1c5676de10>>
Converted ()/{'include_schemes': ['dnssd', 'snmp', 'bluetooth'], 'error_handler': <bound method _AsyncMethodCall.error_handler of <asyncconn._AsyncMethodCall object at 0x7f1c5699c7b8>>, 'reply_handler': <bound method _AsyncMethodCall.reply_handler of <asyncconn._AsyncMethodCall object at 0x7f1c5699c7b8>>, 'auth_handler': <bound method _AsyncMethodCall.auth_handler of <asyncconn._AsyncMethodCall object at 0x7f1c5699c7b8>>} to (0, 0, ['dnssd', 'snmp', 'bluetooth'], [])
+_PK1AsyncMethodCall: <asyncpk1._PK1AsyncMethodCall object at 0x7f1c5699c780>
Calling PK method DevicesGet
<asyncpk1._PK1AsyncMethodCall object at 0x7f1c5699c780>: calling <dbus.proxies._DeferredMethod object at 0x7f1c5699c128>
DESTROY: <asyncpk1._PK1AsyncMethodCall object at 0x7f1c55ccf198>
-<asyncconn._AsyncMethodCall object at 0x7f1c55ccfcc0>
-_PK1AsyncMethodCall: <asyncpk1._PK1AsyncMethodCall object at 0x7f1c55ccf198>
<asyncpk1._PK1AsyncMethodCall object at 0x7f1c5699c780>: no error, calling reply handler <bound method _AsyncMethodCall.reply_handler of <asyncconn._AsyncMethodCall object at 0x7f1c5699c7b8>>
<asyncconn._AsyncMethodCall object at 0x7f1c5699c7b8>: to reply_handler at <bound method Connection._subst_reply_handler of <asyncconn.Connection object at 0x7f1c5676de48>>
DESTROY: <asyncconn._AsyncMethodCall object at 0x7f1c5699c7b8>
<asyncconn.Connection object at 0x7f1c5676de48>: chaining up to <bound method _GetDevicesCall._reply_handler of <cupshelpers.cupshelpers._GetDevicesCall object at 0x7f1c5699c710>>
DESTROY: <asyncconn.Connection object at 0x7f1c5676de48>
DESTROY: <asyncpk1.PK1Connection object at 0x7f1c5676de10>
DESTROY: <asyncipp.IPPAuthConnection object at 0x7f1c5676dcf8>
Stopping worker thread
Next task: None
Thread exiting
-<asyncipp._IPPAuthOperation object at 0x7f1c5676dc18>
+<dnssdresolve.DNSSDHostNamesResolver object at 0x7f1c56781f60>
Resolving address for HP%20Color%20LaserJet%20Pro%20M252n%20(A0A867)._ipp._tcp.local
Skipping firewall adjustment: discovery in progress
-<asyncconn.Connection object at 0x7f1c5676de48>
DESTROY: <asyncpk1._PK1AsyncMethodCall object at 0x7f1c5699c780>
-<asyncconn._AsyncMethodCall object at 0x7f1c5699c7b8>
-<asyncpk1.PK1Connection object at 0x7f1c5676de10>
-_PK1AsyncMethodCall: <asyncpk1._PK1AsyncMethodCall object at 0x7f1c5699c780>
dnssd://HP%20Color%20LaserJet%20Pro%20M252n%20(A0A867)._ipp._tcp.local/?uuid=564e4333-4d33-3337-3831-98e7f4a0a867 is at fe80::9ae7:f4ff:fea0:a867 (printer)
All addresses resolved
Skipping firewall adjustment: network printers found
-<dnssdresolve.DNSSDHostNamesResolver object at 0x7f1c56781f60>
printer.local: ['hp-makeuri', '-c', 'printer.local']
-<asyncipp.IPPAuthConnection object at 0x7f1c5676dcf8>
-<_IPPConnectionThread(Thread-1, stopped daemon 139759554090752)>
Next clicked on page 1
nextNPTab: need PPDs loaded
+<ppdsloader.PPDsLoader object at 0x7f1c56985558 (ppdsloader+PPDsLoader at 0xc068ccb960)>
Asking PackageKit to install drivers
Failed to talk to PackageKit: DBusException('The name org.freedesktop.PackageKit was not provided by any .service files',)
Asking CUPS for PPDs
Using polkit-1 connection class
+<asyncipp._IPPAuthOperation object at 0x7f1c5699c5f8>
New IPPConnection
+<_IPPConnectionThread(Thread-2, initial daemon)>
+<asyncipp.IPPAuthConnection object at 0x7f1c55ccf160>
+<asyncpk1.PK1Connection object at 0x7f1c55ccf0f0>
Awaiting further instructions
+<asyncconn.Connection object at 0x7f1c56e25198>
<asyncconn.Connection object at 0x7f1c56e25198>: chaining up to <bound method PPDsLoader._cups_connect_reply of <ppdsloader.PPDsLoader object at 0x7f1c56985558 (ppdsloader+PPDsLoader at 0xc068ccb960)>>
+<asyncconn._AsyncMethodCall object at 0x7f1c55ccf240>
<asyncconn._AsyncMethodCall object at 0x7f1c55ccf240>: calling <function PK1Connection._make_binding.<locals>.binding at 0x7f1c55cadd90>
+_PK1AsyncMethodCall: <asyncpk1._PK1AsyncMethodCall object at 0x7f1c567818d0>
<asyncpk1._PK1AsyncMethodCall object at 0x7f1c567818d0>: calling <function IPPConnection._make_binding.<locals>.<lambda> at 0x7f1c569c2ea0>
+<asyncipp._IPPAuthOperation object at 0x7f1c5699c710>
Next task: (<method 'getPPDs2' of 'cups.Connection' objects>, (), {}, <bound method _PK1AsyncMethodCall._ipp_reply_handler of <asyncpk1._PK1AsyncMethodCall object at 0x7f1c567818d0>>, <bound method _IPPAuthOperation.error_handler of <asyncipp._IPPAuthOperation object at 0x7f1c5699c710>>, <bound method _IPPAuthOperation.auth_handler of <asyncipp._IPPAuthOperation object at 0x7f1c5699c710>>)
Interrupting execution of nextNPTab(): Operations pending
-<asyncipp._IPPAuthOperation object at 0x7f1c5699c5f8>
Call <method 'getPPDs2' of 'cups.Connection' objects>
...success
Awaiting further instructions
<asyncpk1._PK1AsyncMethodCall object at 0x7f1c567818d0>: chaining up to <bound method _AsyncMethodCall.reply_handler of <asyncconn._AsyncMethodCall object at 0x7f1c55ccf240>>
<asyncconn._AsyncMethodCall object at 0x7f1c55ccf240>: to reply_handler at <bound method Connection._subst_reply_handler of <asyncconn.Connection object at 0x7f1c56e25198>>
DESTROY: <asyncconn._AsyncMethodCall object at 0x7f1c55ccf240>
<asyncconn.Connection object at 0x7f1c56e25198>: chaining up to <bound method PPDsLoader._cups_reply of <ppdsloader.PPDsLoader object at 0x7f1c56985558 (ppdsloader+PPDsLoader at 0xc068ccb960)>>

Hewlett-Packard HP Color LaserJet Pro M252n
Trying make/model names
init_makes: 0.462s
mfgl: hewlett-packard
mdll: hp color laserjet pro m252n
remapped mfgl: hp
make: HP
unprefixed mdll: color laserjet pro m 252 n
exact: driverless:ipp://printer.local:631/ipp/print
Checking CMD field
Trying best match
OAKT <= PCL 3 <= PCL 3
PCL 3: match length 5
generic: foomatic-db-compressed-ppds:0/ppd/foomatic-ppd/Generic-PCL_3_Printer-pcl3.ppd
exact-cmd: driverless:ipp://printer.local:631/ipp/print
Removed set() due to CMD mis-match
Valid driver types for this printer in priority order: ['driverless', 'manufacturer-cmd', 'foomatic-recommended-nonpostscript', 'manufacturer-ricoh-ps', 'manufacturer-ricoh-pxl', 'manufacturer', 'pdf', 'foomatic-recommended-postscript', 'hpcups', 'splix', 'foomatic-postscript', 'gutenprint-simplified', 'gutenprint-expert', 'foomatic-gutenprint', 'foomatic', 'cups', 'generic-postscript', 'generic-foomatic-recommended', 'generic-pcl6', 'generic-pcl5e', 'generic-pcl5', 'generic-pcl', 'generic-escp', 'ghostscript', 'generic', 'foomatic-recommended-hpijs', 'hpijs', 'foomatic-hpijs', 'hpcups-plugin', 'hpijs-plugin', 'turboprint']
PPDs with assigned driver types in priority order: [('driverless', 'driverless:ipp://printer.local:631/ipp/print'), ('generic-foomatic-recommended', 'foomatic-db-compressed-ppds:0/ppd/foomatic-ppd/Generic-PCL_3_Printer-pcl3.ppd')]
Resulting PPD list in priority order: ['driverless:ipp://printer.local:631/ipp/print', 'foomatic-db-compressed-ppds:0/ppd/foomatic-ppd/Generic-PCL_3_Printer-pcl3.ppd']
DESTROY: <asyncconn.Connection object at 0x7f1c56e25198>
DESTROY: <asyncpk1.PK1Connection object at 0x7f1c55ccf0f0>
DESTROY: <asyncipp.IPPAuthConnection object at 0x7f1c55ccf160>
Stopping worker thread
Next task: None
Thread exiting
-<asyncipp._IPPAuthOperation object at 0x7f1c5699c710>
<ppdsloader.PPDsLoader object at 0x7f1c56985558 (ppdsloader+PPDsLoader at 0xc068ccb960)>: stored error is None
DESTROY: <ppdsloader.PPDsLoader object at 0x7f1c56985558 (ppdsloader+PPDsLoader at 0xc068ccb960)>
Loaded PPDs this time; try nextNPTab again...
Next clicked on page 1

Hewlett-Packard HP Color LaserJet Pro M252n
Trying make/model names
mfgl: hewlett-packard
mdll: hp color laserjet pro m252n
remapped mfgl: hp
make: HP
unprefixed mdll: color laserjet pro m 252 n
exact: driverless:ipp://printer.local:631/ipp/print
Checking CMD field
Trying best match
OAKT <= PCL 3 <= PCL 3
PCL 3: match length 5
generic: foomatic-db-compressed-ppds:0/ppd/foomatic-ppd/Generic-PCL_3_Printer-pcl3.ppd
exact-cmd: driverless:ipp://printer.local:631/ipp/print
Removed set() due to CMD mis-match
Suitable PPDs found: {'driverless:ipp://printer.local:631/ipp/print': 'exact-cmd', 'foomatic-db-compressed-ppds:0/ppd/foomatic-ppd/Generic-PCL_3_Printer-pcl3.ppd': 'generic'}
Valid driver types for this printer in priority order: ['driverless', 'manufacturer-cmd', 'foomatic-recommended-nonpostscript', 'manufacturer-ricoh-ps', 'manufacturer-ricoh-pxl', 'manufacturer', 'pdf', 'foomatic-recommended-postscript', 'hpcups', 'splix', 'foomatic-postscript', 'gutenprint-simplified', 'gutenprint-expert', 'foomatic-gutenprint', 'foomatic', 'cups', 'generic-postscript', 'generic-foomatic-recommended', 'generic-pcl6', 'generic-pcl5e', 'generic-pcl5', 'generic-pcl', 'generic-escp', 'ghostscript', 'generic', 'foomatic-recommended-hpijs', 'hpijs', 'foomatic-hpijs', 'hpcups-plugin', 'hpijs-plugin', 'turboprint']
PPDs with assigned driver types in priority order: [('driverless', 'driverless:ipp://printer.local:631/ipp/print'), ('generic-foomatic-recommended', 'foomatic-db-compressed-ppds:0/ppd/foomatic-ppd/Generic-PCL_3_Printer-pcl3.ppd')]
Resulting PPD list in priority order: ['driverless:ipp://printer.local:631/ipp/print', 'foomatic-db-compressed-ppds:0/ppd/foomatic-ppd/Generic-PCL_3_Printer-pcl3.ppd']
PPDs in priority order: ['driverless:ipp://printer.local:631/ipp/print', 'foomatic-db-compressed-ppds:0/ppd/foomatic-ppd/Generic-PCL_3_Printer-pcl3.ppd']
Valid driver types for this printer in priority order: ['driverless', 'manufacturer-cmd', 'foomatic-recommended-nonpostscript', 'manufacturer-ricoh-ps', 'manufacturer-ricoh-pxl', 'manufacturer', 'pdf', 'foomatic-recommended-postscript', 'hpcups', 'splix', 'foomatic-postscript', 'gutenprint-simplified', 'gutenprint-expert', 'foomatic-gutenprint', 'foomatic', 'cups', 'generic-postscript', 'generic-foomatic-recommended', 'generic-pcl6', 'generic-pcl5e', 'generic-pcl5', 'generic-pcl', 'generic-escp', 'ghostscript', 'generic', 'foomatic-recommended-hpijs', 'hpijs', 'foomatic-hpijs', 'hpcups-plugin', 'hpijs-plugin', 'turboprint']
PPDs with assigned driver types in priority order: [('driverless', 'driverless:ipp://printer.local:631/ipp/print')]
Resulting PPD list in priority order: ['driverless:ipp://printer.local:631/ipp/print']
ID matched PPDs: ['driverless:ipp://printer.local:631/ipp/print', 'foomatic-db-compressed-ppds:0/ppd/foomatic-ppd/Generic-PCL_3_Printer-pcl3.ppd']
Will fetch ppd? 1
ppd: 'driverless:ipp://printer.local:631/ipp/print'
<authconn.Connection object at 0x7f1c5677dc50>: Operation += fetching PPD
Authentication pass: 1
Authentication: password callback set
Caught non-fatal exception.  Traceback:
File "/usr/share/system-config-printer/newprinter.py", line 4196, in getNPPPD
    ppd = cups.PPD(f)
RuntimeError: ppdOpenFile failed
Continuing anyway..
libcups from CUPS 1.3 not available: never mind
<authconn.Connection object at 0x7f1c5677dc50>: Operation ended
Examining installable options
No PPD so no installable options
Will advance to page 0
-<ppdsloader.PPDsLoader object at 0x7f1c56985558 (ppdsloader+PPDsLoader at 0xc068ccb960)>
-<asyncconn.Connection object at 0x7f1c56e25198>
DESTROY: <asyncpk1._PK1AsyncMethodCall object at 0x7f1c567818d0>
-<asyncconn._AsyncMethodCall object at 0x7f1c55ccf240>
-<asyncpk1.PK1Connection object at 0x7f1c55ccf0f0>
-_PK1AsyncMethodCall: <asyncpk1._PK1AsyncMethodCall object at 0x7f1c567818d0>
-<asyncipp.IPPAuthConnection object at 0x7f1c55ccf160>
-<_IPPConnectionThread(Thread-2, stopped daemon 139759545698048)>
<authconn.Connection object at 0x7f1c5677dc50>: Operation += adding printer Hewlett-Packard-HP-Color-LaserJet-Pro-M252n
Authentication pass: 1
Authentication: password callback set
PolicyKit call to PrinterAdd did not work: dbus.String('server-error-internal-error')
1280: 'server-error-internal-error'
get_notifications
1 printer-state-changed Printer "Hewlett-Packard-HP-Color-LaserJet-Pro-M252n" state changed.
{'notify-charset': 'utf-8',
 'notify-natural-language': 'en-us',
 'notify-printer-uri': 'ipp://vore/printers/Hewlett-Packard-HP-Color-LaserJet-Pro-M252n',
 'notify-sequence-number': 1,
 'notify-subscribed-event': 'printer-state-changed',
 'notify-subscription-id': 2258,
 'notify-text': 'Printer "Hewlett-Packard-HP-Color-LaserJet-Pro-M252n" state '
                'changed.',
 'printer-is-accepting-jobs': False,
 'printer-name': 'Hewlett-Packard-HP-Color-LaserJet-Pro-M252n',
 'printer-state': 5,
 'printer-state-reasons': ['paused'],
 'printer-up-time': 1503734557}
update_jobs
Next notifications fetch in 60s
D-Bus signal from CUPS... calling update
Next notifications fetch in 200ms (update called)
<newprinter.NewPrinterGUI object at 0x7f1c5674be58 (newprinter+NewPrinterGUI at 0xc0689b6d60)>: IPP error dialog (1280, server-error-internal-error)
get_notifications
update_jobs
<authconn.Connection object at 0x7f1c5677dc50>: Operation ended
<authconn.Connection object at 0x7f1c5677dc50>: Operation += adding printer Hewlett-Packard-HP-Color-LaserJet-Pro-M252n
Authentication pass: 1
Authentication: password callback set
PolicyKit call to PrinterAdd did not work: dbus.String('server-error-internal-error')
1280: 'server-error-internal-error'
D-Bus signal from CUPS... calling update
Next notifications fetch in 200ms (update called)
<newprinter.NewPrinterGUI object at 0x7f1c5674be58 (newprinter+NewPrinterGUI at 0xc0689b6d60)>: IPP error dialog (1280, server-error-internal-error)
get_notifications
2 printer-state-changed Printer "Hewlett-Packard-HP-Color-LaserJet-Pro-M252n" state changed.
{'notify-charset': 'utf-8',
 'notify-natural-language': 'en-us',
 'notify-printer-uri': 'ipp://vore/printers/Hewlett-Packard-HP-Color-LaserJet-Pro-M252n',
 'notify-sequence-number': 2,
 'notify-subscribed-event': 'printer-state-changed',
 'notify-subscription-id': 2258,
 'notify-text': 'Printer "Hewlett-Packard-HP-Color-LaserJet-Pro-M252n" state '
                'changed.',
 'printer-is-accepting-jobs': False,
 'printer-name': 'Hewlett-Packard-HP-Color-LaserJet-Pro-M252n',
 'printer-state': 5,
 'printer-state-reasons': ['paused'],
 'printer-up-time': 1503734580}
update_jobs
<authconn.Connection object at 0x7f1c5677dc50>: Operation ended
Canceled subscription 2258
DESTROY: <printerproperties.PrinterPropertiesDialog object at 0x7f1c56766ab0 (printerproperties+PrinterPropertiesDialog at 0xc068aaf460)>
DESTROY: <newprinter.NewPrinterGUI object at 0x7f1c5674be58 (newprinter+NewPrinterGUI at 0xc0689b6d60)>