From babak yakhchali, 3 Years ago, written in FreeSWITCH.
- go back
Embed
Viewing differences between and outgoing freetdm calls drop
  1. freeswitch@fs-sng-gw> bgapi originate {origination_caller_id_name=103,origination_caller_id_number=103}freetdm/1/a/140 &park()
  2. +OK Job-UUID: 3524bcd5-b2a6-4800-9267-b0aa1bce7870
  3.  
  4. 2018-05-30 13:11:09.086403 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  5. 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:380 Set codec PCMA 20ms
  6. 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:1343 Connect outbound channel FreeTDM/1:1/140
  7. 2018-05-30 13:11:09.086403 [NOTICE] switch_channel.c:1104 New Channel FreeTDM/1:1/140 [41969031-4f5a-45ad-8980-394ac8f55b6f]
  8. 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:1352 (FreeTDM/1:1/140) State Change CS_NEW -> CS_INIT
  9. 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:1371 Attached session 41969031-4f5a-45ad-8980-394ac8f55b6f to channel 1:1
  10. 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:2058 [s1c1][1:1] Changed state from DOWN to DIALING
  11. 2018-05-30 13:11:09.086403 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for DIALING
  12. 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to DIALING
  13. 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]
  14. 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn_support.c:1497 [s1c1][1:1] Overriding bearer cap to a-law
  15. 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn_support.c:1084 No User supplied Calling Party Subaddress found.
  16. 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn_support.c:1136 No User supplied Called Party Subaddress found.
  17. 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)
  18. 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
  19. 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:3208 got clear channel sig [DIALING]
  20. 2018-05-30 13:11:09.086403 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME OUTGOING:
  21.   Prot Disc:Q.931/I.451 (0x08)
  22.   Call Ref:0006 (Originating side)
  23.   Type:SETUP (0x5)
  24.   Sending complete:
  25.   Bearer Capability:Coding:ITU-T(0) TransferCap:Speech(0) TransferRate:64 Kbit/s(16) L1Prot:G.711 A-Law(3)
  26.   Channel Id:No:1 Type:B-chans(3)  Exclusive/Implicit
  27.   Calling Party Number:103(l:3) plan:unknown(0) type:unknown(0)scr:user, passed(1) pres:allowed(0)
  28.   Called Party Number:140(l:3) plan:unknown(0) type:unknown(0)
  29.   [ 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 ]
  30.  
  31. 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)
  32. 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:627 (FreeTDM/1:1/140) State INIT
  33. 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:407 (FreeTDM/1:1/140) State Change CS_INIT -> CS_ROUTING
  34. 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:627 (FreeTDM/1:1/140) State INIT going to sleep
  35. 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)
  36. 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:643 (FreeTDM/1:1/140) State ROUTING
  37. 2018-05-30 13:11:09.086403 [DEBUG] mod_freetdm.c:430 FreeTDM/1:1/140 CHANNEL ROUTING
  38. 2018-05-30 13:11:09.086403 [DEBUG] switch_ivr_originate.c:67 (FreeTDM/1:1/140) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  39. 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:643 (FreeTDM/1:1/140) State ROUTING going to sleep
  40. 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)
  41. 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:662 (FreeTDM/1:1/140) State CONSUME_MEDIA
  42. 2018-05-30 13:11:09.086403 [DEBUG] switch_core_state_machine.c:662 (FreeTDM/1:1/140) State CONSUME_MEDIA going to sleep
  43. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME INCOMING:
  44.   Prot Disc:Q.931/I.451 (0x08)
  45.   Call Ref:0006 (Destination side)
  46.   Type:PROCEED (0x2)
  47.   Channel Id:No:1 Type:B-chans(3)  Exclusive/Implicit
  48.   [ 08 02 80 06 02 18 03 a9 83 81 ]
  49.  
  50. 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)
  51. 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)
  52. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_support.c:566 [s1c1][1:1] User-User IE not present
  53. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:437 [s1c1][1:1] Early media not available
  54. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:448 [s1c1][1:1] Changed state from DIALING to PROCEED
  55. 2018-05-30 13:11:09.126399 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for PROCEED
  56. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to PROCEED
  57. 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
  58. 2018-05-30 13:11:09.126399 [DEBUG] mod_freetdm.c:3208 got clear channel sig [PROCEED]
  59. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME INCOMING:
  60.   Prot Disc:Q.931/I.451 (0x08)
  61.   Call Ref:0006 (Destination side)
  62.   Type:ALERT (0x1)
  63.   Progress Indicator:coding:ITU-T(0) location:Private network, local user(1) descr:In-band data ready(8)
  64.   [ 08 02 80 06 01 1e 02 81 88 ]
  65.  
  66. 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)
  67. 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)
  68. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_support.c:566 [s1c1][1:1] User-User IE not present
  69. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:434 [s1c1][1:1] Early media available
  70. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:459 [s1c1][1:1] Changed state from PROCEED to RINGING
  71. 2018-05-30 13:11:09.126399 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for RINGING
  72. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to RINGING
  73. 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
  74. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:1006 [s1c1][1:1] Changed state from RINGING to PROGRESS_MEDIA
  75. 2018-05-30 13:11:09.126399 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for PROGRESS_MEDIA
  76. 2018-05-30 13:11:09.126399 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to PROGRESS_MEDIA
  77. 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
  78. 2018-05-30 13:11:09.126399 [DEBUG] mod_freetdm.c:3208 got clear channel sig [RINGING]
  79. 2018-05-30 13:11:09.126399 [NOTICE] mod_freetdm.c:3278 Ring-Ready FreeTDM/1:1/140!
  80. 2018-05-30 13:11:09.126399 [DEBUG] switch_channel.c:3346 (FreeTDM/1:1/140) Callstate Change DOWN -> RINGING
  81. 2018-05-30 13:11:09.126399 [DEBUG] mod_freetdm.c:3208 got clear channel sig [PROGRESS_MEDIA]
  82. 2018-05-30 13:11:09.126399 [NOTICE] mod_freetdm.c:3263 Pre-Answer FreeTDM/1:1/140!
  83. 2018-05-30 13:11:09.126399 [DEBUG] switch_channel.c:3474 (FreeTDM/1:1/140) Callstate Change RINGING -> EARLY
  84. 2018-05-30 13:11:09.146390 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [FreeTDM/1:1/140]
  85. 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>
  86. 2018-05-30 13:11:09.146390 [DEBUG] mod_commands.c:4862 (FreeTDM/1:1/140) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
  87. 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)
  88. 2018-05-30 13:11:09.146390 [DEBUG] switch_core_state_machine.c:650 (FreeTDM/1:1/140) State EXECUTE
  89. 2018-05-30 13:11:09.146390 [DEBUG] mod_freetdm.c:450 FreeTDM/1:1/140 CHANNEL EXECUTE
  90. 2018-05-30 13:11:09.146390 [DEBUG] switch_core_state_machine.c:328 FreeTDM/1:1/140 Standard EXECUTE
  91. EXECUTE FreeTDM/1:1/140 park()
  92. 2018-05-30 13:11:09.166401 [DEBUG] ftmod_wanpipe.c:1104 [s1c1][1:1] Flusing on first rx frame
  93. 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
  94. 2018-05-30 13:11:11.966400 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME INCOMING:
  95.   Prot Disc:Q.931/I.451 (0x08)
  96.   Call Ref:0006 (Destination side)
  97.   Type:CONNECT (0x7)
  98.   Unknown:Undecoded  [ 4c 05 09 80 31 34 30 ]
  99.   [ 08 02 80 06 07 4c 05 09 80 31 34 30 ]
  100.  
  101. 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)
  102. 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)
  103. 2018-05-30 13:11:11.966400 [DEBUG] ftmod_sangoma_isdn_support.c:566 [s1c1][1:1] User-User IE not present
  104. 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
  105. 2018-05-30 13:11:11.966400 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for UP
  106. 2018-05-30 13:11:11.966400 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to UP
  107. 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
  108. 2018-05-30 13:11:11.966400 [DEBUG] mod_freetdm.c:3208 got clear channel sig [UP]
  109. 2018-05-30 13:11:11.966400 [NOTICE] mod_freetdm.c:3248 Channel [FreeTDM/1:1/140] has been answered
  110. 2018-05-30 13:11:11.966400 [DEBUG] switch_channel.c:3773 (FreeTDM/1:1/140) Callstate Change EARLY -> ACTIVE
  111. 2018-05-30 13:11:11.966400 [DEBUG] ftmod_wanpipe.c:757 [s1c1][1:1] Enabled DTMF events
  112. 2018-05-30 13:11:15.946399 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME INCOMING:
  113.   Prot Disc:Q.931/I.451 (0x08)
  114.   Call Ref:0006 (Destination side)
  115.   Type:DISCONNECT (0x45)
  116.   Cause:coding:ITU-T(0) location:Private network, local user(1) val:Recovery on timer expired(102)
  117.   Timer T
  118.   [ 08 02 80 06 45 08 05 81 e6 33 31 33 ]
  119.  
  120. 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)
  121. 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)
  122. 2018-05-30 13:11:15.946399 [DEBUG] ftmod_sangoma_isdn_support.c:566 [s1c1][1:1] User-User IE not present
  123. 2018-05-30 13:11:15.946399 [DEBUG] ftmod_sangoma_isdn_stack_hndl.c:587 [s1c1][1:1] Changed state from UP to TERMINATING
  124. 2018-05-30 13:11:15.946399 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for TERMINATING
  125. 2018-05-30 13:11:15.946399 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to TERMINATING
  126. 2018-05-30 13:11:15.946399 [DEBUG] ftdm_io.c:7053 [s1c1][1:1] Scheduling safety hangup timer
  127. 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
  128. 2018-05-30 13:11:15.946399 [DEBUG] mod_freetdm.c:3208 got clear channel sig [STOP]
  129. 2018-05-30 13:11:15.946399 [INFO] mod_freetdm.c:3238 hangup_cause=102
  130. 2018-05-30 13:11:15.946399 [NOTICE] mod_freetdm.c:3239 Hangup FreeTDM/1:1/140 [CS_EXECUTE] [RECOVERY_ON_TIMER_EXPIRE]
  131. 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)
  132. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:650 (FreeTDM/1:1/140) State EXECUTE going to sleep
  133. 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)
  134. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:850 (FreeTDM/1:1/140) Callstate Change ACTIVE -> HANGUP
  135. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:852 (FreeTDM/1:1/140) State HANGUP
  136. 2018-05-30 13:11:15.966398 [DEBUG] mod_freetdm.c:521 [1:1] FreeTDM/1:1/140 CHANNEL HANGUP ENTER
  137. 2018-05-30 13:11:15.966398 [DEBUG] mod_freetdm.c:670 [s1c1][1:1] Changed state from TERMINATING to HANGUP
  138. 2018-05-30 13:11:15.966398 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for HANGUP
  139. 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to HANGUP
  140. 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:1086 [s1c1][1:1] Acknowledging remote hangup
  141. 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)
  142. 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
  143. 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
  144. 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:1132 [s1c1][1:1] Changed state from HANGUP to HANGUP_COMPLETE
  145. 2018-05-30 13:11:15.966398 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for HANGUP_COMPLETE
  146. 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to HANGUP_COMPLETE
  147. 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn.c:1144 [s1c1][1:1] Waiting for release from stack
  148. 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
  149. 2018-05-30 13:11:15.966398 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME OUTGOING:
  150.   Prot Disc:Q.931/I.451 (0x08)
  151.   Call Ref:0006 (Originating side)
  152.   Type:RELEASE (0x4d)
  153.   Cause:coding:ITU-T(0) location:Private network, local user(1) val:Recovery on timer expired(102)
  154.   Timer T
  155.   [ 08 02 00 06 4d 08 02 81 e6 ]
  156.  
  157. 2018-05-30 13:11:15.966398 [DEBUG] mod_freetdm.c:690 [1:1] FreeTDM/1:1/140 CHANNEL HANGUP EXIT
  158. 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
  159. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:852 (FreeTDM/1:1/140) State HANGUP going to sleep
  160. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:619 (FreeTDM/1:1/140) State Change CS_HANGUP -> CS_REPORTING
  161. 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)
  162. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:938 (FreeTDM/1:1/140) State REPORTING
  163. 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
  164. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:938 (FreeTDM/1:1/140) State REPORTING going to sleep
  165. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:610 (FreeTDM/1:1/140) State Change CS_REPORTING -> CS_DESTROY
  166. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_session.c:1665 Session 45 (FreeTDM/1:1/140) Locked, Waiting on external entities
  167. 2018-05-30 13:11:15.966398 [NOTICE] switch_core_session.c:1683 Session 45 (FreeTDM/1:1/140) Ended
  168. 2018-05-30 13:11:15.966398 [NOTICE] switch_core_session.c:1687 Close Channel FreeTDM/1:1/140 [CS_DESTROY]
  169. 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)
  170. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:751 (FreeTDM/1:1/140) State DESTROY
  171. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:181 FreeTDM/1:1/140 Standard DESTROY
  172. 2018-05-30 13:11:15.966398 [DEBUG] switch_core_state_machine.c:751 (FreeTDM/1:1/140) State DESTROY going to sleep
  173. 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)
  174. 2018-05-30 13:11:15.986400 [DEBUG] ftmod_sangoma_isdn_trace.c:228 [SNGISDN Q931] wp1 FRAME INCOMING:
  175.   Prot Disc:Q.931/I.451 (0x08)
  176.   Call Ref:0006 (Destination side)
  177.   Type:RELEASE COMPLETE (0x5a)
  178.   [ 08 02 80 06 5a ]
  179.  
  180. 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)
  181. 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
  182. 2018-05-30 13:11:15.986400 [DEBUG] ftdm_state.c:541 [s1c1][1:1] Executing state processor for DOWN
  183. 2018-05-30 13:11:15.986400 [DEBUG] ftmod_sangoma_isdn.c:896 [s1c1][1:1] processing state change to DOWN
  184. 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)
  185. 2018-05-30 13:11:15.986400 [DEBUG] ftdm_io.c:3419 [s1c1][1:1] DTMF debug is already disabled
  186. 2018-05-30 13:11:15.986400 [DEBUG] ftdm_io.c:3451 [s1c1][1:1] No need to disable input dump
  187. 2018-05-30 13:11:15.986400 [DEBUG] ftdm_io.c:3482 [s1c1][1:1] No need to disable output dump
  188. 2018-05-30 13:11:15.986400 [DEBUG] ftdm_io.c:7747 Cleared call with id 8
  189. 2018-05-30 13:11:15.986400 [DEBUG] ftdm_io.c:3220 [s1c1][1:1] channel done
  190. 2018-05-30 13:11:15.986400 [DEBUG] mod_freetdm.c:3208 got clear channel sig [RELEASED]
  191.