[2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: app_dial.c:3092 dial_exec_full: Exiting with DIALSTATUS=ANSWER. [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: pbx.c:6694 __ast_pbx_run: Spawn extension (debug,OUTGOING_NUM,1) exited non-zero on 'SIP/9XXX-00000082' == Spawn extension (debug, OUTGOING_NUM, 1) exited non-zero on 'SIP/9XXX-00000082' [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: channel.c:2534 ast_softhangup_nolock: Soft-Hanging (0x10) up channel 'SIP/9XXX-00000082' [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: channel.c:2534 ast_softhangup_nolock: Soft-Hanging (0x80) up channel 'SIP/9XXX-00000082' [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: pbx.c:4985 pbx_extension_helper: Launching 'DumpChan' -- Executing [h@debug:1] DumpChan("SIP/9XXX-00000082", "") in new stack Dumping Info For Channel: SIP/9XXX-00000082: ================================================================================ Info: Name= SIP/9XXX-00000082 Type= SIP UniqueID= 1457628812.130 LinkedID= 1457628812.130 CallerIDNum= 9XXX CallerIDName= (N/A) ConnectedLineIDNum= 9XXX ConnectedLineIDName=(N/A) DNIDDigits= (N/A) RDNIS= (N/A) Parkinglot= default Language= en State= Up (6) Rings= 0 NativeFormat= (g729) WriteFormat= g729 ReadFormat= g729 RawWriteFormat= g729 RawReadFormat= g729 WriteTranscode= No ReadTranscode= No 1stFileDescriptor= 33 Framesin= 500 Framesout= 331 TimetoHangup= 0 ElapsedTime= 0h0m11s BridgeID= (Not bridged) Context= debug Extension= h Priority= 1 CallGroup= PickupGroup= Application= DumpChan Data= (Empty) Blocking_in= (Not Blocking) Variables: DIALSTATUS=ANSWER DIALEDTIME=11 ANSWEREDTIME=0 RTPAUDIOQOSRTT=minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000; RTPAUDIOQOSLOSS=minrxlost=0.000000;maxrxlost=0.000000;avgrxlost=0.000000;stdevrxlost=0.000000;reported_minlost=0.000000;reported_maxlost=0.000000;reported_avglost=0.000000;reported_stdevlost=0.000000; RTPAUDIOQOSJITTER=minrxjitter=0.000000;maxrxjitter=0.000000;avgrxjitter=0.000000;stdevrxjitter=0.000000;reported_minjitter=0.000000;reported_maxjitter=0.000000;reported_avgjitter=0.000000;reported_stdevjitter=0.000000; RTPAUDIOQOS=ssrc=1893381535;themssrc=3799437062;lp=1;rxjitter=3.000000;rxcount=485;txjitter=0.002907;txcount=331;rlp=1;rtt=0.000000 RTPAUDIOQOSRTTBRIDGED=minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000; RTPAUDIOQOSLOSSBRIDGED=minrxlost=0.000000;maxrxlost=0.000000;avgrxlost=0.000000;stdevrxlost=0.000000;reported_minlost=0.000000;reported_maxlost=0.000000;reported_avglost=0.000000;reported_stdevlost=0.000000; RTPAUDIOQOSJITTERBRIDGED=minrxjitter=0.000000;maxrxjitter=0.000000;avgrxjitter=0.000000;stdevrxjitter=0.000000;reported_minjitter=0.000000;reported_maxjitter=0.000000;reported_avgjitter=0.000000;reported_stdevjitter=0.000000; RTPAUDIOQOSBRIDGED=ssrc=1842038977;themssrc=1125946426;lp=0;rxjitter=0.000000;rxcount=327;txjitter=0.001914;txcount=392;rlp=0;rtt=0.399968 DIALEDPEERNUMBER=SIP_PROVIDER/OUTGOING_NUM DIALEDPEERNAME=SIP/SIP_PROVIDER-00000083 C2D_TRUNK2=SIP_PROVIDER_2 C2D_TRUNK1=SIP_PROVIDER C2D_CALL_REF=PlatformaTest C2DCLI=0100350909 C2DEXTEN=9XXX SIPCALLID=3056cd1a6e7a37123a0da291548c1520@SERVER_IP:5060 ================================================================================ [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: pbx.c:4985 pbx_extension_helper: Launching 'Hangup' -- Executing [h@debug:2] Hangup("SIP/9XXX-00000082", "") in new stack [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: channel.c:2534 ast_softhangup_nolock: Soft-Hanging (0x20) up channel 'SIP/9XXX-00000082' [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: pbx.c:6263 ast_pbx_h_exten_run: Spawn extension (debug,h,2) exited non-zero on 'SIP/9XXX-00000082' == Spawn extension (debug, h, 2) exited non-zero on 'SIP/9XXX-00000082' [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: channel.c:2683 ast_hangup: Hanging up channel 'SIP/9XXX-00000082' [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: chan_sip.c:6960 sip_hangup: Hangup call SIP/9XXX-00000082, SIP callid 3056cd1a6e7a37123a0da291548c1520@SERVER_IP:5060 [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: chan_sip.c:6965 sip_hangup: update_call_counter(9XXX) - decrement call limit counter on hangup [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: chan_sip.c:6577 update_call_counter: Updating call counter for outgoing call [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: chan_sip.c:6648 update_call_counter: Call to peer '9XXX' removed from call limit 2147483647 [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: res_rtp_asterisk.c:4822 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x20caf18' Scheduling destruction of SIP dialog '3056cd1a6e7a37123a0da291548c1520@SERVER_IP:5060' in 6400 ms (Method: INVITE) [2016-03-10 16:53:43] [DEBUG[13510][C-00000049]: chan_sip.c:11956 reqprep: Strict routing enforced for session 3056cd1a6e7a37123a0da291548c1520@SERVER_IP:5060 [2016-03-10 16:53:43] [DEBUG[18025]: chan_sip.c:29573 sip_devicestate: Checking device state for peer 9XXX [2016-03-10 16:53:43] [DEBUG[18025]: devicestate.c:473 do_state_change: Changing state for SIP/9XXX - state 1 (Not in use) [2016-03-10 16:53:43] [DEBUG[18029]: app_queue.c:2458 extension_state_cb: Extension '9XXX@ext-local' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [2016-03-10 16:53:43] [DEBUG[18029]: app_queue.c:2458 extension_state_cb: Extension '*809XXX@ext-local' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [2016-03-10 16:53:43] [DEBUG[18104]: app_queue.c:2383 device_state_cb: Device 'SIP/9XXX' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [2016-03-10 16:53:43] [DEBUG[18041]: manager.c:5693 match_filter: Examining AMI event: Event: BridgeLeave Privilege: call,all BridgeUniqueid: e07de7fa-9d3a-4caa-8d5c-49b82055cf86 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 0 Channel: SIP/9XXX-00000082 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 9XXX CallerIDName: ConnectedLineNum: 9XXX ConnectedLineName: Language: en AccountCode: enigma_c2d Context: debug Exten: OUTGOING_NUM Priority: 1 Uniqueid: 1457628812.130 Linkedid: 1457628812.130 [2016-03-10 16:53:43] [DEBUG[18041]: manager.c:5693 match_filter: Examining AMI event: Event: VarSet Privilege: dialplan,all Channel: SIP/9XXX-00000082 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 9XXX CallerIDName: ConnectedLineNum: 9XXX ConnectedLineName: Language: en AccountCode: enigma_c2d Context: debug Exten: OUTGOING_NUM Priority: 1 Uniqueid: 1457628812.130 Linkedid: 1457628812.130 Variable: ANSWEREDTIME Value: 0 [2016-03-10 16:53:43] [DEBUG[18041]: manager.c:5693 match_filter: Examining AMI event: Event: VarSet Privilege: dialplan,all Channel: SIP/9XXX-00000082 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 9XXX CallerIDName: ConnectedLineNum: 9XXX ConnectedLineName: Language: en AccountCode: enigma_c2d Context: debug Exten: OUTGOING_NUM Priority: 1 Uniqueid: 1457628812.130 Linkedid: 1457628812.130 Variable: DIALEDTIME Value: 11