mitogen does not deploy class level imports #682

opoplawski · 2020-01-17T17:07:47Z

Please drag-drop large logs as text file attachments.

Feel free to write an issue in your preferred format, however if in doubt, use
the following checklist as a guide for what to include.

Which version of Ansible are you running?
2.9.2
Is your version of Ansible patched in any way?
no
Are you running with any custom modules, or module_utils loaded?
https://github.com/opoplawski/ansible-pfsense
Have you tried the latest master version from Git?
Yes
Do you have some idea of what the underlying problem may be?
I think mitogen is not deploying the local module_utils/network/pfsense/__impl tree
Mention your host and target OS and versions
CentOS 7 -> pfSense
Mention your host and target Python versions
host: python 2.7.5, target: python 2.7.16

The playbook works fine without mitogen.

Logs:

TASK [pfsense : Add NWRA AD CA] *******************************************************************
task path: /export/home/orion-admin/ansible-boulder/roles/pfsense/tasks/auth.yml:2
Friday 17 January 2020  10:05:06 -0700 (0:00:00.351)       0:00:09.112 ******** 
[task 18635] 10:05:06.562908 D ansible_mitogen.affinity: CPU mask for WorkerProcess: 0x000010
[task 18635] 10:05:06.606560 D ansible_mitogen.process: will use multiplexer 0 (/tmp/mitogen_unix_bJRFwG.sock) to connect to "inferno.cora.nwra.com"                                                  
[task 18635] 10:05:06.607083 D mitogen.unix: client: connecting to /tmp/mitogen_unix_bJRFwG.sock
[mux  18565] 10:05:06.607680 D mitogen.io: EpollPoller: POLLIN: 11
[task 18635] 10:05:06.608142 D mitogen.unix: client: local ID is 2003, remote is 0
[mux  18565] 10:05:06.608306 D mitogen.unix: listener: accepted connection from PID 18635: unix_client.18635                                                                                          
[mux  18565] 10:05:06.608628 D mitogen.io: Broker(7f50).start_receive(<Stream unix_client.18635 #2c10>)                                                                                               
[task 18635] 10:05:06.608539 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method Router._on_del_route of Router(Broker(f4d0))>, 104, True)                                                   
[mux  18565] 10:05:06.608878 D mitogen.io: Waker(fd=6/8).defer() [immediate]
[task 18635] 10:05:06.608916 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method IdAllocator.on_allocate_id of IdAllocator(Router(Broker(f4d0)))>, 105, True)                                
[mux  18565] 10:05:06.609231 D mitogen.io: EpollPoller.start_receive(76, (<Side of unix_client.18635 fd 76>, <bound method Stream.on_receive of <Stream unix_client.18635 #2c10>>))                   
[task 18635] 10:05:06.609369 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method ModuleResponder._on_get_module of ModuleResponder>, 100, True)                                              
[mux  18565] 10:05:06.609519 D mitogen.io: EpollPoller._control(76)
[task 18635] 10:05:06.609647 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method LogForwarder._on_forward_log of LogForwarder(Router(Broker(f4d0)))>, 102, True)                             
[mux  18565] 10:05:06.609798 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:06.610044 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method RouteMonitor._on_add_route of RouteMonitor()>, 103, True)                                                   
[mux  18565] 10:05:06.610149 D mitogen.io: EpollPoller.poll(None)
[task 18635] 10:05:06.610288 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method RouteMonitor._on_del_route of RouteMonitor()>, 104, True)                                                   
[task 18635] 10:05:06.610488 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method Router._on_detaching of Router(Broker(f4d0))>, 109, True)                                                   
[task 18635] 10:05:06.610812 D mitogen.io: Broker(f4d0).start_receive(<Stream unix_listener.18565 #e0d0>)                                                                                             
[task 18635] 10:05:06.610990 D mitogen.io: Waker(fd=11/12).defer() [fd=12]
[task 18635] 10:05:06.611625 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method Receiver._on_receive of Receiver(Router(Broker(f4d0)), None)>, 1000, False)                                 
[task 18635] 10:05:06.611826 D mitogen.io: Waker(fd=11/12).defer() [fd=12]
[task 18635] 10:05:06.611999 D mitogen.io: Receiver(Router(Broker(f4d0)), 1000).get(timeout=None, block=True)                                                                                         
[task 18635] 10:05:06.612199 D mitogen.io: Latch(0x7fc9ac59ee50, size=0, t='MainThread').get(timeout=None, block=True)                                                                                
[task 18635] 10:05:06.612498 D mitogen.io: Latch(0x7fc9ac59ee50, size=0, t='MainThread')._get_sleep(timeout=None, block=True, fd=14/15)                                                               
[task 18635] 10:05:06.612656 D mitogen.io: PollPoller.poll(None)
[task 18635] 10:05:06.612844 D mitogen.io: Poller: POLLIN for 11
[task 18635] 10:05:06.613056 D mitogen.io: Waker(fd=11/12).on_receive()
[task 18635] 10:05:06.613217 D mitogen.io: Router(Broker(f4d0))._async_route(Message(0, 2003, 2003, 110, 1000, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..1020), None)
[task 18635] 10:05:06.613430 D mitogen.io: MitogenProtocol(unix_listener.18565)._send(Message(0, 2003, 2003, 110, 1000, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..1020))                                                                                                
[task 18635] 10:05:06.613678 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[mux  18565] 10:05:06.613686 D mitogen.io: EpollPoller: POLLIN: 76
[task 18635] 10:05:06.613910 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:06.613987 D mitogen.io: MitogenProtocol(unix_client.18635).on_receive()
[task 18635] 10:05:06.614182 D mitogen.io: Poller: POLLIN for 11
[mux  18565] 10:05:06.614298 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2003, 2003, 110, 1000, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..1020), <Stream unix_client.18635 #2c10>)                                                                       
[task 18635] 10:05:06.614451 D mitogen.io: Waker(fd=11/12).on_receive()
[mux  18565] 10:05:06.614610 D mitogen.io: Receiver(Router(Broker(7f50)), 110)._on_receive(Message(0, 2003, 0, 110, 1000, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..1020))                                                                                              
[task 18635] 10:05:06.614694 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[mux  18565] 10:05:06.614861 D mitogen.io: Latch(0x7fc9ac6f5690, size=0, t='mitogen.broker').put(Message(0, 2003, 0, 110, 1000, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..1020))                                                                                        
[task 18635] 10:05:06.614913 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:06.615121 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.broker').put(Receiver(Router(Broker(7f50)), 110))                                                                 
[mux  18565] 10:05:06.615292 D mitogen.io: Latch(0x7fc9ac6f5710, size=1, t='mitogen.broker').put() -> waking wfd=21                                                                                   
[mux  18565] 10:05:06.615479 D mitogen.io: PollPoller: POLLIN|POLLHUP for 20
[mux  18565] 10:05:06.615672 D mitogen.io: Latch(0x7fc9ac6f5710, size=1, t='mitogen.Pool.5490.4').get() wake -> Receiver(Router(Broker(7f50)), 110)                                                   
[mux  18565] 10:05:06.615937 D mitogen.io: Receiver(Router(Broker(7f50)), 110).get(timeout=None, block=False)                                                                                         
[mux  18565] 10:05:06.616123 D mitogen.io: Latch(0x7fc9ac6f5690, size=1, t='mitogen.Pool.5490.4').get(timeout=None, block=False)                                                                      
[mux  18565] 10:05:06.616292 D mitogen.io: Latch(0x7fc9ac6f5690, size=1, t='mitogen.Pool.5490.4').get() -> Message(0, 2003, 0, 110, 1000, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..1020)                                                                               
[mux  18565] 10:05:06.616496 D mitogen.io: Message(0, 2003, 0, 110, 1000, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..1020).unpickle()                                 
[mux  18565] 10:05:06.616734 D mitogen.io: Message(0, 2003, 0, 110, 1000, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..1020).unpickle()                                 
[mux  18565] 10:05:06.617193 D mitogen.io: Latch(0x7fc9ac404090, size=0, t='mitogen.Pool.5490.4').put({u'msg': None, u'init_child_result': {u'fork_context': Context(3, u'fork.65779'), u'home_dir': u'/home/orion-admin', u'good_temp_dir': u'/tmp'}, u'via': None, u'context': Context(2, u'ssh.inferno.cora.nwra.com')})                                                                                 
[mux  18565] 10:05:06.617408 D mitogen.io: Latch(0x7fc9ac404090, size=1, t='mitogen.Pool.5490.4').get(timeout=None, block=True)                                                                       
[mux  18565] 10:05:06.617583 D mitogen.io: Latch(0x7fc9ac404090, size=1, t='mitogen.Pool.5490.4').get() -> {u'msg': None, u'init_child_result': {u'fork_context': Context(3, u'fork.65779'), u'home_dir': u'/home/orion-admin', u'good_temp_dir': u'/tmp'}, u'via': None, u'context': Context(2, u'ssh.inferno.cora.nwra.com')}                                                                             
[mux  18565] 10:05:06.617891 D mitogen.io: Latch(0x7fc9ac432a10, size=0, t='mitogen.Pool.5490.4').put({u'msg': None, u'init_child_result': {u'fork_context': Context(1003, u'fork.68631'), u'home_dir': u'/root', u'good_temp_dir': u'/tmp'}, u'via': Context(2, u'ssh.inferno.cora.nwra.com'), u'context': Context(4, u'ssh.inferno.cora.nwra.com.sudo.root')})                                            
[mux  18565] 10:05:06.618140 D mitogen.io: Latch(0x7fc9ac432a10, size=1, t='mitogen.Pool.5490.4').get(timeout=None, block=True)                                                                       
[mux  18565] 10:05:06.618362 D mitogen.io: Latch(0x7fc9ac432a10, size=1, t='mitogen.Pool.5490.4').get() -> {u'msg': None, u'init_child_result': {u'fork_context': Context(1003, u'fork.68631'), u'home_dir': u'/root', u'good_temp_dir': u'/tmp'}, u'via': Context(2, u'ssh.inferno.cora.nwra.com'), u'context': Context(4, u'ssh.inferno.cora.nwra.com.sudo.root')}                                        
[mux  18565] 10:05:06.618631 D mitogen.io: Waker(fd=6/8).defer() [fd=8]
[mux  18565] 10:05:06.618821 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.Pool.5490.4').get(timeout=None, block=True)                                                                       
[mux  18565] 10:05:06.619043 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.Pool.5490.4')._get_sleep(timeout=None, block=True, fd=20/21)                                                      
[mux  18565] 10:05:06.619228 D mitogen.io: PollPoller.poll(None)
[mux  18565] 10:05:06.619442 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.619628 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.619788 D mitogen.io: EpollPoller: POLLIN: 6
[mux  18565] 10:05:06.619946 D mitogen.io: Waker(fd=6/8).on_receive()
[mux  18565] 10:05:06.620105 D mitogen.io: Router(Broker(7f50))._async_route(Message(2003, 0, 0, 1000, None, '\x80\x02}q\x01(X\x03\x00\x00\x00msgq\x02NX\x11\x00\x00\x00init_child_resultq\x03}q\x04(X\x0c\x00\x00\x00'..287), None)                                                                     
[mux  18565] 10:05:06.620297 D mitogen.io: MitogenProtocol(unix_client.18635)._send(Message(2003, 0, 0, 1000, None, '\x80\x02}q\x01(X\x03\x00\x00\x00msgq\x02NX\x11\x00\x00\x00init_child_resultq\x03}q\x04(X\x0c\x00\x00\x00'..287))                                                                    
[mux  18565] 10:05:06.620548 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:06.620559 D mitogen.io: Poller: POLLIN for 13
[mux  18565] 10:05:06.620802 D mitogen.io: EpollPoller.poll(None)
[task 18635] 10:05:06.620897 D mitogen.io: MitogenProtocol(unix_listener.18565).on_receive()
[task 18635] 10:05:06.621204 D mitogen.io: Router(Broker(f4d0))._async_route(Message(2003, 0, 0, 1000, 0, '\x80\x02}q\x01(X\x03\x00\x00\x00msgq\x02NX\x11\x00\x00\x00init_child_resultq\x03}q\x04(X\x0c\x00\x00\x00'..287), <Stream unix_listener.18565 #e0d0>)                                          
[task 18635] 10:05:06.621478 D mitogen.io: Receiver(Router(Broker(f4d0)), 1000)._on_receive(Message(2003, 0, 0, 1000, 0, '\x80\x02}q\x01(X\x03\x00\x00\x00msgq\x02NX\x11\x00\x00\x00init_child_resultq\x03}q\x04(X\x0c\x00\x00\x00'..287))                                                               
[task 18635] 10:05:06.621701 D mitogen.io: Latch(0x7fc9ac59ee50, size=0, t='mitogen.broker').put(Message(2003, 0, 0, 1000, 0, '\x80\x02}q\x01(X\x03\x00\x00\x00msgq\x02NX\x11\x00\x00\x00init_child_resultq\x03}q\x04(X\x0c\x00\x00\x00'..287))                                                          
[task 18635] 10:05:06.621966 D mitogen.io: Latch(0x7fc9ac59ee50, size=1, t='mitogen.broker').put() -> waking wfd=15                                                                                   
[task 18635] 10:05:06.622201 D mitogen.io: PollPoller: POLLIN|POLLHUP for 14
[task 18635] 10:05:06.622429 D mitogen.io: Latch(0x7fc9ac59ee50, size=1, t='MainThread').get() wake -> Message(2003, 0, 0, 1000, 0, '\x80\x02}q\x01(X\x03\x00\x00\x00msgq\x02NX\x11\x00\x00\x00init_child_resultq\x03}q\x04(X\x0c\x00\x00\x00'..287)                                                     
[task 18635] 10:05:06.622663 D mitogen.io: Message(2003, 0, 0, 1000, 0, '\x80\x02}q\x01(X\x03\x00\x00\x00msgq\x02NX\x11\x00\x00\x00init_child_resultq\x03}q\x04(X\x0c\x00\x00\x00'..287).unpickle()   
[task 18635] 10:05:06.623818 D ansible_mitogen.planner: <class 'ansible_mitogen.planner.BinaryPlanner'> rejected u'pfsense_ca'                                                                        
[task 18635] 10:05:06.624058 D ansible_mitogen.planner: <class 'ansible_mitogen.planner.NewStylePlanner'> accepted u'pfsense_ca' (filename u'/export/home/orion-admin/ansible-boulder/library/pfsense_ca.py')                                                                                            
[task 18635] 10:05:06.623402 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[task 18635] 10:05:06.624726 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method Receiver._on_receive of Receiver(Router(Broker(f4d0)), None)>, 1001, False)                                 
[task 18635] 10:05:06.625045 D mitogen.io: Waker(fd=11/12).defer() [fd=12]
[task 18635] 10:05:06.625251 D mitogen.io: Receiver(Router(Broker(f4d0)), 1001).get(timeout=None, block=True)                                                                                         
[task 18635] 10:05:06.625450 D mitogen.io: Latch(0x7fc9ac59e810, size=0, t='MainThread').get(timeout=None, block=True)                                                                                
[task 18635] 10:05:06.625670 D mitogen.io: Latch(0x7fc9ac59e810, size=0, t='MainThread')._get_sleep(timeout=None, block=True, fd=14/15)                                                               
[task 18635] 10:05:06.625852 D mitogen.io: PollPoller.poll(None)
[task 18635] 10:05:06.624821 D mitogen.io: Poller.poll(None)
[task 18635] 10:05:06.626238 D mitogen.io: Poller: POLLIN for 11
[task 18635] 10:05:06.626442 D mitogen.io: Waker(fd=11/12).on_receive()
[task 18635] 10:05:06.626683 D mitogen.io: Router(Broker(f4d0))._async_route(Message(0, 2003, 2003, 110, 1001, '\x80\x02X)\x00\x00\x00ansible_mitogen.services.ModuleDepServiceq\x01'..649), None)    
[task 18635] 10:05:06.626942 D mitogen.io: MitogenProtocol(unix_listener.18565)._send(Message(0, 2003, 2003, 110, 1001, '\x80\x02X)\x00\x00\x00ansible_mitogen.services.ModuleDepServiceq\x01'..649)) 
[task 18635] 10:05:06.627187 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[mux  18565] 10:05:06.627210 D mitogen.io: EpollPoller: POLLIN: 76
[task 18635] 10:05:06.627422 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:06.627579 D mitogen.io: MitogenProtocol(unix_client.18635).on_receive()
[mux  18565] 10:05:06.627804 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2003, 2003, 110, 1001, '\x80\x02X)\x00\x00\x00ansible_mitogen.services.ModuleDepServiceq\x01'..649), <Stream unix_client.18635 #2c10>)                                                                           
[mux  18565] 10:05:06.627998 D mitogen.io: Receiver(Router(Broker(7f50)), 110)._on_receive(Message(0, 2003, 0, 110, 1001, '\x80\x02X)\x00\x00\x00ansible_mitogen.services.ModuleDepServiceq\x01'..649))                                                                                                  
[mux  18565] 10:05:06.628212 D mitogen.io: Latch(0x7fc9ac6f5690, size=0, t='mitogen.broker').put(Message(0, 2003, 0, 110, 1001, '\x80\x02X)\x00\x00\x00ansible_mitogen.services.ModuleDepServiceq\x01'..649))                                                                                            
[mux  18565] 10:05:06.628395 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.broker').put(Receiver(Router(Broker(7f50)), 110))                                                                 
[mux  18565] 10:05:06.628585 D mitogen.io: Latch(0x7fc9ac6f5710, size=1, t='mitogen.broker').put() -> waking wfd=23                                                                                   
[mux  18565] 10:05:06.628803 D mitogen.io: PollPoller: POLLIN|POLLHUP for 22
[mux  18565] 10:05:06.629002 D mitogen.io: Latch(0x7fc9ac6f5710, size=1, t='mitogen.Pool.5490.5').get() wake -> Receiver(Router(Broker(7f50)), 110)                                                   
[mux  18565] 10:05:06.629252 D mitogen.io: Receiver(Router(Broker(7f50)), 110).get(timeout=None, block=False)                                                                                         
[mux  18565] 10:05:06.629590 D mitogen.io: Latch(0x7fc9ac6f5690, size=1, t='mitogen.Pool.5490.5').get(timeout=None, block=False)                                                                      
[mux  18565] 10:05:06.629757 D mitogen.io: Latch(0x7fc9ac6f5690, size=1, t='mitogen.Pool.5490.5').get() -> Message(0, 2003, 0, 110, 1001, '\x80\x02X)\x00\x00\x00ansible_mitogen.services.ModuleDepServiceq\x01'..649)                                                                                   
[mux  18565] 10:05:06.629958 D mitogen.io: Message(0, 2003, 0, 110, 1001, '\x80\x02X)\x00\x00\x00ansible_mitogen.services.ModuleDepServiceq\x01'..649).unpickle()                                     
[mux  18565] 10:05:06.630251 D mitogen.io: Message(0, 2003, 0, 110, 1001, '\x80\x02X)\x00\x00\x00ansible_mitogen.services.ModuleDepServiceq\x01'..649).unpickle()                                     
[mux  18565] 10:05:06.630555 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.633830 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.777922 D mitogen.io: Waker(fd=6/8).defer() [fd=8]
[mux  18565] 10:05:06.778228 D mitogen.io: EpollPoller: POLLIN: 6
[mux  18565] 10:05:06.778396 D mitogen.io: Waker(fd=6/8).on_receive()
[mux  18565] 10:05:06.778545 D mitogen.io: Router(Broker(7f50))._async_route(Message(2003, 0, 0, 1001, None, '\x80\x02}q\x01(X\x07\x00\x00\x00builtinq\x02]q\x03(X\x1a\x00\x00\x00ansible.module_utils.'..1376), None)                                                                                   
[mux  18565] 10:05:06.778719 D mitogen.io: MitogenProtocol(unix_client.18635)._send(Message(2003, 0, 0, 1001, None, '\x80\x02}q\x01(X\x07\x00\x00\x00builtinq\x02]q\x03(X\x1a\x00\x00\x00ansible.module_utils.'..1376))                                                                                  
[mux  18565] 10:05:06.778934 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:06.778954 D mitogen.io: Poller: POLLIN for 13
[mux  18565] 10:05:06.779165 D mitogen.io: EpollPoller.poll(None)
[task 18635] 10:05:06.779325 D mitogen.io: MitogenProtocol(unix_listener.18565).on_receive()
[mux  18565] 10:05:06.779486 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.Pool.5490.5').get(timeout=None, block=True)                                                                       
[task 18635] 10:05:06.779621 D mitogen.io: Router(Broker(f4d0))._async_route(Message(2003, 0, 0, 1001, 0, '\x80\x02}q\x01(X\x07\x00\x00\x00builtinq\x02]q\x03(X\x1a\x00\x00\x00ansible.module_utils.'..1376), <Stream unix_listener.18565 #e0d0>)                                                        
[mux  18565] 10:05:06.779778 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.Pool.5490.5')._get_sleep(timeout=None, block=True, fd=22/23)                                                      
[task 18635] 10:05:06.779927 D mitogen.io: Receiver(Router(Broker(f4d0)), 1001)._on_receive(Message(2003, 0, 0, 1001, 0, '\x80\x02}q\x01(X\x07\x00\x00\x00builtinq\x02]q\x03(X\x1a\x00\x00\x00ansible.module_utils.'..1376))                                                                             
[mux  18565] 10:05:06.780051 D mitogen.io: PollPoller.poll(None)
[task 18635] 10:05:06.780192 D mitogen.io: Latch(0x7fc9ac59e810, size=0, t='mitogen.broker').put(Message(2003, 0, 0, 1001, 0, '\x80\x02}q\x01(X\x07\x00\x00\x00builtinq\x02]q\x03(X\x1a\x00\x00\x00ansible.module_utils.'..1376))                                                                        
[task 18635] 10:05:06.780411 D mitogen.io: Latch(0x7fc9ac59e810, size=1, t='mitogen.broker').put() -> waking wfd=15                                                                                   
[task 18635] 10:05:06.780596 D mitogen.io: PollPoller: POLLIN|POLLHUP for 14
[task 18635] 10:05:06.780769 D mitogen.io: Latch(0x7fc9ac59e810, size=1, t='MainThread').get() wake -> Message(2003, 0, 0, 1001, 0, '\x80\x02}q\x01(X\x07\x00\x00\x00builtinq\x02]q\x03(X\x1a\x00\x00\x00ansible.module_utils.'..1376)                                                                   
[task 18635] 10:05:06.780941 D mitogen.io: Message(2003, 0, 0, 1001, 0, '\x80\x02}q\x01(X\x07\x00\x00\x00builtinq\x02]q\x03(X\x1a\x00\x00\x00ansible.module_utils.'..1376).unpickle()                 
[task 18635] 10:05:06.781269 D mitogen.parent: starting function call to fork.68631: ansible_mitogen.target.spawn_isolated_child()                                                                    
[task 18635] 10:05:06.781593 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method Receiver._on_receive of Receiver(Router(Broker(f4d0)), None)>, 1002, False)                                 
[task 18635] 10:05:06.781795 D mitogen.io: Waker(fd=11/12).defer() [fd=12]
[task 18635] 10:05:06.781973 D mitogen.io: Receiver(Router(Broker(f4d0)), 1002).get(timeout=None, block=True)                                                                                         
[task 18635] 10:05:06.782153 D mitogen.io: Latch(0x7fc9ac58cbd0, size=0, t='MainThread').get(timeout=None, block=True)                                                                                
[task 18635] 10:05:06.782350 D mitogen.io: Latch(0x7fc9ac58cbd0, size=0, t='MainThread')._get_sleep(timeout=None, block=True, fd=14/15)                                                               
[task 18635] 10:05:06.782521 D mitogen.io: PollPoller.poll(None)
[task 18635] 10:05:06.782716 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[task 18635] 10:05:06.782894 D mitogen.io: Poller.poll(None)
[task 18635] 10:05:06.783130 D mitogen.io: Poller: POLLIN for 11
[task 18635] 10:05:06.783313 D mitogen.io: Waker(fd=11/12).on_receive()
[task 18635] 10:05:06.783492 D mitogen.io: Router(Broker(f4d0))._async_route(Message(1003, 2003, 2003, 101, 1002, '\x80\x02(NX\x16\x00\x00\x00ansible_mitogen.targetNX\x14\x00\x00\x00spawn_isolate'..90), None)                                                                                         
[task 18635] 10:05:06.783683 D mitogen.io: MitogenProtocol(unix_listener.18565)._send(Message(1003, 2003, 2003, 101, 1002, '\x80\x02(NX\x16\x00\x00\x00ansible_mitogen.targetNX\x14\x00\x00\x00spawn_isolate'..90))                                                                                      
[mux  18565] 10:05:06.783943 D mitogen.io: EpollPoller: POLLIN: 76
[task 18635] 10:05:06.783952 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[mux  18565] 10:05:06.784186 D mitogen.io: MitogenProtocol(unix_client.18635).on_receive()
[task 18635] 10:05:06.784173 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:06.784411 D mitogen.io: Router(Broker(7f50))._async_route(Message(1003, 2003, 2003, 101, 1002, '\x80\x02(NX\x16\x00\x00\x00ansible_mitogen.targetNX\x14\x00\x00\x00spawn_isolate'..90), <Stream unix_client.18635 #2c10>)                                                             
[mux  18565] 10:05:06.784584 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(1003, 2003, 0, 101, 1002, '\x80\x02(NX\x16\x00\x00\x00ansible_mitogen.targetNX\x14\x00\x00\x00spawn_isolate'..90))                                                                                   
[mux  18565] 10:05:06.784774 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.784921 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.787630 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.787840 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.788004 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen\x0010\x00Dispatcher: dispatching (None, u'ansibl"..115), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.788202 D mitogen.[fork.68631]: Dispatcher: dispatching (None, u'ansible_mitogen.target', None, u'spawn_isolated_child', (), Kwargs({}))                                         
[mux  18565] 10:05:06.788351 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.788493 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.789537 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.789703 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.789862 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00upgraded Poller with KqueuePolle'..106), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.790077 D mitogen.parent.[fork.68631]: upgraded Poller with KqueuePoller (new: 4 readers, 0 writers; old: 4 readers, 0 writers)                                                  
[mux  18565] 10:05:06.790240 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00upgrading Router(Broker(79d0)) w'..88), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.790413 D mitogen.parent.[fork.68631]: upgrading Router(Broker(79d0)) with capabilities to start new children                                                                    
[mux  18565] 10:05:06.790579 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 105, 1000, ''..0), <Stream ssh.inferno.cora.nwra.com #0e90>)                                      
[mux  18565] 10:05:06.790747 D mitogen: IdAllocator(Router(Broker(7f50))): allocating [2004..3004)
[mux  18565] 10:05:06.790894 D mitogen: IdAllocator(Router(Broker(7f50))): allocating [2004..3004) to Context(1003, u'fork.68631')                                                                    
[mux  18565] 10:05:06.791070 D mitogen.io: Waker(fd=6/8).defer() [immediate]
[mux  18565] 10:05:06.791214 D mitogen.io: Router(Broker(7f50))._async_route(Message(1003, 0, 0, 1000, None, '\x80\x02M\xd4\x07M\xbc\x0b\x86q\x01.'..12), None)                                       
[mux  18565] 10:05:06.791368 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(1003, 0, 0, 1000, None, '\x80\x02M\xd4\x07M\xbc\x0b\x86q\x01.'..12))                              
[mux  18565] 10:05:06.791550 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.791699 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.793922 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.794105 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.794280 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00creating connection to context 2'..72), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.794465 D mitogen.parent.[fork.68631]: creating connection to context 2004 using mitogen.fork                                                                                    
[mux  18565] 10:05:06.794625 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.794781 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.798336 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.798523 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.798695 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00child for Connection(None) start'..94), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.798879 D mitogen.parent.[fork.68631]: child for Connection(None) started: pid:72377 stdin:15 stdout:15 stderr:None                                                              
[mux  18565] 10:05:06.799051 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.799209 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.801399 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.801589 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.801816 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00BootstrapProtocol(fork.72377): n'..78), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.802001 D mitogen.parent.[fork.68631]: BootstrapProtocol(fork.72377): new child booted successfully                                                                              
[mux  18565] 10:05:06.802177 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.802332 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.802520 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.802688 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.802907 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen\x0010\x00Router(Broker(79d0)): registering Conte'..109), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.803105 D mitogen.[fork.68631]: Router(Broker(79d0)): registering Context(2004, u'fork.72377') to stream <Stream fork.72377 #e8d0>                                               
[mux  18565] 10:05:06.803303 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.route_monitor\x0010\x00Adding route to 2004 via '..75), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.803563 D mitogen.route_monitor.[ssh.inferno.cora.nwra.com.sudo.root]: Adding route to 2004 via <Stream fork.68631 #7950>                                                        
[mux  18565] 10:05:06.803764 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.803928 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.804100 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.804263 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.804488 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.parent\x0010\x00Router(Broker(2a90)): adding rou'..98), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.804710 D mitogen.parent.[ssh.inferno.cora.nwra.com.sudo.root]: Router(Broker(2a90)): adding route to context 2004 via <Stream fork.68631 #7950>                                 
[mux  18565] 10:05:06.804884 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.route_monitor\x0010\x00Adding route to 2004 via '..74), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.805080 D mitogen.route_monitor.[ssh.inferno.cora.nwra.com]: Adding route to 2004 via <Stream sudo.root #38d0>                                                                   
[mux  18565] 10:05:06.805259 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.parent\x0010\x00Router(Broker(2b90)): adding rou'..97), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.805444 D mitogen.parent.[ssh.inferno.cora.nwra.com]: Router(Broker(2b90)): adding route to context 2004 via <Stream sudo.root #38d0>                                            
[mux  18565] 10:05:06.805617 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 103, 0, '2004:fork.72377'..15), <Stream ssh.inferno.cora.nwra.com #0e90>)                               
[mux  18565] 10:05:06.805819 D mitogen.route_monitor: Adding route to 2004 via <Stream ssh.inferno.cora.nwra.com #0e90>                                                                               
[mux  18565] 10:05:06.805981 D mitogen.parent: Router(Broker(7f50)): adding route to context 2004 via <Stream ssh.inferno.cora.nwra.com #0e90>                                                        
[mux  18565] 10:05:06.806163 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.806324 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.806485 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.806648 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.806829 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'ansible_mitogen.target\x0010\x00create_fork_child() -> C'..77), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.807047 D ansible_mitogen.target.[fork.68631]: create_fork_child() -> Context(2004, u'fork.72377')                                                                               
[mux  18565] 10:05:06.807221 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen\x0010\x00Dispatcher: Message(1003, 2003, 0, 101,'..176), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.807439 D mitogen.[fork.68631]: Dispatcher: Message(1003, 2003, 0, 101, 1002, '\x80\x02(NX\x16\x00\x00\x00ansible_mitogen.targetNX\x14\x00\x00\x00spawn_isolate'..90) -> Context(2004, u'fork.72377')                                                                               
[mux  18565] 10:05:06.807647 D mitogen.io: Router(Broker(7f50))._async_route(Message(2003, 1003, 1003, 1002, 0, '\x80\x02cmitogen.core\n_unpickle_context\nq\x01M\xd4\x07X\n\x00\x00\x00fork.7'..61), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                          
[mux  18565] 10:05:06.807900 D mitogen.io: MitogenProtocol(unix_client.18635)._send(Message(2003, 1003, 1003, 1002, 0, '\x80\x02cmitogen.core\n_unpickle_context\nq\x01M\xd4\x07X\n\x00\x00\x00fork.7'..61))                                                                                             
[mux  18565] 10:05:06.808155 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:06.808181 D mitogen.io: Poller: POLLIN for 13
[mux  18565] 10:05:06.808381 D mitogen.io: EpollPoller.poll(None)
[task 18635] 10:05:06.808494 D mitogen.io: MitogenProtocol(unix_listener.18565).on_receive()
[mux  18565] 10:05:06.808624 D mitogen.io: EpollPoller: POLLIN: 77
[task 18635] 10:05:06.808761 D mitogen.io: Router(Broker(f4d0))._async_route(Message(2003, 1003, 1003, 1002, 0, '\x80\x02cmitogen.core\n_unpickle_context\nq\x01M\xd4\x07X\n\x00\x00\x00fork.7'..61), <Stream unix_listener.18565 #e0d0>)                                                                
[mux  18565] 10:05:06.808862 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[task 18635] 10:05:06.809061 D mitogen.io: Receiver(Router(Broker(f4d0)), 1002)._on_receive(Message(2003, 1003, 1003, 1002, 0, '\x80\x02cmitogen.core\n_unpickle_context\nq\x01M\xd4\x07X\n\x00\x00\x00fork.7'..61))                                                                                     
[mux  18565] 10:05:06.809123 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen\x0010\x00Router(Broker(e590)): registering Conte'..101), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[task 18635] 10:05:06.809308 D mitogen.io: Latch(0x7fc9ac58cbd0, size=0, t='mitogen.broker').put(Message(2003, 1003, 1003, 1002, 0, '\x80\x02cmitogen.core\n_unpickle_context\nq\x01M\xd4\x07X\n\x00\x00\x00fork.7'..61))                                                                                
[mux  18565] 10:05:06.809431 D mitogen.[fork.72377]: Router(Broker(e590)): registering Context(1003, u'parent') to stream <Stream parent #ec50>                                                       
[task 18635] 10:05:06.809564 D mitogen.io: Latch(0x7fc9ac58cbd0, size=1, t='mitogen.broker').put() -> waking wfd=15                                                                                   
[mux  18565] 10:05:06.809687 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen\x0010\x00Python version is 2.7.16 (default, May '..144), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[task 18635] 10:05:06.809824 D mitogen.io: PollPoller: POLLIN|POLLHUP for 14
[mux  18565] 10:05:06.809967 D mitogen.[fork.72377]: Python version is 2.7.16 (default, May 10 2019, 18:33:28)                                                                                        
[GCC 4.2.1 Compatible FreeBSD Clang 6.0.0 (tags/RELEASE_600/final 326565)]
[task 18635] 10:05:06.810070 D mitogen.io: Latch(0x7fc9ac58cbd0, size=1, t='MainThread').get() wake -> Message(2003, 1003, 1003, 1002, 0, '\x80\x02cmitogen.core\n_unpickle_context\nq\x01M\xd4\x07X\n\x00\x00\x00fork.7'..61)                                                                           
[mux  18565] 10:05:06.810223 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen\x0010\x00Parent is context 1003 (parent); my ID '..57), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[task 18635] 10:05:06.810316 D mitogen.io: Message(2003, 1003, 1003, 1002, 0, '\x80\x02cmitogen.core\n_unpickle_context\nq\x01M\xd4\x07X\n\x00\x00\x00fork.7'..61).unpickle()                         
[mux  18565] 10:05:06.810483 D mitogen.[fork.72377]: Parent is context 1003 (parent); my ID is 2004
[mux  18565] 10:05:06.810713 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen\x0010\x00pid:72377 ppid:68631 uid:0/0, gid:0/0 h'..77), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[task 18635] 10:05:06.810763 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method Receiver._on_receive of Receiver(Router(Broker(f4d0)), None)>, 1003, False)                                 
[mux  18565] 10:05:06.810978 D mitogen.[fork.72377]: pid:72377 ppid:68631 uid:0/0, gid:0/0 host:'inferno.cora.nwra.com'                                                                               
[task 18635] 10:05:06.811008 D mitogen.io: Waker(fd=11/12).defer() [fd=12]
[task 18635] 10:05:06.811233 D mitogen.io: Receiver(Router(Broker(f4d0)), 1003).get(timeout=None, block=True)                                                                                         
[mux  18565] 10:05:06.811233 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, "mitogen\x0010\x00Recovered sys.executable: '/usr/local/b"..63), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.811474 D mitogen.[fork.72377]: Recovered sys.executable: '/usr/local/bin/python2.7'                                                                                             
[task 18635] 10:05:06.811452 D mitogen.io: Latch(0x7fc9ac58ce10, size=0, t='MainThread').get(timeout=None, block=True)                                                                                
[mux  18565] 10:05:06.811681 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:06.811703 D mitogen.io: Latch(0x7fc9ac58ce10, size=0, t='MainThread')._get_sleep(timeout=None, block=True, fd=14/15)                                                               
[task 18635] 10:05:06.811905 D mitogen.io: PollPoller.poll(None)
[mux  18565] 10:05:06.811899 D mitogen.io: EpollPoller.poll(None)
[task 18635] 10:05:06.812159 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[task 18635] 10:05:06.812328 D mitogen.io: Poller.poll(None)
[task 18635] 10:05:06.812494 D mitogen.io: Poller: POLLIN for 11
[task 18635] 10:05:06.812667 D mitogen.io: Waker(fd=11/12).on_receive()
[task 18635] 10:05:06.812839 D mitogen.io: Router(Broker(f4d0))._async_route(Message(0, 2003, 2003, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x1b\x00\x00\x00propa'..1480), None)                                                                                       
[task 18635] 10:05:06.813021 D mitogen.io: MitogenProtocol(unix_listener.18565)._send(Message(0, 2003, 2003, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x1b\x00\x00\x00propa'..1480))                                                                                    
[task 18635] 10:05:06.813257 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[mux  18565] 10:05:06.813255 D mitogen.io: EpollPoller: POLLIN: 76
[mux  18565] 10:05:06.813506 D mitogen.io: MitogenProtocol(unix_client.18635).on_receive()
[task 18635] 10:05:06.813490 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:06.813746 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2003, 2003, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x1b\x00\x00\x00propa'..1480), <Stream unix_client.18635 #2c10>)                                                           
[mux  18565] 10:05:06.813936 D mitogen.io: Receiver(Router(Broker(7f50)), 110)._on_receive(Message(0, 2003, 0, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x1b\x00\x00\x00propa'..1480))                                                                                  
[mux  18565] 10:05:06.814111 D mitogen.io: Latch(0x7fc9ac6f5690, size=0, t='mitogen.broker').put(Message(0, 2003, 0, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x1b\x00\x00\x00propa'..1480))                                                                            
[mux  18565] 10:05:06.814284 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.broker').put(Receiver(Router(Broker(7f50)), 110))                                                                 
[mux  18565] 10:05:06.814449 D mitogen.io: Latch(0x7fc9ac6f5710, size=1, t='mitogen.broker').put() -> waking wfd=25                                                                                   
[mux  18565] 10:05:06.814640 D mitogen.io: PollPoller: POLLIN|POLLHUP for 24
[mux  18565] 10:05:06.814811 D mitogen.io: Latch(0x7fc9ac6f5710, size=1, t='mitogen.Pool.5490.6').get() wake -> Receiver(Router(Broker(7f50)), 110)                                                   
[mux  18565] 10:05:06.814979 D mitogen.io: Receiver(Router(Broker(7f50)), 110).get(timeout=None, block=False)                                                                                         
[mux  18565] 10:05:06.815137 D mitogen.io: Latch(0x7fc9ac6f5690, size=1, t='mitogen.Pool.5490.6').get(timeout=None, block=False)                                                                      
[mux  18565] 10:05:06.815285 D mitogen.io: Latch(0x7fc9ac6f5690, size=1, t='mitogen.Pool.5490.6').get() -> Message(0, 2003, 0, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x1b\x00\x00\x00propa'..1480)                                                                   
[mux  18565] 10:05:06.815462 D mitogen.io: Message(0, 2003, 0, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x1b\x00\x00\x00propa'..1480).unpickle()                     
[mux  18565] 10:05:06.815656 D mitogen.io: Message(0, 2003, 0, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x1b\x00\x00\x00propa'..1480).unpickle()                     
[mux  18565] 10:05:06.815862 D mitogen.service: caching small file /export/home/orion-admin/ansible-boulder/library/pfsense_ca.py                                                                     
[mux  18565] 10:05:06.816309 D mitogen.service: requesting Context(2, u'ssh.inferno.cora.nwra.com') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/library/pfsense_ca.py                                                                         
[mux  18565] 10:05:06.816058 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.816577 D mitogen.io: Router(Broker(7f50)).add_handler(<bound method Receiver._on_receive of Receiver(Router(Broker(7f50)), None)>, 1004, False)                                 
[mux  18565] 10:05:06.816837 D mitogen.io: Waker(fd=6/8).defer() [fd=8]
[mux  18565] 10:05:06.817006 D mitogen.service: caching small file /export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py                                                          
[mux  18565] 10:05:06.816653 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.817343 D mitogen.service: requesting Context(2, u'ssh.inferno.cora.nwra.com') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py                                                              
[mux  18565] 10:05:06.817588 D mitogen.io: Router(Broker(7f50)).add_handler(<bound method Receiver._on_receive of Receiver(Router(Broker(7f50)), None)>, 1005, False)                                 
[mux  18565] 10:05:06.817792 D mitogen.io: Waker(fd=6/8).defer() [fd=8]
[mux  18565] 10:05:06.817958 D mitogen.service: caching small file /export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py                                                  
[mux  18565] 10:05:06.818425 D mitogen.service: requesting Context(2, u'ssh.inferno.cora.nwra.com') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py                                                      
[mux  18565] 10:05:06.818232 D mitogen.io: EpollPoller: POLLIN: 6
[mux  18565] 10:05:06.818657 D mitogen.io: Router(Broker(7f50)).add_handler(<bound method Receiver._on_receive of Receiver(Router(Broker(7f50)), None)>, 1006, False)                                 
[mux  18565] 10:05:06.818872 D mitogen.io: Waker(fd=6/8).defer() [fd=8]
[mux  18565] 10:05:06.819095 D mitogen.service: caching small file /export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py                                                   
[mux  18565] 10:05:06.819500 D mitogen.service: requesting Context(2, u'ssh.inferno.cora.nwra.com') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py                                                       
[mux  18565] 10:05:06.819275 D mitogen.io: Waker(fd=6/8).on_receive()
[mux  18565] 10:05:06.819756 D mitogen.io: Router(Broker(7f50)).add_handler(<bound method Receiver._on_receive of Receiver(Router(Broker(7f50)), None)>, 1007, False)                                 
[mux  18565] 10:05:06.820012 D mitogen.io: Waker(fd=6/8).defer() [fd=8]
[mux  18565] 10:05:06.820251 D mitogen.io: Waker(fd=6/8).defer() [fd=8]
[mux  18565] 10:05:06.820408 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.Pool.5490.6').get(timeout=None, block=True)                                                                       
[mux  18565] 10:05:06.820581 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.Pool.5490.6')._get_sleep(timeout=None, block=True, fd=24/25)                                                      
[mux  18565] 10:05:06.820730 D mitogen.io: PollPoller.poll(None)
[mux  18565] 10:05:06.819837 D mitogen.io: Router(Broker(7f50))._async_route(Message(2, 0, 0, 110, 1004, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..8938), None)                                                                                             
[mux  18565] 10:05:06.821096 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(2, 0, 0, 110, 1004, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..8938))                                                                                    
[mux  18565] 10:05:06.821288 D mitogen.io: Router(Broker(7f50))._async_route(Message(2, 0, 0, 110, 1005, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..290), None)                                                                                              
[mux  18565] 10:05:06.821452 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(2, 0, 0, 110, 1005, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..290))                                                                                     
[mux  18565] 10:05:06.821627 D mitogen.io: Router(Broker(7f50))._async_route(Message(2, 0, 0, 110, 1006, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..298), None)                                                                                              
[mux  18565] 10:05:06.821788 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(2, 0, 0, 110, 1006, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..298))                                                                                     
[mux  18565] 10:05:06.821969 D mitogen.io: Router(Broker(7f50))._async_route(Message(2, 0, 0, 110, 1007, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..23436), None)                                                                                            
[mux  18565] 10:05:06.822221 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(2, 0, 0, 110, 1007, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..23436))                                                                                   
[mux  18565] 10:05:06.822445 D mitogen.io: Router(Broker(7f50))._async_route(Message(2003, 0, 0, 1003, None, '\x80\x02N.'..4), None)                                                                  
[mux  18565] 10:05:06.822606 D mitogen.io: MitogenProtocol(unix_client.18635)._send(Message(2003, 0, 0, 1003, None, '\x80\x02N.'..4))                                                                 
[mux  18565] 10:05:06.822805 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:06.822818 D mitogen.io: Poller: POLLIN for 13
[mux  18565] 10:05:06.823035 D mitogen.io: EpollPoller.poll(None)
[task 18635] 10:05:06.823118 D mitogen.io: MitogenProtocol(unix_listener.18565).on_receive()
[mux  18565] 10:05:06.823298 D mitogen.io: EpollPoller: POLLIN: 6
[task 18635] 10:05:06.823378 D mitogen.io: Router(Broker(f4d0))._async_route(Message(2003, 0, 0, 1003, 0, '\x80\x02N.'..4), <Stream unix_listener.18565 #e0d0>)                                       
[mux  18565] 10:05:06.823563 D mitogen.io: Waker(fd=6/8).on_receive()
[task 18635] 10:05:06.823645 D mitogen.io: Receiver(Router(Broker(f4d0)), 1003)._on_receive(Message(2003, 0, 0, 1003, 0, '\x80\x02N.'..4))                                                            
[mux  18565] 10:05:06.823876 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:06.823870 D mitogen.io: Latch(0x7fc9ac58ce10, size=0, t='mitogen.broker').put(Message(2003, 0, 0, 1003, 0, '\x80\x02N.'..4))                                                       
[task 18635] 10:05:06.824113 D mitogen.io: Latch(0x7fc9ac58ce10, size=1, t='mitogen.broker').put() -> waking wfd=15                                                                                   
[mux  18565] 10:05:06.824104 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.824340 D mitogen.io: EpollPoller: POLLIN: 6
[task 18635] 10:05:06.824342 D mitogen.io: PollPoller: POLLIN|POLLHUP for 14
[mux  18565] 10:05:06.824563 D mitogen.io: Waker(fd=6/8).on_receive()
[task 18635] 10:05:06.824661 D mitogen.io: Latch(0x7fc9ac58ce10, size=1, t='MainThread').get() wake -> Message(2003, 0, 0, 1003, 0, '\x80\x02N.'..4)                                                  
[mux  18565] 10:05:06.824789 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:06.824905 D mitogen.io: Message(2003, 0, 0, 1003, 0, '\x80\x02N.'..4).unpickle()
[mux  18565] 10:05:06.825016 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.825259 D mitogen.io: EpollPoller: POLLIN: 6
[mux  18565] 10:05:06.825492 D mitogen.io: Waker(fd=6/8).on_receive()
[mux  18565] 10:05:06.825722 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:06.825495 D mitogen.parent: starting function call to fork.72377: ansible_mitogen.target.run_module(kwargs={u'module_map': {u'builtin': [u'ansible.module_utils._text', u'ansible.module_utils.basic', u'ansible.module_utils.common', u'ansible.module_utils.common._collections_compat', u'ansible.module_utils.common._json_compat', u'ansible.module_utils.common._utils', u'ansible.module_utils.common.collections', u'ansible.module_utils.common.file', u'ansible.module_utils.common.parameters', u'ansible.module_utils.common.process', u'ansible.module_utils.common.sys_info', u'ansible.module_utils.common.text', u'ansible.module_utils.common.text.converters', u'ansible.module_utils.common.text.formatters', u'ansible.module_utils.common.validation', u'ansible.module_utils.compat', u'ansible.module_utils.compat.ipaddress', u'ansible.module_utils.distro', u'ansible.module_utils.distro._distro', u'ansible.module_utils.parsing', u'ansible.module_utils.parsing.convert_bool', u'ansible.module_utils.pycompat24', u'ansible.module_utils.six'], u'custom': [(u'ansible.module_utils.network', u'/export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py', True), (u'ansible.module_utils.network.pfsense', u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py', True), (u'ansible.module_utils.network.pfsense.pfsense', u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py', False)]}, u'emulate_tty': True, u'good_temp_dir': u'/tmp', u'module': u'pfsense_ca', u'cwd': None, u'py_module_name': u'ansible.modules.pfsense_ca', u'service_context': Context(0, None), u'extra_env': None, u'is_python': None, u'env': {}, u'path': u'/export/home/orion-admin/ansible-boulder/library/pfsense_ca.py', u'runner_name': u'NewStyleRunner', u'interpreter_fragment': None, u'json_args': '{"_ansible_version": "2.9.2", "_ansible_selinux_special_fs": ["fuse", "nfs", "vboxsf", "ramfs", "9p", "vfat"], "name": "NWRA AD CA", "certificate": "LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tDQpNSUlGcXpDQ0E1T2dBd0lCQWdJUVBreXdYdWRkZnFOR2h2aWExVDVYZ3pBTkJna3Foa2lHOXcwQkFRMEZBREJjDQpNUk13RVFZS0NaSW1pWlB5TEdRQkdSWURZMjl0TVJRd0VnWUtDWkltaVpQeUxHUUJHUllFYm5keVlURVNNQkFHDQpDZ21TSm9tVDhpeGtBUmtXQW1Ga01Sc3dHUVlEVlFRREV4SmhaQzFCUkMxVFJVRlVWRXhGTURFdFEwRXdIaGNODQpNVFl3TkRBM01UWTBOVEE0V2hjTk1qWXdOREEzTVRZMU5UQTNXakJjTVJNd0VRWUtDWkltaVpQeUxHUUJHUllEDQpZMjl0TVJRd0VnWUtDWkltaVpQeUxHUUJHUllFYm5keVlURVNNQkFHQ2dtU0pvbVQ4aXhrQVJrV0FtRmtNUnN3DQpHUVlEVlFRREV4SmhaQzFCUkMxVFJVRlVWRXhGTURFdFEwRXdnZ0lpTUEwR0NTcUdTSWIzRFFFQkFRVUFBNElDDQpEd0F3Z2dJS0FvSUNBUUNWdGM0dzBnY0h5aFkzRkVpUENVMmZLYXAyWnFHb0ROL1VuRkVRRVBqZ1R4NmE4UEF5DQpqWjRMS2o2N1AybkRLTFA0ZVFQSFFzQmRkTVNneVl1RzdCQTlycmNCaFIzY0VlZ1RmNm9CSjdKUG1zZTJTS3dtDQp6QnhTTTJQaHM4OUFsR21xQ24xcWhCQUdhSklpbDQzaVRGWnRUcHB3VmliSFl0NitlK2IyRDRBV2tKS2RvTlloDQpUR3R3QmdBaDBxWmhzV0lIV3FHVmZCREpQUXhXVks4YmxwMCtkTi9odnZEeGh4NVBKaU5ZSEFreG9MWTFxM1hrDQpDcTlOZzlRQ1c5K3NGbjd0S3oxZnBkQjMzS0J3bFZqY25VaStFYngzY1d6N3JTenQxRjZ1bFhybGIzdUVCWkIzDQpTMkFCRHZjK0RRT0owWFZKTWRLWnJEOXluVUtzUWRjUmJ6cTRIdVBWODNzNkNRQU9KWFlid25yQVIzTzVwMjRkDQpFUmZZZzFOQWdjTk94azlzeVUzY3dvVEluTCtWM1hPbUhNa05mQUxBVFkyNk11SEdyMFQ5RWlBcVViNTMyd210DQpZTnY5d3NqTHExR0tGRXc3ZnJHVUFOYTJCNU8vaWtDeDF5ck14YVA4elNqT1FjeHppYVZ6QVlCa00rQURhM1ZYDQppTGhtRmFUemVFMHpBVngzNS9xSFE1Yks1K1VkejF4ZzhSMWtvT2taMVVDeWVvWFFEdVk3UWpvRkZrVDJ6VXV2DQpOYjgzR3BEdWZTd29TeTR3QUg0RE9ZWEdSWkx2VG16NGxlbjNVNVFIZmIzWEUxMVJyS1hBVnBCcVJybHJQbzhjDQpET2FqdHJNYzVTMkpaY3padSsyMVFoR082WndTWWZqTzJ6MTcxdE5wV1ZBd1I1QVAwNVRCcWVVaktRSURBUUFCDQpvMmt3WnpBVEJna3JCZ0VFQVlJM0ZBSUVCaDRFQUVNQVFUQU9CZ05WSFE4QkFmOEVCQU1DQVlZd0R3WURWUjBUDQpBUUgvQkFVd0F3RUIvekFkQmdOVkhRNEVGZ1FVZk5KTWFaQThrNTIwemtQRnZ2NUhmbDRNRFRrd0VBWUpLd1lCDQpCQUdDTnhVQkJBTUNBUUF3RFFZSktvWklodmNOQVFFTkJRQURnZ0lCQUZWMDIyYmNrd3VPTUs2MXU5dkkwUFZUDQpDWjRUWUxyZlcrV3JONmdXQnN0ejg3MWpDb09BM0tvcFNiUUNOdThLekhhYm1BTzlmZ1VtclRObWxFM2l4QXpIDQowNUszNzJCNm1yUlE3RGphMHB6TFhrOEhIRm1oYjZ2NjRjNGMwZCtNZUNNb3pZZ0QvVi91UUZQWFhCbmZWVExJDQpNN1RpU3ZDUTU0d3lyZWQrNERxb2t1b3NyMUNWc1BsS25yTTF1YjFWSjBZbEV5SmxHMWl1VUNobGR6OExLRllrDQp5Rys3RjFVTlkrRWw1TkZFODFnZldIejBxUWx3WnZTNmo2TzV6cGl0dDVBeDZLUGFrTDdqTWRQZE9CMHBFcFJwDQpmblN1Ymo3aFcyYjFqWWpNSVhjeE0wV2hqVnFwb0hXRkF1cXRVbGtZSFVzaWl4a3lQemhZYjlGSGorTGptSEM0DQpHcTNGV3dpbVNSU0lUeTNNd3NjY20rTEdmVit3elpYWU5yMzV5S09PRkJpTU1odnRoK0tnbjBtNFdkb0hOVmVsDQpiSzFKQVB5YjZZdFVEOVhOYkRVWTMvRWhmZU10KzRYdHhnU0RQT1ZleUxWcUlTbERqMDdWclpMc0U1bkRIN0lGDQo5Ni9QYzN1cklOV044MlBYeGFHWlYvTFVUdU1PLzE2cFhkMS84WVlIYUpmVWlObkliejZHaWpyeHZ3bDYwWEhxDQpUUnk4b2xuZmU2S1kvZHN3U1RBSHNBWk1OZkRyNCtQaXdBeDhWbkxnbHUveGUxUGNXZy9XTUlhNEhYaC9KM1ZFDQovajQvaEFCS1BEdXNPK0tMMDFqa1RJNE1tdDFlODRtU052SkUzdmFMNFRJMGxmUzE5bDNDOFBoNndWbHpvNCsvDQp3UHVCSk96RFpnRGdvR0Q3UUhXcQ0KLS0tLS1FTkQgQ0VSVElGSUNBVEUtLS0tLQ==", "_ansible_module_name": "pfsense_ca", "_ansible_debug": false, "_ansible_verbosity": 4, "_ansible_keep_remote_files": false, "_ansible_syslog_facility": "LOG_USER", "_ansible_socket": null, "state": "present", "_ansible_diff": true, "_ansible_remote_tmp": "/tmp", "_ansible_shell_executable": "/bin/sh", "_ansible_check_mode": false, "_ansible_tmpdir": null, "_ansible_string_conversion_action": "warn", "_ansible_no_log": false}'})                                                                
[mux  18565] 10:05:06.825946 D mitogen.io: EpollPoller.poll(None)
[task 18635] 10:05:06.824563 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[mux  18565] 10:05:06.846251 D mitogen.io: EpollPoller: POLLIN: 6
[mux  18565] 10:05:06.846601 D mitogen.io: Waker(fd=6/8).on_receive()
[task 18635] 10:05:06.846423 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method Receiver._on_receive of Receiver(Router(Broker(f4d0)), None)>, 1004, False)                                 
[mux  18565] 10:05:06.846843 D mitogen.io: EpollPoller: POLLIN: 77
[task 18635] 10:05:06.846868 D mitogen.io: Waker(fd=11/12).defer() [fd=12]
[task 18635] 10:05:06.847128 D mitogen.io: Receiver(Router(Broker(f4d0)), 1004).get(timeout=None, block=True)                                                                                         
[mux  18565] 10:05:06.847150 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[task 18635] 10:05:06.847357 D mitogen.io: Latch(0x7fc9ac58cf10, size=0, t='MainThread').get(timeout=None, block=True)                                                                                
[mux  18565] 10:05:06.847399 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..193), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[task 18635] 10:05:06.847624 D mitogen.io: Latch(0x7fc9ac58cf10, size=0, t='MainThread')._get_sleep(timeout=None, block=True, fd=14/15)                                                               
[mux  18565] 10:05:06.847684 D mitogen.service.[ssh.inferno.cora.nwra.com]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/library/pfsense_ca.py', [blob: 8656 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.6c10.1'                                               
[task 18635] 10:05:06.847858 D mitogen.io: PollPoller.poll(None)
[task 18635] 10:05:06.846557 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:06.848006 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, "mitogen.service\x0010\x00requesting Context(4, u'sudo.ro"..179), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[task 18635] 10:05:06.848294 D mitogen.io: Poller: POLLIN for 11
[mux  18565] 10:05:06.848348 D mitogen.service.[ssh.inferno.cora.nwra.com]: requesting Context(4, u'sudo.root') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/library/pfsense_ca.py                                                             
[task 18635] 10:05:06.848535 D mitogen.io: Waker(fd=11/12).on_receive()
[mux  18565] 10:05:06.848668 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..256), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[task 18635] 10:05:06.848757 D mitogen.io: Router(Broker(f4d0))._async_route(Message(2004, 2003, 2003, 101, 1004, '\x80\x02(NX\x16\x00\x00\x00ansible_mitogen.targetNX\n\x00\x00\x00run_module)cm'..5212), None)                                                                                         
[mux  18565] 10:05:06.848942 D mitogen.[ssh.inferno.cora.nwra.com]: calling service mitogen.service.PushFileService.store_and_forward of Context(4, u'sudo.root'), args: {'path': u'/export/home/orion-admin/ansible-boulder/library/pfsense_ca.py', 'data': [blob: 8656 bytes], 'context': Context(2004, u'fork.72377')}                                                                                   
[task 18635] 10:05:06.848997 D mitogen.io: MitogenProtocol(unix_listener.18565)._send(Message(2004, 2003, 2003, 101, 1004, '\x80\x02(NX\x16\x00\x00\x00ansible_mitogen.targetNX\n\x00\x00\x00run_module)cm'..5212))                                                                                      
[mux  18565] 10:05:06.849210 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..201), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[task 18635] 10:05:06.849285 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[task 18635] 10:05:06.849503 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:06.849480 D mitogen.service.[ssh.inferno.cora.nwra.com]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py', [blob: 0 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.6c10.0'                                       
[mux  18565] 10:05:06.849751 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, "mitogen\x0010\x00sending message to Context(4, u'sudo.ro"..176), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.849933 D mitogen.[ssh.inferno.cora.nwra.com]: sending message to Context(4, u'sudo.root'): Message(4, 2, 2, 110, 1002, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..8938)                                                                
[mux  18565] 10:05:06.850129 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, "mitogen.service\x0010\x00requesting Context(4, u'sudo.ro"..190), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.850307 D mitogen.service.[ssh.inferno.cora.nwra.com]: requesting Context(4, u'sudo.root') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py                                                  
[mux  18565] 10:05:06.850477 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..209), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.850654 D mitogen.service.[ssh.inferno.cora.nwra.com]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py', [blob: 0 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.6c10.1'                               
[mux  18565] 10:05:06.850824 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..264), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.851003 D mitogen.[ssh.inferno.cora.nwra.com]: calling service mitogen.service.PushFileService.store_and_forward of Context(4, u'sudo.root'), args: {'path': u'/export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py', 'data': [blob: 0 bytes], 'context': Context(2004, u'fork.72377')}                                                                           
[mux  18565] 10:05:06.851245 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, "mitogen.service\x0010\x00requesting Context(4, u'sudo.ro"..198), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.851424 D mitogen.service.[ssh.inferno.cora.nwra.com]: requesting Context(4, u'sudo.root') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py                                          
[mux  18565] 10:05:06.851592 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, "mitogen\x0010\x00sending message to Context(4, u'sudo.ro"..175), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.851769 D mitogen.[ssh.inferno.cora.nwra.com]: sending message to Context(4, u'sudo.root'): Message(4, 2, 2, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..290)                                                                 
[mux  18565] 10:05:06.851937 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..272), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.852132 D mitogen.[ssh.inferno.cora.nwra.com]: calling service mitogen.service.PushFileService.store_and_forward of Context(4, u'sudo.root'), args: {'path': u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py', 'data': [blob: 0 bytes], 'context': Context(2004, u'fork.72377')}                                                                   
[mux  18565] 10:05:06.852302 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..193), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.852489 D mitogen.service.[ssh.inferno.cora.nwra.com.sudo.root]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/library/pfsense_ca.py', [blob: 8656 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.8790.0'                                     
[mux  18565] 10:05:06.852659 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, "mitogen.service\x0010\x00requesting Context(1003, u'fork"..183), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.852838 D mitogen.service.[ssh.inferno.cora.nwra.com.sudo.root]: requesting Context(1003, u'fork.68631') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/library/pfsense_ca.py                                               
[mux  18565] 10:05:06.853008 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..260), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.853207 D mitogen.[ssh.inferno.cora.nwra.com.sudo.root]: calling service mitogen.service.PushFileService.store_and_forward of Context(1003, u'fork.68631'), args: {'path': u'/export/home/orion-admin/ansible-boulder/library/pfsense_ca.py', 'data': [blob: 8656 bytes], 'context': Context(2004, u'fork.72377')}                                                                     
[mux  18565] 10:05:06.853378 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, "mitogen\x0010\x00sending message to Context(1003, u'fork"..183), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.853557 D mitogen.[ssh.inferno.cora.nwra.com.sudo.root]: sending message to Context(1003, u'fork.68631'): Message(1003, 4, 4, 110, 1001, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..8938)                                               
[mux  18565] 10:05:06.853727 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, "mitogen\x0010\x00sending message to Context(4, u'sudo.ro"..175), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.853905 D mitogen.[ssh.inferno.cora.nwra.com]: sending message to Context(4, u'sudo.root'): Message(4, 2, 2, 110, 1004, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..298)                                                                 
[mux  18565] 10:05:06.854122 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..212), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.854301 D mitogen.service.[ssh.inferno.cora.nwra.com]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py', [blob: 23136 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.6c10.0'                            
[mux  18565] 10:05:06.854471 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, "mitogen.service\x0010\x00requesting Context(4, u'sudo.ro"..197), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.854648 D mitogen.service.[ssh.inferno.cora.nwra.com]: requesting Context(4, u'sudo.root') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py                                           
[mux  18565] 10:05:06.854817 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..275), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.854995 D mitogen.[ssh.inferno.cora.nwra.com]: calling service mitogen.service.PushFileService.store_and_forward of Context(4, u'sudo.root'), args: {'path': u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py', 'data': [blob: 23136 bytes], 'context': Context(2004, u'fork.72377')}                                                                
[mux  18565] 10:05:06.855184 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, "mitogen\x0010\x00sending message to Context(4, u'sudo.ro"..177), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.855369 D mitogen.[ssh.inferno.cora.nwra.com]: sending message to Context(4, u'sudo.root'): Message(4, 2, 2, 110, 1005, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..23436)                                                               
[mux  18565] 10:05:06.855536 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..201), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.855714 D mitogen.service.[ssh.inferno.cora.nwra.com.sudo.root]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py', [blob: 0 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.8790.1'                             
[mux  18565] 10:05:06.855904 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, "mitogen.service\x0010\x00requesting Context(1003, u'fork"..194), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.856089 D mitogen.service.[ssh.inferno.cora.nwra.com.sudo.root]: requesting Context(1003, u'fork.68631') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py                                    
[mux  18565] 10:05:06.856257 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..268), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.856441 D mitogen.[ssh.inferno.cora.nwra.com.sudo.root]: calling service mitogen.service.PushFileService.store_and_forward of Context(1003, u'fork.68631'), args: {'path': u'/export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py', 'data': [blob: 0 bytes], 'context': Context(2004, u'fork.72377')}                                                             
[mux  18565] 10:05:06.856611 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, "mitogen\x0010\x00sending message to Context(1003, u'fork"..182), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.856788 D mitogen.[ssh.inferno.cora.nwra.com.sudo.root]: sending message to Context(1003, u'fork.68631'): Message(1003, 4, 4, 110, 1002, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..290)                                                
[mux  18565] 10:05:06.856957 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..209), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.857186 D mitogen.service.[ssh.inferno.cora.nwra.com.sudo.root]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py', [blob: 0 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.8790.0'                     
[mux  18565] 10:05:06.857355 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..212), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.857533 D mitogen.service.[ssh.inferno.cora.nwra.com.sudo.root]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py', [blob: 23136 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.8790.1'                  
[mux  18565] 10:05:06.857708 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..193), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.857915 D mitogen.service.[fork.68631]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/library/pfsense_ca.py', [blob: 8656 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.e490.0'                                                              
[mux  18565] 10:05:06.858091 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen.service\x0010\x00requesting Context(2004, u'fork"..183), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.858269 D mitogen.service.[fork.68631]: requesting Context(2004, u'fork.72377') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/library/pfsense_ca.py                                                                        
[mux  18565] 10:05:06.858437 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..260), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.858615 D mitogen.[fork.68631]: calling service mitogen.service.PushFileService.store_and_forward of Context(2004, u'fork.72377'), args: {'path': u'/export/home/orion-admin/ansible-boulder/library/pfsense_ca.py', 'data': [blob: 8656 bytes], 'context': Context(2004, u'fork.72377')}                                                                                              
[mux  18565] 10:05:06.858791 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, "mitogen.service\x0010\x00requesting Context(1003, u'fork"..201), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.858969 D mitogen.service.[ssh.inferno.cora.nwra.com.sudo.root]: requesting Context(1003, u'fork.68631') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py                             
[mux  18565] 10:05:06.859146 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..279), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.859323 D mitogen.[ssh.inferno.cora.nwra.com.sudo.root]: calling service mitogen.service.PushFileService.store_and_forward of Context(1003, u'fork.68631'), args: {'path': u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py', 'data': [blob: 23136 bytes], 'context': Context(2004, u'fork.72377')}                                                  
[mux  18565] 10:05:06.859493 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, "mitogen.service\x0010\x00requesting Context(1003, u'fork"..202), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.859671 D mitogen.service.[ssh.inferno.cora.nwra.com.sudo.root]: requesting Context(1003, u'fork.68631') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py                            
[mux  18565] 10:05:06.859840 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen\x0010\x00sending message to Context(2004, u'fork"..189), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.860031 D mitogen.[fork.68631]: sending message to Context(2004, u'fork.72377'): Message(2004, 1003, 1003, 110, 1001, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..8938)                                                                  
[mux  18565] 10:05:06.860201 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen.service\x0010\x00Pool(e490, size=2, th='mitogen."..71), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.860378 D mitogen.service.[fork.68631]: Pool(e490, size=2, th='mitogen.broker'): initialized                                                                                     
[mux  18565] 10:05:06.860543 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, "mitogen\x0010\x00sending message to Context(1003, u'fork"..184), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.860720 D mitogen.[ssh.inferno.cora.nwra.com.sudo.root]: sending message to Context(1003, u'fork.68631'): Message(1003, 4, 4, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..23436)                                              
[mux  18565] 10:05:06.860888 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..276), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.861075 D mitogen.[ssh.inferno.cora.nwra.com.sudo.root]: calling service mitogen.service.PushFileService.store_and_forward of Context(1003, u'fork.68631'), args: {'path': u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py', 'data': [blob: 0 bytes], 'context': Context(2004, u'fork.72377')}                                                     
[mux  18565] 10:05:06.861251 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, "mitogen\x0010\x00sending message to Context(1003, u'fork"..182), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.861429 D mitogen.[ssh.inferno.cora.nwra.com.sudo.root]: sending message to Context(1003, u'fork.68631'): Message(1003, 4, 4, 110, 1004, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..298)                                                
[mux  18565] 10:05:06.861596 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..201), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.861773 D mitogen.service.[fork.68631]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py', [blob: 0 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.e490.0'                                                      
[mux  18565] 10:05:06.861941 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen.service\x0010\x00requesting Context(2004, u'fork"..194), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.862134 D mitogen.service.[fork.68631]: requesting Context(2004, u'fork.72377') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py                                                             
[mux  18565] 10:05:06.862307 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..268), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.862487 D mitogen.[fork.68631]: calling service mitogen.service.PushFileService.store_and_forward of Context(2004, u'fork.72377'), args: {'path': u'/export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py', 'data': [blob: 0 bytes], 'context': Context(2004, u'fork.72377')}                                                                                      
[mux  18565] 10:05:06.862657 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen\x0010\x00sending message to Context(2004, u'fork"..188), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.862834 D mitogen.[fork.68631]: sending message to Context(2004, u'fork.72377'): Message(2004, 1003, 1003, 110, 1002, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..290)                                                                   
[mux  18565] 10:05:06.862999 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..212), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.863186 D mitogen.service.[fork.68631]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py', [blob: 23136 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.e490.1'                                           
[mux  18565] 10:05:06.863354 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen.service\x0010\x00requesting Context(2004, u'fork"..201), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.863538 D mitogen.service.[fork.68631]: requesting Context(2004, u'fork.72377') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py                                                      
[mux  18565] 10:05:06.863705 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..279), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.863901 D mitogen.[fork.68631]: calling service mitogen.service.PushFileService.store_and_forward of Context(2004, u'fork.72377'), args: {'path': u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py', 'data': [blob: 23136 bytes], 'context': Context(2004, u'fork.72377')}                                                                           
[mux  18565] 10:05:06.864075 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen\x0010\x00sending message to Context(2004, u'fork"..190), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.864252 D mitogen.[fork.68631]: sending message to Context(2004, u'fork.72377'): Message(2004, 1003, 1003, 110, 1003, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..23436)                                                                 
[mux  18565] 10:05:06.864419 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..193), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.864619 D mitogen.service.[fork.72377]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/library/pfsense_ca.py', [blob: 8656 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.b350.0'                                                              
[mux  18565] 10:05:06.864781 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.864933 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.865134 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.865293 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.865462 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..201), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.865647 D mitogen.service.[fork.72377]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py', [blob: 0 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.b350.0'                                                      
[mux  18565] 10:05:06.865814 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..209), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.865998 D mitogen.service.[fork.68631]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py', [blob: 0 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.e490.0'                                              
[mux  18565] 10:05:06.866176 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen.service\x0010\x00requesting Context(2004, u'fork"..202), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.866353 D mitogen.service.[fork.68631]: requesting Context(2004, u'fork.72377') cache and forward small file to Context(2004, u'fork.72377'): /export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py                                                     
[mux  18565] 10:05:06.866561 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen\x0010\x00calling service mitogen.service.PushFil'..276), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.866739 D mitogen.[fork.68631]: calling service mitogen.service.PushFileService.store_and_forward of Context(2004, u'fork.72377'), args: {'path': u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py', 'data': [blob: 0 bytes], 'context': Context(2004, u'fork.72377')}                                                                              
[mux  18565] 10:05:06.866908 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen\x0010\x00sending message to Context(2004, u'fork"..188), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.867098 D mitogen.[fork.68631]: sending message to Context(2004, u'fork.72377'): Message(2004, 1003, 1003, 110, 1004, '\x80\x02X\x1f\x00\x00\x00mitogen.service.PushFileServiceq\x01X\x11\x00\x00\x00store'..298)                                                                   
[mux  18565] 10:05:06.867265 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, "mitogen.service\x0010\x00Pool(b350, size=2, th='mitogen."..71), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.867442 D mitogen.service.[fork.72377]: Pool(b350, size=2, th='mitogen.broker'): initialized                                                                                     
[mux  18565] 10:05:06.867605 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..212), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.867782 D mitogen.service.[fork.72377]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py', [blob: 23136 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.b350.0'                                           
[mux  18565] 10:05:06.867950 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.service\x0010\x00PushFileService().store_and_for'..209), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.868135 D mitogen.service.[fork.72377]: PushFileService().store_and_forward(u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py', [blob: 0 bytes], Context(2004, u'fork.72377')) 'mitogen.Pool.b350.1'                                              
[mux  18565] 10:05:06.868299 D mitogen.io: EpollPoller: POLLIN: 76
[mux  18565] 10:05:06.868458 D mitogen.io: MitogenProtocol(unix_client.18635).on_receive()
[mux  18565] 10:05:06.868625 D mitogen.io: Router(Broker(7f50))._async_route(Message(2004, 2003, 2003, 101, 1004, '\x80\x02(NX\x16\x00\x00\x00ansible_mitogen.targetNX\n\x00\x00\x00run_module)cm'..5212), <Stream unix_client.18635 #2c10>)                                                             
[mux  18565] 10:05:06.868796 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(2004, 2003, 0, 101, 1004, '\x80\x02(NX\x16\x00\x00\x00ansible_mitogen.targetNX\n\x00\x00\x00run_module)cm'..5212))                                                                                   
[mux  18565] 10:05:06.868982 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.869142 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.874258 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.874428 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.874595 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, "mitogen\x0010\x00Dispatcher: dispatching (None, u'ansibl"..5171), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                 
[mux  18565] 10:05:06.874789 D mitogen.[fork.72377]: Dispatcher: dispatching (None, u'ansible_mitogen.target', None, u'run_module', (), Kwargs({'kwargs': {u'module_map': {u'builtin': [u'ansible.module_utils._text', u'ansible.module_utils.basic', u'ansible.module_utils.common', u'ansible.module_utils.common._collections_compat', u'ansible.module_utils.common._json_compat', u'ansible.module_utils.common._utils', u'ansible.module_utils.common.collections', u'ansible.module_utils.common.file', u'ansible.module_utils.common.parameters', u'ansible.module_utils.common.process', u'ansible.module_utils.common.sys_info', u'ansible.module_utils.common.text', u'ansible.module_utils.common.text.converters', u'ansible.module_utils.common.text.formatters', u'ansible.module_utils.common.validation', u'ansible.module_utils.compat', u'ansible.module_utils.compat.ipaddress', u'ansible.module_utils.distro', u'ansible.module_utils.distro._distro', u'ansible.module_utils.parsing', u'ansible.module_utils.parsing.convert_bool', u'ansible.module_utils.pycompat24', u'ansible.module_utils.six'], u'custom': [(u'ansible.module_utils.network', u'/export/home/orion-admin/ansible-boulder/module_utils/network/__init__.py', True), (u'ansible.module_utils.network.pfsense', u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/__init__.py', True), (u'ansible.module_utils.network.pfsense.pfsense', u'/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py', False)]}, u'emulate_tty': True, u'good_temp_dir': u'/tmp', u'module': u'pfsense_ca', u'interpreter_fragment': None, u'py_module_name': u'ansible.modules.pfsense_ca', u'service_context': Context(0, None), u'extra_env': None, u'is_python': None, u'env': {}, u'path': u'/export/home/orion-admin/ansible-boulder/library/pfsense_ca.py', u'json_args': '{"_ansible_version": "2.9.2", "_ansible_selinux_special_fs": ["fuse", "nfs", "vboxsf", "ramfs", "9p", "vfat"], "name": "NWRA AD CA", "certificate": "LS0tLS1CRUdJTiBDRVJUSUZJQ0FURS0tLS0tDQpNSUlGcXpDQ0E1T2dBd0lCQWdJUVBreXdYdWRkZnFOR2h2aWExVDVYZ3pBTkJna3Foa2lHOXcwQkFRMEZBREJjDQpNUk13RVFZS0NaSW1pWlB5TEdRQkdSWURZMjl0TVJRd0VnWUtDWkltaVpQeUxHUUJHUllFYm5keVlURVNNQkFHDQpDZ21TSm9tVDhpeGtBUmtXQW1Ga01Sc3dHUVlEVlFRREV4SmhaQzFCUkMxVFJVRlVWRXhGTURFdFEwRXdIaGNODQpNVFl3TkRBM01UWTBOVEE0V2hjTk1qWXdOREEzTVRZMU5UQTNXakJjTVJNd0VRWUtDWkltaVpQeUxHUUJHUllEDQpZMjl0TVJRd0VnWUtDWkltaVpQeUxHUUJHUllFYm5keVlURVNNQkFHQ2dtU0pvbVQ4aXhrQVJrV0FtRmtNUnN3DQpHUVlEVlFRREV4SmhaQzFCUkMxVFJVRlVWRXhGTURFdFEwRXdnZ0lpTUEwR0NTcUdTSWIzRFFFQkFRVUFBNElDDQpEd0F3Z2dJS0FvSUNBUUNWdGM0dzBnY0h5aFkzRkVpUENVMmZLYXAyWnFHb0ROL1VuRkVRRVBqZ1R4NmE4UEF5DQpqWjRMS2o2N1AybkRLTFA0ZVFQSFFzQmRkTVNneVl1RzdCQTlycmNCaFIzY0VlZ1RmNm9CSjdKUG1zZTJTS3dtDQp6QnhTTTJQaHM4OUFsR21xQ24xcWhCQUdhSklpbDQzaVRGWnRUcHB3VmliSFl0NitlK2IyRDRBV2tKS2RvTlloDQpUR3R3QmdBaDBxWmhzV0lIV3FHVmZCREpQUXhXVks4YmxwMCtkTi9odnZEeGh4NVBKaU5ZSEFreG9MWTFxM1hrDQpDcTlOZzlRQ1c5K3NGbjd0S3oxZnBkQjMzS0J3bFZqY25VaStFYngzY1d6N3JTenQxRjZ1bFhybGIzdUVCWkIzDQpTMkFCRHZjK0RRT0owWFZKTWRLWnJEOXluVUtzUWRjUmJ6cTRIdVBWODNzNkNRQU9KWFlid25yQVIzTzVwMjRkDQpFUmZZZzFOQWdjTk94azlzeVUzY3dvVEluTCtWM1hPbUhNa05mQUxBVFkyNk11SEdyMFQ5RWlBcVViNTMyd210DQpZTnY5d3NqTHExR0tGRXc3ZnJHVUFOYTJCNU8vaWtDeDF5ck14YVA4elNqT1FjeHppYVZ6QVlCa00rQURhM1ZYDQppTGhtRmFUemVFMHpBVngzNS9xSFE1Yks1K1VkejF4ZzhSMWtvT2taMVVDeWVvWFFEdVk3UWpvRkZrVDJ6VXV2DQpOYjgzR3BEdWZTd29TeTR3QUg0RE9ZWEdSWkx2VG16NGxlbjNVNVFIZmIzWEUxMVJyS1hBVnBCcVJybHJQbzhjDQpET2FqdHJNYzVTMkpaY3padSsyMVFoR082WndTWWZqTzJ6MTcxdE5wV1ZBd1I1QVAwNVRCcWVVaktRSURBUUFCDQpvMmt3WnpBVEJna3JCZ0VFQVlJM0ZBSUVCaDRFQUVNQVFUQU9CZ05WSFE4QkFmOEVCQU1DQVlZd0R3WURWUjBUDQpBUUgvQkFVd0F3RUIvekFkQmdOVkhRNEVGZ1FVZk5KTWFaQThrNTIwemtQRnZ2NUhmbDRNRFRrd0VBWUpLd1lCDQpCQUdDTnhVQkJBTUNBUUF3RFFZSktvWklodmNOQVFFTkJRQURnZ0lCQUZWMDIyYmNrd3VPTUs2MXU5dkkwUFZUDQpDWjRUWUxyZlcrV3JONmdXQnN0ejg3MWpDb09BM0tvcFNiUUNOdThLekhhYm1BTzlmZ1VtclRObWxFM2l4QXpIDQowNUszNzJCNm1yUlE3RGphMHB6TFhrOEhIRm1oYjZ2NjRjNGMwZCtNZUNNb3pZZ0QvVi91UUZQWFhCbmZWVExJDQpNN1RpU3ZDUTU0d3lyZWQrNERxb2t1b3NyMUNWc1BsS25yTTF1YjFWSjBZbEV5SmxHMWl1VUNobGR6OExLRllrDQp5Rys3RjFVTlkrRWw1TkZFODFnZldIejBxUWx3WnZTNmo2TzV6cGl0dDVBeDZLUGFrTDdqTWRQZE9CMHBFcFJwDQpmblN1Ymo3aFcyYjFqWWpNSVhjeE0wV2hqVnFwb0hXRkF1cXRVbGtZSFVzaWl4a3lQemhZYjlGSGorTGptSEM0DQpHcTNGV3dpbVNSU0lUeTNNd3NjY20rTEdmVit3elpYWU5yMzV5S09PRkJpTU1odnRoK0tnbjBtNFdkb0hOVmVsDQpiSzFKQVB5YjZZdFVEOVhOYkRVWTMvRWhmZU10KzRYdHhnU0RQT1ZleUxWcUlTbERqMDdWclpMc0U1bkRIN0lGDQo5Ni9QYzN1cklOV044MlBYeGFHWlYvTFVUdU1PLzE2cFhkMS84WVlIYUpmVWlObkliejZHaWpyeHZ3bDYwWEhxDQpUUnk4b2xuZmU2S1kvZHN3U1RBSHNBWk1OZkRyNCtQaXdBeDhWbkxnbHUveGUxUGNXZy9XTUlhNEhYaC9KM1ZFDQovajQvaEFCS1BEdXNPK0tMMDFqa1RJNE1tdDFlODRtU052SkUzdmFMNFRJMGxmUzE5bDNDOFBoNndWbHpvNCsvDQp3UHVCSk96RFpnRGdvR0Q3UUhXcQ0KLS0tLS1FTkQgQ0VSVElGSUNBVEUtLS0tLQ==", "_ansible_module_name": "pfsense_ca", "_ansible_debug": false, "_ansible_verbosity": 4, "_ansible_keep_remote_files": false, "_ansible_syslog_facility": "LOG_USER", "_ansible_socket": null, "state": "present", "_ansible_diff": true, "_ansible_remote_tmp": "/tmp", "_ansible_shell_executable": "/bin/sh", "_ansible_check_mode": false, "_ansible_tmpdir": null, "_ansible_string_conversion_action": "warn", "_ansible_no_log": false}', u'cwd': None, u'runner_name': u'NewStyleRunner'}}))                                  
[mux  18565] 10:05:06.875007 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.875168 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.877373 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.877572 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.877741 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.network. '..90), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.877953 D mitogen.importer.[fork.72377]: ansible.module_utils.network. is submodule of a locally loaded package                                                                  
[mux  18565] 10:05:06.878127 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.network. '..90), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.878326 D mitogen.importer.[fork.72377]: ansible.module_utils.network. is submodule of a locally loaded package                                                                  
[mux  18565] 10:05:06.878522 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.network. '..90), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.878698 D mitogen.importer.[fork.72377]: ansible.module_utils.network. is submodule of a locally loaded package                                                                  
[mux  18565] 10:05:06.878850 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.878999 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.879324 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.879529 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.879766 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.network.p'..98), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.880120 D mitogen.importer.[fork.72377]: ansible.module_utils.network.pfsense. is submodule of a locally loaded package                                                          
[mux  18565] 10:05:06.880384 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.network.p'..98), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.880622 D mitogen.importer.[fork.72377]: ansible.module_utils.network.pfsense. is submodule of a locally loaded package                                                          
[mux  18565] 10:05:06.880851 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.network.p'..98), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.881111 D mitogen.importer.[fork.72377]: ansible.module_utils.network.pfsense. is submodule of a locally loaded package                                                          
[mux  18565] 10:05:06.881333 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.881560 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.893939 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.894264 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.894464 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00requesting ansible.module_util'..58), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.894686 D mitogen.importer.[fork.72377]: requesting ansible.module_utils.compat                                                                                                  
[mux  18565] 10:05:06.894864 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.895035 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.895193 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.895374 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.895567 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00sending new ansible.module_uti'..77), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.895754 D mitogen.importer.[fork.72377]: sending new ansible.module_utils.compat request to parent                                                                               
[mux  18565] 10:05:06.895976 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: ansible.module_'..88), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.896213 D mitogen.parent.[fork.68631]: ModuleForwarder: ansible.module_utils.compat requested by context 2004                                                                    
[mux  18565] 10:05:06.896382 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.importer\x0010\x00sending new ansible.module_uti'..77), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.896589 D mitogen.importer.[fork.68631]: sending new ansible.module_utils.compat request to parent                                                                               
[mux  18565] 10:05:06.896757 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: ansible.module_'..88), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.896933 D mitogen.parent.[ssh.inferno.cora.nwra.com.sudo.root]: ModuleForwarder: ansible.module_utils.compat requested by context 1003                                           
[mux  18565] 10:05:06.897098 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.897247 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.897401 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.897556 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.897722 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.importer\x0010\x00sending new ansible.module_uti'..77), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.897905 D mitogen.importer.[ssh.inferno.cora.nwra.com.sudo.root]: sending new ansible.module_utils.compat request to parent                                                      
[mux  18565] 10:05:06.898076 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: ansible.module_'..85), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.898254 D mitogen.parent.[ssh.inferno.cora.nwra.com]: ModuleForwarder: ansible.module_utils.compat requested by context 4                                                        
[mux  18565] 10:05:06.898452 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.importer\x0010\x00sending new ansible.module_uti'..77), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.898689 D mitogen.importer.[ssh.inferno.cora.nwra.com]: sending new ansible.module_utils.compat request to parent                                                                
[mux  18565] 10:05:06.898859 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 100, 0, 'ansible.module_utils.compat'..27), <Stream ssh.inferno.cora.nwra.com #0e90>)                   
[mux  18565] 10:05:06.899119 D mitogen.responder: ssh.inferno.cora.nwra.com requested module ansible.module_utils.compat                                                                              
[mux  18565] 10:05:06.899449 D mitogen.io: PkgutilMethod()._get_module_via_pkgutil(u'ansible.module_utils.compat') -> <pkgutil.ImpLoader instance at 0x7fc9ac3f7b90>                                  
[mux  18565] 10:05:06.901021 D mitogen.responder: ansible.module_utils.compat is a package at /usr/lib/python2.7/site-packages/ansible/module_utils/compat/__init__.py with submodules [u'ipaddress', u'paramiko']                                                                                       
[mux  18565] 10:05:06.901597 D mitogen.responder: sending ansible.module_utils.compat (0.23 KiB) to ssh.inferno.cora.nwra.com                                                                         
[mux  18565] 10:05:06.901770 D mitogen.io: Router(Broker(7f50))._async_route(Message(2, 0, 0, 107, None, '\x80\x02(X\x1b\x00\x00\x00ansible.module_utils.compatq\x01]q\x02(X\t\x00\x00\x00ipad'..235), None)                                                                                             
[mux  18565] 10:05:06.901934 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(2, 0, 0, 107, None, '\x80\x02(X\x1b\x00\x00\x00ansible.module_utils.compatq\x01]q\x02(X\t\x00\x00\x00ipad'..235))                                                                                    
[mux  18565] 10:05:06.902209 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.902390 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.903560 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.903757 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.903928 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.importer\x0010\x00received ansible.module_utils.'..56), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.904134 D mitogen.importer.[ssh.inferno.cora.nwra.com]: received ansible.module_utils.compat                                                                                     
[mux  18565] 10:05:06.904327 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: sending ansible'..98), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.904560 D mitogen.parent.[ssh.inferno.cora.nwra.com]: ModuleForwarder: sending ansible.module_utils.compat to <Stream sudo.root #38d0>                                           
[mux  18565] 10:05:06.904750 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.904962 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.905160 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.905318 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.905485 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.importer\x0010\x00received ansible.module_utils.'..56), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.905721 D mitogen.importer.[ssh.inferno.cora.nwra.com.sudo.root]: received ansible.module_utils.compat                                                                           
[mux  18565] 10:05:06.905886 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: sending ansible'..99), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.906112 D mitogen.parent.[ssh.inferno.cora.nwra.com.sudo.root]: ModuleForwarder: sending ansible.module_utils.compat to <Stream fork.68631 #7950>                                
[mux  18565] 10:05:06.906338 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.906534 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.906722 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.906879 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.907097 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.importer\x0010\x00received ansible'..36), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                                 
[mux  18565] 10:05:06.907287 D mitogen.importer.[fork.68631]: received ansible
[mux  18565] 10:05:06.907451 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.importer\x0010\x00received ansible.module_utils'..49), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                    
[mux  18565] 10:05:06.907627 D mitogen.importer.[fork.68631]: received ansible.module_utils
[mux  18565] 10:05:06.907790 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.importer\x0010\x00received ansible.module_utils.'..56), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.907999 D mitogen.importer.[fork.68631]: received ansible.module_utils.compat
[mux  18565] 10:05:06.908170 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.908320 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.908499 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.908662 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.908829 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: sending ansible'..99), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.909048 D mitogen.parent.[fork.68631]: ModuleForwarder: sending ansible.module_utils.compat to <Stream fork.72377 #e8d0>                                                         
[mux  18565] 10:05:06.909219 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00received ansible'..36), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                                 
[mux  18565] 10:05:06.909398 D mitogen.importer.[fork.72377]: received ansible
[mux  18565] 10:05:06.909561 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00received ansible.module_utils'..49), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                    
[mux  18565] 10:05:06.909778 D mitogen.importer.[fork.72377]: received ansible.module_utils
[mux  18565] 10:05:06.909980 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.910148 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.910384 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.910598 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.910767 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00received ansible.module_utils.'..56), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.911002 D mitogen.importer.[fork.72377]: received ansible.module_utils.compat
[mux  18565] 10:05:06.911185 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00requesting ansible.module_util'..68), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.911394 D mitogen.importer.[fork.72377]: requesting ansible.module_utils.compat.ipaddress                                                                                        
[mux  18565] 10:05:06.911593 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00sending new ansible.module_uti'..87), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.911771 D mitogen.importer.[fork.72377]: sending new ansible.module_utils.compat.ipaddress request to parent                                                                     
[mux  18565] 10:05:06.911951 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.912174 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.912340 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.912498 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.912672 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: ansible.module_'..98), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.912849 D mitogen.parent.[fork.68631]: ModuleForwarder: ansible.module_utils.compat.ipaddress requested by context 2004                                                          
[mux  18565] 10:05:06.913118 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.importer\x0010\x00sending new ansible.module_uti'..87), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.913319 D mitogen.importer.[fork.68631]: sending new ansible.module_utils.compat.ipaddress request to parent                                                                     
[mux  18565] 10:05:06.913485 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: ansible.module_'..98), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.913662 D mitogen.parent.[ssh.inferno.cora.nwra.com.sudo.root]: ModuleForwarder: ansible.module_utils.compat.ipaddress requested by context 1003                                 
[mux  18565] 10:05:06.913836 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.importer\x0010\x00sending new ansible.module_uti'..87), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.914048 D mitogen.importer.[ssh.inferno.cora.nwra.com.sudo.root]: sending new ansible.module_utils.compat.ipaddress request to parent                                            
[mux  18565] 10:05:06.914281 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: ansible.module_'..95), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.914467 D mitogen.parent.[ssh.inferno.cora.nwra.com]: ModuleForwarder: ansible.module_utils.compat.ipaddress requested by context 4                                              
[mux  18565] 10:05:06.914633 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.importer\x0010\x00sending new ansible.module_uti'..87), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.914811 D mitogen.importer.[ssh.inferno.cora.nwra.com]: sending new ansible.module_utils.compat.ipaddress request to parent                                                      
[mux  18565] 10:05:06.914982 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 100, 0, 'ansible.module_utils.compat.ipaddress'..37), <Stream ssh.inferno.cora.nwra.com #0e90>)         
[mux  18565] 10:05:06.915177 D mitogen.responder: ssh.inferno.cora.nwra.com requested module ansible.module_utils.compat.ipaddress                                                                    
[mux  18565] 10:05:06.915822 D mitogen.io: PkgutilMethod()._get_module_via_pkgutil(u'ansible.module_utils.compat.ipaddress') -> <pkgutil.ImpLoader instance at 0x7fc9ac3f7518>                        
[mux  18565] 10:05:06.947502 D mitogen.responder: sending ansible.module_utils.compat.ipaddress (16.25 KiB) to ssh.inferno.cora.nwra.com                                                              
[mux  18565] 10:05:06.947749 D mitogen.io: Router(Broker(7f50))._async_route(Message(2, 0, 0, 107, None, '\x80\x02(X%\x00\x00\x00ansible.module_utils.compat.ipaddressq\x01NXI'..16639), None)        
[mux  18565] 10:05:06.947917 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(2, 0, 0, 107, None, '\x80\x02(X%\x00\x00\x00ansible.module_utils.compat.ipaddressq\x01NXI'..16639))                                                                                                  
[mux  18565] 10:05:06.948144 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.948301 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.951217 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.951471 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.951659 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.importer\x0010\x00received ansible.module_utils.'..66), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.951877 D mitogen.importer.[ssh.inferno.cora.nwra.com]: received ansible.module_utils.compat.ipaddress                                                                           
[mux  18565] 10:05:06.952118 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.952295 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.952491 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.952704 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.952979 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: sending ansible'..108), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.953182 D mitogen.parent.[ssh.inferno.cora.nwra.com]: ModuleForwarder: sending ansible.module_utils.compat.ipaddress to <Stream sudo.root #38d0>                                 
[mux  18565] 10:05:06.953350 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.importer\x0010\x00received ansible.module_utils.'..66), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[mux  18565] 10:05:06.953527 D mitogen.importer.[ssh.inferno.cora.nwra.com.sudo.root]: received ansible.module_utils.compat.ipaddress                                                                 
[mux  18565] 10:05:06.953688 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.953837 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.953990 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.954160 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.954325 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: sending ansible'..109), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:06.954502 D mitogen.parent.[ssh.inferno.cora.nwra.com.sudo.root]: ModuleForwarder: sending ansible.module_utils.compat.ipaddress to <Stream fork.68631 #7950>                      
[mux  18565] 10:05:06.954656 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.954805 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.955007 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.955180 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.955345 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.importer\x0010\x00received ansible.module_utils.'..66), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.955572 D mitogen.importer.[fork.68631]: received ansible.module_utils.compat.ipaddress                                                                                          
[mux  18565] 10:05:06.955729 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.955878 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.956046 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.956243 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.956409 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00ModuleForwarder: sending ansible'..109), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:06.956584 D mitogen.parent.[fork.68631]: ModuleForwarder: sending ansible.module_utils.compat.ipaddress to <Stream fork.72377 #e8d0>                                               
[mux  18565] 10:05:06.956749 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00received ansible.module_utils.'..66), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.956925 D mitogen.importer.[fork.72377]: received ansible.module_utils.compat.ipaddress                                                                                          
[mux  18565] 10:05:06.957087 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.957236 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.985554 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.985870 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.986221 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.compat ha'..75), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.986446 D mitogen.importer.[fork.72377]: ansible.module_utils.compat has no submodule __future__                                                                                 
[mux  18565] 10:05:06.986613 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.compat ha'..75), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.986828 D mitogen.importer.[fork.72377]: ansible.module_utils.compat has no submodule __future__                                                                                 
[mux  18565] 10:05:06.986982 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.987223 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.987426 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.987658 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.987842 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.compat ha'..75), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.988019 D mitogen.importer.[fork.72377]: ansible.module_utils.compat has no submodule __future__                                                                                 
[mux  18565] 10:05:06.988207 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.compat ha'..74), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.988430 D mitogen.importer.[fork.72377]: ansible.module_utils.compat has no submodule itertools                                                                                  
[mux  18565] 10:05:06.988595 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.compat ha'..74), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.988771 D mitogen.importer.[fork.72377]: ansible.module_utils.compat has no submodule itertools                                                                                  
[mux  18565] 10:05:06.988971 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.compat ha'..74), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.989198 D mitogen.importer.[fork.72377]: ansible.module_utils.compat has no submodule itertools                                                                                  
[mux  18565] 10:05:06.989363 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.compat ha'..71), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.989539 D mitogen.importer.[fork.72377]: ansible.module_utils.compat has no submodule struct                                                                                     
[mux  18565] 10:05:06.989692 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.989843 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:06.990109 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:06.990328 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:06.990578 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.compat ha'..71), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.990845 D mitogen.importer.[fork.72377]: ansible.module_utils.compat has no submodule struct                                                                                     
[mux  18565] 10:05:06.991102 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.compat ha'..71), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:06.991361 D mitogen.importer.[fork.72377]: ansible.module_utils.compat has no submodule struct                                                                                     
[mux  18565] 10:05:06.991582 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:06.991802 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.001093 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.001360 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.001619 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml._xmlplus is submodule of a'..73), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.001882 D mitogen.importer.[fork.72377]: xml._xmlplus is submodule of a locally loaded package                                                                                   
[mux  18565] 10:05:07.002119 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml._xmlplus is submodule of a'..73), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.002299 D mitogen.importer.[fork.72377]: xml._xmlplus is submodule of a locally loaded package                                                                                   
[mux  18565] 10:05:07.002463 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml._xmlplus is submodule of a'..73), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.002680 D mitogen.importer.[fork.72377]: xml._xmlplus is submodule of a locally loaded package                                                                                   
[mux  18565] 10:05:07.002971 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00requesting _xmlplus'..39), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                              
[mux  18565] 10:05:07.003191 D mitogen.importer.[fork.72377]: requesting _xmlplus
[mux  18565] 10:05:07.003347 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.003500 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.003658 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.003815 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.003984 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree is submodule of a lo'..70), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.004221 D mitogen.importer.[fork.72377]: xml.etree is submodule of a locally loaded package                                                                                      
[mux  18565] 10:05:07.004509 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree is submodule of a lo'..70), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.004718 D mitogen.importer.[fork.72377]: xml.etree is submodule of a locally loaded package                                                                                      
[mux  18565] 10:05:07.004884 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree is submodule of a lo'..70), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.005077 D mitogen.importer.[fork.72377]: xml.etree is submodule of a locally loaded package                                                                                      
[mux  18565] 10:05:07.005231 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.005383 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.005538 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.005694 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.005861 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.ElementTree is submo'..82), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.006096 D mitogen.importer.[fork.72377]: xml.etree.ElementTree is submodule of a locally loaded package                                                                          
[mux  18565] 10:05:07.006278 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.ElementTree is submo'..82), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.006467 D mitogen.importer.[fork.72377]: xml.etree.ElementTree is submodule of a locally loaded package                                                                          
[mux  18565] 10:05:07.006637 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.ElementTree is submo'..82), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.006854 D mitogen.importer.[fork.72377]: xml.etree.ElementTree is submodule of a locally loaded package                                                                          
[mux  18565] 10:05:07.007013 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.007222 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.008089 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.008317 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.008525 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.sys is submodule of '..74), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.008746 D mitogen.importer.[fork.72377]: xml.etree.sys is submodule of a locally loaded package                                                                                  
[mux  18565] 10:05:07.008984 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.009222 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.009404 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.009611 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.009847 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.sys is submodule of '..74), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.010035 D mitogen.importer.[fork.72377]: xml.etree.sys is submodule of a locally loaded package                                                                                  
[mux  18565] 10:05:07.010231 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.sys is submodule of '..74), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.010408 D mitogen.importer.[fork.72377]: xml.etree.sys is submodule of a locally loaded package                                                                                  
[mux  18565] 10:05:07.010572 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.re is submodule of a'..73), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.010748 D mitogen.importer.[fork.72377]: xml.etree.re is submodule of a locally loaded package                                                                                   
[mux  18565] 10:05:07.010910 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.re is submodule of a'..73), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.011103 D mitogen.importer.[fork.72377]: xml.etree.re is submodule of a locally loaded package                                                                                   
[mux  18565] 10:05:07.011290 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.re is submodule of a'..73), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.011495 D mitogen.importer.[fork.72377]: xml.etree.re is submodule of a locally loaded package                                                                                   
[mux  18565] 10:05:07.011657 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.warnings is submodul'..79), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.011832 D mitogen.importer.[fork.72377]: xml.etree.warnings is submodule of a locally loaded package                                                                             
[mux  18565] 10:05:07.011985 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.012179 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.012334 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.012512 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.012678 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.warnings is submodul'..79), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.012854 D mitogen.importer.[fork.72377]: xml.etree.warnings is submodule of a locally loaded package                                                                             
[mux  18565] 10:05:07.013021 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.warnings is submodul'..79), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.013219 D mitogen.importer.[fork.72377]: xml.etree.warnings is submodule of a locally loaded package                                                                             
[mux  18565] 10:05:07.013385 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.ElementPath is submo'..82), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.013561 D mitogen.importer.[fork.72377]: xml.etree.ElementPath is submodule of a locally loaded package                                                                          
[mux  18565] 10:05:07.013727 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.ElementPath is submo'..82), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.013904 D mitogen.importer.[fork.72377]: xml.etree.ElementPath is submodule of a locally loaded package                                                                          
[mux  18565] 10:05:07.014091 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.ElementPath is submo'..82), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.014270 D mitogen.importer.[fork.72377]: xml.etree.ElementPath is submodule of a locally loaded package                                                                          
[mux  18565] 10:05:07.014426 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.014577 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.017122 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.017307 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.017487 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.ElementC14N is submo'..82), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.017670 D mitogen.importer.[fork.72377]: xml.etree.ElementC14N is submodule of a locally loaded package                                                                          
[mux  18565] 10:05:07.017836 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.ElementC14N is submo'..82), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.018013 D mitogen.importer.[fork.72377]: xml.etree.ElementC14N is submodule of a locally loaded package                                                                          
[mux  18565] 10:05:07.018202 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00xml.etree.ElementC14N is submo'..82), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.018410 D mitogen.importer.[fork.72377]: xml.etree.ElementC14N is submodule of a locally loaded package                                                                          
[mux  18565] 10:05:07.018566 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.018716 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.018907 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.019077 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.019273 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00requesting ElementC14N'..42), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                           
[mux  18565] 10:05:07.019452 D mitogen.importer.[fork.72377]: requesting ElementC14N
[mux  18565] 10:05:07.019638 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.network.p'..104), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:07.019824 D mitogen.importer.[fork.72377]: ansible.module_utils.network.pfsense.__impl is submodule of a locally loaded package                                                    
[mux  18565] 10:05:07.019980 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.020142 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.020297 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.020453 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.020620 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.network.p'..104), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:07.020798 D mitogen.importer.[fork.72377]: ansible.module_utils.network.pfsense.__impl is submodule of a locally loaded package                                                    
[mux  18565] 10:05:07.020964 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen.importer\x0010\x00ansible.module_utils.network.p'..104), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                  
[mux  18565] 10:05:07.021165 D mitogen.importer.[fork.72377]: ansible.module_utils.network.pfsense.__impl is submodule of a locally loaded package                                                    
[mux  18565] 10:05:07.021319 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.021470 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.022936 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.023150 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.023322 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen\x0010\x00Dispatcher: Message(2004, 2003, 0, 101,'..1267), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                 
[mux  18565] 10:05:07.023505 D mitogen.[fork.72377]: Dispatcher: Message(2004, 2003, 0, 101, 1004, '\x80\x02(NX\x16\x00\x00\x00ansible_mitogen.targetNX\n\x00\x00\x00run_module)cm'..5212) -> CallError(u'exceptions.ImportError: No module named __impl.parse_address\n  File "<stdin>", line 3669, in _dispatch_one\n  File "master:/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/target.py", line 422, in run_module\n    return impl.run()\n  File "master:/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/runner.py", line 440, in run\n    self.setup()\n  File "master:/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/runner.py", line 863, in setup\n    self._setup_imports()\n  File "master:/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/runner.py", line 824, in _setup_imports\n    mitogen.core.import_module(fullname)\n  File "<stdin>", line 668, in import_module\n  File "master:/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/runner.py", line 534, in load_module\n    exec(code, mod.__dict__)\n  File "/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py", line 21, in <module>\n  File "/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py", line 24, in PFSenseModule\n',)                                                                             
[mux  18565] 10:05:07.023694 D mitogen.io: Router(Broker(7f50))._async_route(Message(2003, 2004, 2004, 1004, 0, '\x80\x02cmitogen.core\n_unpickle_call_error\nq\x01XA\x04\x00\x00except'..1140), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                               
[mux  18565] 10:05:07.023864 D mitogen.io: MitogenProtocol(unix_client.18635)._send(Message(2003, 2004, 2004, 1004, 0, '\x80\x02cmitogen.core\n_unpickle_call_error\nq\x01XA\x04\x00\x00except'..1140))                                                                                                  
[mux  18565] 10:05:07.024076 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:07.024099 D mitogen.io: Poller: POLLIN for 13
[mux  18565] 10:05:07.024295 D mitogen.io: EpollPoller.poll(None)
[task 18635] 10:05:07.024402 D mitogen.io: MitogenProtocol(unix_listener.18565).on_receive()
[task 18635] 10:05:07.024618 D mitogen.io: Router(Broker(f4d0))._async_route(Message(2003, 2004, 2004, 1004, 0, '\x80\x02cmitogen.core\n_unpickle_call_error\nq\x01XA\x04\x00\x00except'..1140), <Stream unix_listener.18565 #e0d0>)                                                                     
[task 18635] 10:05:07.024803 D mitogen.io: Receiver(Router(Broker(f4d0)), 1004)._on_receive(Message(2003, 2004, 2004, 1004, 0, '\x80\x02cmitogen.core\n_unpickle_call_error\nq\x01XA\x04\x00\x00except'..1140))                                                                                          
[task 18635] 10:05:07.024962 D mitogen.io: Latch(0x7fc9ac58cf10, size=0, t='mitogen.broker').put(Message(2003, 2004, 2004, 1004, 0, '\x80\x02cmitogen.core\n_unpickle_call_error\nq\x01XA\x04\x00\x00except'..1140))                                                                                     
[task 18635] 10:05:07.025149 D mitogen.io: Latch(0x7fc9ac58cf10, size=1, t='mitogen.broker').put() -> waking wfd=15                                                                                   
[task 18635] 10:05:07.025321 D mitogen.io: PollPoller: POLLIN|POLLHUP for 14
[task 18635] 10:05:07.025491 D mitogen.io: Latch(0x7fc9ac58cf10, size=1, t='MainThread').get() wake -> Message(2003, 2004, 2004, 1004, 0, '\x80\x02cmitogen.core\n_unpickle_call_error\nq\x01XA\x04\x00\x00except'..1140)                                                                                
[task 18635] 10:05:07.025660 D mitogen.io: Message(2003, 2004, 2004, 1004, 0, '\x80\x02cmitogen.core\n_unpickle_call_error\nq\x01XA\x04\x00\x00except'..1140).unpickle()                              
[task 18635] 10:05:07.025860 D mitogen.parent: Context(2004, u'fork.72377').shutdown() sending SHUTDOWN                                                                                               
[task 18635] 10:05:07.026047 D mitogen.io: Waker(fd=11/12).defer() [fd=12]
[task 18635] 10:05:07.026362 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[task 18635] 10:05:07.026521 D mitogen.io: Poller.poll(None)
[task 18635] 10:05:07.027070 D mitogen.io: Poller: POLLIN for 11
[task 18635] 10:05:07.027453 D mitogen.io: Waker(fd=11/12).on_receive()
[task 18635] 10:05:07.027747 D mitogen.io: Router(Broker(f4d0))._async_route(Message(2004, 2003, 2003, 106, None, ''..0), None)                                                                       
[task 18635] 10:05:07.029827 D mitogen.parent: starting no-reply function call to u'ssh.inferno.cora.nwra.com.sudo.root': mitogen.core.Dispatcher.forget_chain('barry.cora.nwra.com-18635-7fc9bec14740-59c58f184d629')                                                                                   
[task 18635] 10:05:07.028788 D mitogen.io: MitogenProtocol(unix_listener.18565)._send(Message(2004, 2003, 2003, 106, None, ''..0))                                                                    
[task 18635] 10:05:07.030143 D mitogen.io: Waker(fd=11/12).defer() [fd=12]
[task 18635] 10:05:07.030425 D mitogen.io: Router(Broker(f4d0)).add_handler(<bound method Receiver._on_receive of Receiver(Router(Broker(f4d0)), None)>, 1005, False)                                 
[task 18635] 10:05:07.030596 D mitogen.io: Waker(fd=11/12).defer() [fd=12]
[task 18635] 10:05:07.030755 D mitogen.io: Receiver(Router(Broker(f4d0)), 1005).get(timeout=None, block=True)                                                                                         
[task 18635] 10:05:07.030906 D mitogen.io: Latch(0x7fc9ac47b750, size=0, t='MainThread').get(timeout=None, block=True)                                                                                
[task 18635] 10:05:07.031092 D mitogen.io: Latch(0x7fc9ac47b750, size=0, t='MainThread')._get_sleep(timeout=None, block=True, fd=14/15)                                                               
[task 18635] 10:05:07.031246 D mitogen.io: PollPoller.poll(None)
[task 18635] 10:05:07.031429 D mitogen.io: Router(Broker(f4d0))._async_route(Message(4, 2003, 2003, 101, None, '\x80\x02(NX\x0c\x00\x00\x00mitogen.coreX\n\x00\x00\x00Dispatcherq\x01X\x0c\x00\x00\x00forget_'..146), None)                                                                              
[mux  18565] 10:05:07.031461 D mitogen.io: EpollPoller: POLLIN: 76
[task 18635] 10:05:07.031667 D mitogen.io: MitogenProtocol(unix_listener.18565)._send(Message(4, 2003, 2003, 101, None, '\x80\x02(NX\x0c\x00\x00\x00mitogen.coreX\n\x00\x00\x00Dispatcherq\x01X\x0c\x00\x00\x00forget_'..146))                                                                           
[mux  18565] 10:05:07.031762 D mitogen.io: MitogenProtocol(unix_client.18635).on_receive()
[task 18635] 10:05:07.031934 D mitogen.io: Router(Broker(f4d0))._async_route(Message(0, 2003, 2003, 110, 1005, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..186), None) 
[mux  18565] 10:05:07.032020 D mitogen.io: Router(Broker(7f50))._async_route(Message(2004, 2003, 2003, 106, 0, ''..0), <Stream unix_client.18635 #2c10>)                                              
[task 18635] 10:05:07.032180 D mitogen.io: MitogenProtocol(unix_listener.18565)._send(Message(0, 2003, 2003, 110, 1005, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..186))                                                                                                 
[mux  18565] 10:05:07.032288 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(2004, 2003, 0, 106, 0, ''..0))                                                                    
[task 18635] 10:05:07.032453 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[mux  18565] 10:05:07.032558 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:07.032768 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:07.032820 D mitogen.io: EpollPoller.poll(None)
[task 18635] 10:05:07.033008 D mitogen.io: Poller: POLLIN for 11
[mux  18565] 10:05:07.033056 D mitogen.io: EpollPoller: POLLIN: 76
[task 18635] 10:05:07.033248 D mitogen.io: Waker(fd=11/12).on_receive()
[mux  18565] 10:05:07.033275 D mitogen.io: MitogenProtocol(unix_client.18635).on_receive()
[task 18635] 10:05:07.033484 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[mux  18565] 10:05:07.033502 D mitogen.io: Router(Broker(7f50))._async_route(Message(4, 2003, 2003, 101, 0, '\x80\x02(NX\x0c\x00\x00\x00mitogen.coreX\n\x00\x00\x00Dispatcherq\x01X\x0c\x00\x00\x00forget_'..146), <Stream unix_client.18635 #2c10>)                                                     
[task 18635] 10:05:07.033707 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:07.033741 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com)._send(Message(4, 2003, 0, 101, 0, '\x80\x02(NX\x0c\x00\x00\x00mitogen.coreX\n\x00\x00\x00Dispatcherq\x01X\x0c\x00\x00\x00forget_'..146))                                                                           
[mux  18565] 10:05:07.034021 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2003, 2003, 110, 1005, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..186), <Stream unix_client.18635 #2c10>)                                                                        
[task 18635] 10:05:07.033942 D mitogen.io: Poller: POLLIN for 11
[task 18635] 10:05:07.034332 D mitogen.io: Waker(fd=11/12).on_receive()
[mux  18565] 10:05:07.034323 D mitogen.io: Receiver(Router(Broker(7f50)), 110)._on_receive(Message(0, 2003, 0, 110, 1005, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..186))                                                                                               
[task 18635] 10:05:07.034557 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[mux  18565] 10:05:07.034590 D mitogen.io: Latch(0x7fc9ac6f5690, size=0, t='mitogen.broker').put(Message(0, 2003, 0, 110, 1005, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..186))                                                                                         
[task 18635] 10:05:07.034780 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:07.034861 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.broker').put(Receiver(Router(Broker(7f50)), 110))                                                                 
[mux  18565] 10:05:07.035098 D mitogen.io: Latch(0x7fc9ac6f5710, size=1, t='mitogen.broker').put() -> waking wfd=27                                                                                   
[mux  18565] 10:05:07.035285 D mitogen.io: PollPoller: POLLIN|POLLHUP for 26
[mux  18565] 10:05:07.035456 D mitogen.io: Latch(0x7fc9ac6f5710, size=1, t='mitogen.Pool.5490.7').get() wake -> Receiver(Router(Broker(7f50)), 110)                                                   
[mux  18565] 10:05:07.035618 D mitogen.io: Receiver(Router(Broker(7f50)), 110).get(timeout=None, block=False)                                                                                         
[mux  18565] 10:05:07.035759 D mitogen.io: Latch(0x7fc9ac6f5690, size=1, t='mitogen.Pool.5490.7').get(timeout=None, block=False)                                                                      
[mux  18565] 10:05:07.035934 D mitogen.io: Latch(0x7fc9ac6f5690, size=1, t='mitogen.Pool.5490.7').get() -> Message(0, 2003, 0, 110, 1005, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..186)                                                                                
[mux  18565] 10:05:07.036147 D mitogen.io: Message(0, 2003, 0, 110, 1005, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..186).unpickle()                                  
[mux  18565] 10:05:07.036335 D mitogen.io: Message(0, 2003, 0, 110, 1005, "\x80\x02X'\x00\x00\x00ansible_mitogen.services.ContextServiceq\x01X\x03"..186).unpickle()                                  
[mux  18565] 10:05:07.036510 D ansible_mitogen.services: decrementing reference count for Context(4, u'ssh.inferno.cora.nwra.com.sudo.root')                                                          
[mux  18565] 10:05:07.036686 D mitogen.io: Waker(fd=6/8).defer() [fd=8]
[mux  18565] 10:05:07.036840 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.Pool.5490.7').get(timeout=None, block=True)                                                                       
[mux  18565] 10:05:07.037007 D mitogen.io: Latch(0x7fc9ac6f5710, size=0, t='mitogen.Pool.5490.7')._get_sleep(timeout=None, block=True, fd=26/27)                                                      
[mux  18565] 10:05:07.037170 D mitogen.io: PollPoller.poll(None)
[mux  18565] 10:05:07.037365 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.037516 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.037693 D mitogen.io: EpollPoller: POLLIN: 77
[mux  18565] 10:05:07.037868 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[mux  18565] 10:05:07.038074 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen\x0010\x00shutdown request from context 2003'..45), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:07.038263 D mitogen.[fork.72377]: shutdown request from context 2003
[mux  18565] 10:05:07.038450 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2004, 2004, 102, 0, 'mitogen\x0010\x00Broker(e590): shutting down'..38), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                               
[mux  18565] 10:05:07.038655 D mitogen.[fork.72377]: Broker(e590): shutting down
[mux  18565] 10:05:07.038818 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, "mitogen\x0010\x00Dispatcher: dispatching (None, u'mitoge"..161), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:07.039017 D mitogen.[ssh.inferno.cora.nwra.com.sudo.root]: Dispatcher: dispatching (None, u'mitogen.core', u'Dispatcher', u'forget_chain', ('barry.cora.nwra.com-18635-7fc9bec14740-59c58f184d629',), Kwargs({}))                                                                     
[mux  18565] 10:05:07.039212 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen\x0010\x00Dispatcher: Message(4, 2003, 0, 101, 0,'..160), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                        
[mux  18565] 10:05:07.039416 D mitogen.[ssh.inferno.cora.nwra.com.sudo.root]: Dispatcher: Message(4, 2003, 0, 101, 0, '\x80\x02(NX\x0c\x00\x00\x00mitogen.coreX\n\x00\x00\x00Dispatcherq\x01X\x0c\x00\x00\x00forget_'..146) -> None                                                                      
[mux  18565] 10:05:07.039604 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen\x0010\x00<Side of fork.72377 fd 15>: empty read,'..64), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.039791 D mitogen.[fork.68631]: <Side of fork.72377 fd 15>: empty read, disconnecting                                                                                            
[mux  18565] 10:05:07.039953 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00Process fork.72377 pid 72377 sti'..99), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[mux  18565] 10:05:07.040171 D mitogen.parent.[fork.68631]: Process fork.72377 pid 72377 still running after IO disconnect, recheck in 0.050s                                                         
[mux  18565] 10:05:07.040351 D mitogen.io: EpollPoller: POLLIN: 6
[mux  18565] 10:05:07.040541 D mitogen.io: Waker(fd=6/8).on_receive()
[mux  18565] 10:05:07.040720 D mitogen.io: Router(Broker(7f50))._async_route(Message(2003, 0, 0, 1005, None, '\x80\x02N.'..4), None)                                                                  
[mux  18565] 10:05:07.040904 D mitogen.io: MitogenProtocol(unix_client.18635)._send(Message(2003, 0, 0, 1005, None, '\x80\x02N.'..4))                                                                 
[mux  18565] 10:05:07.041122 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[task 18635] 10:05:07.041139 D mitogen.io: Poller: POLLIN for 13
[mux  18565] 10:05:07.041336 D mitogen.io: EpollPoller.poll(None)
[task 18635] 10:05:07.041453 D mitogen.io: MitogenProtocol(unix_listener.18565).on_receive()
[mux  18565] 10:05:07.041566 D mitogen.io: EpollPoller: POLLIN: 77
[task 18635] 10:05:07.049453 D mitogen.io: Router(Broker(f4d0))._async_route(Message(2003, 0, 0, 1005, 0, '\x80\x02N.'..4), <Stream unix_listener.18565 #e0d0>)                                       
[mux  18565] 10:05:07.049675 D mitogen.io: MitogenProtocol(ssh.inferno.cora.nwra.com).on_receive()
[task 18635] 10:05:07.049805 D mitogen.io: Receiver(Router(Broker(f4d0)), 1005)._on_receive(Message(2003, 0, 0, 1005, 0, '\x80\x02N.'..4))                                                            
[mux  18565] 10:05:07.049971 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, "mitogen\x0010\x00Context(2004, u'fork.72377'): disconnec"..54), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[task 18635] 10:05:07.050022 D mitogen.io: Latch(0x7fc9ac47b750, size=0, t='mitogen.broker').put(Message(2003, 0, 0, 1005, 0, '\x80\x02N.'..4))                                                       
[task 18635] 10:05:07.050255 D mitogen.io: Latch(0x7fc9ac47b750, size=1, t='mitogen.broker').put() -> waking wfd=15                                                                                   
[mux  18565] 10:05:07.050256 D mitogen.[fork.68631]: Context(2004, u'fork.72377'): disconnecting
[task 18635] 10:05:07.050487 D mitogen.io: Broker(f4d0)._loop_once(None, Poller)
[mux  18565] 10:05:07.050485 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.route_monitor\x0010\x00stream fork.72377 is gone'..89), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[task 18635] 10:05:07.050702 D mitogen.io: Poller.poll(None)
[mux  18565] 10:05:07.050767 D mitogen.route_monitor.[fork.68631]: stream fork.72377 is gone; propagating DEL_ROUTE for set([2004])                                                                   
[task 18635] 10:05:07.050949 D mitogen.io: PollPoller: POLLIN|POLLHUP for 14
[mux  18565] 10:05:07.051014 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen.parent\x0010\x00Router(Broker(79d0)): deleting r'..62), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[task 18635] 10:05:07.051193 D mitogen.io: Latch(0x7fc9ac47b750, size=1, t='MainThread').get() wake -> Message(2003, 0, 0, 1005, 0, '\x80\x02N.'..4)                                                  
[mux  18565] 10:05:07.051275 D mitogen.parent.[fork.68631]: Router(Broker(79d0)): deleting route to 2004                                                                                              
[task 18635] 10:05:07.051430 D mitogen.io: Message(2003, 0, 0, 1005, 0, '\x80\x02N.'..4).unpickle()
[mux  18565] 10:05:07.051509 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.route_monitor\x0010\x00firing local disconnect s'..88), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[task 18635] 10:05:07.051688 D mitogen.io: Waker(fd=11/12).defer() [fd=12]
[mux  18565] 10:05:07.051757 D mitogen.route_monitor.[ssh.inferno.cora.nwra.com.sudo.root]: firing local disconnect signal for Context(2004, u'fork.72377')                                           
[task 18635] 10:05:07.051973 D mitogen.io: Poller: POLLIN for 11
[task 18635] 10:05:07.052210 D mitogen.io: Waker(fd=11/12).on_receive()
[mux  18565] 10:05:07.052261 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.route_monitor\x0010\x00deleting route to 2004 vi'..77), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[task 18635] 10:05:07.052449 D mitogen.io: Waker(fd=11/12).defer() [immediate]
[mux  18565] 10:05:07.052510 D mitogen.route_monitor.[ssh.inferno.cora.nwra.com.sudo.root]: deleting route to 2004 via <Stream fork.68631 #7950>                                                      
[task 18635] 10:05:07.052706 D mitogen: MitogenProtocol(unix_listener.18565): disconnecting
[mux  18565] 10:05:07.052744 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 4, 4, 102, 0, 'mitogen.parent\x0010\x00Router(Broker(2a90)): deleting r'..62), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[task 18635] 10:05:07.052920 D mitogen.io: Broker(f4d0).stop_receive(<Stream unix_listener.18565 #e0d0>)                                                                                              
[mux  18565] 10:05:07.052987 D mitogen.parent.[ssh.inferno.cora.nwra.com.sudo.root]: Router(Broker(2a90)): deleting route to 2004                                                                     
[task 18635] 10:05:07.053149 D mitogen.io: Waker(fd=11/12).defer() [immediate]
[mux  18565] 10:05:07.053238 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.route_monitor\x0010\x00firing local disconnect s'..88), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[task 18635] 10:05:07.053359 D mitogen.io: Broker(f4d0)._stop_transmit(<Stream unix_listener.18565 #e0d0>)                                                                                            
[mux  18565] 10:05:07.053488 D mitogen.route_monitor.[ssh.inferno.cora.nwra.com]: firing local disconnect signal for Context(2004, u'fork.72377')                                                     
[task 18635] 10:05:07.053576 D mitogen.io: <Side of unix_listener.18565 fd 13>.close()
[mux  18565] 10:05:07.053725 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.route_monitor\x0010\x00deleting route to 2004 vi'..76), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[task 18635] 10:05:07.053835 D mitogen.io: <Side of unix_listener.18565 fd 13>.close()
[mux  18565] 10:05:07.053977 D mitogen.route_monitor.[ssh.inferno.cora.nwra.com]: deleting route to 2004 via <Stream sudo.root #38d0>                                                                 
[task 18635] 10:05:07.054083 D mitogen: Waker(fd=11/12): disconnecting
[task 18635] 10:05:07.054285 D mitogen.io: Broker(f4d0).stop_receive(<Stream default #e150>)
[mux  18565] 10:05:07.054267 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 102, 0, 'mitogen.parent\x0010\x00Router(Broker(2b90)): deleting r'..62), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                         
[task 18635] 10:05:07.054494 D mitogen.io: Waker(fd=11/12).defer() [immediate]
[mux  18565] 10:05:07.054515 D mitogen.parent.[ssh.inferno.cora.nwra.com]: Router(Broker(2b90)): deleting route to 2004                                                                               
[task 18635] 10:05:07.054704 D mitogen.io: Broker(f4d0)._stop_transmit(<Stream default #e150>)
[mux  18565] 10:05:07.054726 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 2, 2, 104, 0, '2004'..4), <Stream ssh.inferno.cora.nwra.com #0e90>)                                           
[task 18635] 10:05:07.054923 D mitogen.io: <Side of default fd 11>.close()
[mux  18565] 10:05:07.054962 D mitogen.route_monitor: firing local disconnect signal for Context(2004, u'fork.72377')                                                                                 
[task 18635] 10:05:07.055150 D mitogen.io: <Side of default fd 12>.close()
[mux  18565] 10:05:07.055225 D mitogen.route_monitor: deleting route to 2004 via <Stream ssh.inferno.cora.nwra.com #0e90>                                                                             
[mux  18565] 10:05:07.055488 D mitogen.parent: Router(Broker(7f50)): deleting route to 2004
[task 18635] 10:05:07.055632 D mitogen: Router(Broker(f4d0)): stats: 0 module requests in 0 ms, 0 sent (0 ms minify time), 0 negative responses. Sent 0.0 kb total, 0.0 kb avg.                       
[mux  18565] 10:05:07.055738 D mitogen.io: Waker(fd=6/8).defer() [immediate]
[mux  18565] 10:05:07.056080 D mitogen.io: MitogenProtocol(unix_client.18635)._send(Message(2003, 0, 0, 104, None, '2004'..4))                                                                        
[mux  18565] 10:05:07.056385 D mitogen.io: io_op(<built-in function write>) -> OSError: [Errno 32] Broken pipe                                                                                        
[task 18635] 10:05:07.056490 D mitogen.io: Side._on_fork() closing <Side of default fd 11>
[mux  18565] 10:05:07.056627 D mitogen: <Side of unix_client.18635 fd 76>: disconnected during write: [Errno 32] Broken pipe                                                                          
[task 18635] 10:05:07.056720 D mitogen.io: <Side of default fd 11>.close()
[mux  18565] 10:05:07.056843 D mitogen.io: Broker(7f50)._start_transmit(<Stream unix_client.18635 #2c10>)                                                                                             
[task 18635] 10:05:07.056943 D mitogen.io: Side._on_fork() closing <Side of default fd 12>
[mux  18565] 10:05:07.057071 D mitogen.io: EpollPoller.start_transmit(76, (<Side of unix_client.18635 fd 76>, <bound method Stream.on_transmit of <Stream unix_client.18635 #2c10>>))                 
[task 18635] 10:05:07.057155 D mitogen.io: <Side of default fd 12>.close()
[mux  18565] 10:05:07.057304 D mitogen.io: EpollPoller._control(76)
[task 18635] 10:05:07.057369 D mitogen.io: Side._on_fork() closing <Side of unix_listener.18565 fd 13>                                                                                                
[task 18635] 10:05:07.057574 D mitogen.io: <Side of unix_listener.18565 fd 13>.close()
[mux  18565] 10:05:07.057563 D mitogen.io: Router(Broker(7f50))._async_route(Message(0, 1003, 1003, 102, 0, 'mitogen\x0010\x00MitogenProtocol(fork.72377): disconnect'..53), <Stream ssh.inferno.cora.nwra.com #0e90>)                                                                                   
[task 18635] 10:05:07.057786 D mitogen.io: Side._on_fork() closing <Side of unix_listener.18565 fd 13>                                                                                                
[mux  18565] 10:05:07.057808 D mitogen.[fork.68631]: MitogenProtocol(fork.72377): disconnecting
[task 18635] 10:05:07.057994 D mitogen.io: <Side of unix_listener.18565 fd 13>.close()
[mux  18565] 10:05:07.058004 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.058217 D mitogen.io: EpollPoller.poll(None)
[mux  18565] 10:05:07.058434 D mitogen.io: EpollPoller: POLLIN: 76
[mux  18565] 10:05:07.058647 D mitogen: <Side of unix_client.18635 fd 76>: empty read, disconnecting                                                                                                  
[mux  18565] 10:05:07.058904 D mitogen: MitogenProtocol(unix_client.18635): disconnecting
[mux  18565] 10:05:07.059401 D mitogen.io: Broker(7f50).stop_receive(<Stream unix_client.18635 #2c10>)                                                                                                
[mux  18565] 10:05:07.059639 D mitogen.io: Waker(fd=6/8).defer() [immediate]
[mux  18565] 10:05:07.059847 D mitogen.io: EpollPoller.stop_receive(76)
[mux  18565] 10:05:07.060062 D mitogen.io: EpollPoller._control(76)
[mux  18565] 10:05:07.060275 D mitogen.io: Broker(7f50)._stop_transmit(<Stream unix_client.18635 #2c10>)                                                                                              
[mux  18565] 10:05:07.060470 D mitogen.io: EpollPoller.stop_transmit(76)
[mux  18565] 10:05:07.060651 D mitogen.io: EpollPoller._control(76)
[mux  18565] 10:05:07.061101 D mitogen.io: <Side of unix_client.18635 fd 76>.close()
[mux  18565] 10:05:07.061322 D mitogen.io: <Side of unix_client.18635 fd 76>.close()
[mux  18565] 10:05:07.061525 D mitogen.io: Broker(7f50)._loop_once(None, EpollPoller)
[mux  18565] 10:05:07.061722 D mitogen.io: EpollPoller.poll(None)
The full traceback is:
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/ansible/executor/task_executor.py", line 146, in run
    res = self._execute()
  File "/usr/lib/python2.7/site-packages/ansible/executor/task_executor.py", line 645, in _execute
    result = self._handler.run(task_vars=variables)
  File "/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/mixins.py", line 121, in run                                                                                                 
    return super(ActionModuleMixin, self).run(tmp, task_vars)
  File "/usr/lib/python2.7/site-packages/ansible/plugins/action/normal.py", line 46, in run
    result = merge_hash(result, self._execute_module(task_vars=task_vars, wrap_async=wrap_async))
  File "/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/mixins.py", line 364, in _execute_module                                                                                     
    timeout_secs=self.get_task_timeout_secs(),
  File "/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/planner.py", line 568, in invoke                                                                                             
    response = _invoke_isolated_task(invocation, planner)
  File "/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/planner.py", line 521, in _invoke_isolated_task                                                                              
    kwargs=planner.get_kwargs(),
  File "/export/home/orion-admin/ansible-boulder/mitogen/mitogen/parent.py", line 2017, in call
    return self.default_call_chain.call(fn, *args, **kwargs)
  File "/export/home/orion-admin/ansible-boulder/mitogen/mitogen/parent.py", line 1974, in call
    return receiver.get().unpickle(throw_dead=False)
  File "/export/home/orion-admin/ansible-boulder/mitogen/mitogen/core.py", line 963, in unpickle
    raise obj
CallError: exceptions.ImportError: No module named __impl.parse_address
  File "<stdin>", line 3669, in _dispatch_one
  File "master:/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/target.py", line 422, in run_module                                                                                   
    return impl.run()
  File "master:/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/runner.py", line 440, in run                                                                                          
    self.setup()
  File "master:/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/runner.py", line 863, in setup                                                                                        
    self._setup_imports()
  File "master:/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/runner.py", line 824, in _setup_imports                                                                               
    mitogen.core.import_module(fullname)
  File "<stdin>", line 668, in import_module
  File "master:/export/home/orion-admin/ansible-boulder/mitogen/ansible_mitogen/runner.py", line 534, in load_module                                                                                  
    exec(code, mod.__dict__)
  File "/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py", line 21, in <module>                                                                                       
  File "/export/home/orion-admin/ansible-boulder/module_utils/network/pfsense/pfsense.py", line 24, in PFSenseModule                                                                                  


fatal: [inferno.cora.nwra.com]: FAILED! => {
    "msg": "Unexpected failure during module execution.", 
    "stdout": ""
}

The text was updated successfully, but these errors were encountered:

opoplawski · 2020-04-01T22:52:03Z

Anything else I can provide to help debug this? Really missing being able to use mitogen for this.

s1113950 · 2020-04-01T23:02:15Z

Can you try latest master with Ansible 2.8.8 and python3? I'm working on getting Ansible 2.9+ support fully working, so that might be why. Collections also don't really work either; see #652 , so that could also be why. 🤔

opoplawski · 2020-04-01T23:16:40Z

Thanks, but I still get the same error with that. Not using collections directly - I have the module installed directly in my library/module_utils directories.

s1113950 · 2020-04-01T23:22:02Z

Ahh ok. I'll try and repro at some point soon; not sure how many other collaborators are currently working on mitogen 😬 lately it's just been me afaict.

opoplawski · 2020-07-09T23:06:02Z

This is still a big thorn in my side. I'd be happy to try to help, but I really have no idea where to start looking.

s1113950 · 2020-07-10T20:57:14Z

Ah my bad, somehow this ticket slipped my mind 😞 if you wanna debug it yourself you could put in an epdb.set_trace() at where it failed and it should drop you into the env. I found that epdb works better than pdb for me when doing Ansible stuff.

s1113950 · 2020-07-10T21:00:32Z

If you'd like me to debug it instead could you dump the task snippet from here?
roles/pfsense/tasks/auth.yml I went to your repo and then tried to go to file and there was nothing called auth.yml

opoplawski · 2020-07-10T21:31:01Z

Thanks, I'll try to poke around. Really any pfsense_ task should trigger it. Something simple would be:

- name: Add adservers alias
  pfsense_alias:
    name: adservers
    address: 10.0.0.1 10.0.0.2
    state: present

opoplawski · 2020-07-10T21:42:53Z

Well, renaming __impl to impl doesn't make a difference. Perhaps it is because we are doing imports at the class level and not top?

class PFSenseModule(object):
    """ class managing pfsense base configuration """

    # pylint: disable=import-outside-toplevel
    from ansible.module_utils.network.pfsense.impl.interfaces import (
        get_interface_display_name,
...

opoplawski · 2020-07-10T21:45:23Z

That seems to be it - if I duplicate the imports at the top-level then it runs fine.

s1113950 · 2020-07-14T04:24:35Z

Oh that makes sense. Check out the various FinderMethod classes here (base class for reference): https://github.com/dw/mitogen/blob/master/mitogen/master.py#L421 . Seems like it would be a good idea to have another one for class-level imports added to here: https://github.com/dw/mitogen/blob/master/mitogen/master.py#L735 🤔

opoplawski · 2020-07-19T22:33:02Z

Actually I think the code in question is module_finder.scan() - https://github.com/dw/mitogen/blob/master/ansible_mitogen/module_finder.py#L128 which appears to only scan the top level modules for imports. I suspect it would be a fairly significant change to also scan any defined classes inside those modules.

s1113950 · 2020-07-19T22:44:38Z

ooh good point 👍

opoplawski · 2020-07-19T22:48:57Z

So, I'm no python opcode expert, but it seems like we could find the code objects for classes in scan_code_imports() and do some kind of recursive scan:

            for (op1, arg1), (op2, arg2), (op3, arg3) in izip(opit, opit2, opit3):
               if (detect a LOAD_CONST code object):
                    scan_code_imports(code object)

opoplawski changed the title ~~mitogen does not deploy modules with "__" in the name?~~ mitogen does not deploy class level imports Jul 10, 2020

opoplawski added a commit to opoplawski/mitogen that referenced this issue Jul 20, 2020

Scan class definitions for import statements. Resolves mitogen-hq#682

90251fc

opoplawski added a commit to opoplawski/mitogen that referenced this issue Oct 6, 2020

Scan class definitions for import statements. Resolves mitogen-hq#682

d0a8a91

opoplawski added a commit to opoplawski/mitogen that referenced this issue Jan 20, 2021

Scan class definitions for import statements. Resolves mitogen-hq#682

44a77cb

opoplawski added a commit to opoplawski/mitogen that referenced this issue May 23, 2022

Scan class definitions for import statements. Resolves mitogen-hq#682

97aa474

opoplawski added a commit to opoplawski/mitogen that referenced this issue Jan 11, 2024

Scan class definitions for import statements. Resolves mitogen-hq#682

f0b1f41

opoplawski added a commit to opoplawski/mitogen that referenced this issue Mar 19, 2024

Scan class definitions for import statements. Resolves mitogen-hq#682

db9bd3d

opoplawski added a commit to opoplawski/mitogen that referenced this issue Apr 1, 2024

Scan class definitions for import statements. Resolves mitogen-hq#682

a29bedf

opoplawski added a commit to opoplawski/mitogen that referenced this issue May 7, 2024

Scan class definitions for import statements. Resolves mitogen-hq#682

1f7c33b

opoplawski added a commit to opoplawski/mitogen that referenced this issue May 7, 2024

Scan class definitions for import statements. Resolves mitogen-hq#682

4a9c7d0

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

mitogen does not deploy class level imports #682

mitogen does not deploy class level imports #682

opoplawski commented Jan 17, 2020

opoplawski commented Apr 1, 2020

s1113950 commented Apr 1, 2020

opoplawski commented Apr 1, 2020

s1113950 commented Apr 1, 2020

opoplawski commented Jul 9, 2020

s1113950 commented Jul 10, 2020

s1113950 commented Jul 10, 2020

opoplawski commented Jul 10, 2020

opoplawski commented Jul 10, 2020

opoplawski commented Jul 10, 2020

s1113950 commented Jul 14, 2020

opoplawski commented Jul 19, 2020

s1113950 commented Jul 19, 2020

opoplawski commented Jul 19, 2020

mitogen does not deploy class level imports #682

mitogen does not deploy class level imports #682

Comments

opoplawski commented Jan 17, 2020

opoplawski commented Apr 1, 2020

s1113950 commented Apr 1, 2020

opoplawski commented Apr 1, 2020

s1113950 commented Apr 1, 2020

opoplawski commented Jul 9, 2020

s1113950 commented Jul 10, 2020

s1113950 commented Jul 10, 2020

opoplawski commented Jul 10, 2020

opoplawski commented Jul 10, 2020

opoplawski commented Jul 10, 2020

s1113950 commented Jul 14, 2020

opoplawski commented Jul 19, 2020

s1113950 commented Jul 19, 2020

opoplawski commented Jul 19, 2020