freeswitch@fs-sng-gw> bgapi originate {origination_caller_id_name=103,origination_caller_id_number=103}freetdm/1/a/140 &park() +OK Job-UUID: 3524bcd5-b2a6-4800-9267-b0aa1bce7870 2018-05-30 13:11:09.086403 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:380 Set codec PCMA 20ms 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:1343 Connect outbound channel FreeTDM/1:1/140 2018-05-30 13:11:09.086403 [NOTICE] switch_channel.c:1104 New Channel FreeTDM/1:1/140 [41969031-4f5a-45ad-8980-394ac8f55b6f] 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:1352 (FreeTDM/1:1/140) State Change CS_NEW -> CS_INIT 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:1371 Attached session 41969031-4f5a-45ad-8980-394ac8f55b6f to channel 1:1 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:2058 [s1c1][1:1] Changed state from DOWN to DIALING 2018-05-30 13:11:09.086403 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for DIALING 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to DIALING 2018-05-30 13:11:09.086403 [INFO] ftmod_sangoma_isdn_stack_out.c:66 [s1c1][1:1] Outgoing call: Called No:[140] Calling No:[103] 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn_support.c:1497 [s1c1][1:1] Overriding bearer cap to a-law 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn_support.c:1084 No User supplied Calling Party Subaddress found. 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn_support.c:1136 No User supplied Called Party Subaddress found. 2018-05-30 13:11:09.086403 [INFO] ftmod_sangoma_isdn_stack_out.c:88 [s1c1][1:1] Sending SETUP (suId:1 suInstId:8 spInstId:0 dchan:1 ces:0) 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn.c:1202 [s1c1][1:1] Completed state change from DOWN to DIALING in 1 ms 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:3208 got clear channel sig [DIALING] 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME OUTGOING: Prot Disc:Q.931/I.451 (0x08) Call Ref:0006 (Originating side) Type:SETUP (0x5) Sending complete: Bearer Capability:Coding:ITU-T(0) TransferCap:Speech(0) TransferRate:64 Kbit/s(16) L1Prot:G.711 A-Law(3) Channel Id:No:1 Type:B-chans(3) Exclusive/Implicit Calling Party Number:103(l:3) plan:unknown(0) type:unknown(0)scr:user, passed(1) pres:allowed(0) Called Party Number:140(l:3) plan:unknown(0) type:unknown(0) [ 08 02 00 06 05 a1 04 03 80 90 a3 18 03 a9 83 81 6c 05 00 81 31 30 33 70 04 80 31 34 30 ] 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:584 (FreeTDM/1:1/140) Running State Change CS_INIT (Cur 1 Tot 45) 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:627 (FreeTDM/1:1/140) State INIT 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:407 (FreeTDM/1:1/140) State Change CS_INIT -> CS_ROUTING 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:627 (FreeTDM/1:1/140) State INIT going to sleep 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:584 (FreeTDM/1:1/140) Running State Change CS_ROUTING (Cur 1 Tot 45) 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:643 (FreeTDM/1:1/140) State ROUTING 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:430 FreeTDM/1:1/140 CHANNEL ROUTING 2018-05-30 13:11:09.086403 [DEBUG] switch_ivr_originate.c:67 (FreeTDM/1:1/140) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:643 (FreeTDM/1:1/140) State ROUTING going to sleep 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:584 (FreeTDM/1:1/140) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 45) 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:662 (FreeTDM/1:1/140) State CONSUME_MEDIA 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:662 (FreeTDM/1:1/140) State CONSUME_MEDIA going to sleep 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME INCOMING: Prot Disc:Q.931/I.451 (0x08) Call Ref:0006 (Destination side) Type:PROCEED (0x2) Channel Id:No:1 Type:B-chans(3) Exclusive/Implicit [ 08 02 80 06 02 18 03 a9 83 81 ] 2018-05-30 13:11:09.126399 [INFO] ftmod_sangoma_isdn_stack_rcv.c:196 [s1c1][1:1] Received PROCEED (suId:1 suInstId:8 spInstId:8 ces:0) 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:402 [s1c1][1:1] Processing PROCEED (suId:1 suInstId:8 spInstId:8 ces:0) 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_support.c:566 [s1c1][1:1] User-User IE not present 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:437 [s1c1][1:1] Early media not available 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:448 [s1c1][1:1] Changed state from DIALING to PROCEED 2018-05-30 13:11:09.126399 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for PROCEED 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to PROCEED 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:1202 [s1c1][1:1] Completed state change from DIALING to PROCEED in 0 ms 2018-05-30 13:11:09.126399 [DEBUG] mod_freetdm.c:3208 got clear channel sig [PROCEED] 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME INCOMING: Prot Disc:Q.931/I.451 (0x08) Call Ref:0006 (Destination side) Type:ALERT (0x1) Progress Indicator:coding:ITU-T(0) location:Private network, local user(1) descr:In-band data ready(8) [ 08 02 80 06 01 1e 02 81 88 ] 2018-05-30 13:11:09.126399 [INFO] ftmod_sangoma_isdn_stack_rcv.c:196 [s1c1][1:1] Received ALERT (suId:1 suInstId:8 spInstId:8 ces:0) 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:402 [s1c1][1:1] Processing ALERT (suId:1 suInstId:8 spInstId:8 ces:0) 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_support.c:566 [s1c1][1:1] User-User IE not present 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:434 [s1c1][1:1] Early media available 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:459 [s1c1][1:1] Changed state from PROCEED to RINGING 2018-05-30 13:11:09.126399 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for RINGING 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to RINGING 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:1006 [s1c1][1:1] Completed state change from PROCEED to RINGING in 0 ms 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:1006 [s1c1][1:1] Changed state from RINGING to PROGRESS_MEDIA 2018-05-30 13:11:09.126399 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for PROGRESS_MEDIA 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to PROGRESS_MEDIA 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:1202 [s1c1][1:1] Completed state change from RINGING to PROGRESS_MEDIA in 0 ms 2018-05-30 13:11:09.126399 [DEBUG] mod_freetdm.c:3208 got clear channel sig [RINGING] 2018-05-30 13:11:09.126399 [NOTICE] mod_freetdm.c:3278 Ring-Ready FreeTDM/1:1/140! 2018-05-30 13:11:09.126399 [DEBUG] switch_channel.c:3346 (FreeTDM/1:1/140) Callstate Change DOWN -> RINGING 2018-05-30 13:11:09.126399 [DEBUG] mod_freetdm.c:3208 got clear channel sig [PROGRESS_MEDIA] 2018-05-30 13:11:09.126399 [NOTICE] mod_freetdm.c:3263 Pre-Answer FreeTDM/1:1/140! 2018-05-30 13:11:09.126399 [DEBUG] switch_channel.c:3474 (FreeTDM/1:1/140) Callstate Change RINGING -> EARLY 2018-05-30 13:11:09.146390 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [FreeTDM/1:1/140] 2018-05-30 13:11:09.146390 [INFO] switch_channel.c:3129 FreeTDM/1:1/140 Flipping CID from "103" <103> to "Outbound Call" <140> 2018-05-30 13:11:09.146390 [DEBUG] mod_commands.c:4862 (FreeTDM/1:1/140) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2018-05-30 13:11:09.146390 [DEBUG] switch_core_state_machine.c:584 (FreeTDM/1:1/140) Running State Change CS_EXECUTE (Cur 1 Tot 45) 2018-05-30 13:11:09.146390 [DEBUG] switch_core_state_machine.c:650 (FreeTDM/1:1/140) State EXECUTE 2018-05-30 13:11:09.146390 [DEBUG] mod_freetdm.c:450 FreeTDM/1:1/140 CHANNEL EXECUTE 2018-05-30 13:11:09.146390 [DEBUG] switch_core_state_machine.c:328 FreeTDM/1:1/140 Standard EXECUTE EXECUTE FreeTDM/1:1/140 park() 2018-05-30 13:11:09.166401 [DEBUG] ftmod_wanpipe.c:1104 [s1c1][1:1] Flusing on first rx frame 2018-05-30 13:11:09.166401 [DEBUG] ftmod_wanpipe.c:1023 [s1c1][1:1] First packet read stats: Rx queue len: 3, Rx queue size: 10 2018-05-30 13:11:11.966400 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME INCOMING: Prot Disc:Q.931/I.451 (0x08) Call Ref:0006 (Destination side) Type:CONNECT (0x7) Unknown:Undecoded [ 4c 05 09 80 31 34 30 ] [ 08 02 80 06 07 4c 05 09 80 31 34 30 ] 2018-05-30 13:11:11.966400 [INFO] ftmod_sangoma_isdn_stack_rcv.c:146 [s1c1][1:1] Received CONNECT/CONNECT ACK (suId:1 suInstId:8 spInstId:8 ces:0) 2018-05-30 13:11:11.966400 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:306 [s1c1][1:1] Processing CONNECT/CONNECT ACK (suId:1 suInstId:8 spInstId:8 ces:0) 2018-05-30 13:11:11.966400 [DEBUG] ftmod_sangoma_isdn_support.c:566 [s1c1][1:1] User-User IE not present 2018-05-30 13:11:11.966400 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:335 [s1c1][1:1] Changed state from PROGRESS_MEDIA to UP 2018-05-30 13:11:11.966400 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for UP 2018-05-30 13:11:11.966400 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to UP 2018-05-30 13:11:11.966400 [DEBUG] ftmod_sangoma_isdn.c:1202 [s1c1][1:1] Completed state change from PROGRESS_MEDIA to UP in 0 ms 2018-05-30 13:11:11.966400 [DEBUG] mod_freetdm.c:3208 got clear channel sig [UP] 2018-05-30 13:11:11.966400 [NOTICE] mod_freetdm.c:3248 Channel [FreeTDM/1:1/140] has been answered 2018-05-30 13:11:11.966400 [DEBUG] switch_channel.c:3773 (FreeTDM/1:1/140) Callstate Change EARLY -> ACTIVE 2018-05-30 13:11:11.966400 [DEBUG] ftmod_wanpipe.c:757 [s1c1][1:1] Enabled DTMF events 2018-05-30 13:11:15.946399 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME INCOMING: Prot Disc:Q.931/I.451 (0x08) Call Ref:0006 (Destination side) Type:DISCONNECT (0x45) Cause:coding:ITU-T(0) location:Private network, local user(1) val:Recovery on timer expired(102) Timer T [ 08 02 80 06 45 08 05 81 e6 33 31 33 ] 2018-05-30 13:11:15.946399 [INFO] ftmod_sangoma_isdn_stack_rcv.c:234 [s1c1][1:1] Received DISCONNECT (suId:1 suInstId:8 spInstId:8) 2018-05-30 13:11:15.946399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:566 [s1c1][1:1] Processing DISCONNECT (suId:1 suInstId:8 spInstId:8) 2018-05-30 13:11:15.946399 [DEBUG] ftmod_sangoma_isdn_support.c:566 [s1c1][1:1] User-User IE not present 2018-05-30 13:11:15.946399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:587 [s1c1][1:1] Changed state from UP to TERMINATING 2018-05-30 13:11:15.946399 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for TERMINATING 2018-05-30 13:11:15.946399 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to TERMINATING 2018-05-30 13:11:15.946399 [DEBUG] ftdm_io.c:7053 [s1c1][1:1] Scheduling safety hangup timer 2018-05-30 13:11:15.946399 [DEBUG] ftmod_sangoma_isdn.c:1202 [s1c1][1:1] Completed state change from UP to TERMINATING in 0 ms 2018-05-30 13:11:15.946399 [DEBUG] mod_freetdm.c:3208 got clear channel sig [STOP] 2018-05-30 13:11:15.946399 [INFO] mod_freetdm.c:3238 hangup_cause=102 2018-05-30 13:11:15.946399 [NOTICE] mod_freetdm.c:3239 Hangup FreeTDM/1:1/140 [CS_EXECUTE] [RECOVERY_ON_TIMER_EXPIRE] 2018-05-30 13:11:15.966398 [DEBUG] switch_core_session.c:2815 FreeTDM/1:1/140 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:650 (FreeTDM/1:1/140) State EXECUTE going to sleep 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:584 (FreeTDM/1:1/140) Running State Change CS_HANGUP (Cur 1 Tot 45) 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:850 (FreeTDM/1:1/140) Callstate Change ACTIVE -> HANGUP 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:852 (FreeTDM/1:1/140) State HANGUP 2018-05-30 13:11:15.966398 [DEBUG] mod_freetdm.c:521 [1:1] FreeTDM/1:1/140 CHANNEL HANGUP ENTER 2018-05-30 13:11:15.966398 [DEBUG] mod_freetdm.c:670 [s1c1][1:1] Changed state from TERMINATING to HANGUP 2018-05-30 13:11:15.966398 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for HANGUP 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to HANGUP 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:1086 [s1c1][1:1] Acknowledging remote hangup 2018-05-30 13:11:15.966398 [INFO] ftmod_sangoma_isdn_stack_out.c:525 [s1c1][1:1] Sending RELEASE/RELEASE COMPLETE (suId:1 suInstId:8 spInstId:8) 2018-05-30 13:11:15.966398 [DEBUG] ftdm_io.c:497 [s1c1][1:1] Enabling ec back on call end in channel state HANGUP 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:1132 [s1c1][1:1] Completed state change from TERMINATING to HANGUP in 0 ms 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:1132 [s1c1][1:1] Changed state from HANGUP to HANGUP_COMPLETE 2018-05-30 13:11:15.966398 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for HANGUP_COMPLETE 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to HANGUP_COMPLETE 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:1144 [s1c1][1:1] Waiting for release from stack 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:1202 [s1c1][1:1] Completed state change from HANGUP to HANGUP_COMPLETE in 0 ms 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME OUTGOING: Prot Disc:Q.931/I.451 (0x08) Call Ref:0006 (Originating side) Type:RELEASE (0x4d) Cause:coding:ITU-T(0) location:Private network, local user(1) val:Recovery on timer expired(102) Timer T [ 08 02 00 06 4d 08 02 81 e6 ] 2018-05-30 13:11:15.966398 [DEBUG] mod_freetdm.c:690 [1:1] FreeTDM/1:1/140 CHANNEL HANGUP EXIT 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:60 FreeTDM/1:1/140 Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:852 (FreeTDM/1:1/140) State HANGUP going to sleep 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:619 (FreeTDM/1:1/140) State Change CS_HANGUP -> CS_REPORTING 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:584 (FreeTDM/1:1/140) Running State Change CS_REPORTING (Cur 1 Tot 45) 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:938 (FreeTDM/1:1/140) State REPORTING 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:174 FreeTDM/1:1/140 Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:938 (FreeTDM/1:1/140) State REPORTING going to sleep 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:610 (FreeTDM/1:1/140) State Change CS_REPORTING -> CS_DESTROY 2018-05-30 13:11:15.966398 [DEBUG] switch_core_session.c:1665 Session 45 (FreeTDM/1:1/140) Locked, Waiting on external entities 2018-05-30 13:11:15.966398 [NOTICE] switch_core_session.c:1683 Session 45 (FreeTDM/1:1/140) Ended 2018-05-30 13:11:15.966398 [NOTICE] switch_core_session.c:1687 Close Channel FreeTDM/1:1/140 [CS_DESTROY] 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:741 (FreeTDM/1:1/140) Running State Change CS_DESTROY (Cur 0 Tot 45) 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:751 (FreeTDM/1:1/140) State DESTROY 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:181 FreeTDM/1:1/140 Standard DESTROY 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:751 (FreeTDM/1:1/140) State DESTROY going to sleep 2018-05-30 13:11:15.986400 [INFO] ftmod_sangoma_isdn_stack_rcv.c:269 [s1c1][1:1] Received RELEASE/RELEASE COMPLETE (suId:1 suInstId:8 spInstId:8) 2018-05-30 13:11:15.986400 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME INCOMING: Prot Disc:Q.931/I.451 (0x08) Call Ref:0006 (Destination side) Type:RELEASE COMPLETE (0x5a) [ 08 02 80 06 5a ] 2018-05-30 13:11:15.986400 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:631 [s1c1][1:1] Processing RELEASE/RELEASE COMPLETE (suId:1 suInstId:8 spInstId:8) 2018-05-30 13:11:15.986400 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:650 [s1c1][1:1] Changed state from HANGUP_COMPLETE to DOWN 2018-05-30 13:11:15.986400 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for DOWN 2018-05-30 13:11:15.986400 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to DOWN 2018-05-30 13:11:15.986400 [DEBUG] ftmod_sangoma_isdn_support.c:186 [s1c1][1:1] Clearing call data (suId:1 suInstId:8 spInstId:8) 2018-05-30 13:11:15.986400 [DEBUG] ftdm_io.c:3419 [s1c1][1:1] DTMF debug is already disabled 2018-05-30 13:11:15.986400 [DEBUG] ftdm_io.c:3451 [s1c1][1:1] No need to disable input dump 2018-05-30 13:11:15.986400 [DEBUG] ftdm_io.c:3482 [s1c1][1:1] No need to disable output dump 2018-05-30 13:11:15.986400 [DEBUG] ftdm_io.c:7747 Cleared call with id 8 2018-05-30 13:11:15.986400 [DEBUG] ftdm_io.c:3220 [s1c1][1:1] channel done 2018-05-30 13:11:15.986400 [DEBUG] mod_freetdm.c:3208 got clear channel sig [RELEASED]