From Bruce, 5 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: cornflowerblue; font-weight: bold;"> 2019-04-02 18:21:55.692810 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1003@riverwalk.cust.tellarc.com [27a10d0e-9622-4210-af84-ca802ece35a8]
  2. 2019-04-02 18:21:55.692810 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@riverwalk.cust.tellarc.com) Running State Change CS_NEW (Cur 5 Tot 31356)
  3. 2019-04-02 18:21:55.692810 [DEBUG] sofia.c:10092 sofia/internal/1003@riverwalk.cust.tellarc.com receiving invite from 96.65.187.108:8133 version: 1.8.3 -4-4d4c454d3e 64bit
  4. 2019-04-02 18:21:55.692810 [DEBUG] sofia.c:10263 IP 96.65.187.108 Rejected by acl "domains". Falling back to Digest auth.
  5. 2019-04-02 18:21:55.692810 [WARNING] sofia_reg.c:1796 SIP auth challenge (INVITE) on sofia profile 'internal' for [*97@riverwalk.cust.tellarc.com] from ip 96.65.187.108
  6. 2019-04-02 18:21:55.692810 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1003@riverwalk.cust.tellarc.com) State NEW
  7. 2019-04-02 18:21:55.692810 [DEBUG] sofia.c:2413 detaching session 27a10d0e-9622-4210-af84-ca802ece35a8
  8. 2019-04-02 18:21:55.852848 [DEBUG] sofia.c:2522 Re-attaching to session 27a10d0e-9622-4210-af84-ca802ece35a8
  9. 2019-04-02 18:21:55.852848 [DEBUG] sofia.c:10092 sofia/internal/1003@riverwalk.cust.tellarc.com receiving invite from 96.65.187.108:8133 version: 1.8.3 -4-4d4c454d3e 64bit
  10. 2019-04-02 18:21:55.852848 [DEBUG] sofia.c:10263 IP 96.65.187.108 Rejected by acl "domains". Falling back to Digest auth.
  11. 2019-04-02 18:21:55.872794 [DEBUG] sofia.c:11400 Setting NAT mode based on via received
  12. 2019-04-02 18:21:55.872794 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@riverwalk.cust.tellarc.com entering state [received][100]
  13. 2019-04-02 18:21:55.872794 [DEBUG] sofia.c:7301 Remote SDP:
  14. v=0
  15. o=- 114909373 114909373 IN IP4 192.168.1.67
  16. s=-
  17. c=IN IP4 192.168.1.67
  18. t=0 0
  19. m=audio 16468 RTP/AVP 0 2 8 9 18 96 97 98 101
  20. a=rtpmap:0 PCMU/8000
  21. a=rtpmap:2 G726-32/8000
  22. a=rtpmap:8 PCMA/8000
  23. a=rtpmap:9 G722/8000
  24. a=rtpmap:18 G729a/8000
  25. a=rtpmap:96 G726-40/8000
  26. a=rtpmap:97 G726-24/8000
  27. a=rtpmap:98 G726-16/8000
  28. a=rtpmap:101 telephone-event/8000
  29. a=fmtp:101 0-15
  30. a=ptime:20
  31.  
  32. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  33. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  34. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5533 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  35. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G726-32:2:8000:20:0:1]/[opus:116:48000:20:0:1]
  36. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G726-32:2:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  37. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  38. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  39. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
  40. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  41. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729a:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  42. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729a:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  43. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G726-40:96:8000:20:0:1]/[opus:116:48000:20:0:1]
  44. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G726-40:96:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  45. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G726-24:97:8000:20:0:1]/[opus:116:48000:20:0:1]
  46. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G726-24:97:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  47. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G726-16:98:8000:20:0:1]/[opus:116:48000:20:0:1]
  48. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G726-16:98:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  49. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5394 Set telephone-event payload to 101@8000
  50. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:3781 Set Codec sofia/internal/1003@riverwalk.cust.tellarc.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  51. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_codec.c:111 sofia/internal/1003@riverwalk.cust.tellarc.com Original read codec set to PCMU:0
  52. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5737 Set telephone-event payload to 101@8000
  53. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_media.c:5795 sofia/internal/1003@riverwalk.cust.tellarc.com Set 2833 dtmf send payload to 101 recv payload to 101
  54. 2019-04-02 18:21:55.872794 [DEBUG] sofia.c:7716 (sofia/internal/1003@riverwalk.cust.tellarc.com) State Change CS_NEW -> CS_INIT
  55. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@riverwalk.cust.tellarc.com) Running State Change CS_INIT (Cur 5 Tot 31356)
  56. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@riverwalk.cust.tellarc.com) State INIT
  57. 2019-04-02 18:21:55.872794 [DEBUG] mod_sofia.c:93 sofia/internal/1003@riverwalk.cust.tellarc.com SOFIA INIT
  58. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1003@riverwalk.cust.tellarc.com Standard INIT
  59. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1003@riverwalk.cust.tellarc.com) State Change CS_INIT -> CS_ROUTING
  60. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1003@riverwalk.cust.tellarc.com) State INIT going to sleep
  61. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@riverwalk.cust.tellarc.com) Running State Change CS_ROUTING (Cur 5 Tot 31356)
  62. 2019-04-02 18:21:55.872794 [DEBUG] switch_channel.c:2249 (sofia/internal/1003@riverwalk.cust.tellarc.com) Callstate Change DOWN -> RINGING
  63. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@riverwalk.cust.tellarc.com) State ROUTING
  64. 2019-04-02 18:21:55.872794 [DEBUG] mod_sofia.c:154 sofia/internal/1003@riverwalk.cust.tellarc.com SOFIA ROUTING
  65. 2019-04-02 18:21:55.872794 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1003@riverwalk.cust.tellarc.com Standard ROUTING
  66. 2019-04-02 18:21:55.872794 [INFO] mod_dialplan_xml.c:637 Processing Ilissa <1003>->*97 in context riverwalk.cust.tellarc.com
  67. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->user_exists] continue=true
  68. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [user_exists] () =~ // break=on-false
  69. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  70. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(user_exists=false)
  71. 2019-04-02 18:21:55.872794 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [user_exists]=[false]
  72. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
  73. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->user_exists] continue=true
  74. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [user_exists] () =~ // break=on-false
  75. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  76. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(user_exists=false)
  77. 2019-04-02 18:21:55.872794 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [user_exists]=[false]
  78. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
  79. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->caller-details] continue=true
  80. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
  81. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(caller_destination=${destination_number}) INLINE
  82. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(caller_destination=*97)
  83. 2019-04-02 18:21:55.872794 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [caller_destination]=[*97]
  84. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(caller_id_name=${caller_id_name}) INLINE
  85. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(caller_id_name=1003)
  86. 2019-04-02 18:21:55.872794 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [caller_id_name]=[1003]
  87. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(caller_id_number=${caller_id_number}) INLINE
  88. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(caller_id_number=1003)
  89. 2019-04-02 18:21:55.872794 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [caller_id_number]=[1003]
  90. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->call-direction] continue=true
  91. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
  92. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com ANTI-Action set(call_direction=local)
  93. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->variables] continue=true
  94. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [variables] () =~ // break=on-false
  95. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action export(origination_callee_id_name=${destination_number})
  96. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  97. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(timezone=America/New_York) INLINE
  98. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(timezone=America/New_York)
  99. 2019-04-02 18:21:55.872794 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [timezone]=[America/New_York]
  100. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->is_local] continue=true
  101. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [is_local] () =~ // break=on-false
  102. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action lua(app.lua is_local)
  103. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->is_loopback] continue=
  104. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
  105. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->user_record] continue=true
  106. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [user_record] () =~ // break=on-false
  107. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  108. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(user_record=)
  109. 2019-04-02 18:21:55.912891 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [user_record]=[UNDEF]
  110. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  111. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(from_user_exists=true)
  112. 2019-04-02 18:21:55.912891 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [from_user_exists]=[true]
  113. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  114. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  115. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  116. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
  117. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  118. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  119. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
  120. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  121. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  122. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
  123. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  124. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  125. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
  126. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(from_user_record=)
  127. 2019-04-02 18:21:55.912891 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [from_user_record]=[UNDEF]
  128. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  129. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  130. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  131. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
  132. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  133. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  134. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
  135. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  136. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  137. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
  138. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  139. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  140. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->redial] continue=true
  141. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [redial] destination_number(*97) =~ /^(redial|\*870)$/ break=on-true
  142. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [redial] () =~ // break=never
  143. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  144. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->speed_dial] continue=false
  145. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [speed_dial] destination_number(*97) =~ /^\*0(.*)$/ break=on-false
  146. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->default_caller_id] continue=true
  147. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [default_caller_id] ${emergency_caller_id_number}(5616977712) =~ /^$/ break=never
  148. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [default_caller_id] ${outbound_caller_id_number}(5616977712) =~ /^$/ break=never
  149. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->911-v1voip] continue=false
  150. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [911-v1voip] destination_number(*97) =~ /^(911)$/ break=on-false
  151. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->teli.911-global] continue=false
  152. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [teli.911-global] ${user_exists}(false) =~ /false/ break=on-false
  153. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [teli.911-global] destination_number(*97) =~ /^(911|933)$/ break=on-false
  154. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->ring group] continue=
  155. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [ring group] destination_number(*97) =~ /^7501$/ break=on-false
  156. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->sbc0.7d] continue=false
  157. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [sbc0.7d] destination_number(*97) =~ /^(\d{7})$/ break=on-false
  158. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->sbc0.d4] continue=false
  159. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [sbc0.d4] destination_number(*97) =~ /^(1?411)$/ break=on-false
  160. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->fax] continue=true
  161. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [fax] destination_number(*97) =~ /^919191?(\d{10})$/ break=on-false
  162. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->10-11-mix] continue=false
  163. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [10-11-mix] destination_number(*97) =~ /^\+?1?(\d{10})$/ break=on-false
  164. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->agent_status] continue=false
  165. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [agent_status] destination_number(*97) =~ /^\*22$/ break=on-false
  166. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->agent_status_id] continue=false
  167. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [agent_status_id] destination_number(*97) =~ /^\*23$/ break=on-false
  168. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->group-intercept] continue=false
  169. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [group-intercept] destination_number(*97) =~ /^\*8$/ break=on-false
  170. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->page-extension] continue=false
  171. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [page-extension] destination_number(*97) =~ /^\*8(\d{2,7})$/ break=on-false
  172. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->eavesdrop] continue=false
  173. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [eavesdrop] destination_number(*97) =~ /^\*33(\d{2,7})$/ break=on-false
  174. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->call_privacy] continue=false
  175. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [call_privacy] destination_number(*97) =~ /^\*67(\d+)$/ break=on-false
  176. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->call_return] continue=false
  177. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [call_return] destination_number(*97) =~ /^\*69$/ break=on-false
  178. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->intercept-ext] continue=false
  179. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [intercept-ext] destination_number(*97) =~ /^\*\*(\d+)$/ break=on-false
  180. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->intercept-ext-polycom] continue=false
  181. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [intercept-ext-polycom] destination_number(*97) =~ /^\*97(\d+)$/ break=on-false
  182. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->extension_queue] continue=false
  183. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [extension_queue] destination_number(*97) =~ /^\*800(.*)$/ break=on-false
  184. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->Riverwalk-Main] continue=true
  185. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [Riverwalk-Main] destination_number(*97) =~ /^7601$/ break=on-false
  186. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->dx] continue=false
  187. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [dx] destination_number(*97) =~ /^dx$/ break=on-false
  188. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->att_xfer] continue=false
  189. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [att_xfer] destination_number(*97) =~ /^att_xfer$/ break=on-false
  190. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->extension-to-voicemail] continue=false
  191. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false
  192. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->send_to_voicemail] continue=false
  193. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [send_to_voicemail] destination_number(*97) =~ /^\*99(\d{2,7})$/ break=on-false
  194. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->Riverwalk-fax] continue=false
  195. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [Riverwalk-fax] destination_number(*97) =~ /^7901$/ break=on-false
  196. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->extension-to-voicemail] continue=false
  197. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [extension-to-voicemail] username(1003) =~ /^1003$/ break=on-false
  198. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [extension-to-voicemail] destination_number(*97) =~ /^1003$/ break=on-false
  199. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->xfer_vm] continue=false
  200. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [xfer_vm] destination_number(*97) =~ /^xfer_vm$/ break=on-false
  201. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->vmain] continue=false
  202. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (FAIL) [vmain] destination_number(*97) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
  203. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com parsing [riverwalk.cust.tellarc.com->vmain_user] continue=false
  204. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Regex (PASS) [vmain_user] destination_number(*97) =~ /^\*97$/ break=on-false
  205. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action answer()
  206. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action sleep(1000)
  207. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(voicemail_action=check)
  208. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(voicemail_id=${caller_id_number})
  209. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action set(voicemail_profile=default)
  210. Dialplan: sofia/internal/1003@riverwalk.cust.tellarc.com Action lua(app.lua voicemail)
  211. 2019-04-02 18:21:55.912891 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1003@riverwalk.cust.tellarc.com) State Change CS_ROUTING -> CS_EXECUTE
  212. 2019-04-02 18:21:55.912891 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1003@riverwalk.cust.tellarc.com) State ROUTING going to sleep
  213. 2019-04-02 18:21:55.912891 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@riverwalk.cust.tellarc.com) Running State Change CS_EXECUTE (Cur 5 Tot 31356)
  214. 2019-04-02 18:21:55.912891 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1003@riverwalk.cust.tellarc.com) State EXECUTE
  215. 2019-04-02 18:21:55.912891 [DEBUG] mod_sofia.c:209 sofia/internal/1003@riverwalk.cust.tellarc.com SOFIA EXECUTE
  216. 2019-04-02 18:21:55.912891 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1003@riverwalk.cust.tellarc.com Standard EXECUTE
  217. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(call_direction=local)
  218. 2019-04-02 18:21:55.912891 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [call_direction]=[local]
  219. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com export(origination_callee_id_name=*97)
  220. 2019-04-02 18:21:55.912891 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[*97]
  221. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(RFC2822_DATE=Tue, 02 Apr 2019 18:21:55 -0400)
  222. 2019-04-02 18:21:55.912891 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [RFC2822_DATE]=[Tue, 02 Apr 2019 18:21:55 -0400]
  223. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com lua(app.lua is_local)
  224. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(caller_id_name=Riverwalk)
  225. 2019-04-02 18:21:55.912891 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [caller_id_name]=[Riverwalk]
  226. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(effective_caller_id_name=Riverwalk)
  227. 2019-04-02 18:21:55.912891 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [effective_caller_id_name]=[Riverwalk]
  228. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(caller_id_number=5616977712)
  229. 2019-04-02 18:21:55.912891 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [caller_id_number]=[5616977712]
  230. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(effective_caller_id_number=5616977712)
  231. 2019-04-02 18:21:55.912891 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [effective_caller_id_number]=[5616977712]
  232. 2019-04-02 18:21:55.932800 [DEBUG] switch_cpp.cpp:1187 sofia/internal/1003@riverwalk.cust.tellarc.com destroy/unlink session from object
  233. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com hash(insert/riverwalk.cust.tellarc.com-last_dial/5616977712/*97)
  234. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com answer()
  235. 2019-04-02 18:21:55.932800 [DEBUG] switch_core_media.c:8511 AUDIO RTP [sofia/internal/1003@riverwalk.cust.tellarc.com] 162.208.16.4 port 31794 -> 192.168.1.67 port 16468 codec: 0 ms: 20
  236. 2019-04-02 18:21:55.932800 [DEBUG] switch_rtp.c:4300 Starting timer [soft] 160 bytes per 20ms
  237. 2019-04-02 18:21:55.932800 [DEBUG] switch_core_media.c:8815 sofia/internal/1003@riverwalk.cust.tellarc.com Set 2833 dtmf send payload to 101
  238. 2019-04-02 18:21:55.932800 [DEBUG] switch_core_media.c:8822 sofia/internal/1003@riverwalk.cust.tellarc.com Set 2833 dtmf receive payload to 101
  239. 2019-04-02 18:21:55.932800 [DEBUG] switch_core_media.c:8845 sofia/internal/1003@riverwalk.cust.tellarc.com Set rtp dtmf delay to 40
  240. 2019-04-02 18:21:55.932800 [DEBUG] mod_sofia.c:881 Local SDP sofia/internal/1003@riverwalk.cust.tellarc.com:
  241. v=0
  242. o=FreeSWITCH 1554211921 1554211922 IN IP4 162.208.16.4
  243. s=FreeSWITCH
  244. c=IN IP4 162.208.16.4
  245. t=0 0
  246. m=audio 31794 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. 2019-04-02 18:21:55.932800 [NOTICE] mod_dptools.c:1360 Channel [sofia/internal/1003@riverwalk.cust.tellarc.com] has been answered
  254. 2019-04-02 18:21:55.932800 [DEBUG] switch_channel.c:3781 (sofia/internal/1003@riverwalk.cust.tellarc.com) Callstate Change RINGING -> ACTIVE
  255. 2019-04-02 18:21:55.932800 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@riverwalk.cust.tellarc.com entering state [completed][200]
  256. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com sleep(1000)
  257. 2019-04-02 18:21:56.072822 [DEBUG] sofia.c:7291 Channel sofia/internal/1003@riverwalk.cust.tellarc.com entering state [ready][200]
  258. 2019-04-02 18:21:56.252825 [INFO] switch_rtp.c:7510 Auto Changing audio port from 192.168.1.67:16468 to 96.65.187.108:9270
  259. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(voicemail_action=check)
  260. 2019-04-02 18:21:56.932810 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [voicemail_action]=[check]
  261. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(voicemail_id=5616977712)
  262. 2019-04-02 18:21:56.932810 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [voicemail_id]=[5616977712]
  263. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com set(voicemail_profile=default)
  264. 2019-04-02 18:21:56.932810 [DEBUG] mod_dptools.c:1598 SET sofia/internal/1003@riverwalk.cust.tellarc.com [voicemail_profile]=[default]
  265. EXECUTE sofia/internal/1003@riverwalk.cust.tellarc.com lua(app.lua voicemail)
  266. 2019-04-02 18:21:56.932810 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('playback_terminators', '#')
  267. span style="color: goldenrod; font-weight: bold;"> 2019-04-02 18:21:56.952812 [DEBUG] switch_cpp.cpp:731 CoreSession::hangup
  268. 2019-04-02 18:21:56.952812 [NOTICE] switch_cpp.cpp:733 Hangup sofia/internal/1003@riverwalk.cust.tellarc.com [CS_EXECUTE] [NORMAL_CLEARING]
  269. 2019-04-02 18:21:56.952812 [DEBUG] switch_cpp.cpp:1187 sofia/internal/1003@riverwalk.cust.tellarc.com destroy/unlink session from object
  270. 2019-04-02 18:21:56.952812 [DEBUG] switch_core_session.c:2887 sofia/internal/1003@riverwalk.cust.tellarc.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  271. 2019-04-02 18:21:56.952812 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1003@riverwalk.cust.tellarc.com) State EXECUTE going to sleep
  272. 2019-04-02 18:21:56.952812 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@riverwalk.cust.tellarc.com) Running State Change CS_HANGUP (Cur 5 Tot 31356)
  273. 2019-04-02 18:21:56.952812 [DEBUG] switch_core_state_machine.c:847 (sofia/internal/1003@riverwalk.cust.tellarc.com) Callstate Change ACTIVE -> HANGUP
  274. 2019-04-02 18:21:56.952812 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/1003@riverwalk.cust.tellarc.com) State HANGUP
  275. 2019-04-02 18:21:56.952812 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1003@riverwalk.cust.tellarc.com hanging up, cause: NORMAL_CLEARING
  276. 2019-04-02 18:21:56.952812 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/internal/1003@riverwalk.cust.tellarc.com
  277. 2019-04-02 18:21:56.952812 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1003@riverwalk.cust.tellarc.com Standard HANGUP, cause: NORMAL_CLEARING
  278. 2019-04-02 18:21:56.952812 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/1003@riverwalk.cust.tellarc.com) State HANGUP going to sleep
  279. 2019-04-02 18:21:56.952812 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1003@riverwalk.cust.tellarc.com) State Change CS_HANGUP -> CS_REPORTING
  280. 2019-04-02 18:21:56.952812 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1003@riverwalk.cust.tellarc.com) Running State Change CS_REPORTING (Cur 5 Tot 31356)
  281. 2019-04-02 18:21:56.952812 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/1003@riverwalk.cust.tellarc.com) State REPORTING
  282. 2019-04-02 18:21:57.012818 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1003@riverwalk.cust.tellarc.com Standard REPORTING, cause: NORMAL_CLEARING
  283. 2019-04-02 18:21:57.012818 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/1003@riverwalk.cust.tellarc.com) State REPORTING going to sleep
  284. 2019-04-02 18:21:57.012818 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1003@riverwalk.cust.tellarc.com) State Change CS_REPORTING -> CS_DESTROY
  285. 2019-04-02 18:21:57.012818 [DEBUG] switch_core_session.c:1715 Session 31356 (sofia/internal/1003@riverwalk.cust.tellarc.com) Locked, Waiting on external entities
  286. 2019-04-02 18:21:57.012818 [NOTICE] switch_core_session.c:1733 Session 31356 (sofia/internal/1003@riverwalk.cust.tellarc.com) Ended
  287. 2019-04-02 18:21:57.012818 [NOTICE] switch_core_session.c:1737 Close Channel sofia/internal/1003@riverwalk.cust.tellarc.com [CS_DESTROY]
  288. 2019-04-02 18:21:57.012818 [DEBUG] switch_core_state_machine.c:738 (sofia/internal/1003@riverwalk.cust.tellarc.com) Running State Change CS_DESTROY (Cur 4 Tot 31356)
  289. 2019-04-02 18:21:57.012818 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/1003@riverwalk.cust.tellarc.com) State DESTROY
  290. 2019-04-02 18:21:57.012818 [DEBUG] mod_sofia.c:354 sofia/internal/1003@riverwalk.cust.tellarc.com SOFIA DESTROY
  291. 2019-04-02 18:21:57.012818 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1003@riverwalk.cust.tellarc.com Standard DESTROY
  292. 2019-04-02 18:21:57.012818 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/1003@riverwalk.cust.tellarc.com) State DESTROY going to sleep
  293.  
  294.  
  295.