From neteng, 2 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: cornflowerblue; font-weight: bold;"> 2016-05-17 21:26:58.897799 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 [c453b31f-8773-40cc-bbaa-43eb322f839f]
  2. 2016-05-17 21:26:58.897799 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Running State Change CS_NEW
  3. 2016-05-17 21:26:58.897799 [DEBUG] sofia.c:9376 sofia/internal/2002@bes2.sip.urbancom.net:5060 receiving invite from 73.72.98.76:5060 version: 1.6.8 -15-99de0ad 64bit
  4. 2016-05-17 21:26:58.897799 [DEBUG] sofia.c:9543 IP 73.72.98.76 Rejected by acl "domains". Falling back to Digest auth.
  5. 2016-05-17 21:26:58.897799 [WARNING] sofia_reg.c:1775 SIP auth challenge (INVITE) on sofia profile 'internal' for [17085143073@bes2.sip.urbancom.net] from ip 73.72.98.76
  6. 2016-05-17 21:26:58.897799 [DEBUG] switch_core_state_machine.c:562 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State NEW
  7. 2016-05-17 21:26:58.897799 [DEBUG] sofia.c:2214 detaching session c453b31f-8773-40cc-bbaa-43eb322f839f
  8. 2016-05-17 21:26:58.977861 [DEBUG] sofia.c:2322 Re-attaching to session c453b31f-8773-40cc-bbaa-43eb322f839f
  9. 2016-05-17 21:26:58.997808 [DEBUG] sofia.c:9376 sofia/internal/2002@bes2.sip.urbancom.net:5060 receiving invite from 73.72.98.76:5060 version: 1.6.8 -15-99de0ad 64bit
  10. 2016-05-17 21:26:58.997808 [DEBUG] sofia.c:9543 IP 73.72.98.76 Rejected by acl "domains". Falling back to Digest auth.
  11. 2016-05-17 21:26:58.997808 [DEBUG] sofia.c:6858 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 entering state [received][100]
  12. 2016-05-17 21:26:58.997808 [DEBUG] sofia.c:6868 Remote SDP:
  13. v=0
  14. o=- 20008 20008 IN IP4 73.72.98.76
  15. s=SDP data
  16. c=IN IP4 73.72.98.76
  17. t=0 0
  18. m=audio 11796 RTP/AVP 0 8 18 9 101
  19. a=rtpmap:0 PCMU/8000
  20. a=rtpmap:8 PCMA/8000
  21. a=rtpmap:18 G729/8000
  22. a=fmtp:18 annexb=no
  23. a=rtpmap:9 G722/8000
  24. a=rtpmap:101 telephone-event/8000
  25. a=fmtp:101 0-15
  26. a=ptime:20
  27.  
  28. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  29. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  30. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  31. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  32. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  33. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  34. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  35. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  36. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  37. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  38. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  39. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  40. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  41. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
  42. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  43. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  44. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  45. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  46. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  47. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4271 Set telephone-event payload to 101@8000
  48. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:3090 Set Codec sofia/internal/2002@bes2.sip.urbancom.net:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  49. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_codec.c:111 sofia/internal/2002@bes2.sip.urbancom.net:5060 Original read codec set to PCMU:0
  50. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4623 Set telephone-event payload to 101@8000
  51. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_media.c:4681 sofia/internal/2002@bes2.sip.urbancom.net:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  52. 2016-05-17 21:26:58.997808 [DEBUG] sofia.c:7248 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State Change CS_NEW -> CS_INIT
  53. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Running State Change CS_INIT
  54. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State INIT
  55. 2016-05-17 21:26:58.997808 [DEBUG] mod_sofia.c:89 sofia/internal/2002@bes2.sip.urbancom.net:5060 SOFIA INIT
  56. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2002@bes2.sip.urbancom.net:5060 Standard INIT
  57. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State Change CS_INIT -> CS_ROUTING
  58. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State INIT going to sleep
  59. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Running State Change CS_ROUTING
  60. 2016-05-17 21:26:58.997808 [DEBUG] switch_channel.c:2249 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Callstate Change DOWN -> RINGING
  61. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State ROUTING
  62. 2016-05-17 21:26:58.997808 [DEBUG] mod_sofia.c:142 sofia/internal/2002@bes2.sip.urbancom.net:5060 SOFIA ROUTING
  63. 2016-05-17 21:26:58.997808 [DEBUG] switch_core_state_machine.c:236 sofia/internal/2002@bes2.sip.urbancom.net:5060 Standard ROUTING
  64. 2016-05-17 21:26:58.997808 [INFO] mod_dialplan_xml.c:637 Processing 2002 <2002>->17085143073 in context bes2.sip.urbancom.net
  65. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 parsing [bes2.sip.urbancom.net->user_exists] continue=true
  66. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (PASS) [user_exists] () =~ // break=on-false
  67. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  68. 2016-05-17 21:26:59.017797 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fa65402aa40 Connected.
  69. 2016-05-17 21:26:59.017797 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fa65402aa40 released.
  70. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(user_exists=false)
  71. 2016-05-17 21:26:59.017797 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [user_exists]=[false]
  72. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
  73. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 parsing [bes2.sip.urbancom.net->call-direction] continue=true
  74. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
  75. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 ANTI-Action set(call_direction=local)
  76. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 parsing [bes2.sip.urbancom.net->variables] continue=true
  77. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (PASS) [variables] () =~ // break=on-false
  78. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action export(origination_callee_id_name=${destination_number})
  79. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  80. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 parsing [bes2.sip.urbancom.net->user_record] continue=true
  81. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (PASS) [user_record] () =~ // break=on-false
  82. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  83. 2016-05-17 21:26:59.037790 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fa65402aa40 Connected.
  84. 2016-05-17 21:26:59.037790 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fa65402aa40 released.
  85. 2016-05-17 21:26:59.037790 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fa65402aa40 Connected.
  86. 2016-05-17 21:26:59.037790 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fa65402aa40 released.
  87. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(user_record=)
  88. 2016-05-17 21:26:59.037790 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [user_record]=[UNDEF]
  89. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  90. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(from_user_exists=true)
  91. 2016-05-17 21:26:59.037790 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [from_user_exists]=[true]
  92. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  93. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  94. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  95. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
  96. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  97. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  98. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
  99. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  100. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  101. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
  102. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  103. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  104. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
  105. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(from_user_record=)
  106. 2016-05-17 21:26:59.037790 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [from_user_record]=[UNDEF]
  107. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  108. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  109. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  110. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
  111. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  112. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  113. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
  114. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  115. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  116. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
  117. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  118. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  119. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 parsing [bes2.sip.urbancom.net->redial] continue=true
  120. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (FAIL) [redial] destination_number(17085143073) =~ /^(redial|\*870)$/ break=on-true
  121. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (PASS) [redial] () =~ // break=never
  122. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  123. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 parsing [bes2.sip.urbancom.net->sip.urbancom.net.11d] continue=false
  124. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Regex (PASS) [sip.urbancom.net.11d] destination_number(17085143073) =~ /^\+?(\d{11})$/ break=on-false
  125. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(sip_h_X-accountcode=${accountcode})
  126. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(call_direction=outbound)
  127. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(hangup_after_bridge=true)
  128. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(effective_caller_id_name=${outbound_caller_id_name})
  129. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(effective_caller_id_number=${outbound_caller_id_number})
  130. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(inherit_codec=true)
  131. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(ignore_display_updates=true)
  132. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(callee_id_number=17085143073)
  133. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action set(continue_on_fail=true)
  134. Dialplan: sofia/internal/2002@bes2.sip.urbancom.net:5060 Action bridge(sofia/gateway/f85a3f69-aedb-4dc1-8185-c45ad7f3ab97/17085143073)
  135. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State Change CS_ROUTING -> CS_EXECUTE
  136. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State ROUTING going to sleep
  137. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Running State Change CS_EXECUTE
  138. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State EXECUTE
  139. 2016-05-17 21:26:59.057791 [DEBUG] mod_sofia.c:197 sofia/internal/2002@bes2.sip.urbancom.net:5060 SOFIA EXECUTE
  140. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:328 sofia/internal/2002@bes2.sip.urbancom.net:5060 Standard EXECUTE
  141. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(call_direction=local)
  142. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [call_direction]=[local]
  143. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 export(origination_callee_id_name=17085143073)
  144. 2016-05-17 21:26:59.057791 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[17085143073]
  145. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(RFC2822_DATE=Tue, 17 May 2016 21:26:59 -0500)
  146. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [RFC2822_DATE]=[Tue, 17 May 2016 21:26:59 -0500]
  147. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 hash(insert/bes2.sip.urbancom.net-last_dial/2002/17085143073)
  148. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(sip_h_X-accountcode=bes2.sip.urbancom.net)
  149. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [sip_h_X-accountcode]=[bes2.sip.urbancom.net]
  150. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(call_direction=outbound)
  151. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [call_direction]=[outbound]
  152. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(hangup_after_bridge=true)
  153. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [hangup_after_bridge]=[true]
  154. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(effective_caller_id_name=Brent Desk Phone 2)
  155. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [effective_caller_id_name]=[Brent Desk Phone 2]
  156. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(effective_caller_id_number=6303437372)
  157. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [effective_caller_id_number]=[6303437372]
  158. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(inherit_codec=true)
  159. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [inherit_codec]=[true]
  160. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(ignore_display_updates=true)
  161. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [ignore_display_updates]=[true]
  162. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(callee_id_number=17085143073)
  163. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [callee_id_number]=[17085143073]
  164. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 set(continue_on_fail=true)
  165. 2016-05-17 21:26:59.057791 [DEBUG] mod_dptools.c:1519 SET sofia/internal/2002@bes2.sip.urbancom.net:5060 [continue_on_fail]=[true]
  166. EXECUTE sofia/internal/2002@bes2.sip.urbancom.net:5060 bridge(sofia/gateway/f85a3f69-aedb-4dc1-8185-c45ad7f3ab97/17085143073)
  167. 2016-05-17 21:26:59.057791 [DEBUG] switch_channel.c:1250 sofia/internal/2002@bes2.sip.urbancom.net:5060 EXPORTING[export_vars] [domain_name]=[bes2.sip.urbancom.net] to event
  168. 2016-05-17 21:26:59.057791 [DEBUG] switch_channel.c:1250 sofia/internal/2002@bes2.sip.urbancom.net:5060 EXPORTING[export_vars] [origination_callee_id_name]=[17085143073] to event
  169. 2016-05-17 21:26:59.057791 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
  170. 2016-05-17 21:26:59.057791 [NOTICE] switch_channel.c:1104 New Channel sofia/external/17085143073 [accf12ad-ebfb-4c4c-84f8-717521d81cb6]
  171. 2016-05-17 21:26:59.057791 [DEBUG] mod_sofia.c:4813 (sofia/external/17085143073) State Change CS_NEW -> CS_INIT
  172. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:543 (sofia/external/17085143073) Running State Change CS_INIT
  173. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:586 (sofia/external/17085143073) State INIT
  174. 2016-05-17 21:26:59.057791 [DEBUG] mod_sofia.c:89 sofia/external/17085143073 SOFIA INIT
  175. 2016-05-17 21:26:59.057791 [DEBUG] sofia_glue.c:1257 sofia/external/17085143073 sending invite version: 1.6.8 -15-99de0ad 64bit
  176. Local SDP:
  177. v=0
  178. o=FreeSWITCH 1463518333 1463518334 IN IP4 64.7.99.51
  179. s=FreeSWITCH
  180. c=IN IP4 64.7.99.51
  181. t=0 0
  182. m=audio 20086 RTP/AVP 0 8 3 101 13
  183. a=rtpmap:0 PCMU/8000
  184. a=rtpmap:8 PCMA/8000
  185. a=rtpmap:3 GSM/8000
  186. a=rtpmap:101 telephone-event/8000
  187. a=fmtp:101 0-16
  188. a=rtpmap:13 CN/8000
  189. a=ptime:20
  190. a=sendrecv
  191.  
  192. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:40 sofia/external/17085143073 Standard INIT
  193. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:48 (sofia/external/17085143073) State Change CS_INIT -> CS_ROUTING
  194. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:586 (sofia/external/17085143073) State INIT going to sleep
  195. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:543 (sofia/external/17085143073) Running State Change CS_ROUTING
  196. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:602 (sofia/external/17085143073) State ROUTING
  197. 2016-05-17 21:26:59.057791 [DEBUG] mod_sofia.c:142 sofia/external/17085143073 SOFIA ROUTING
  198. 2016-05-17 21:26:59.057791 [DEBUG] switch_ivr_originate.c:67 (sofia/external/17085143073) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  199. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:602 (sofia/external/17085143073) State ROUTING going to sleep
  200. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:543 (sofia/external/17085143073) Running State Change CS_CONSUME_MEDIA
  201. 2016-05-17 21:26:59.057791 [DEBUG] sofia.c:6858 Channel sofia/external/17085143073 entering state [calling][0]
  202. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:621 (sofia/external/17085143073) State CONSUME_MEDIA
  203. 2016-05-17 21:26:59.057791 [DEBUG] switch_core_state_machine.c:621 (sofia/external/17085143073) State CONSUME_MEDIA going to sleep
  204. 2016-05-17 21:27:00.117788 [DEBUG] sofia.c:6858 Channel sofia/external/17085143073 entering state [proceeding][183]
  205. 2016-05-17 21:27:00.117788 [DEBUG] sofia.c:6868 Remote SDP:
  206. v=0
  207. o=Sonus_UAC 588145 860659 IN IP4 67.231.1.110
  208. s=SIP Media Capabilities
  209. c=IN IP4 64.7.96.3
  210. t=0 0
  211. m=audio 59148 RTP/AVP 0 101
  212. a=rtpmap:0 PCMU/8000
  213. a=rtpmap:101 telephone-event/8000
  214. a=fmtp:101 0-15
  215. a=ptime:20
  216.  
  217. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  218. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  219. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  220. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  221. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:4271 Set telephone-event payload to 101@8000
  222. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:3090 Set Codec sofia/external/17085143073 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  223. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_codec.c:111 sofia/external/17085143073 Original read codec set to PCMU:0
  224. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:4623 Set telephone-event payload to 101@8000
  225. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:4681 sofia/external/17085143073 Set 2833 dtmf send payload to 101 recv payload to 101
  226. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:6464 AUDIO RTP [sofia/external/17085143073] 10.20.12.51 port 20086 -> 64.7.96.3 port 59148 codec: 0 ms: 20
  227. 2016-05-17 21:27:00.117788 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms
  228. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:6763 sofia/external/17085143073 Set 2833 dtmf send payload to 101
  229. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:6770 sofia/external/17085143073 Set 2833 dtmf receive payload to 101
  230. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:6793 sofia/external/17085143073 Set rtp dtmf delay to 40
  231. 2016-05-17 21:27:00.117788 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/17085143073!
  232. 2016-05-17 21:27:00.117788 [DEBUG] switch_channel.c:3471 (sofia/external/17085143073) Callstate Change DOWN -> EARLY
  233. 2016-05-17 21:27:00.117788 [DEBUG] switch_ivr_originate.c:410 Setting codec string on sofia/internal/2002@bes2.sip.urbancom.net:5060 to PCMU@8000h@20i
  234. 2016-05-17 21:27:00.117788 [INFO] switch_ivr_originate.c:3556 Sending early media
  235. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:6464 AUDIO RTP [sofia/internal/2002@bes2.sip.urbancom.net:5060] 10.20.12.51 port 17346 -> 73.72.98.76 port 11796 codec: 0 ms: 20
  236. 2016-05-17 21:27:00.117788 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms
  237. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:6763 sofia/internal/2002@bes2.sip.urbancom.net:5060 Set 2833 dtmf send payload to 101
  238. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:6770 sofia/internal/2002@bes2.sip.urbancom.net:5060 Set 2833 dtmf receive payload to 101
  239. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_media.c:6793 sofia/internal/2002@bes2.sip.urbancom.net:5060 Set rtp dtmf delay to 40
  240. 2016-05-17 21:27:00.117788 [DEBUG] mod_sofia.c:2367 Ring SDP:
  241. v=0
  242. o=FreeSWITCH 1463521074 1463521075 IN IP4 64.7.99.51
  243. s=FreeSWITCH
  244. c=IN IP4 64.7.99.51
  245. t=0 0
  246. m=audio 17346 RTP/AVP 0 101
  247. a=rtpmap:0 PCMU/8000
  248. a=rtpmap:101 telephone-event/8000
  249. a=fmtp:101 0-16
  250. a=ptime:20
  251. a=sendrecv
  252.  
  253. 2016-05-17 21:27:00.117788 [NOTICE] mod_sofia.c:2370 Pre-Answer sofia/internal/2002@bes2.sip.urbancom.net:5060!
  254. 2016-05-17 21:27:00.117788 [DEBUG] switch_channel.c:3471 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Callstate Change RINGING -> EARLY
  255. 2016-05-17 21:27:00.117788 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/external/17085143073]
  256. 2016-05-17 21:27:00.117788 [DEBUG] sofia.c:6858 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 entering state [early][183]
  257. 2016-05-17 21:27:00.117788 [DEBUG] switch_ivr_bridge.c:1594 (sofia/external/17085143073) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  258. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_state_machine.c:543 (sofia/external/17085143073) Running State Change CS_EXCHANGE_MEDIA
  259. 2016-05-17 21:27:00.117788 [DEBUG] switch_core_state_machine.c:612 (sofia/external/17085143073) State EXCHANGE_MEDIA
  260. 2016-05-17 21:27:00.117788 [DEBUG] mod_sofia.c:628 SOFIA EXCHANGE_MEDIA
  261. 2016-05-17 21:27:00.877795 [DEBUG] sofia.c:6858 Channel sofia/external/17085143073 entering state [proceeding][183]
  262. 2016-05-17 21:27:00.877795 [DEBUG] sofia.c:6865 Duplicate SDP
  263. v=0
  264. o=Sonus_UAC 588145 860659 IN IP4 67.231.1.110
  265. s=SIP Media Capabilities
  266. c=IN IP4 64.7.96.3
  267. t=0 0
  268. m=audio 59148 RTP/AVP 0 101
  269. a=rtpmap:0 PCMU/8000
  270. a=rtpmap:101 telephone-event/8000
  271. a=fmtp:101 0-15
  272. a=ptime:20
  273.  
  274. 2016-05-17 21:27:00.877795 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  275. 2016-05-17 21:27:00.877795 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  276. 2016-05-17 21:27:00.877795 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  277. 2016-05-17 21:27:00.877795 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  278. 2016-05-17 21:27:00.877795 [DEBUG] switch_core_media.c:4271 Set telephone-event payload to 101@8000
  279. 2016-05-17 21:27:00.877795 [DEBUG] switch_core_media.c:4623 Set telephone-event payload to 101@8000
  280. 2016-05-17 21:27:00.877795 [DEBUG] switch_core_media.c:4681 sofia/external/17085143073 Set 2833 dtmf send payload to 101 recv payload to 101
  281. 2016-05-17 21:27:00.877795 [DEBUG] switch_core_media.c:6447 Audio params are unchanged for sofia/external/17085143073.
  282. 2016-05-17 21:27:01.817859 [DEBUG] sofia.c:6858 Channel sofia/external/17085143073 entering state [completing][200]
  283. 2016-05-17 21:27:01.817859 [DEBUG] sofia.c:6865 Duplicate SDP
  284. v=0
  285. o=Sonus_UAC 588145 860659 IN IP4 67.231.1.110
  286. s=SIP Media Capabilities
  287. c=IN IP4 64.7.96.3
  288. t=0 0
  289. m=audio 59148 RTP/AVP 0 101
  290. a=rtpmap:0 PCMU/8000
  291. a=rtpmap:101 telephone-event/8000
  292. a=fmtp:101 0-15
  293. a=ptime:20
  294.  
  295. 2016-05-17 21:27:01.837797 [DEBUG] sofia.c:6858 Channel sofia/external/17085143073 entering state [ready][200]
  296. 2016-05-17 21:27:01.837797 [NOTICE] sofia.c:7767 Channel [sofia/external/17085143073] has been answered
  297. 2016-05-17 21:27:01.837797 [DEBUG] switch_channel.c:3770 (sofia/external/17085143073) Callstate Change EARLY -> ACTIVE
  298. 2016-05-17 21:27:01.837797 [DEBUG] mod_sofia.c:814 Local SDP sofia/internal/2002@bes2.sip.urbancom.net:5060:
  299. v=0
  300. o=FreeSWITCH 1463521074 1463521076 IN IP4 64.7.99.51
  301. s=FreeSWITCH
  302. c=IN IP4 64.7.99.51
  303. t=0 0
  304. m=audio 17346 RTP/AVP 0 101
  305. a=rtpmap:0 PCMU/8000
  306. a=rtpmap:101 telephone-event/8000
  307. a=fmtp:101 0-16
  308. a=ptime:20
  309. a=sendrecv
  310.  
  311. 2016-05-17 21:27:01.837797 [NOTICE] switch_ivr_bridge.c:618 Channel [sofia/internal/2002@bes2.sip.urbancom.net:5060] has been answered
  312. 2016-05-17 21:27:01.837797 [DEBUG] switch_channel.c:3770 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Callstate Change EARLY -> ACTIVE
  313. 2016-05-17 21:27:01.837797 [DEBUG] sofia.c:6858 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 entering state [completed][200]
  314. 2016-05-17 21:27:01.917856 [DEBUG] sofia.c:6858 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 entering state [ready][200]
  315. freeswitch@fusionpbx02-beta>
  316. freeswitch@fusionpbx02-beta>
  317. freeswitch@fusionpbx02-beta>
  318. freeswitch@fusionpbx02-beta>
  319. freeswitch@fusionpbx02-beta>
  320. freeswitch@fusionpbx02-beta>
  321. freeswitch@fusionpbx02-beta>
  322. freeswitch@fusionpbx02-beta>
  323. freeswitch@fusionpbx02-beta>
  324. freeswitch@fusionpbx02-beta>
  325. freeswitch@fusionpbx02-beta>
  326. freeswitch@fusionpbx02-beta>
  327. freeswitch@fusionpbx02-beta>
  328. freeswitch@fusionpbx02-beta>
  329. freeswitch@fusionpbx02-beta>
  330. freeswitch@fusionpbx02-beta>
  331. freeswitch@fusionpbx02-beta>
  332. freeswitch@fusionpbx02-beta>
  333. freeswitch@fusionpbx02-beta>
  334. freeswitch@fusionpbx02-beta>
  335. freeswitch@fusionpbx02-beta>
  336. freeswitch@fusionpbx02-beta>
  337. freeswitch@fusionpbx02-beta>
  338. freeswitch@fusionpbx02-beta>
  339. freeswitch@fusionpbx02-beta>
  340. 2016-05-17 21:27:06.297788 [DEBUG] sofia.c:6858 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 entering state [received][100]
  341. 2016-05-17 21:27:06.297788 [DEBUG] sofia.c:6868 Remote SDP:
  342. v=0
  343. o=- 20008 20010 IN IP4 73.72.98.76
  344. s=SDP data
  345. c=IN IP4 73.72.98.76
  346. t=0 0
  347. m=audio 11796 RTP/AVP 0 8 18 9 101
  348. a=rtpmap:0 PCMU/8000
  349. a=rtpmap:8 PCMA/8000
  350. a=rtpmap:18 G729/8000
  351. a=fmtp:18 annexb=no
  352. a=rtpmap:9 G722/8000
  353. a=rtpmap:101 telephone-event/8000
  354. a=fmtp:101 0-15
  355. a=sendonly
  356. a=ptime:20
  357.  
  358. 2016-05-17 21:27:06.297788 [DEBUG] switch_channel.c:1844 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Callstate Change ACTIVE -> HELD
  359. 2016-05-17 21:27:06.497859 [DEBUG] switch_ivr.c:623 sofia/external/17085143073 Command Execute playback(local_stream://default)
  360. EXECUTE sofia/external/17085143073 playback(local_stream://default)
  361. 2016-05-17 21:27:06.497859 [DEBUG] mod_local_stream.c:843 Opening Stream [default] 8000hz
  362. 2016-05-17 21:27:06.497859 [DEBUG] switch_ivr_play_say.c:1467 Codec Activated L16@8000hz 1 channels 20ms
  363. 2016-05-17 21:27:06.537803 [DEBUG] switch_rtp.c:6707 Correct audio ip/port confirmed.
  364. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  365. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  366. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  367. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  368. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  369. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  370. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  371. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  372. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  373. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  374. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  375. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  376. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  377. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  378. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  379. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4271 Set telephone-event payload to 101@8000
  380. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4623 Set telephone-event payload to 101@8000
  381. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:4681 sofia/internal/2002@bes2.sip.urbancom.net:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  382. 2016-05-17 21:27:06.557794 [DEBUG] switch_core_media.c:6447 Audio params are unchanged for sofia/internal/2002@bes2.sip.urbancom.net:5060.
  383. 2016-05-17 21:27:06.557794 [DEBUG] sofia.c:7616 Processing updated SDP
  384. 2016-05-17 21:27:06.577782 [DEBUG] sofia.c:6858 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 entering state [completed][200]
  385. 2016-05-17 21:27:06.617809 [DEBUG] sofia.c:6858 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 entering state [ready][200]
  386. freeswitch@fusionpbx02-beta>
  387. freeswitch@fusionpbx02-beta>
  388. freeswitch@fusionpbx02-beta>
  389. freeswitch@fusionpbx02-beta>
  390. freeswitch@fusionpbx02-beta>
  391. freeswitch@fusionpbx02-beta>
  392. freeswitch@fusionpbx02-beta>
  393. freeswitch@fusionpbx02-beta>
  394. freeswitch@fusionpbx02-beta>
  395. freeswitch@fusionpbx02-beta>
  396. freeswitch@fusionpbx02-beta>
  397. freeswitch@fusionpbx02-beta>
  398. freeswitch@fusionpbx02-beta>
  399. freeswitch@fusionpbx02-beta>
  400. freeswitch@fusionpbx02-beta>
  401. freeswitch@fusionpbx02-beta>
  402. freeswitch@fusionpbx02-beta>
  403. freeswitch@fusionpbx02-beta>
  404. freeswitch@fusionpbx02-beta>
  405. freeswitch@fusionpbx02-beta>
  406. freeswitch@fusionpbx02-beta>
  407. freeswitch@fusionpbx02-beta>
  408. freeswitch@fusionpbx02-beta>
  409. freeswitch@fusionpbx02-beta>
  410. freeswitch@fusionpbx02-beta>
  411. freeswitch@fusionpbx02-beta>
  412. 2016-05-17 21:27:09.777862 [DEBUG] sofia.c:6858 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 entering state [received][100]
  413. 2016-05-17 21:27:09.777862 [DEBUG] sofia.c:6868 Remote SDP:
  414. v=0
  415. o=- 20008 20011 IN IP4 73.72.98.76
  416. s=SDP data
  417. c=IN IP4 73.72.98.76
  418. t=0 0
  419. m=audio 11796 RTP/AVP 0 8 18 9 101
  420. a=rtpmap:0 PCMU/8000
  421. a=rtpmap:8 PCMA/8000
  422. a=rtpmap:18 G729/8000
  423. a=fmtp:18 annexb=no
  424. a=rtpmap:9 G722/8000
  425. a=ptime:20
  426. a=rtpmap:101 telephone-event/8000
  427. a=fmtp:101 0-15
  428. a=sendrecv
  429.  
  430. 2016-05-17 21:27:10.037876 [DEBUG] switch_ivr_play_say.c:1910 done playing file local_stream://default
  431. 2016-05-17 21:27:10.037876 [DEBUG] switch_channel.c:2029 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Callstate Change HELD -> UNHELD
  432. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  433. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  434. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  435. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  436. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  437. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  438. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  439. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  440. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  441. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  442. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  443. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  444. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  445. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4355 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  446. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4410 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  447. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4271 Set telephone-event payload to 101@8000
  448. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4623 Set telephone-event payload to 101@8000
  449. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:4681 sofia/internal/2002@bes2.sip.urbancom.net:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  450. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_media.c:6447 Audio params are unchanged for sofia/internal/2002@bes2.sip.urbancom.net:5060.
  451. 2016-05-17 21:27:10.037876 [DEBUG] sofia.c:7616 Processing updated SDP
  452. 2016-05-17 21:27:10.037876 [DEBUG] switch_core_io.c:941 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Callstate Change UNHELD -> ACTIVE
  453. 2016-05-17 21:27:10.037876 [DEBUG] sofia.c:6858 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 entering state [completed][200]
  454. 2016-05-17 21:27:10.057785 [DEBUG] switch_rtp.c:6707 Correct audio ip/port confirmed.
  455. 2016-05-17 21:27:10.077787 [DEBUG] sofia.c:6858 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 entering state [ready][200]
  456. 2016-05-17 21:27:12.837785 [NOTICE] sofia.c:988 Hangup sofia/external/17085143073 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  457. 2016-05-17 21:27:12.837785 [DEBUG] switch_ivr_bridge.c:780 BRIDGE THREAD DONE [sofia/external/17085143073]
  458. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:612 (sofia/external/17085143073) State EXCHANGE_MEDIA going to sleep
  459. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:543 (sofia/external/17085143073) Running State Change CS_HANGUP
  460. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:809 (sofia/external/17085143073) Callstate Change ACTIVE -> HANGUP
  461. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:811 (sofia/external/17085143073) State HANGUP
  462. 2016-05-17 21:27:12.837785 [DEBUG] mod_sofia.c:437 Channel sofia/external/17085143073 hanging up, cause: NORMAL_CLEARING
  463. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:60 sofia/external/17085143073 Standard HANGUP, cause: NORMAL_CLEARING
  464. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:811 (sofia/external/17085143073) State HANGUP going to sleep
  465. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:578 (sofia/external/17085143073) State Change CS_HANGUP -> CS_REPORTING
  466. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:543 (sofia/external/17085143073) Running State Change CS_REPORTING
  467. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:897 (sofia/external/17085143073) State REPORTING
  468. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:174 sofia/external/17085143073 Standard REPORTING, cause: NORMAL_CLEARING
  469. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:897 (sofia/external/17085143073) State REPORTING going to sleep
  470. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:569 (sofia/external/17085143073) State Change CS_REPORTING -> CS_DESTROY
  471. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_session.c:1646 Session 16 (sofia/external/17085143073) Locked, Waiting on external entities
  472. 2016-05-17 21:27:12.837785 [DEBUG] switch_ivr_bridge.c:701 sofia/external/17085143073 ending bridge by request from write function
  473. 2016-05-17 21:27:12.837785 [DEBUG] switch_ivr_bridge.c:780 BRIDGE THREAD DONE [sofia/internal/2002@bes2.sip.urbancom.net:5060]
  474. 2016-05-17 21:27:12.837785 [NOTICE] switch_ivr_bridge.c:1744 Hangup sofia/internal/2002@bes2.sip.urbancom.net:5060 [CS_EXECUTE] [NORMAL_CLEARING]
  475. 2016-05-17 21:27:12.837785 [NOTICE] switch_core_session.c:1664 Session 16 (sofia/external/17085143073) Ended
  476. 2016-05-17 21:27:12.837785 [NOTICE] switch_core_session.c:1668 Close Channel sofia/external/17085143073 [CS_DESTROY]
  477. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_session.c:2796 sofia/internal/2002@bes2.sip.urbancom.net:5060 skip receive message [PHONE_EVENT] (channel is hungup already)
  478. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State EXECUTE going to sleep
  479. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Running State Change CS_HANGUP
  480. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:700 (sofia/external/17085143073) Running State Change CS_DESTROY
  481. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:710 (sofia/external/17085143073) State DESTROY
  482. 2016-05-17 21:27:12.837785 [DEBUG] mod_sofia.c:342 sofia/external/17085143073 SOFIA DESTROY
  483. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:181 sofia/external/17085143073 Standard DESTROY
  484. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:710 (sofia/external/17085143073) State DESTROY going to sleep
  485. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Callstate Change ACTIVE -> HANGUP
  486. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State HANGUP
  487. 2016-05-17 21:27:12.837785 [DEBUG] mod_sofia.c:431 sofia/internal/2002@bes2.sip.urbancom.net:5060 Overriding SIP cause 480 with 200 from the other leg
  488. 2016-05-17 21:27:12.837785 [DEBUG] mod_sofia.c:437 Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 hanging up, cause: NORMAL_CLEARING
  489. 2016-05-17 21:27:12.837785 [DEBUG] mod_sofia.c:490 Sending BYE to sofia/internal/2002@bes2.sip.urbancom.net:5060
  490. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2002@bes2.sip.urbancom.net:5060 Standard HANGUP, cause: NORMAL_CLEARING
  491. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State HANGUP going to sleep
  492. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State Change CS_HANGUP -> CS_REPORTING
  493. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Running State Change CS_REPORTING
  494. 2016-05-17 21:27:12.837785 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State REPORTING
  495. 2016-05-17 21:27:12.917804 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2002@bes2.sip.urbancom.net:5060 Standard REPORTING, cause: NORMAL_CLEARING
  496. 2016-05-17 21:27:12.917804 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State REPORTING going to sleep
  497. 2016-05-17 21:27:12.917804 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State Change CS_REPORTING -> CS_DESTROY
  498. 2016-05-17 21:27:12.917804 [DEBUG] switch_core_session.c:1646 Session 15 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Locked, Waiting on external entities
  499. 2016-05-17 21:27:12.917804 [NOTICE] switch_core_session.c:1664 Session 15 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Ended
  500. 2016-05-17 21:27:12.917804 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/2002@bes2.sip.urbancom.net:5060 [CS_DESTROY]
  501. 2016-05-17 21:27:12.917804 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/2002@bes2.sip.urbancom.net:5060) Running State Change CS_DESTROY
  502. 2016-05-17 21:27:12.917804 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State DESTROY
  503. 2016-05-17 21:27:12.917804 [DEBUG] mod_sofia.c:342 sofia/internal/2002@bes2.sip.urbancom.net:5060 SOFIA DESTROY
  504. 2016-05-17 21:27:12.917804 [DEBUG] switch_core_state_machine.c:181 sofia/internal/2002@bes2.sip.urbancom.net:5060 Standard DESTROY
  505. 2016-05-17 21:27:12.917804 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/2002@bes2.sip.urbancom.net:5060) State DESTROY going to sleep
  506. freeswitch@fusionpbx02-beta>