API tools faq
paste
Advertisement
xofapcom

ftdm_log1

xofapcom
Oct 8th, 2012
507
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
FreeSWITCH 23.70 KB | None | 0 0
raw download clone embed print report
  1. span class="re1"> 2012-10-08 14:28:19.544935 [NOTICE] switch_channel.c:951 New Channel sofia/internal/101@192.168.1.88 [bbf3ba14-1119-11e2-87c4-ebdb123c970c]
  2. 2012-10-08 14:28:19.544935 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  3. 2012-10-08 14:28:19.544935 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/101@192.168.1.88) Running State Change CS_NEW
  4. 2012-10-08 14:28:19.544935 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/101@192.168.1.88) State NEW
  5. 2012-10-08 14:28:19.544935 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  6. 2012-10-08 14:28:19.564938 [DEBUG] sofia.c:8412 IP 192.168.1.63 Rejected by acl "domains". Falling back to Digest auth.
  7. 2012-10-08 14:28:19.564938 [WARNING] sofia_reg.c:1484 SIP auth challenge (INVITE) on sofia profile 'internal' for [945867833@192.168.1.88] from ip 192.168.1.63
  8. 2012-10-08 14:28:19.564938 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  9. 2012-10-08 14:28:19.564938 [DEBUG] sofia.c:1728 detaching session bbf3ba14-1119-11e2-87c4-ebdb123c970c
  10. 2012-10-08 14:28:19.804945 [DEBUG] sofia.c:1820 Re-attaching to session bbf3ba14-1119-11e2-87c4-ebdb123c970c
  11. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  12. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  13. 2012-10-08 14:28:19.804945 [DEBUG] sofia.c:8412 IP 192.168.1.63 Rejected by acl "domains". Falling back to Digest auth.
  14. 2012-10-08 14:28:19.804945 [DEBUG] sofia.c:6282 Channel sofia/internal/101@192.168.1.88 entering state [received][100]
  15. 2012-10-08 14:28:19.804945 [DEBUG] sofia.c:6293 Remote SDP:
  16. v=0
  17. o=CMI-SIPUA 15999 0 IN IP4 192.168.1.63
  18. s=SIP CALL
  19. c=IN IP4 192.168.1.63
  20. t=0 0
  21. m=audio 23424 RTP/AVP 0 8 18 101
  22. a=rtpmap:0 PCMU/8000
  23. a=rtpmap:8 PCMA/8000
  24. a=rtpmap:18 G729/8000
  25. a=fmtp:18 annexb=no
  26. a=rtpmap:101 telephone-event/8000
  27. a=fmtp:101 0-15
  28. a=ptime:20
  29. a=rtcp:23425
  30.  
  31. 2012-10-08 14:28:19.804945 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]
  32. 2012-10-08 14:28:19.804945 [DEBUG] sofia_glue.c:5094 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
  33. 2012-10-08 14:28:19.804945 [DEBUG] sofia_glue.c:3077 Set Codec sofia/internal/101@192.168.1.88 PCMU/8000 20 ms 160 samples 64000 bits
  34. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_codec.c:111 sofia/internal/101@192.168.1.88 Original read codec set to PCMU:0
  35. 2012-10-08 14:28:19.804945 [DEBUG] sofia_glue.c:5219 Set 2833 dtmf send/recv payload to 101
  36. 2012-10-08 14:28:19.804945 [DEBUG] sofia.c:6526 (sofia/internal/101@192.168.1.88) State Change CS_NEW -> CS_INIT
  37. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_session.c:1210 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  38. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/101@192.168.1.88) Running State Change CS_INIT
  39. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:437 (sofia/internal/101@192.168.1.88) State INIT
  40. 2012-10-08 14:28:19.804945 [DEBUG] mod_sofia.c:86 sofia/internal/101@192.168.1.88 SOFIA INIT
  41. 2012-10-08 14:28:19.804945 [DEBUG] mod_sofia.c:126 (sofia/internal/101@192.168.1.88) State Change CS_INIT -> CS_ROUTING
  42. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_session.c:1210 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  43. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:437 (sofia/internal/101@192.168.1.88) State INIT going to sleep
  44. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/101@192.168.1.88) Running State Change CS_ROUTING
  45. 2012-10-08 14:28:19.804945 [DEBUG] switch_channel.c:1964 (sofia/internal/101@192.168.1.88) Callstate Change DOWN -> RINGING
  46. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/101@192.168.1.88) State ROUTING
  47. 2012-10-08 14:28:19.804945 [DEBUG] mod_sofia.c:149 sofia/internal/101@192.168.1.88 SOFIA ROUTING
  48. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:117 sofia/internal/101@192.168.1.88 Standard ROUTING
  49. 2012-10-08 14:28:19.804945 [INFO] mod_dialplan_xml.c:485 Processing 101 <101>->945867833 in context default
  50. Dialplan: sofia/internal/101@192.168.1.88 parsing [default->freetdm.9d616] continue=false
  51. Dialplan: sofia/internal/101@192.168.1.88 Regex (PASS) [freetdm.9d616] destination_number(945867833) =~ /^9(\d{6,16})$/ break=on-false
  52. Dialplan: sofia/internal/101@192.168.1.88 Action set(sip_h_X-accountcode=${accountcode})
  53. Dialplan: sofia/internal/101@192.168.1.88 Action set(sip_h_X-Tag=)
  54. Dialplan: sofia/internal/101@192.168.1.88 Action set(call_direction=outbound)
  55. Dialplan: sofia/internal/101@192.168.1.88 Action set(hangup_after_bridge=true)
  56. Dialplan: sofia/internal/101@192.168.1.88 Action set(effective_caller_id_name=${outbound_caller_id_name})
  57. Dialplan: sofia/internal/101@192.168.1.88 Action set(effective_caller_id_number=${outbound_caller_id_number})
  58. Dialplan: sofia/internal/101@192.168.1.88 Action set(inherit_codec=true)
  59. Dialplan: sofia/internal/101@192.168.1.88 Action bridge(freetdm/1/a/45867833)
  60. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/101@192.168.1.88) State Change CS_ROUTING -> CS_EXECUTE
  61. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_session.c:1210 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  62. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/101@192.168.1.88) State ROUTING going to sleep
  63. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/101@192.168.1.88) Running State Change CS_EXECUTE
  64. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:453 (sofia/internal/101@192.168.1.88) State EXECUTE
  65. 2012-10-08 14:28:19.804945 [DEBUG] mod_sofia.c:242 sofia/internal/101@192.168.1.88 SOFIA EXECUTE
  66. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_state_machine.c:209 sofia/internal/101@192.168.1.88 Standard EXECUTE
  67. EXECUTE sofia/internal/101@192.168.1.88 set(sip_h_X-accountcode=)
  68. 2012-10-08 14:28:19.804945 [DEBUG] mod_dptools.c:1319 sofia/internal/101@192.168.1.88 SET [sip_h_X-accountcode]=[UNDEF]
  69. EXECUTE sofia/internal/101@192.168.1.88 set(sip_h_X-Tag=)
  70. 2012-10-08 14:28:19.804945 [DEBUG] mod_dptools.c:1319 sofia/internal/101@192.168.1.88 SET [sip_h_X-Tag]=[UNDEF]
  71. EXECUTE sofia/internal/101@192.168.1.88 set(call_direction=outbound)
  72. 2012-10-08 14:28:19.804945 [DEBUG] mod_dptools.c:1319 sofia/internal/101@192.168.1.88 SET [call_direction]=[outbound]
  73. EXECUTE sofia/internal/101@192.168.1.88 set(hangup_after_bridge=true)
  74. 2012-10-08 14:28:19.804945 [DEBUG] mod_dptools.c:1319 sofia/internal/101@192.168.1.88 SET [hangup_after_bridge]=[true]
  75. EXECUTE sofia/internal/101@192.168.1.88 set(effective_caller_id_name=)
  76. 2012-10-08 14:28:19.804945 [DEBUG] mod_dptools.c:1319 sofia/internal/101@192.168.1.88 SET [effective_caller_id_name]=[UNDEF]
  77. EXECUTE sofia/internal/101@192.168.1.88 set(effective_caller_id_number=)
  78. 2012-10-08 14:28:19.804945 [DEBUG] mod_dptools.c:1319 sofia/internal/101@192.168.1.88 SET [effective_caller_id_number]=[UNDEF]
  79. EXECUTE sofia/internal/101@192.168.1.88 set(inherit_codec=true)
  80. 2012-10-08 14:28:19.804945 [DEBUG] mod_dptools.c:1319 sofia/internal/101@192.168.1.88 SET [inherit_codec]=[true]
  81. EXECUTE sofia/internal/101@192.168.1.88 bridge(freetdm/1/a/45867833)
  82. 2012-10-08 14:28:19.804945 [DEBUG] switch_channel.c:1072 sofia/internal/101@192.168.1.88 EXPORTING[export_vars] [domain_name]=[192.168.1.88] to event
  83. 2012-10-08 14:28:19.804945 [DEBUG] switch_ivr_originate.c:2005 Parsing global variables
  84. 2012-10-08 14:28:19.804945 [INFO] ftmod_zt.c:656 Setting echo cancel to 64 taps for 1:4
  85. 2012-10-08 14:28:19.804945 [DEBUG] mod_freetdm.c:403 Set codec PCMU 20ms
  86. 2012-10-08 14:28:19.804945 [DEBUG] mod_freetdm.c:1238 Connect outbound channel FreeTDM/1:4/45867833
  87. 2012-10-08 14:28:19.804945 [NOTICE] switch_channel.c:951 New Channel FreeTDM/1:4/45867833 [bc1d2e9e-1119-11e2-87d1-ebdb123c970c]
  88. 2012-10-08 14:28:19.804945 [DEBUG] mod_freetdm.c:1247 (FreeTDM/1:4/45867833) State Change CS_NEW -> CS_INIT
  89. 2012-10-08 14:28:19.804945 [DEBUG] switch_core_session.c:1210 Send signal FreeTDM/1:4/45867833 [BREAK]
  90. 2012-10-08 14:28:19.804945 [DEBUG] mod_freetdm.c:1266 Attached session bc1d2e9e-1119-11e2-87d1-ebdb123c970c to channel 1:4
  91. 2012-10-08 14:28:19.804945 [DEBUG] ftmod_analog.c:62 [s1c4][1:4] Changed state from DOWN to DIALING
  92. 2012-10-08 14:28:19.804945 [DEBUG] ftmod_analog.c:439 [s1c4][1:4] ANALOG CHANNEL thread starting.
  93. 2012-10-08 14:28:19.804945 [INFO] ftmod_zt.c:656 Setting echo cancel to 64 taps for 1:4
  94. 2012-10-08 14:28:19.804945 [DEBUG] ftdm_io.c:3386 [s1c4][1:4] Enabled software DTMF detector
  95. 2012-10-08 14:28:19.804945 [DEBUG] ftmod_analog.c:459 [s1c4][1:4] Initialized DTMF detection
  96. 2012-10-08 14:28:19.804945 [DEBUG] ftmod_analog.c:640 [s1c4][1:4] Completed state change from DOWN to DIALING in 0 ms
  97. 2012-10-08 14:28:19.804945 [DEBUG] ftmod_analog.c:646 [s1c4][1:4] Executing state handler on 1:4 for DIALING
  98. 2012-10-08 14:28:20.304932 [DEBUG] switch_core_state_machine.c:398 (FreeTDM/1:4/45867833) Running State Change CS_INIT
  99. 2012-10-08 14:28:20.304932 [DEBUG] switch_core_state_machine.c:437 (FreeTDM/1:4/45867833) State INIT
  100. 2012-10-08 14:28:20.304932 [DEBUG] mod_freetdm.c:430 (FreeTDM/1:4/45867833) State Change CS_INIT -> CS_ROUTING
  101. 2012-10-08 14:28:20.304932 [DEBUG] switch_core_session.c:1210 Send signal FreeTDM/1:4/45867833 [BREAK]
  102. 2012-10-08 14:28:20.304932 [DEBUG] switch_core_state_machine.c:437 (FreeTDM/1:4/45867833) State INIT going to sleep
  103. 2012-10-08 14:28:20.304932 [DEBUG] switch_core_state_machine.c:398 (FreeTDM/1:4/45867833) Running State Change CS_ROUTING
  104. 2012-10-08 14:28:20.304932 [DEBUG] switch_channel.c:1964 (FreeTDM/1:4/45867833) Callstate Change DOWN -> RINGING
  105. 2012-10-08 14:28:20.304932 [DEBUG] switch_core_state_machine.c:446 (FreeTDM/1:4/45867833) State ROUTING
  106. 2012-10-08 14:28:20.304932 [DEBUG] mod_freetdm.c:453 FreeTDM/1:4/45867833 CHANNEL ROUTING
  107. 2012-10-08 14:28:20.304932 [DEBUG] switch_ivr_originate.c:67 (FreeTDM/1:4/45867833) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  108. 2012-10-08 14:28:20.304932 [DEBUG] switch_core_session.c:1210 Send signal FreeTDM/1:4/45867833 [BREAK]
  109. 2012-10-08 14:28:20.324956 [DEBUG] switch_core_state_machine.c:446 (FreeTDM/1:4/45867833) State ROUTING going to sleep
  110. 2012-10-08 14:28:20.324956 [DEBUG] switch_core_state_machine.c:398 (FreeTDM/1:4/45867833) Running State Change CS_CONSUME_MEDIA
  111. 2012-10-08 14:28:20.324956 [DEBUG] switch_core_state_machine.c:465 (FreeTDM/1:4/45867833) State CONSUME_MEDIA
  112. 2012-10-08 14:28:20.324956 [DEBUG] switch_core_state_machine.c:465 (FreeTDM/1:4/45867833) State CONSUME_MEDIA going to sleep
  113. 2012-10-08 14:28:23.604943 [DEBUG] ftmod_analog.c:872 [s1c4][1:4] Detected tone DIAL on 1:4
  114. 2012-10-08 14:28:23.624945 [DEBUG] ftdm_io.c:3979 [s1c4][1:4] Generating DTMF [45867833]
  115. 2012-10-08 14:28:23.944933 [DEBUG] ftdm_io.c:3783 [s1c4][1:4] Queuing DTMF 4 (debug = 0)
  116. 2012-10-08 14:28:28.004932 [DEBUG] ftmod_analog.c:511 [s1c4][1:4] Changed state from DIALING to UP
  117. 2012-10-08 14:28:28.024933 [DEBUG] ftmod_analog.c:640 [s1c4][1:4] Completed state change from DIALING to UP in 20 ms
  118. 2012-10-08 14:28:28.024933 [DEBUG] ftmod_analog.c:646 [s1c4][1:4] Executing state handler on 1:4 for UP
  119. 2012-10-08 14:28:28.024933 [DEBUG] mod_freetdm.c:2243 got FXO sig 1:4 [UP]
  120. 2012-10-08 14:28:28.024933 [DEBUG] switch_channel.c:3351 (FreeTDM/1:4/45867833) Callstate Change RINGING -> ACTIVE
  121. 2012-10-08 14:28:28.024933 [DEBUG] switch_channel.c:3363 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  122. 2012-10-08 14:28:28.024933 [NOTICE] mod_freetdm.c:2273 Channel [FreeTDM/1:4/45867833] has been answered
  123. 2012-10-08 14:28:28.024933 [DEBUG] ftdm_io.c:3386 [s1c4][1:4] Enabled software DTMF detector
  124. 2012-10-08 14:28:28.024933 [DEBUG] switch_ivr_originate.c:414 Codec string PCMU@8000h@20i not supported on sofia/internal/101@192.168.1.88, skipping inheritance
  125. 2012-10-08 14:28:28.024933 [DEBUG] sofia_glue.c:3327 AUDIO RTP [sofia/internal/101@192.168.1.88] 192.168.1.88 port 23608 -> 192.168.1.63 port 23424 codec: 0 ms: 20
  126. 2012-10-08 14:28:28.024933 [DEBUG] switch_rtp.c:1927 Starting timer [soft] 160 bytes per 20ms
  127. 2012-10-08 14:28:28.044936 [DEBUG] sofia_glue.c:3591 Set 2833 dtmf send payload to 101
  128. 2012-10-08 14:28:28.044936 [DEBUG] sofia_glue.c:3597 Set 2833 dtmf receive payload to 101
  129. 2012-10-08 14:28:28.044936 [DEBUG] sofia_glue.c:3624 sofia/internal/101@192.168.1.88 Set rtp dtmf delay to 40
  130. 2012-10-08 14:28:28.044936 [DEBUG] mod_sofia.c:836 Local SDP sofia/internal/101@192.168.1.88:
  131. v=0
  132. o=FreeSWITCH 1349657700 1349657701 IN IP4 192.168.1.88
  133. s=FreeSWITCH
  134. c=IN IP4 192.168.1.88
  135. t=0 0
  136. m=audio 23608 RTP/AVP 0 101
  137. a=rtpmap:0 PCMU/8000
  138. a=rtpmap:101 telephone-event/8000
  139. a=fmtp:101 0-16
  140. a=silenceSupp:off - - - -
  141. a=ptime:20
  142. a=sendrecv
  143.  
  144. 2012-10-08 14:28:28.044936 [DEBUG] switch_core_session.c:759 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  145. 2012-10-08 14:28:28.044936 [DEBUG] switch_channel.c:3351 (sofia/internal/101@192.168.1.88) Callstate Change RINGING -> ACTIVE
  146. 2012-10-08 14:28:28.044936 [NOTICE] switch_ivr_originate.c:3353 Channel [sofia/internal/101@192.168.1.88] has been answered
  147. 2012-10-08 14:28:28.044936 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  148. 2012-10-08 14:28:28.044936 [DEBUG] sofia.c:6282 Channel sofia/internal/101@192.168.1.88 entering state [completed][200]
  149. 2012-10-08 14:28:28.044936 [DEBUG] switch_ivr_originate.c:3410 Originate Resulted in Success: [FreeTDM/1:4/45867833]
  150. 2012-10-08 14:28:28.044936 [DEBUG] switch_core_session.c:759 Send signal FreeTDM/1:4/45867833 [BREAK]
  151. 2012-10-08 14:28:28.044936 [DEBUG] switch_core_session.c:759 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  152. 2012-10-08 14:28:28.044936 [DEBUG] switch_ivr_bridge.c:1361 (FreeTDM/1:4/45867833) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  153. 2012-10-08 14:28:28.044936 [DEBUG] switch_core_session.c:1210 Send signal FreeTDM/1:4/45867833 [BREAK]
  154. 2012-10-08 14:28:28.044936 [DEBUG] switch_core_state_machine.c:398 (FreeTDM/1:4/45867833) Running State Change CS_EXCHANGE_MEDIA
  155. 2012-10-08 14:28:28.044936 [DEBUG] switch_core_state_machine.c:456 (FreeTDM/1:4/45867833) State EXCHANGE_MEDIA
  156. 2012-10-08 14:28:28.044936 [DEBUG] mod_freetdm.c:668 CHANNEL EXCHANGE_MEDIA
  157. 2012-10-08 14:28:28.244936 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  158. 2012-10-08 14:28:28.244936 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  159. 2012-10-08 14:28:28.244936 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  160. 2012-10-08 14:28:28.264937 [DEBUG] switch_rtp.c:3596 Correct ip/port confirmed.
  161. 2012-10-08 14:28:28.264937 [DEBUG] sofia.c:6282 Channel sofia/internal/101@192.168.1.88 entering state [ready][200]
  162. 2012-10-08 14:28:28.264937 [DEBUG] switch_core_session.c:821 Send signal FreeTDM/1:4/45867833 [BREAK]
  163. 2012-10-08 14:28:28.264937 [DEBUG] switch_core_session.c:821 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  164. 2012-10-08 14:28:29.844944 [DEBUG] switch_core_session.c:905 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  165. 2012-10-08 14:28:29.864944 [DEBUG] switch_channel.c:2950 (sofia/internal/101@192.168.1.88) Callstate Change ACTIVE -> HANGUP
  166. 2012-10-08 14:28:29.864944 [NOTICE] sofia.c:711 Hangup sofia/internal/101@192.168.1.88 [CS_EXECUTE] [NORMAL_CLEARING]
  167. 2012-10-08 14:28:29.864944 [DEBUG] switch_channel.c:2973 Send signal sofia/internal/101@192.168.1.88 [KILL]
  168. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_session.c:1210 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  169. 2012-10-08 14:28:29.864944 [DEBUG] switch_ivr_bridge.c:588 BRIDGE THREAD DONE [sofia/internal/101@192.168.1.88]
  170. 2012-10-08 14:28:29.864944 [DEBUG] switch_ivr_bridge.c:613 Send signal FreeTDM/1:4/45867833 [BREAK]
  171. 2012-10-08 14:28:29.864944 [DEBUG] switch_ivr_bridge.c:501 sofia/internal/101@192.168.1.88 ending bridge by request from write function
  172. 2012-10-08 14:28:29.864944 [DEBUG] switch_ivr_bridge.c:588 BRIDGE THREAD DONE [FreeTDM/1:4/45867833]
  173. 2012-10-08 14:28:29.864944 [DEBUG] switch_ivr_bridge.c:613 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  174. 2012-10-08 14:28:29.864944 [DEBUG] switch_channel.c:2950 (FreeTDM/1:4/45867833) Callstate Change ACTIVE -> HANGUP
  175. 2012-10-08 14:28:29.864944 [NOTICE] switch_ivr_bridge.c:676 Hangup FreeTDM/1:4/45867833 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  176. 2012-10-08 14:28:29.864944 [DEBUG] switch_channel.c:2973 Send signal FreeTDM/1:4/45867833 [KILL]
  177. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_session.c:1210 Send signal FreeTDM/1:4/45867833 [BREAK]
  178. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:456 (FreeTDM/1:4/45867833) State EXCHANGE_MEDIA going to sleep
  179. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:398 (FreeTDM/1:4/45867833) Running State Change CS_HANGUP
  180. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:638 (FreeTDM/1:4/45867833) State HANGUP
  181. 2012-10-08 14:28:29.864944 [DEBUG] mod_freetdm.c:540 [1:4] FreeTDM/1:4/45867833 CHANNEL HANGUP ENTER
  182. 2012-10-08 14:28:29.864944 [DEBUG] switch_ivr_bridge.c:1458 FreeTDM/1:4/45867833 skip receive message [UNBRIDGE] (channel is hungup already)
  183. 2012-10-08 14:28:29.864944 [DEBUG] mod_freetdm.c:586 [s1c4][1:4] Changed state from UP to HANGUP
  184. 2012-10-08 14:28:29.864944 [DEBUG] switch_ivr_bridge.c:1461 sofia/internal/101@192.168.1.88 skip receive message [UNBRIDGE] (channel is hungup already)
  185. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_session.c:2553 sofia/internal/101@192.168.1.88 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  186. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:453 (sofia/internal/101@192.168.1.88) State EXECUTE going to sleep
  187. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/101@192.168.1.88) Running State Change CS_HANGUP
  188. 2012-10-08 14:28:29.864944 [DEBUG] ftmod_analog.c:640 [s1c4][1:4] Completed state change from UP to HANGUP in 1 ms
  189. 2012-10-08 14:28:29.864944 [DEBUG] mod_freetdm.c:636 [1:4] FreeTDM/1:4/45867833 CHANNEL HANGUP EXIT
  190. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:48 FreeTDM/1:4/45867833 Standard HANGUP, cause: NORMAL_CLEARING
  191. 2012-10-08 14:28:29.864944 [DEBUG] ftmod_analog.c:646 [s1c4][1:4] Executing state handler on 1:4 for HANGUP
  192. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:638 (FreeTDM/1:4/45867833) State HANGUP going to sleep
  193. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:429 (FreeTDM/1:4/45867833) State Change CS_HANGUP -> CS_REPORTING
  194. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_session.c:1210 Send signal FreeTDM/1:4/45867833 [BREAK]
  195. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:398 (FreeTDM/1:4/45867833) Running State Change CS_REPORTING
  196. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:703 (FreeTDM/1:4/45867833) State REPORTING
  197. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:92 FreeTDM/1:4/45867833 Standard REPORTING, cause: NORMAL_CLEARING
  198. 2012-10-08 14:28:29.864944 [DEBUG] switch_core_state_machine.c:703 (FreeTDM/1:4/45867833) State REPORTING going to sleep
  199. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:423 (FreeTDM/1:4/45867833) State Change CS_REPORTING -> CS_DESTROY
  200. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_session.c:1210 Send signal FreeTDM/1:4/45867833 [BREAK]
  201. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_session.c:1415 Session 18 (FreeTDM/1:4/45867833) Locked, Waiting on external entities
  202. 2012-10-08 14:28:29.884939 [NOTICE] switch_core_session.c:1433 Session 18 (FreeTDM/1:4/45867833) Ended
  203. 2012-10-08 14:28:29.884939 [NOTICE] switch_core_session.c:1437 Close Channel FreeTDM/1:4/45867833 [CS_DESTROY]
  204. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:638 (sofia/internal/101@192.168.1.88) State HANGUP
  205. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:527 (FreeTDM/1:4/45867833) Callstate Change HANGUP -> DOWN
  206. 2012-10-08 14:28:29.884939 [DEBUG] mod_sofia.c:483 Channel sofia/internal/101@192.168.1.88 hanging up, cause: NORMAL_CLEARING
  207. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:530 (FreeTDM/1:4/45867833) Running State Change CS_DESTROY
  208. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:540 (FreeTDM/1:4/45867833) State DESTROY
  209. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:99 FreeTDM/1:4/45867833 Standard DESTROY
  210. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:540 (FreeTDM/1:4/45867833) State DESTROY going to sleep
  211. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:48 sofia/internal/101@192.168.1.88 Standard HANGUP, cause: NORMAL_CLEARING
  212. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:638 (sofia/internal/101@192.168.1.88) State HANGUP going to sleep
  213. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:429 (sofia/internal/101@192.168.1.88) State Change CS_HANGUP -> CS_REPORTING
  214. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_session.c:1210 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  215. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/101@192.168.1.88) Running State Change CS_REPORTING
  216. 2012-10-08 14:28:29.884939 [DEBUG] switch_core_state_machine.c:703 (sofia/internal/101@192.168.1.88) State REPORTING
  217. 2012-10-08 14:28:29.964946 [DEBUG] switch_core_state_machine.c:92 sofia/internal/101@192.168.1.88 Standard REPORTING, cause: NORMAL_CLEARING
  218. 2012-10-08 14:28:29.964946 [DEBUG] switch_core_state_machine.c:703 (sofia/internal/101@192.168.1.88) State REPORTING going to sleep
  219. 2012-10-08 14:28:29.964946 [DEBUG] switch_core_state_machine.c:423 (sofia/internal/101@192.168.1.88) State Change CS_REPORTING -> CS_DESTROY
  220. 2012-10-08 14:28:29.964946 [DEBUG] switch_core_session.c:1210 Send signal sofia/internal/101@192.168.1.88 [BREAK]
  221. 2012-10-08 14:28:29.964946 [DEBUG] switch_core_session.c:1415 Session 17 (sofia/internal/101@192.168.1.88) Locked, Waiting on external entities
  222. 2012-10-08 14:28:29.964946 [NOTICE] switch_core_session.c:1433 Session 17 (sofia/internal/101@192.168.1.88) Ended
  223. 2012-10-08 14:28:29.964946 [NOTICE] switch_core_session.c:1437 Close Channel sofia/internal/101@192.168.1.88 [CS_DESTROY]
  224. 2012-10-08 14:28:29.964946 [DEBUG] switch_core_state_machine.c:527 (sofia/internal/101@192.168.1.88) Callstate Change HANGUP -> DOWN
  225. 2012-10-08 14:28:29.964946 [DEBUG] switch_core_state_machine.c:530 (sofia/internal/101@192.168.1.88) Running State Change CS_DESTROY
  226. 2012-10-08 14:28:29.964946 [DEBUG] switch_core_state_machine.c:540 (sofia/internal/101@192.168.1.88) State DESTROY
  227. 2012-10-08 14:28:29.964946 [DEBUG] mod_sofia.c:376 sofia/internal/101@192.168.1.88 SOFIA DESTROY
  228. 2012-10-08 14:28:29.964946 [DEBUG] switch_core_state_machine.c:99 sofia/internal/101@192.168.1.88 Standard DESTROY
  229. 2012-10-08 14:28:29.964946 [DEBUG] switch_core_state_machine.c:540 (sofia/internal/101@192.168.1.88) State DESTROY going to sleep
  230. 2012-10-08 14:28:30.384932 [DEBUG] ftmod_analog.c:564 [s1c4][1:4] Changed state from HANGUP to DOWN
  231. 2012-10-08 14:28:30.404934 [DEBUG] ftmod_analog.c:640 [s1c4][1:4] Completed state change from HANGUP to DOWN in 20 ms
  232. 2012-10-08 14:28:30.404934 [DEBUG] ftmod_analog.c:646 [s1c4][1:4] Executing state handler on 1:4 for DOWN
  233. 2012-10-08 14:28:30.404934 [DEBUG] ftdm_io.c:6145 [s1c4][1:4] Ignoring SIGEVENT_STOP since user already requested hangup
  234. 2012-10-08 14:28:30.404934 [DEBUG] ftmod_analog.c:944 [s1c4][1:4] Going onhook2012-10-08 14:28:30.404934 [DEBUG] ftdm_io.c:3185 [s1c4][1:4] DTMF debug is already disabled
  235. 2012-10-08 14:28:30.404934 [DEBUG] ftdm_io.c:3217 [s1c4][1:4] No need to disable input dump
  236. 2012-10-08 14:28:30.404934 [DEBUG] ftdm_io.c:3248 [s1c4][1:4] No need to disable output dump
  237. 2012-10-08 14:28:30.404934 [DEBUG] ftdm_io.c:6780 Cleared call with id 8
  238. 2012-10-08 14:28:30.404934 [DEBUG] ftdm_io.c:2990 [s1c4][1:4] channel done
  239. 2012-10-08 14:28:30.404934 [DEBUG] ftmod_analog.c:971 [s1c4][1:4] ANALOG CHANNEL 1:4 thread ended.
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement
Public Pastes
Advertisement
We use cookies for various purposes including analytics. By continuing to use Pastebin, you agree to our use of cookies as described in the Cookies Policy.  OK, I Understand
Not a member of Pastebin yet?
Sign Up, it unlocks many cool features!