FreeSWITCH Pastebin

Untitled

From Sludgy Pintail, 7 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/9aab8d26
Embed
Download Paste or View Raw
  1. freeswitch@freeswitch>
  2. 2017-10-12 20:15:21.565460 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1001@192.168.1.118 [34d2e12a-9015-4582-a882-e80ae4bf567a]
  3. 2017-10-12 20:15:21.565460 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.1.118) Running State Change CS_NEW (Cur 1 Tot 73)
  4. 2017-10-12 20:15:21.565460 [DEBUG] sofia.c:9837 sofia/internal/1001@192.168.1.118 receiving invite from 192.168.1.33:51282 version: 1.6.18  64bit
  5. 2017-10-12 20:15:21.565460 [DEBUG] sofia.c:10008 IP 192.168.1.33 Rejected by acl "domains". Falling back to Digest auth.
  6. 2017-10-12 20:15:21.565460 [DEBUG] sofia.c:2334 detaching session 34d2e12a-9015-4582-a882-e80ae4bf567a
  7. 2017-10-12 20:15:21.565460 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1001@192.168.1.118) State NEW
  8. 2017-10-12 20:15:21.605455 [DEBUG] sofia.c:2442 Re-attaching to session 34d2e12a-9015-4582-a882-e80ae4bf567a
  9. 2017-10-12 20:15:21.605455 [DEBUG] sofia.c:9837 sofia/internal/1001@192.168.1.118 receiving invite from 192.168.1.33:51282 version: 1.6.18  64bit
  10. 2017-10-12 20:15:21.605455 [DEBUG] sofia.c:10008 IP 192.168.1.33 Rejected by acl "domains". Falling back to Digest auth.
  11. 2017-10-12 20:15:21.605455 [DEBUG] sofia.c:7048 Channel sofia/internal/1001@192.168.1.118 entering state [received][100]
  12. 2017-10-12 20:15:21.605455 [DEBUG] sofia.c:7058 Remote SDP:
  13. v=0
  14. o=Z 0 0 IN IP4 117.247.91.162
  15. s=Z
  16. c=IN IP4 117.247.91.162
  17. t=0 0
  18. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  19. a=rtpmap:110 speex/8000
  20. a=rtpmap:98 iLBC/8000
  21. a=fmtp:98 mode=20
  22. a=rtpmap:101 telephone-event/8000
  23. a=fmtp:101 0-15
  24.  
  25. 2017-10-12 20:15:21.605455 [DEBUG] sofia.c:7450 (sofia/internal/1001@192.168.1.118) State Change CS_NEW -> CS_INIT
  26. 2017-10-12 20:15:21.605455 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.1.118) Running State Change CS_INIT (Cur 1 Tot 73)
  27. 2017-10-12 20:15:21.605455 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1001@192.168.1.118) State INIT
  28. 2017-10-12 20:15:21.605455 [DEBUG] mod_sofia.c:90 sofia/internal/1001@192.168.1.118 SOFIA INIT
  29. 2017-10-12 20:15:21.605455 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@192.168.1.118 Standard INIT
  30. 2017-10-12 20:15:21.605455 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@192.168.1.118) State Change CS_INIT -> CS_ROUTING
  31. 2017-10-12 20:15:21.605455 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1001@192.168.1.118) State INIT going to sleep
  32. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.1.118) Running State Change CS_ROUTING (Cur 1 Tot 73)
  33. 2017-10-12 20:15:21.625456 [DEBUG] switch_channel.c:2249 (sofia/internal/1001@192.168.1.118) Callstate Change DOWN -> RINGING
  34. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1001@192.168.1.118) State ROUTING
  35. 2017-10-12 20:15:21.625456 [DEBUG] mod_sofia.c:143 sofia/internal/1001@192.168.1.118 SOFIA ROUTING
  36. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1001@192.168.1.118 Standard ROUTING
  37. 2017-10-12 20:15:21.625456 [INFO] mod_dialplan_xml.c:637 Processing 1001 <1001>->3000 in context default
  38. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->unloop] continue=false
  39. Dialplan: sofia/internal/1001@192.168.1.118 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  40. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  41. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->tod_example] continue=true
  42. Dialplan: sofia/internal/1001@192.168.1.118 Date/TimeMatch (FAIL) [tod_example] break=on-false
  43. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->holiday_example] continue=true
  44. Dialplan: sofia/internal/1001@192.168.1.118 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  45. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->global-intercept] continue=false
  46. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [global-intercept] destination_number(3000) =~ /^886$/ break=on-false
  47. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->group-intercept] continue=false
  48. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [group-intercept] destination_number(3000) =~ /^\*8$/ break=on-false
  49. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->intercept-ext] continue=false
  50. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [intercept-ext] destination_number(3000) =~ /^\*\*(\d+)$/ break=on-false
  51. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->redial] continue=false
  52. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [redial] destination_number(3000) =~ /^(redial|870)$/ break=on-false
  53. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->global] continue=true
  54. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  55. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [global] ${default_password}(4321) =~ /^1234$/ break=never
  56. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
  57. Dialplan: sofia/internal/1001@192.168.1.118 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  58. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [global] ${switch_r_sdp}(v=0
  59. o=Z 0 0 IN IP4 117.247.91.162
  60. s=Z
  61. c=IN IP4 117.247.91.162
  62. t=0 0
  63. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  64. a=rtpmap:110 speex/8000
  65. a=rtpmap:98 iLBC/8000
  66. a=fmtp:98 mode=20
  67. a=rtpmap:101 telephone-event/8000
  68. a=fmtp:101 0-15
  69. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
  70. Dialplan: sofia/internal/1001@192.168.1.118 Absolute Condition [global]
  71. Dialplan: sofia/internal/1001@192.168.1.118 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  72. Dialplan: sofia/internal/1001@192.168.1.118 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  73. Dialplan: sofia/internal/1001@192.168.1.118 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  74. Dialplan: sofia/internal/1001@192.168.1.118 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  75. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->snom-demo-2] continue=false
  76. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [snom-demo-2] destination_number(3000) =~ /^9001$/ break=on-false
  77. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->snom-demo-1] continue=false
  78. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [snom-demo-1] destination_number(3000) =~ /^9000$/ break=on-false
  79. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->eavesdrop] continue=false
  80. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [eavesdrop] destination_number(3000) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  81. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->eavesdrop] continue=false
  82. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [eavesdrop] destination_number(3000) =~ /^779$/ break=on-false
  83. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->call_return] continue=false
  84. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [call_return] destination_number(3000) =~ /^\*69$|^869$|^lcr$/ break=on-false
  85. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->del-group] continue=false
  86. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [del-group] destination_number(3000) =~ /^80(\d{2})$/ break=on-false
  87. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->add-group] continue=false
  88. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [add-group] destination_number(3000) =~ /^81(\d{2})$/ break=on-false
  89. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->call-group-simo] continue=false
  90. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [call-group-simo] destination_number(3000) =~ /^82(\d{2})$/ break=on-false
  91. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->call-group-order] continue=false
  92. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [call-group-order] destination_number(3000) =~ /^83(\d{2})$/ break=on-false
  93. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->extension-intercom] continue=false
  94. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [extension-intercom] destination_number(3000) =~ /^8(10[01][0-9])$/ break=on-false
  95. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->Local_Extension] continue=false
  96. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [Local_Extension] destination_number(3000) =~ /^(10[01][0-9])$/ break=on-false
  97. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->Local_Extension_Skinny] continue=false
  98. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [Local_Extension_Skinny] destination_number(3000) =~ /^(11[01][0-9])$/ break=on-false
  99. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->group_dial_sales] continue=false
  100. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [group_dial_sales] destination_number(3000) =~ /^2000$/ break=on-false
  101. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->group_dial_support] continue=false
  102. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [group_dial_support] destination_number(3000) =~ /^2001$/ break=on-false
  103. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->group_dial_billing] continue=false
  104. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [group_dial_billing] destination_number(3000) =~ /^2002$/ break=on-false
  105. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->operator] continue=false
  106. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [operator] destination_number(3000) =~ /^(operator|0)$/ break=on-false
  107. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->vmain] continue=false
  108. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [vmain] destination_number(3000) =~ /^vmain$|^4000$|^\*98$/ break=on-false
  109. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->sip_uri] continue=false
  110. Dialplan: sofia/internal/1001@192.168.1.118 Regex (FAIL) [sip_uri] destination_number(3000) =~ /^sip:(.*)$/ break=on-false
  111. Dialplan: sofia/internal/1001@192.168.1.118 parsing [default->nb_conferences] continue=false
  112. Dialplan: sofia/internal/1001@192.168.1.118 Regex (PASS) [nb_conferences] destination_number(3000) =~ /^(30\d{2})$/ break=on-false
  113. Dialplan: sofia/internal/1001@192.168.1.118 Action answer()
  114. Dialplan: sofia/internal/1001@192.168.1.118 Action conference(3000-${domain_name}@default)
  115. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1001@192.168.1.118) State Change CS_ROUTING -> CS_EXECUTE
  116. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1001@192.168.1.118) State ROUTING going to sleep
  117. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.1.118) Running State Change CS_EXECUTE (Cur 1 Tot 73)
  118. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1001@192.168.1.118) State EXECUTE
  119. 2017-10-12 20:15:21.625456 [DEBUG] mod_sofia.c:198 sofia/internal/1001@192.168.1.118 SOFIA EXECUTE
  120. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1001@192.168.1.118 Standard EXECUTE
  121. EXECUTE sofia/internal/1001@192.168.1.118 hash(insert/192.168.1.118-spymap/1001/34d2e12a-9015-4582-a882-e80ae4bf567a)
  122. EXECUTE sofia/internal/1001@192.168.1.118 hash(insert/192.168.1.118-last_dial/1001/3000)
  123. EXECUTE sofia/internal/1001@192.168.1.118 hash(insert/192.168.1.118-last_dial/global/34d2e12a-9015-4582-a882-e80ae4bf567a)
  124. EXECUTE sofia/internal/1001@192.168.1.118 export(RFC2822_DATE=Thu, 12 Oct 2017 20:15:21 +0530)
  125. 2017-10-12 20:15:21.625456 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 12 Oct 2017 20:15:21 +0530]
  126. EXECUTE sofia/internal/1001@192.168.1.118 answer()
  127. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
  128. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  129. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  130. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [speex:110:8000:20:0:1]/[G722:9:8000:20:64000:1]
  131. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  132. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  133. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  134. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  135. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  136. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  137. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  138. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  139. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  140. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  141. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:98:8000:30:0:1]/[G722:9:8000:20:64000:1]
  142. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  143. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  144. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
  145. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:3057 Set Codec sofia/internal/1001@192.168.1.118 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  146. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@192.168.1.118 Original read codec set to PCMA:8
  147. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000
  148. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:4763 sofia/internal/1001@192.168.1.118 Set 2833 dtmf send payload to 101 recv payload to 101
  149. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/internal/1001@192.168.1.118] 192.168.1.118 port 30206 -> 117.247.91.162 port 8000 codec: 8 ms: 20
  150. 2017-10-12 20:15:21.625456 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
  151. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:7175 sofia/internal/1001@192.168.1.118 Set 2833 dtmf send payload to 101
  152. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:7182 sofia/internal/1001@192.168.1.118 Set 2833 dtmf receive payload to 101
  153. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:7205 sofia/internal/1001@192.168.1.118 Set rtp dtmf delay to 40
  154. 2017-10-12 20:15:21.625456 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1001@192.168.1.118!
  155. 2017-10-12 20:15:21.625456 [DEBUG] switch_channel.c:3474 (sofia/internal/1001@192.168.1.118) Callstate Change RINGING -> EARLY
  156. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/internal/1001@192.168.1.118.
  157. 2017-10-12 20:15:21.625456 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/1001@192.168.1.118:
  158. v=0
  159. o=FreeSWITCH 1507789315 1507789316 IN IP4 192.168.1.118
  160. s=FreeSWITCH
  161. c=IN IP4 192.168.1.118
  162. t=0 0
  163. m=audio 30206 RTP/AVP 8 101
  164. a=rtpmap:8 PCMA/8000
  165. a=rtpmap:101 telephone-event/8000
  166. a=fmtp:101 0-16
  167. a=ptime:20
  168. a=sendrecv
  169.  
  170. 2017-10-12 20:15:21.625456 [DEBUG] sofia.c:7048 Channel sofia/internal/1001@192.168.1.118 entering state [completed][200]
  171. 2017-10-12 20:15:21.625456 [NOTICE] mod_dptools.c:1312 Channel [sofia/internal/1001@192.168.1.118] has been answered
  172. 2017-10-12 20:15:21.625456 [DEBUG] switch_channel.c:3773 (sofia/internal/1001@192.168.1.118) Callstate Change EARLY -> ACTIVE
  173. EXECUTE sofia/internal/1001@192.168.1.118 conference(3000-192.168.1.118@default)
  174. 2017-10-12 20:15:21.625456 [DEBUG] mod_conference.c:3065 using channel sound prefix: /usr/local/freeswitch/sounds/en/us/callie
  175. 2017-10-12 20:15:21.625456 [DEBUG] conference_member.c:1690 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  176. 2017-10-12 20:15:21.625456 [DEBUG] conference_member.c:1737 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  177. 2017-10-12 20:15:21.625456 [DEBUG] switch_core_codec.c:223 sofia/internal/1001@192.168.1.118 Push codec L16:100
  178. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '0' to 'mute'
  179. 2017-10-12 20:15:21.645451 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
  180. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f86c783eb40 data: 0x14d4bc8
  181. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '*' to 'deaf mute'
  182. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7f86c783eb40 data: 0x14d4bf8
  183. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '9' to 'energy up'
  184. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f86c783eb40 data: 0x14d4c28
  185. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '8' to 'energy equ'
  186. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f86c783eb40 data: 0x14d4c58
  187. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '7' to 'energy dn'
  188. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f86c783eb40 data: 0x14d4c88
  189. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '3' to 'vol talk up'
  190. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f86c783eb40 data: 0x14d4cb8
  191. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '2' to 'vol talk zero'
  192. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f86c783eb40 data: 0x14d4ce8
  193. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '1' to 'vol talk dn'
  194. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f86c783eb40 data: 0x14d4d18
  195. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '6' to 'vol listen up'
  196. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f86c783eb40 data: 0x14d4d48
  197. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '5' to 'vol listen zero'
  198. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f86c783eb40 data: 0x14d4d78
  199. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '4' to 'vol listen dn'
  200. 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f86c783eb40 data: 0x14d4da8
  201. 2017-10-12 20:15:21.645451 [DEBUG] conference_member.c:128 sofia/internal/1001@192.168.1.118 binding '#' to 'execute_application'
  202. span style="color: goldenrod; font-weight: bold;"> 2017-10-12 20:15:21.645451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7f86c783eb40 data: 0x14d4dd8
  203. span style="color: goldenrod; font-weight: bold;"> 2017-10-12 20:15:21.645451 [DEBUG] sofia.c:7048 Channel sofia/internal/1001@192.168.1.118 entering state [ready][200]
  204. 2017-10-12 20:15:21.645451 [DEBUG] conference_loop.c:1140 Setup timer soft success interval: 20  samples: 160 from codec PCMA
  205. 2017-10-12 20:15:21.645451 [DEBUG] mod_conference.c:220 Setup timer success interval: 20  samples: 160
  206. 2017-10-12 20:15:21.885458 [INFO] switch_rtp.c:7214 Auto Changing audio port from 117.247.91.162:8000 to 192.168.1.33:8000
  207. 2017-10-12 20:15:24.405458 [DEBUG] mod_local_stream.c:871 Opening Stream [moh/8000] 8000hz
  208. 2017-10-12 20:15:39.245458 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1002@192.168.1.118 [5f1f3380-3364-49e0-9f29-a91db70b894a]
  209. 2017-10-12 20:15:39.245458 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.1.118) Running State Change CS_NEW (Cur 2 Tot 74)
  210. 2017-10-12 20:15:39.245458 [DEBUG] sofia.c:9837 sofia/internal/1002@192.168.1.118 receiving invite from 192.168.1.156:5062 version: 1.6.18  64bit
  211. 2017-10-12 20:15:39.245458 [DEBUG] sofia.c:10008 IP 192.168.1.156 Rejected by acl "domains". Falling back to Digest auth.
  212. 2017-10-12 20:15:39.245458 [DEBUG] sofia.c:2334 detaching session 5f1f3380-3364-49e0-9f29-a91db70b894a
  213. 2017-10-12 20:15:39.245458 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1002@192.168.1.118) State NEW
  214. 2017-10-12 20:15:39.485459 [DEBUG] sofia.c:2442 Re-attaching to session 5f1f3380-3364-49e0-9f29-a91db70b894a
  215. 2017-10-12 20:15:39.485459 [DEBUG] sofia.c:9837 sofia/internal/1002@192.168.1.118 receiving invite from 192.168.1.156:5062 version: 1.6.18  64bit
  216. 2017-10-12 20:15:39.485459 [DEBUG] sofia.c:10008 IP 192.168.1.156 Rejected by acl "domains". Falling back to Digest auth.
  217. 2017-10-12 20:15:39.485459 [DEBUG] sofia.c:7048 Channel sofia/internal/1002@192.168.1.118 entering state [received][100]
  218. 2017-10-12 20:15:39.485459 [DEBUG] sofia.c:7058 Remote SDP:
  219. v=0
  220. o=- 20016 20016 IN IP4 192.168.1.156
  221. s=SDP data
  222. c=IN IP4 192.168.1.156
  223. t=0 0
  224. m=audio 11790 RTP/AVP 0 4 8 101
  225. a=rtpmap:0 PCMU/8000
  226. a=rtpmap:4 G723/8000
  227. a=rtpmap:8 PCMA/8000
  228. a=rtpmap:101 telephone-event/8000
  229. a=fmtp:101 0-15
  230. a=ptime:20
  231.  
  232. 2017-10-12 20:15:39.485459 [DEBUG] sofia.c:7450 (sofia/internal/1002@192.168.1.118) State Change CS_NEW -> CS_INIT
  233. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.1.118) Running State Change CS_INIT (Cur 2 Tot 74)
  234. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@192.168.1.118) State INIT
  235. 2017-10-12 20:15:39.485459 [DEBUG] mod_sofia.c:90 sofia/internal/1002@192.168.1.118 SOFIA INIT
  236. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1002@192.168.1.118 Standard INIT
  237. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1002@192.168.1.118) State Change CS_INIT -> CS_ROUTING
  238. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@192.168.1.118) State INIT going to sleep
  239. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.1.118) Running State Change CS_ROUTING (Cur 2 Tot 74)
  240. 2017-10-12 20:15:39.485459 [DEBUG] switch_channel.c:2249 (sofia/internal/1002@192.168.1.118) Callstate Change DOWN -> RINGING
  241. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@192.168.1.118) State ROUTING
  242. 2017-10-12 20:15:39.485459 [DEBUG] mod_sofia.c:143 sofia/internal/1002@192.168.1.118 SOFIA ROUTING
  243. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1002@192.168.1.118 Standard ROUTING
  244. 2017-10-12 20:15:39.485459 [INFO] mod_dialplan_xml.c:637 Processing mayur <1002>->3000 in context default
  245. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->unloop] continue=false
  246. Dialplan: sofia/internal/1002@192.168.1.118 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  247. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  248. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->tod_example] continue=true
  249. Dialplan: sofia/internal/1002@192.168.1.118 Date/TimeMatch (FAIL) [tod_example] break=on-false
  250. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->holiday_example] continue=true
  251. Dialplan: sofia/internal/1002@192.168.1.118 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  252. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->global-intercept] continue=false
  253. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [global-intercept] destination_number(3000) =~ /^886$/ break=on-false
  254. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->group-intercept] continue=false
  255. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [group-intercept] destination_number(3000) =~ /^\*8$/ break=on-false
  256. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->intercept-ext] continue=false
  257. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [intercept-ext] destination_number(3000) =~ /^\*\*(\d+)$/ break=on-false
  258. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->redial] continue=false
  259. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [redial] destination_number(3000) =~ /^(redial|870)$/ break=on-false
  260. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->global] continue=true
  261. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  262. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [global] ${default_password}(4321) =~ /^1234$/ break=never
  263. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
  264. Dialplan: sofia/internal/1002@192.168.1.118 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  265. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [global] ${switch_r_sdp}(v=0
  266. o=- 20016 20016 IN IP4 192.168.1.156
  267. s=SDP data
  268. c=IN IP4 192.168.1.156
  269. t=0 0
  270. m=audio 11790 RTP/AVP 0 4 8 101
  271. a=rtpmap:0 PCMU/8000
  272. a=rtpmap:4 G723/8000
  273. a=rtpmap:8 PCMA/8000
  274. a=rtpmap:101 telephone-event/8000
  275. a=fmtp:101 0-15
  276. a=ptime:20
  277. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
  278. Dialplan: sofia/internal/1002@192.168.1.118 Absolute Condition [global]
  279. Dialplan: sofia/internal/1002@192.168.1.118 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  280. Dialplan: sofia/internal/1002@192.168.1.118 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  281. Dialplan: sofia/internal/1002@192.168.1.118 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  282. Dialplan: sofia/internal/1002@192.168.1.118 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  283. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->snom-demo-2] continue=false
  284. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [snom-demo-2] destination_number(3000) =~ /^9001$/ break=on-false
  285. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->snom-demo-1] continue=false
  286. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [snom-demo-1] destination_number(3000) =~ /^9000$/ break=on-false
  287. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->eavesdrop] continue=false
  288. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [eavesdrop] destination_number(3000) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  289. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->eavesdrop] continue=false
  290. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [eavesdrop] destination_number(3000) =~ /^779$/ break=on-false
  291. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->call_return] continue=false
  292. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [call_return] destination_number(3000) =~ /^\*69$|^869$|^lcr$/ break=on-false
  293. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->del-group] continue=false
  294. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [del-group] destination_number(3000) =~ /^80(\d{2})$/ break=on-false
  295. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->add-group] continue=false
  296. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [add-group] destination_number(3000) =~ /^81(\d{2})$/ break=on-false
  297. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->call-group-simo] continue=false
  298. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [call-group-simo] destination_number(3000) =~ /^82(\d{2})$/ break=on-false
  299. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->call-group-order] continue=false
  300. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [call-group-order] destination_number(3000) =~ /^83(\d{2})$/ break=on-false
  301. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->extension-intercom] continue=false
  302. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [extension-intercom] destination_number(3000) =~ /^8(10[01][0-9])$/ break=on-false
  303. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->Local_Extension] continue=false
  304. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [Local_Extension] destination_number(3000) =~ /^(10[01][0-9])$/ break=on-false
  305. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->Local_Extension_Skinny] continue=false
  306. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [Local_Extension_Skinny] destination_number(3000) =~ /^(11[01][0-9])$/ break=on-false
  307. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->group_dial_sales] continue=false
  308. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [group_dial_sales] destination_number(3000) =~ /^2000$/ break=on-false
  309. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->group_dial_support] continue=false
  310. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [group_dial_support] destination_number(3000) =~ /^2001$/ break=on-false
  311. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->group_dial_billing] continue=false
  312. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [group_dial_billing] destination_number(3000) =~ /^2002$/ break=on-false
  313. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->operator] continue=false
  314. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [operator] destination_number(3000) =~ /^(operator|0)$/ break=on-false
  315. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->vmain] continue=false
  316. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [vmain] destination_number(3000) =~ /^vmain$|^4000$|^\*98$/ break=on-false
  317. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->sip_uri] continue=false
  318. Dialplan: sofia/internal/1002@192.168.1.118 Regex (FAIL) [sip_uri] destination_number(3000) =~ /^sip:(.*)$/ break=on-false
  319. Dialplan: sofia/internal/1002@192.168.1.118 parsing [default->nb_conferences] continue=false
  320. Dialplan: sofia/internal/1002@192.168.1.118 Regex (PASS) [nb_conferences] destination_number(3000) =~ /^(30\d{2})$/ break=on-false
  321. Dialplan: sofia/internal/1002@192.168.1.118 Action answer()
  322. Dialplan: sofia/internal/1002@192.168.1.118 Action conference(3000-${domain_name}@default)
  323. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1002@192.168.1.118) State Change CS_ROUTING -> CS_EXECUTE
  324. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@192.168.1.118) State ROUTING going to sleep
  325. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.1.118) Running State Change CS_EXECUTE (Cur 2 Tot 74)
  326. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1002@192.168.1.118) State EXECUTE
  327. 2017-10-12 20:15:39.485459 [DEBUG] mod_sofia.c:198 sofia/internal/1002@192.168.1.118 SOFIA EXECUTE
  328. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1002@192.168.1.118 Standard EXECUTE
  329. EXECUTE sofia/internal/1002@192.168.1.118 hash(insert/192.168.1.118-spymap/1002/5f1f3380-3364-49e0-9f29-a91db70b894a)
  330. EXECUTE sofia/internal/1002@192.168.1.118 hash(insert/192.168.1.118-last_dial/1002/3000)
  331. EXECUTE sofia/internal/1002@192.168.1.118 hash(insert/192.168.1.118-last_dial/global/5f1f3380-3364-49e0-9f29-a91db70b894a)
  332. EXECUTE sofia/internal/1002@192.168.1.118 export(RFC2822_DATE=Thu, 12 Oct 2017 20:15:39 +0530)
  333. 2017-10-12 20:15:39.485459 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 12 Oct 2017 20:15:39 +0530]
  334. EXECUTE sofia/internal/1002@192.168.1.118 answer()
  335. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  336. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  337. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  338. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  339. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G723:4:8000:20:6300:1]/[G722:9:8000:20:64000:1]
  340. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G723:4:8000:20:6300:1]/[PCMU:0:8000:20:64000:1]
  341. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [G723:4:8000:20:6300:1]/[PCMA:8:8000:20:64000:1]
  342. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  343. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  344. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  345. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  346. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
  347. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:3057 Set Codec sofia/internal/1002@192.168.1.118 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  348. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_codec.c:111 sofia/internal/1002@192.168.1.118 Original read codec set to PCMU:0
  349. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000
  350. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:4763 sofia/internal/1002@192.168.1.118 Set 2833 dtmf send payload to 101 recv payload to 101
  351. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/internal/1002@192.168.1.118] 192.168.1.118 port 19834 -> 192.168.1.156 port 11790 codec: 0 ms: 20
  352. 2017-10-12 20:15:39.485459 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
  353. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:7175 sofia/internal/1002@192.168.1.118 Set 2833 dtmf send payload to 101
  354. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:7182 sofia/internal/1002@192.168.1.118 Set 2833 dtmf receive payload to 101
  355. 2017-10-12 20:15:39.485459 [DEBUG] switch_core_media.c:7205 sofia/internal/1002@192.168.1.118 Set rtp dtmf delay to 40
  356. 2017-10-12 20:15:39.485459 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1002@192.168.1.118!
  357. 2017-10-12 20:15:39.485459 [DEBUG] switch_channel.c:3474 (sofia/internal/1002@192.168.1.118) Callstate Change RINGING -> EARLY
  358. 2017-10-12 20:15:39.505451 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/internal/1002@192.168.1.118.
  359. 2017-10-12 20:15:39.505451 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/1002@192.168.1.118:
  360. v=0
  361. o=FreeSWITCH 1507799705 1507799706 IN IP4 192.168.1.118
  362. s=FreeSWITCH
  363. c=IN IP4 192.168.1.118
  364. t=0 0
  365. m=audio 19834 RTP/AVP 0 101
  366. a=rtpmap:0 PCMU/8000
  367. a=rtpmap:101 telephone-event/8000
  368. a=fmtp:101 0-16
  369. a=ptime:20
  370. a=sendrecv
  371.  
  372. 2017-10-12 20:15:39.505451 [DEBUG] sofia.c:7048 Channel sofia/internal/1002@192.168.1.118 entering state [completed][200]
  373. 2017-10-12 20:15:39.505451 [NOTICE] mod_dptools.c:1312 Channel [sofia/internal/1002@192.168.1.118] has been answered
  374. 2017-10-12 20:15:39.505451 [DEBUG] switch_channel.c:3773 (sofia/internal/1002@192.168.1.118) Callstate Change EARLY -> ACTIVE
  375. EXECUTE sofia/internal/1002@192.168.1.118 conference(3000-192.168.1.118@default)
  376. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:1690 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  377. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:1737 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  378. 2017-10-12 20:15:39.505451 [DEBUG] switch_core_codec.c:223 sofia/internal/1002@192.168.1.118 Push codec L16:100
  379. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '0' to 'mute'
  380. 2017-10-12 20:15:39.505451 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
  381. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f86c783eb40 data: 0x126afa8
  382. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '*' to 'deaf mute'
  383. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7f86c783eb40 data: 0x126afd8
  384. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '9' to 'energy up'
  385. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f86c783eb40 data: 0x126b008
  386. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '8' to 'energy equ'
  387. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f86c783eb40 data: 0x126b038
  388. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '7' to 'energy dn'
  389. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f86c783eb40 data: 0x126b068
  390. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '3' to 'vol talk up'
  391. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f86c783eb40 data: 0x126b098
  392. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '2' to 'vol talk zero'
  393. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f86c783eb40 data: 0x126b0c8
  394. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '1' to 'vol talk dn'
  395. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f86c783eb40 data: 0x126b0f8
  396. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '6' to 'vol listen up'
  397. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f86c783eb40 data: 0x126b128
  398. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '5' to 'vol listen zero'
  399. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f86c783eb40 data: 0x126b158
  400. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '4' to 'vol listen dn'
  401. 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f86c783eb40 data: 0x126b188
  402. 2017-10-12 20:15:39.505451 [DEBUG] conference_member.c:128 sofia/internal/1002@192.168.1.118 binding '#' to 'execute_application'
  403. span style="color: goldenrod; font-weight: bold;"> 2017-10-12 20:15:39.505451 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7f86c783eb40 data: 0x126b1b8
  404. span style="color: goldenrod; font-weight: bold;"> 2017-10-12 20:15:39.525460 [DEBUG] conference_loop.c:1140 Setup timer soft success interval: 20  samples: 160 from codec PCMU
  405. 2017-10-12 20:15:39.765459 [DEBUG] sofia.c:7048 Channel sofia/internal/1002@192.168.1.118 entering state [ready][200]
  406. 2017-10-12 20:15:39.885458 [DEBUG] switch_rtp.c:7254 Correct audio ip/port confirmed.
  407. 2017-10-12 20:15:46.365500 [NOTICE] sofia.c:1012 Hangup sofia/internal/1002@192.168.1.118 [CS_EXECUTE] [NORMAL_CLEARING]
  408. 2017-10-12 20:15:46.365500 [INFO] conference_loop.c:1455 Channel leaving conference, cause: NORMAL_CLEARING
  409. 2017-10-12 20:15:46.365500 [DEBUG] mod_conference.c:2242 sofia/internal/1002@192.168.1.118 skip receive message [UNBRIDGE] (channel is hungup already)
  410. 2017-10-12 20:15:46.365500 [DEBUG] switch_core_media.c:9775 sofia/internal/1002@192.168.1.118 skip receive message [HARD_MUTE] (channel is hungup already)
  411. 2017-10-12 20:15:46.365500 [DEBUG] switch_core_codec.c:248 sofia/internal/1002@192.168.1.118 Restore previous codec PCMU:0.
  412. 2017-10-12 20:15:46.365500 [DEBUG] switch_core_session.c:2814 sofia/internal/1002@192.168.1.118 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  413. 2017-10-12 20:15:46.365500 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1002@192.168.1.118) State EXECUTE going to sleep
  414. 2017-10-12 20:15:46.365500 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.1.118) Running State Change CS_HANGUP (Cur 2 Tot 74)
  415. 2017-10-12 20:15:46.365500 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1002@192.168.1.118) Callstate Change ACTIVE -> HANGUP
  416. 2017-10-12 20:15:46.365500 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1002@192.168.1.118) State HANGUP
  417. 2017-10-12 20:15:46.365500 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1002@192.168.1.118 hanging up, cause: NORMAL_CLEARING
  418. 2017-10-12 20:15:46.365500 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1002@192.168.1.118 Standard HANGUP, cause: NORMAL_CLEARING
  419. 2017-10-12 20:15:46.365500 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1002@192.168.1.118) State HANGUP going to sleep
  420. 2017-10-12 20:15:46.365500 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1002@192.168.1.118) State Change CS_HANGUP -> CS_REPORTING
  421. 2017-10-12 20:15:46.385455 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.1.118) Running State Change CS_REPORTING (Cur 2 Tot 74)
  422. 2017-10-12 20:15:46.385455 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1002@192.168.1.118) State REPORTING
  423. 2017-10-12 20:15:46.385455 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1002@192.168.1.118 Standard REPORTING, cause: NORMAL_CLEARING
  424. 2017-10-12 20:15:46.385455 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1002@192.168.1.118) State REPORTING going to sleep
  425. 2017-10-12 20:15:46.385455 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1002@192.168.1.118) State Change CS_REPORTING -> CS_DESTROY
  426. 2017-10-12 20:15:46.385455 [DEBUG] switch_core_session.c:1664 Session 74 (sofia/internal/1002@192.168.1.118) Locked, Waiting on external entities
  427. 2017-10-12 20:15:46.385455 [NOTICE] switch_core_session.c:1682 Session 74 (sofia/internal/1002@192.168.1.118) Ended
  428. 2017-10-12 20:15:46.385455 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/1002@192.168.1.118 [CS_DESTROY]
  429. 2017-10-12 20:15:46.385455 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1002@192.168.1.118) Running State Change CS_DESTROY (Cur 1 Tot 74)
  430. 2017-10-12 20:15:46.385455 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1002@192.168.1.118) State DESTROY
  431. 2017-10-12 20:15:46.385455 [DEBUG] mod_sofia.c:343 sofia/internal/1002@192.168.1.118 SOFIA DESTROY
  432. 2017-10-12 20:15:46.385455 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1002@192.168.1.118 Standard DESTROY
  433. 2017-10-12 20:15:46.385455 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1002@192.168.1.118) State DESTROY going to sleep
  434. 2017-10-12 20:15:49.245457 [DEBUG] mod_local_stream.c:871 Opening Stream [moh/8000] 8000hz
  435. 2017-10-12 20:15:49.385461 [NOTICE] sofia.c:1012 Hangup sofia/internal/1001@192.168.1.118 [CS_EXECUTE] [NORMAL_CLEARING]
  436. 2017-10-12 20:15:49.405454 [INFO] conference_loop.c:1455 Channel leaving conference, cause: NORMAL_CLEARING
  437. 2017-10-12 20:15:49.405454 [DEBUG] mod_conference.c:2242 sofia/internal/1001@192.168.1.118 skip receive message [UNBRIDGE] (channel is hungup already)
  438. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_media.c:9775 sofia/internal/1001@192.168.1.118 skip receive message [HARD_MUTE] (channel is hungup already)
  439. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_codec.c:248 sofia/internal/1001@192.168.1.118 Restore previous codec PCMA:8.
  440. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_session.c:2814 sofia/internal/1001@192.168.1.118 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  441. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1001@192.168.1.118) State EXECUTE going to sleep
  442. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.1.118) Running State Change CS_HANGUP (Cur 1 Tot 74)
  443. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1001@192.168.1.118) Callstate Change ACTIVE -> HANGUP
  444. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1001@192.168.1.118) State HANGUP
  445. 2017-10-12 20:15:49.405454 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1001@192.168.1.118 hanging up, cause: NORMAL_CLEARING
  446. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@192.168.1.118 Standard HANGUP, cause: NORMAL_CLEARING
  447. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1001@192.168.1.118) State HANGUP going to sleep
  448. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1001@192.168.1.118) State Change CS_HANGUP -> CS_REPORTING
  449. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.1.118) Running State Change CS_REPORTING (Cur 1 Tot 74)
  450. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1001@192.168.1.118) State REPORTING
  451. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1001@192.168.1.118 Standard REPORTING, cause: NORMAL_CLEARING
  452. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1001@192.168.1.118) State REPORTING going to sleep
  453. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1001@192.168.1.118) State Change CS_REPORTING -> CS_DESTROY
  454. 2017-10-12 20:15:49.405454 [DEBUG] mod_conference.c:794 Write Lock ON
  455. 2017-10-12 20:15:49.405454 [DEBUG] mod_conference.c:797 Write Lock OFF
  456. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_session.c:1664 Session 73 (sofia/internal/1001@192.168.1.118) Locked, Waiting on external entities
  457. 2017-10-12 20:15:49.405454 [NOTICE] switch_core_session.c:1682 Session 73 (sofia/internal/1001@192.168.1.118) Ended
  458. 2017-10-12 20:15:49.405454 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/1001@192.168.1.118 [CS_DESTROY]
  459. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1001@192.168.1.118) Running State Change CS_DESTROY (Cur 0 Tot 74)
  460. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1001@192.168.1.118) State DESTROY
  461. 2017-10-12 20:15:49.405454 [DEBUG] mod_sofia.c:343 sofia/internal/1001@192.168.1.118 SOFIA DESTROY
  462. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1001@192.168.1.118 Standard DESTROY
  463. 2017-10-12 20:15:49.405454 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1001@192.168.1.118) State DESTROY going to sleep
  464.  

Reply to "Untitled"