[Jan 19 16:27:26] INFO[3741]: asterisk.test_case:173 __init__: Executing tests/channels/pjsip/sdp_offer_answer/incoming/nominal/multiple-media-stream/video/remove
[Jan 19 16:27:26] DEBUG[3741]: asterisk.test_conditions:143 register_observer: Registering: <function handle_condition_failure at 0x7f7dcb210320>
[Jan 19 16:27:26] DEBUG[3741]: asterisk.test_conditions:143 register_observer: Registering: <function handle_condition_failure at 0x7f7dcb210320>
[Jan 19 16:27:26] DEBUG[3741]: asterisk.test_conditions:143 register_observer: Registering: <bound method SIPpTestCase.handle_condition_failure of <asterisk.sipp.SIPpTestCase object at 0x7f7dcbafd3d0>>
[Jan 19 16:27:26] INFO[3741]: asterisk.test_case:278 create_asterisk: Creating Asterisk instance 1
[Jan 19 16:27:27] INFO[3741]: asterisk.test_case:444 _start_asterisk: Starting Asterisk instance 1
[Jan 19 16:27:27] DEBUG[3741]: asterisk.asterisk:239 connectionMade: Asterisk 127.0.0.1 - connection made
[Jan 19 16:27:28] DEBUG[3741]: asterisk.asterisk:874 cli_exec: Executing ['/sbin/asterisk', '-C', '/tmp/asterisk-testsuite/8119f75f851d793c3e72e2572e85aa68/run_1/ast1/etc/asterisk/asterisk.conf', '-rx', 'core waitfullybooted'] ...
[Jan 19 16:27:28] DEBUG[3741]: asterisk.asterisk:179 __cli_output_callback: Asterisk CLI 127.0.0.1 exited 0
[Jan 19 16:27:28] DEBUG[3741]: asterisk.asterisk:874 cli_exec: Executing ['/sbin/asterisk', '-C', '/tmp/asterisk-testsuite/8119f75f851d793c3e72e2572e85aa68/run_1/ast1/etc/asterisk/asterisk.conf', '-rx', 'sip set debug on'] ...
[Jan 19 16:27:28] DEBUG[3741]: asterisk.asterisk:874 cli_exec: Executing ['/sbin/asterisk', '-C', '/tmp/asterisk-testsuite/8119f75f851d793c3e72e2572e85aa68/run_1/ast1/etc/asterisk/asterisk.conf', '-rx', 'pjsip set logger on'] ...
[Jan 19 16:27:28] INFO[3741]: asterisk.test_case:329 create_ami_factory: Creating AMIFactory 1 to 127.0.0.1
[Jan 19 16:27:28] INFO[3741]: twisted:154 publishToNewObserver: Starting factory <starpy.manager.AMIFactory instance at 0x7f7dcad62b00>
[Jan 19 16:27:28] INFO[3741]: AMI:184 connectionMade: Connection Made
[Jan 19 16:27:28] DEBUG[3741]: AMI:361 sendMessage: MSG OUT: {'action': 'login', 'username': 'user', 'secret': 'mysecret', 'actionid': 'dcf90cf3b528-140178250673688-1'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Asterisk Call Manager/5.0.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Response: Success'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ActionID: dcf90cf3b528-140178250673688-1'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Message: Authentication accepted'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'message': u'Authentication accepted', u'response': u'Success', u'actionid': u'dcf90cf3b528-140178250673688-1'}
[Jan 19 16:27:28] INFO[3741]: AMI:203 onComplete: Login Complete: {u'message': u'Authentication accepted', u'response': u'Success', u'actionid': u'dcf90cf3b528-140178250673688-1'}
[Jan 19 16:27:28] INFO[3741]: asterisk.test_case:582 _ami_connect: AMI Connect instance 1
[Jan 19 16:27:28] DEBUG[3741]: asterisk.utils_socket:199 get_avail: Checking the following UDP/IPv4 ports for availability: [0]
[Jan 19 16:27:28] DEBUG[3741]: asterisk.utils_socket:199 get_avail: Checking the following UDP/IPv4 ports for availability: [40336, 40337, 40338]
[Jan 19 16:27:28] INFO[3741]: asterisk.sipp:702 run: Executing SIPp scenario: uac-multiple-video.xml
[Jan 19 16:27:28] DEBUG[3741]: asterisk.sipp:703 run: ['/bin/sipp', '127.0.0.1', '-sf', 'tests/channels/pjsip/sdp_offer_answer/incoming/nominal/multiple-media-stream/video/remove/sipp/uac-multiple-video.xml', '-nostdin', '-skip_rlimit', '-timeout', '20s', '-m', '1', '-i', '127.0.0.1', '-mp', '40335', '-p', '5061', '-s', 'alice', '-nr']
[Jan 19 16:27:28] DEBUG[3741]: asterisk.sipp:502 connectionMade: Connection made to SIPp scenario uac-multiple-video.xml
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: FullyBooted'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: system,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Uptime: 1'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'LastReload: 1'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Status: Fully Booted'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'privilege': u'system,all', u'status': u'Fully Booted', u'uptime': u'1', u'event': u'FullyBooted', u'lastreload': u'1'}
[Jan 19 16:27:28] DEBUG[3741]: asterisk.asterisk:179 __cli_output_callback: Asterisk CLI 127.0.0.1 exited 0
[Jan 19 16:27:28] DEBUG[3741]: asterisk.asterisk:179 __cli_output_callback: Asterisk CLI 127.0.0.1 exited 0
[Jan 19 16:27:28] DEBUG[3741]: asterisk.sipp:497 outReceived: Received from SIPp scenario uac-multiple-video.xml: ------------------------------ Scenario Screen -------- [1-9]: Change Screen --
  Call-rate(length)   Port   Total-time  Total-calls  Remote-host
  10.0(0 ms)/1.000s   5061       0.00 s            0  127.0.0.1:5060(UDP)

  0 new calls during 0.000 s period      0 ms scheduler resolution
  0 calls (limit 30)                     Peak was 0 calls, after 0 s
  0 Running, 0 Paused, 0 Woken up
  0 dead call msg (discarded)            0 out-of-call msg (discarded)        
  2 open sockets                        

                                 Messages  Retrans   Timeout   Unexpected-Msg
      INVITE ---------->         0         0         0                  
         100 <----------         0         0         0         0        
         181 <----------         0         0         0         0        
         180 <----------         0         0         0         0        
         183 <----------         0         0         0         0        
         200 <----------  E-RTD1 0         0         0         0        
         ACK ---------->         0         0                            
      INVITE <----------         0         0         0         0        
         200 ---------->         0         0         0                  
         ACK <----------  E-RTD1 0         0         0         0        

         BYE ---------->         0         0         0                  
         200 <----------         0         0         0         0        

------ [+|-|*|/]: Adjust rate ---- [q]: Soft exit ---- [p]: Pause traffic -----


[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: VarSet'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: dialplan,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Channel: none'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Uniqueid: none'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Variable: SIPDOMAIN'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Value: 127.0.0.1'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'value': u'127.0.0.1', u'variable': u'SIPDOMAIN', u'uniqueid': u'none', u'privilege': u'dialplan,all', u'event': u'VarSet', u'channel': u'none'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: TestEvent'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: reporting,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Type: StateChange'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'State: RTP_PORT_ALLOCATED'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppFile: res_rtp_asterisk.c'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppFunction: rtp_allocate_transport'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppLine: 3496'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Port: 19220'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'appfile': u'res_rtp_asterisk.c', u'appline': u'3496', u'appfunction': u'rtp_allocate_transport', u'port': u'19220', u'state': u'RTP_PORT_ALLOCATED', u'privilege': u'reporting,all', u'type': u'StateChange', u'event': u'TestEvent'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: TestEvent'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: reporting,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Type: StateChange'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'State: RTP_PORT_ALLOCATED'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppFile: res_rtp_asterisk.c'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppFunction: rtp_allocate_transport'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppLine: 3496'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Port: 16816'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'appfile': u'res_rtp_asterisk.c', u'appline': u'3496', u'appfunction': u'rtp_allocate_transport', u'port': u'16816', u'state': u'RTP_PORT_ALLOCATED', u'privilege': u'reporting,all', u'type': u'StateChange', u'event': u'TestEvent'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: TestEvent'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: reporting,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Type: StateChange'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'State: RTP_PORT_ALLOCATED'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppFile: res_rtp_asterisk.c'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppFunction: rtp_allocate_transport'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppLine: 3496'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Port: 13670'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'appfile': u'res_rtp_asterisk.c', u'appline': u'3496', u'appfunction': u'rtp_allocate_transport', u'port': u'13670', u'state': u'RTP_PORT_ALLOCATED', u'privilege': u'reporting,all', u'type': u'StateChange', u'event': u'TestEvent'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: Newchannel'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: call,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Channel: PJSIP/alice-00000000'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ChannelState: 4'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ChannelStateDesc: Ring'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDNum: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDName: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ConnectedLineNum: <unknown>'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ConnectedLineName: <unknown>'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Language: en'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AccountCode: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Context: default'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Exten: answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Priority: 1'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Uniqueid: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Linkedid: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'connectedlinenum': u'<unknown>', u'linkedid': u'1547915248.0', u'uniqueid': u'1547915248.0', u'language': u'en', u'accountcode': u'', u'channelstate': u'4', u'exten': u'answer', u'calleridnum': u'alice', u'priority': u'1', u'connectedlinename': u'<unknown>', u'context': u'default', u'calleridname': u'alice', u'privilege': u'call,all', u'event': u'Newchannel', u'channel': u'PJSIP/alice-00000000', u'channelstatedesc': u'Ring'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: TestEvent'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: reporting,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Type: StateChange'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'State: CallIDChange'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppFile: channel_internal_api.c'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppFunction: ast_channel_callid_set'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppLine: 811'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'State: CallIDChange'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Channel: PJSIP/alice-00000000'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallID: [C-00000001]'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'PriorCallID: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'appfile': u'channel_internal_api.c', u'priorcallid': u'', u'appline': u'811', u'appfunction': u'ast_channel_callid_set', u'state': u'CallIDChange', u'callid': u'[C-00000001]', u'privilege': u'reporting,all', u'type': u'StateChange', u'event': u'TestEvent', u'channel': u'PJSIP/alice-00000000'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: Newexten'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: dialplan,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Channel: PJSIP/alice-00000000'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ChannelState: 4'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ChannelStateDesc: Ring'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDNum: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDName: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ConnectedLineNum: <unknown>'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ConnectedLineName: <unknown>'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Language: en'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AccountCode: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Context: default'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Exten: answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Priority: 1'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Uniqueid: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Linkedid: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Extension: answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Application: NoOp'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppData: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'connectedlinenum': u'<unknown>', u'linkedid': u'1547915248.0', u'uniqueid': u'1547915248.0', u'language': u'en', u'accountcode': u'', u'channelstate': u'4', u'exten': u'answer', u'calleridnum': u'alice', u'priority': u'1', u'extension': u'answer', u'connectedlinename': u'<unknown>', u'context': u'default', u'calleridname': u'alice', u'privilege': u'dialplan,all', u'appdata': u'', u'application': u'NoOp', u'event': u'Newexten', u'channel': u'PJSIP/alice-00000000', u'channelstatedesc': u'Ring'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: Newexten'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: dialplan,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Channel: PJSIP/alice-00000000'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ChannelState: 4'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ChannelStateDesc: Ring'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDNum: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDName: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ConnectedLineNum: <unknown>'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ConnectedLineName: <unknown>'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Language: en'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AccountCode: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Context: default'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Exten: answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Priority: 2'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Uniqueid: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Linkedid: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Extension: answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Application: Answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppData: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'connectedlinenum': u'<unknown>', u'linkedid': u'1547915248.0', u'uniqueid': u'1547915248.0', u'language': u'en', u'accountcode': u'', u'channelstate': u'4', u'exten': u'answer', u'calleridnum': u'alice', u'priority': u'2', u'extension': u'answer', u'connectedlinename': u'<unknown>', u'context': u'default', u'calleridname': u'alice', u'privilege': u'dialplan,all', u'appdata': u'', u'application': u'Answer', u'event': u'Newexten', u'channel': u'PJSIP/alice-00000000', u'channelstatedesc': u'Ring'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: Newstate'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: call,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Channel: PJSIP/alice-00000000'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ChannelState: 6'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ChannelStateDesc: Up'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDNum: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDName: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ConnectedLineNum: <unknown>'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ConnectedLineName: <unknown>'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Language: en'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AccountCode: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Context: default'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Exten: answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Priority: 2'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Uniqueid: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Linkedid: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'connectedlinenum': u'<unknown>', u'linkedid': u'1547915248.0', u'uniqueid': u'1547915248.0', u'language': u'en', u'accountcode': u'', u'channelstate': u'6', u'exten': u'answer', u'calleridnum': u'alice', u'priority': u'2', u'connectedlinename': u'<unknown>', u'context': u'default', u'calleridname': u'alice', u'privilege': u'call,all', u'event': u'Newstate', u'channel': u'PJSIP/alice-00000000', u'channelstatedesc': u'Up'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: CEL'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: call,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'EventName: CHAN_START'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AccountCode: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDnum: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDname: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDani: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDrdnis: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDdnid: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Exten: answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Context: default'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Channel: PJSIP/alice-00000000'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Application: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppData: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'EventTime: 2019-01-19 16:27:28'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AMAFlags: DOCUMENTATION'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'UniqueID: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'LinkedID: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Userfield: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Peer: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'PeerAccount: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Extra: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'linkedid': u'1547915248.0', u'appdata': u'', u'extra': u'', u'exten': u'answer', u'amaflags': u'DOCUMENTATION', u'calleridrdnis': u'', u'event': u'CEL', u'calleriddnid': u'', u'calleridnum': u'alice', u'application': u'', u'calleridname': u'alice', u'privilege': u'call,all', u'channel': u'PJSIP/alice-00000000', u'eventtime': u'2019-01-19 16:27:28', u'eventname': u'CHAN_START', u'peeraccount': u'', u'uniqueid': u'1547915248.0', u'peer': u'', u'accountcode': u'', u'userfield': u'', u'calleridani': u'', u'context': u'default'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: DeviceStateChange'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: call,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Device: PJSIP/alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'State: INUSE'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'privilege': u'call,all', u'device': u'PJSIP/alice', u'state': u'INUSE', u'event': u'DeviceStateChange'}
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: CEL'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: call,all'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'EventName: ANSWER'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AccountCode: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDnum: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDname: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDani: alice'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDrdnis: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDdnid: answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Exten: answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Context: default'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Channel: PJSIP/alice-00000000'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Application: Answer'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppData: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'EventTime: 2019-01-19 16:27:28'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AMAFlags: DOCUMENTATION'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'UniqueID: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'LinkedID: 1547915248.0'
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Userfield: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Peer: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'PeerAccount: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Extra: '
[Jan 19 16:27:28] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:28] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:28] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'linkedid': u'1547915248.0', u'appdata': u'', u'extra': u'', u'exten': u'answer', u'amaflags': u'DOCUMENTATION', u'calleridrdnis': u'', u'event': u'CEL', u'calleriddnid': u'answer', u'calleridnum': u'alice', u'application': u'Answer', u'calleridname': u'alice', u'privilege': u'call,all', u'channel': u'PJSIP/alice-00000000', u'eventtime': u'2019-01-19 16:27:28', u'eventname': u'ANSWER', u'peeraccount': u'', u'uniqueid': u'1547915248.0', u'peer': u'', u'accountcode': u'', u'userfield': u'', u'calleridani': u'alice', u'context': u'default'}
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Event: Newexten'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Privilege: dialplan,all'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Channel: PJSIP/alice-00000000'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ChannelState: 6'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ChannelStateDesc: Up'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDNum: alice'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'CallerIDName: alice'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ConnectedLineNum: <unknown>'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'ConnectedLineName: <unknown>'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Language: en'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AccountCode: '
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Context: default'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Exten: answer'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Priority: 3'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Uniqueid: 1547915248.0'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Linkedid: 1547915248.0'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Extension: answer'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'Application: StreamEcho'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: 'AppData: 1,video'
[Jan 19 16:27:29] DEBUG[3741]: AMI:171 lineReceived: Line In: ''
[Jan 19 16:27:29] DEBUG[3741]: AMI:233 dispatchIncoming: Dispatch Incoming
[Jan 19 16:27:29] DEBUG[3741]: AMI:263 dispatchIncoming: Incoming Message: {u'connectedlinenum': u'<unknown>', u'linkedid': u'1547915248.0', u'uniqueid': u'1547915248.0', u'language': u'en', u'accountcode': u'', u'channelstate': u'6', u'exten': u'answer', u'calleridnum': u'alice', u'priority': u'3', u'extension': u'answer', u'connectedlinename': u'<unknown>', u'context': u'default', u'calleridname': u'alice', u'privilege': u'dialplan,all', u'appdata': u'1,video', u'application': u'StreamEcho', u'event': u'Newexten', u'channel': u'PJSIP/alice-00000000', u'channelstatedesc': u'Up'}
[Jan 19 16:27:30] DEBUG[3741]: asterisk.sipp:497 outReceived: Received from SIPp scenario uac-multiple-video.xml: ------------------------------ Scenario Screen -------- [1-9]: Change Screen --
  Call-rate(length)   Port   Total-time  Total-calls  Remote-host
  10.0(0 ms)/1.000s   5061       1.96 s            1  127.0.0.1:5060(UDP)

  Call limit reached (-m 1), 1.967 s period  4 ms scheduler resolution
  1 calls (limit 30)                     Peak was 1 calls, after 0 s
  1 Running, 0 Paused, 5 Woken up
  0 dead call msg (discarded)            0 out-of-call msg (discarded)        
  2 open sockets                        

                                 Messages  Retrans   Timeout   Unexpected-Msg
      INVITE ---------->         1         0         0                  
         100 <----------         1         0         0         0        
         181 <----------         0         0         0         0        
         180 <----------         0         0         0         0        
         183 <----------         0         0         0         0        
         200 <----------  E-RTD1 1         0         0         0        
         ACK ---------->         1         0                            
      INVITE <----------         1         0         0         0        
         200 ---------->         1         0         0                  
         ACK <----------  E-RTD1 0         0         0         0        

         BYE ---------->         0         0         0                  
         200 <----------         0         0         0         0        

------- Waiting for active calls to end. Press [q] again to force exit. -------


[Jan 19 16:27:30] DEBUG[3741]: asterisk.sipp:497 outReceived: Received from SIPp scenario uac-multiple-video.xml: Last Error: Overload warning: the minor watchdog timer 500ms has been tr...
------------------------------ Scenario Screen -------- [1-9]: Change Screen --
  Call-rate(length)   Port   Total-time  Total-calls  Remote-host
  10.0(0 ms)/1.000s   5061       1.96 s            1  127.0.0.1:5060(UDP)

  Call limit reached (-m 1), 0.000 s period  0 ms scheduler resolution
  0 calls (limit 30)                     Peak was 1 calls, after 0 s
  1 Running, 0 Paused, 0 Woken up
  0 dead call msg (discarded)            0 out-of-call msg (discarded)        
  1 open sockets                        

                                 Messages  Retrans   Timeout   Unexpected-Msg
      INVITE ---------->         1         0         0                  
         100 <----------         1         0         0         0        
         181 <----------         0         0         0         0        
         180 <----------         0         0         0         0        
         183 <----------         0         0         0         0        
         200 <----------  E-RTD1 1         0         0         0        
         ACK ---------->         1         0                            
      INVITE <----------         1         0         0         0        
         200 ---------->         1         0         0                  
         ACK <----------  E-RTD1 0         0         0         1        

         BYE ---------->         0         0         0                  
         200 <----------         0         0         0         0        

------------------------------ Test Terminated --------------------------------


------------------------------ Scenario Screen -------- [1-9]: Change Screen --
  Call-rate(length)   Port   Total-time  Total-calls  Remote-host
  10.0(0 ms)/1.000s   5061       1.96 s            1  127.0.0.1:5060(UDP)

  Call limit reached (-m 1), 0.000 s period  0 ms scheduler resolution
  0 calls (limit 30)                     Peak was 1 calls, after 0 s
  1 Running, 0 Paused, 0 Woken up
  0 dead call msg (discarded)            0 out-of-call msg (discarded)        
  1 open sockets                        

                                 Messages  Retrans   Timeout   Unexpected-Msg
      INVITE ---------->         1         0         0                  
         100 <----------         1         0         0         0        
         181 <----------         0         0         0         0        
         180 <----------         0         0         0         0        
         183 <----------         0         0         0         0        
         200 <----------  E-RTD1 1         0         0         0        
         ACK ---------->         1         0                            
      INVITE <----------         1         0         0         0        
         200 ---------->         1         0         0                  
         ACK <----------  E-RTD1 0         0         0         1        

         BYE ---------->         0         0         0                  
         200 <----------         0         0         0         0        

------------------------------ Test Terminated --------------------------------


----------------------------- Statistics Screen ------- [1-9]: Change Screen --
  Start Time             | 2019-01-19	16:27:28.650340	1547915248.650340         
  Last Reset Time        | 2019-01-19	16:27:30.619064	1547915250.619064         
  Current Time           | 2019-01-19	16:27:30.619090	1547915250.619090         
-------------------------+---------------------------+--------------------------
  Counter Name           | Periodic value            | Cumulative value
-------------------------+---------------------------+--------------------------
  Elapsed Time           | 00:00:00:000000           | 00:00:01:968000          
  Call Rate              |    0.000 cps              |    0.508 cps             
-------------------------+---------------------------+--------------------------
  Incoming call created  |        0                  |        0                 
  OutGoing call created  |        0                  |        1                 
  Total Call created     |                           |        1                 
  Current Call           |        0                  |                          
-------------------------+---------------------------+--------------------------
  Successful call        |        0                  |        0                 
  Failed call            |        0                  |        1                 
-------------------------+---------------------------+--------------------------
  Response Time 1        | 00:00:00:000000           | 00:00:00:018000          
  Call Length            | 00:00:00:000000           | 00:00:01:867000          
------------------------------ Test Terminated --------------------------------



[Jan 19 16:27:30] WARNING[3741]: asterisk.sipp:524 processEnded: Resolving remote host '127.0.0.1'... Done.

[Jan 19 16:27:30] WARNING[3741]: asterisk.sipp:524 processEnded: 2019-01-19	16:27:30.618917	1547915250.618917: Aborting call on unexpected message for Call-Id '1-3844@127.0.0.1': while expecting 'ACK' (index 9), received 'INVITE sip:test@127.0.0.1:5061 SIP/2.0
Via: SIP/2.0/UDP 127.0.0.1:5060;rport;branch=z9hG4bKPje6715d60-02f5-419b-ac81-4bf3c9752373
From: "sut" <sip:test@127.0.0.1>;tag=23ada974-d27d-429a-a2c7-d571557773de
To: "alice" <sip:alice@127.0.0.1>;tag=3844SIPpTag001
Contact: <sip:127.0.0.1:5060>
Call-ID: 1-3844@127.0.0.1
CSeq: 20590 INVITE
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
User-Agent: Asterisk PBX GIT-master-2e22cf5
Content-Type: application/sdp
Content-Length:   308

v=0
o=- 53655765 2353687640 IN IP4 127.0.0.1
s=Asterisk
c=IN IP4 127.0.0.1
t=0 0
m=video 19220 RTP/AVP 99 34
a=rtpmap:99 H264/90000
a=rtpmap:34 H263/90000
a=fmtp:34 SQCIF=0;QCIF=0;CIF=0;CIF4=0;CIF16=0;VGA=0;F=0;I=0;J=0;T=0;K=0;N=0;BPP=0;HRD=0
a=sendrecv
m=video 0 RTP/AVP 34
m=video 0 RTP/AVP 99
'.
sipp: There were more errors, enable -trace_err to log them.

[Jan 19 16:27:30] WARNING[3741]: asterisk.sipp:628 __scenario_callback: SIPp Scenario uac-multiple-video.xml Failed [1]
[Jan 19 16:27:30] WARNING[3741]: asterisk.sipp:203 _handle_scenario_finished: Scenario uac-multiple-video.xml failed
[Jan 19 16:27:30] WARNING[3741]: asterisk.sipp:426 __execute_next: SIPp Scenario uac-multiple-video.xml Failed
[Jan 19 16:27:30] INFO[3741]: asterisk.sipp:217 _finish_test: All SIPp Scenarios executed; stopping reactor
[Jan 19 16:27:30] INFO[3741]: asterisk.test_case:487 __stop_instances: Stopping Asterisk instance 1
[Jan 19 16:27:30] INFO[3741]: asterisk.sipp:532 processEnded: SIPp scenario uac-multiple-video.xml ended with code 1
[Jan 19 16:27:30] DEBUG[3741]: asterisk.asterisk:566 __send_stop_gracefully: sending stop gracefully
[Jan 19 16:27:30] DEBUG[3741]: asterisk.asterisk:874 cli_exec: Executing ['/sbin/asterisk', '-C', '/tmp/asterisk-testsuite/8119f75f851d793c3e72e2572e85aa68/run_1/ast1/etc/asterisk/asterisk.conf', '-rx', 'core stop gracefully'] ...
[Jan 19 16:27:58] INFO[3741]: asterisk.test_case:534 _reactor_timeout: Reactor timeout: '30' seconds (ignored; already stopping)
[Jan 19 16:28:15] WARNING[3741]: asterisk.asterisk:585 __send_kill: Sending KILL to Asterisk 127.0.0.1
[Jan 19 16:28:15] INFO[3741]: asterisk.test_case:508 __stop_reactor: Stopping Reactor
[Jan 19 16:28:15] INFO[3741]: AMI:1136 clientConnectionLost: connection lost, reconnecting...
[Jan 19 16:28:15] INFO[3741]: twisted:154 publishToNewObserver: <twisted.internet.tcp.Connector instance at 0x7f7dcad62b90> will retry in 2 seconds
[Jan 19 16:28:15] INFO[3741]: AMI:1139 clientConnectionLost: None
[Jan 19 16:28:15] INFO[3741]: twisted:154 publishToNewObserver: Stopping factory <starpy.manager.AMIFactory instance at 0x7f7dcad62b00>
[Jan 19 16:28:15] INFO[3741]: twisted:154 publishToNewObserver: Main loop terminated.
[Jan 19 16:28:15] INFO[3741]: test_runner:308 main: Test run for tests/channels/pjsip/sdp_offer_answer/incoming/nominal/multiple-media-stream/video/remove completed with result False