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]