mitogen-hq / mitogen

Distributed self-replicating programs in Python
https://mitogen.networkgenomics.com/
BSD 3-Clause "New" or "Revised" License
2.32k stars 197 forks source link

mitogen does not deploy class level imports #682

Open opoplawski opened 4 years ago

opoplawski commented 4 years ago

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.

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": ""
}
opoplawski commented 4 years ago

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

s1113950 commented 4 years ago

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 commented 4 years ago

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 commented 4 years ago

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 commented 4 years ago

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 commented 4 years ago

Ah my bad, somehow this ticket slipped my mind :disappointed: 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 commented 4 years ago

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 commented 4 years ago

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 commented 4 years ago

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 commented 4 years ago

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

s1113950 commented 4 years ago

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 commented 4 years ago

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 commented 4 years ago

ooh good point 👍

opoplawski commented 4 years ago

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)