From Viz, 5 Years ago, written in Plain Text.
- view diff
Embed
  1. Type /help <enter> to see a list of commands
  2.  
  3.  
  4.  
  5. +OK log level  [7]
  6. 2017-05-16 11:41:25.927464 [WARNING] sofia_reg.c:1758 SIP auth challenge (REGISTER) on sofia profile 'internal' for [200@pbx.ripple.jennyme.co.za] from ip 10.19.223.190
  7. 2017-05-16 11:41:26.187367 [NOTICE] switch_channel.c:1077 New Channel sofia/internal/106@pbx.probemo.jennyme.co.za [d48288f0-3a1b-11e7-a623-ebab4664731f]
  8. 2017-05-16 11:41:26.187367 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [BREAK]
  9. 2017-05-16 11:41:26.187367 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [BREAK]
  10. 2017-05-16 11:41:26.187367 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/106@pbx.probemo.jennyme.co.za) Running State Change CS_NEW
  11. 2017-05-16 11:41:26.187367 [DEBUG] sofia.c:8957 sofia/internal/106@pbx.probemo.jennyme.co.za receiving invite from 10.5.126.4:2048 version: 1.4.26  64bit
  12. 2017-05-16 11:41:26.187367 [DEBUG] sofia.c:9124 IP 10.5.126.4 Rejected by acl "domains". Falling back to Digest auth.
  13. 2017-05-16 11:41:26.187367 [WARNING] sofia_reg.c:1758 SIP auth challenge (INVITE) on sofia profile 'internal' for [00260962200587@pbx.probemo.jennyme.co.za] from ip 10.5.126.4
  14. 2017-05-16 11:41:26.187367 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/106@pbx.probemo.jennyme.co.za) State NEW
  15. 2017-05-16 11:41:26.187367 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [BREAK]
  16. 2017-05-16 11:41:26.187367 [DEBUG] sofia.c:2065 detaching session d48288f0-3a1b-11e7-a623-ebab4664731f
  17. 2017-05-16 11:41:26.247354 [DEBUG] sofia.c:2173 Re-attaching to session d48288f0-3a1b-11e7-a623-ebab4664731f
  18. 2017-05-16 11:41:26.247354 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [BREAK]
  19. 2017-05-16 11:41:26.247354 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [BREAK]
  20. 2017-05-16 11:41:26.267339 [DEBUG] sofia.c:8957 sofia/internal/106@pbx.probemo.jennyme.co.za receiving invite from 10.5.126.4:2048 version: 1.4.26  64bit
  21. 2017-05-16 11:41:26.267339 [DEBUG] sofia.c:9124 IP 10.5.126.4 Rejected by acl "domains". Falling back to Digest auth.
  22. 2017-05-16 11:41:26.267339 [DEBUG] sofia.c:6658 Channel sofia/internal/106@pbx.probemo.jennyme.co.za entering state [received][100]
  23. 2017-05-16 11:41:26.267339 [DEBUG] sofia.c:6668 Remote SDP:
  24. v=0
  25. o=root 657155282 657155282 IN IP4 10.5.126.4
  26. s=call
  27. c=IN IP4 10.5.126.4
  28. t=0 0
  29. m=audio 64300 RTP/AVP 18 8 0 99 3 4 101
  30. a=rtpmap:18 G729/8000
  31. a=fmtp:18 annexb=no
  32. a=rtpmap:8 PCMA/8000
  33. a=rtpmap:0 PCMU/8000
  34. a=rtpmap:99 G726-32/8000
  35. a=rtpmap:3 GSM/8000
  36. a=rtpmap:4 G723/8000
  37. a=rtpmap:101 telephone-event/8000
  38. a=fmtp:101 0-16
  39. a=ptime:20
  40.  
  41. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  42. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  43. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  44. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  45. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  46. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
  47. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  48. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  49. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  50. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  51. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  52. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  53. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  54. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  55. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  56. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  57. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  58. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  59. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G726-32:99:8000:20:0:1]/[G729:18:8000:20:8000:1]
  60. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G726-32:99:8000:20:0:1]/[G722:9:8000:20:64000:1]
  61. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G726-32:99:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  62. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G726-32:99:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  63. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G726-32:99:8000:20:0:1]/[GSM:3:8000:20:13200:1]
  64. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
  65. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
  66. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  67. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  68. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [GSM:3:8000:20:13200:1]/[GSM:3:8000:20:13200:1]
  69. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [GSM:3:8000:20:13200:1] ++++ is saved as a match
  70. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G723:4:8000:20:6300:1]/[G729:18:8000:20:8000:1]
  71. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G723:4:8000:20:6300:1]/[G722:9:8000:20:64000:1]
  72. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G723:4:8000:20:6300:1]/[PCMU:0:8000:20:64000:1]
  73. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G723:4:8000:20:6300:1]/[PCMA:8:8000:20:64000:1]
  74. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [G723:4:8000:20:6300:1]/[GSM:3:8000:20:13200:1]
  75. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3595 Set telephone-event payload to 101
  76. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:2506 Set Codec sofia/internal/106@pbx.probemo.jennyme.co.za G729/8000 20 ms 160 samples 8000 bits 1 channels
  77. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_codec.c:111 sofia/internal/106@pbx.probemo.jennyme.co.za Original read codec set to G729:18
  78. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_media.c:3942 Set 2833 dtmf send/recv payload to 101
  79. 2017-05-16 11:41:26.267339 [DEBUG] sofia.c:6955 (sofia/internal/106@pbx.probemo.jennyme.co.za) State Change CS_NEW -> CS_INIT
  80. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [BREAK]
  81. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/106@pbx.probemo.jennyme.co.za) Running State Change CS_INIT
  82. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/106@pbx.probemo.jennyme.co.za) State INIT
  83. 2017-05-16 11:41:26.267339 [DEBUG] mod_sofia.c:87 sofia/internal/106@pbx.probemo.jennyme.co.za SOFIA INIT
  84. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_state_machine.c:40 sofia/internal/106@pbx.probemo.jennyme.co.za Standard INIT
  85. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/106@pbx.probemo.jennyme.co.za) State Change CS_INIT -> CS_ROUTING
  86. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [BREAK]
  87. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/106@pbx.probemo.jennyme.co.za) State INIT going to sleep
  88. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/106@pbx.probemo.jennyme.co.za) Running State Change CS_ROUTING
  89. 2017-05-16 11:41:26.267339 [DEBUG] switch_channel.c:2206 (sofia/internal/106@pbx.probemo.jennyme.co.za) Callstate Change DOWN -> RINGING
  90. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/106@pbx.probemo.jennyme.co.za) State ROUTING
  91. 2017-05-16 11:41:26.267339 [DEBUG] mod_sofia.c:123 sofia/internal/106@pbx.probemo.jennyme.co.za SOFIA ROUTING
  92. 2017-05-16 11:41:26.267339 [DEBUG] switch_core_state_machine.c:166 sofia/internal/106@pbx.probemo.jennyme.co.za Standard ROUTING
  93. 2017-05-16 11:41:26.267339 [INFO] mod_dialplan_xml.c:635 Processing 106 <106>->00260962200587 in context pbx.probemo.jennyme.co.za
  94. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za parsing [pbx.probemo.jennyme.co.za->user_exists] continue=true
  95. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [user_exists] () =~ // break=on-false
  96. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  97. 2017-05-16 11:41:26.287478 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f8d648c8420 Connected.
  98. 2017-05-16 11:41:26.287478 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f8d648c8420 released.
  99. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(user_exists=false)
  100. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [user_exists]=[false]
  101. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
  102. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za parsing [pbx.probemo.jennyme.co.za->call-direction] continue=true
  103. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
  104. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za ANTI-Action set(call_direction=local)
  105. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [call-direction] ${user_exists}(false) =~ /^false$/ break=on-false
  106. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [call-direction] destination_number(00260962200587) =~ /^\d{7,20}$/ break=on-false
  107. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(call_direction=outbound)
  108. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za parsing [pbx.probemo.jennyme.co.za->variables] continue=true
  109. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [variables] () =~ // break=on-false
  110. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action export(origination_callee_id_name=${destination_number})
  111. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  112. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za parsing [pbx.probemo.jennyme.co.za->user_record] continue=true
  113. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [user_record] () =~ // break=on-false
  114. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  115. 2017-05-16 11:41:26.287478 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f8d648c8420 Connected.
  116. 2017-05-16 11:41:26.287478 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f8d648c8420 released.
  117. 2017-05-16 11:41:26.287478 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f8d648c8420 Connected.
  118. 2017-05-16 11:41:26.287478 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f8d648c8420 released.
  119. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(user_record=)
  120. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [user_record]=[UNDEF]
  121. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  122. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(from_user_exists=true)
  123. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [from_user_exists]=[true]
  124. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  125. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  126. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  127. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
  128. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  129. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  130. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
  131. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  132. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  133. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
  134. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  135. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  136. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
  137. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(from_user_record=)
  138. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [from_user_record]=[UNDEF]
  139. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  140. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  141. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  142. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
  143. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  144. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  145. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
  146. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  147. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  148. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
  149. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  150. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  151. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za parsing [pbx.probemo.jennyme.co.za->redial] continue=true
  152. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [redial] destination_number(00260962200587) =~ /^(redial|\*870)$/ break=on-true
  153. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [redial] () =~ // break=never
  154. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  155. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za parsing [pbx.probemo.jennyme.co.za->5444jennyv.10d] continue=false
  156. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [5444jennyv.10d] destination_number(00260962200587) =~ /^(\d{10})$/ break=on-false
  157. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za parsing [pbx.probemo.jennyme.co.za->5444jennyv.10d] continue=false
  158. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (FAIL) [5444jennyv.10d] destination_number(00260962200587) =~ /^(\d{10})$/ break=on-false
  159. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za parsing [pbx.probemo.jennyme.co.za->5444jennyv.International] continue=false
  160. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Regex (PASS) [5444jennyv.International] destination_number(00260962200587) =~ /^(\d{12,20})$/ break=on-false
  161. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(sip_h_X-accountcode=${accountcode})
  162. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(call_direction=outbound)
  163. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(hangup_after_bridge=true)
  164. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(effective_caller_id_name=${outbound_caller_id_name})
  165. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(effective_caller_id_number=${outbound_caller_id_number})
  166. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(inherit_codec=true)
  167. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(ignore_display_updates=true)
  168. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(callee_id_number=00260962200587)
  169. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action set(continue_on_fail=true)
  170. Dialplan: sofia/internal/106@pbx.probemo.jennyme.co.za Action bridge(sofia/gateway/edec09e1-0920-4adc-848b-7d1683acf58d/00260962200587)
  171. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/106@pbx.probemo.jennyme.co.za) State Change CS_ROUTING -> CS_EXECUTE
  172. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [BREAK]
  173. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/106@pbx.probemo.jennyme.co.za) State ROUTING going to sleep
  174. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/106@pbx.probemo.jennyme.co.za) Running State Change CS_EXECUTE
  175. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/106@pbx.probemo.jennyme.co.za) State EXECUTE
  176. 2017-05-16 11:41:26.287478 [DEBUG] mod_sofia.c:178 sofia/internal/106@pbx.probemo.jennyme.co.za SOFIA EXECUTE
  177. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:258 sofia/internal/106@pbx.probemo.jennyme.co.za Standard EXECUTE
  178. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(call_direction=local)
  179. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [call_direction]=[local]
  180. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(call_direction=outbound)
  181. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [call_direction]=[outbound]
  182. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za export(origination_callee_id_name=00260962200587)
  183. 2017-05-16 11:41:26.287478 [DEBUG] switch_channel.c:1269 EXPORT (export_vars) [origination_callee_id_name]=[00260962200587]
  184. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(RFC2822_DATE=Tue, 16 May 2017 11:41:26 +0200)
  185. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [RFC2822_DATE]=[Tue, 16 May 2017 11:41:26 +0200]
  186. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za hash(insert/pbx.probemo.jennyme.co.za-last_dial/106/00260962200587)
  187. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(sip_h_X-accountcode=pbx.probemo.jennyme.co.za)
  188. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [sip_h_X-accountcode]=[pbx.probemo.jennyme.co.za]
  189. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(call_direction=outbound)
  190. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [call_direction]=[outbound]
  191. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(hangup_after_bridge=true)
  192. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [hangup_after_bridge]=[true]
  193. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(effective_caller_id_name=)
  194. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [effective_caller_id_name]=[UNDEF]
  195. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(effective_caller_id_number=)
  196. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [effective_caller_id_number]=[UNDEF]
  197. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(inherit_codec=true)
  198. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [inherit_codec]=[true]
  199. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(ignore_display_updates=true)
  200. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [ignore_display_updates]=[true]
  201. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(callee_id_number=00260962200587)
  202. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [callee_id_number]=[00260962200587]
  203. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za set(continue_on_fail=true)
  204. 2017-05-16 11:41:26.287478 [DEBUG] mod_dptools.c:1477 sofia/internal/106@pbx.probemo.jennyme.co.za SET [continue_on_fail]=[true]
  205. EXECUTE sofia/internal/106@pbx.probemo.jennyme.co.za bridge(sofia/gateway/edec09e1-0920-4adc-848b-7d1683acf58d/00260962200587)
  206. 2017-05-16 11:41:26.287478 [DEBUG] switch_channel.c:1223 sofia/internal/106@pbx.probemo.jennyme.co.za EXPORTING[export_vars] [domain_name]=[pbx.probemo.jennyme.co.za] to event
  207. 2017-05-16 11:41:26.287478 [DEBUG] switch_channel.c:1223 sofia/internal/106@pbx.probemo.jennyme.co.za EXPORTING[export_vars] [origination_callee_id_name]=[00260962200587] to event
  208. 2017-05-16 11:41:26.287478 [DEBUG] switch_ivr_originate.c:2124 Parsing global variables
  209. 2017-05-16 11:41:26.287478 [NOTICE] switch_channel.c:1077 New Channel sofia/external/00260962200587 [d4953928-3a1b-11e7-a63b-ebab4664731f]
  210. 2017-05-16 11:41:26.287478 [DEBUG] mod_sofia.c:4700 (sofia/external/00260962200587) State Change CS_NEW -> CS_INIT
  211. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/00260962200587 [BREAK]
  212. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:472 (sofia/external/00260962200587) Running State Change CS_INIT
  213. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:512 (sofia/external/00260962200587) State INIT
  214. 2017-05-16 11:41:26.287478 [DEBUG] mod_sofia.c:87 sofia/external/00260962200587 SOFIA INIT
  215. 2017-05-16 11:41:26.287478 [DEBUG] sofia_glue.c:1241 sofia/external/00260962200587 sending invite version: 1.4.26  64bit
  216. Local SDP:
  217. v=0
  218. o=FreeSWITCH 1494910974 1494910975 IN IP4 154.126.201.120
  219. s=FreeSWITCH
  220. c=IN IP4 154.126.201.120
  221. t=0 0
  222. m=audio 16712 RTP/AVP 18 0 8 3 101 13
  223. a=rtpmap:18 G729/8000
  224. a=rtpmap:0 PCMU/8000
  225. a=rtpmap:8 PCMA/8000
  226. a=rtpmap:3 GSM/8000
  227. a=rtpmap:101 telephone-event/8000
  228. a=fmtp:101 0-16
  229. a=ptime:20
  230. a=sendrecv
  231.  
  232. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:40 sofia/external/00260962200587 Standard INIT
  233. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:48 (sofia/external/00260962200587) State Change CS_INIT -> CS_ROUTING
  234. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/00260962200587 [BREAK]
  235. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:512 (sofia/external/00260962200587) State INIT going to sleep
  236. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_session.c:1062 Send signal sofia/external/00260962200587 [BREAK]
  237. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:472 (sofia/external/00260962200587) Running State Change CS_ROUTING
  238. 2017-05-16 11:41:26.287478 [DEBUG] sofia.c:6658 Channel sofia/external/00260962200587 entering state [calling][0]
  239. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:528 (sofia/external/00260962200587) State ROUTING
  240. 2017-05-16 11:41:26.287478 [DEBUG] mod_sofia.c:123 sofia/external/00260962200587 SOFIA ROUTING
  241. 2017-05-16 11:41:26.287478 [DEBUG] switch_ivr_originate.c:67 (sofia/external/00260962200587) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  242. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/00260962200587 [BREAK]
  243. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:528 (sofia/external/00260962200587) State ROUTING going to sleep
  244. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:472 (sofia/external/00260962200587) Running State Change CS_CONSUME_MEDIA
  245. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:547 (sofia/external/00260962200587) State CONSUME_MEDIA
  246. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_state_machine.c:547 (sofia/external/00260962200587) State CONSUME_MEDIA going to sleep
  247. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_session.c:1062 Send signal sofia/external/00260962200587 [BREAK]
  248. 2017-05-16 11:41:26.287478 [DEBUG] switch_core_session.c:1062 Send signal sofia/external/00260962200587 [BREAK]
  249. 2017-05-16 11:41:26.287478 [DEBUG] sofia.c:6658 Channel sofia/external/00260962200587 entering state [calling][0]
  250. 2017-05-16 11:41:26.327336 [NOTICE] switch_channel.c:1077 New Channel sofia/internal/117@pbx.sizwe.jennyme.co.za:5060 [d4987eee-3a1b-11e7-a63f-ebab4664731f]
  251. 2017-05-16 11:41:26.327336 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/117@pbx.sizwe.jennyme.co.za:5060 [BREAK]
  252. 2017-05-16 11:41:26.327336 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/117@pbx.sizwe.jennyme.co.za:5060 [BREAK]
  253. 2017-05-16 11:41:26.327336 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/117@pbx.sizwe.jennyme.co.za:5060) Running State Change CS_NEW
  254. 2017-05-16 11:41:26.327336 [DEBUG] sofia.c:8957 sofia/internal/117@pbx.sizwe.jennyme.co.za:5060 receiving invite from 154.126.209.220:1290 version: 1.4.26  64bit
  255. 2017-05-16 11:41:26.327336 [DEBUG] sofia.c:9124 IP 154.126.209.220 Rejected by acl "domains". Falling back to Digest auth.
  256. 2017-05-16 11:41:26.327336 [WARNING] sofia_reg.c:1758 SIP auth challenge (INVITE) on sofia profile 'internal' for [0727958573@pbx.sizwe.jennyme.co.za] from ip 154.126.209.220
  257. 2017-05-16 11:41:26.327336 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/117@pbx.sizwe.jennyme.co.za:5060) State NEW
  258. 2017-05-16 11:41:26.327336 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/117@pbx.sizwe.jennyme.co.za:5060 [BREAK]
  259. 2017-05-16 11:41:26.327336 [DEBUG] sofia.c:2065 detaching session d4987eee-3a1b-11e7-a63f-ebab4664731f
  260. 2017-05-16 11:41:26.347350 [DEBUG] switch_core_session.c:1062 Send signal sofia/external/00260962200587 [BREAK]
  261. 2017-05-16 11:41:26.347350 [DEBUG] switch_core_session.c:1062 Send signal sofia/external/00260962200587 [BREAK]
  262. 2017-05-16 11:41:26.347350 [DEBUG] switch_core_session.c:1062 Send signal sofia/external/00260962200587 [BREAK]
  263. 2017-05-16 11:41:26.367338 [DEBUG] sofia.c:6658 Channel sofia/external/00260962200587 entering state [terminated][503]
  264. 2017-05-16 11:41:26.367338 [NOTICE] sofia.c:7580 Hangup sofia/external/00260962200587 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  265. 2017-05-16 11:41:26.367338 [DEBUG] switch_channel.c:3244 Send signal sofia/external/00260962200587 [KILL]
  266. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/00260962200587 [BREAK]
  267. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:472 (sofia/external/00260962200587) Running State Change CS_HANGUP
  268. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:735 (sofia/external/00260962200587) Callstate Change DOWN -> HANGUP
  269. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:737 (sofia/external/00260962200587) State HANGUP
  270. 2017-05-16 11:41:26.367338 [DEBUG] mod_sofia.c:413 Channel sofia/external/00260962200587 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  271. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:60 sofia/external/00260962200587 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  272. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:737 (sofia/external/00260962200587) State HANGUP going to sleep
  273. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:504 (sofia/external/00260962200587) State Change CS_HANGUP -> CS_REPORTING
  274. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/00260962200587 [BREAK]
  275. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:472 (sofia/external/00260962200587) Running State Change CS_REPORTING
  276. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:823 (sofia/external/00260962200587) State REPORTING
  277. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:104 sofia/external/00260962200587 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  278. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:823 (sofia/external/00260962200587) State REPORTING going to sleep
  279. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:498 (sofia/external/00260962200587) State Change CS_REPORTING -> CS_DESTROY
  280. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/00260962200587 [BREAK]
  281. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_session.c:1624 Session 20683 (sofia/external/00260962200587) Locked, Waiting on external entities
  282. 2017-05-16 11:41:26.367338 [DEBUG] switch_ivr_originate.c:3747 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
  283. 2017-05-16 11:41:26.367338 [NOTICE] switch_core_session.c:1642 Session 20683 (sofia/external/00260962200587) Ended
  284. 2017-05-16 11:41:26.367338 [NOTICE] switch_core_session.c:1646 Close Channel sofia/external/00260962200587 [CS_DESTROY]
  285. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:626 (sofia/external/00260962200587) Running State Change CS_DESTROY
  286. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:636 (sofia/external/00260962200587) State DESTROY
  287. 2017-05-16 11:41:26.367338 [DEBUG] mod_sofia.c:323 sofia/external/00260962200587 SOFIA DESTROY
  288. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:111 sofia/external/00260962200587 Standard DESTROY
  289. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:636 (sofia/external/00260962200587) State DESTROY going to sleep
  290. 2017-05-16 11:41:26.367338 [INFO] mod_dptools.c:3276 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
  291. 2017-05-16 11:41:26.367338 [NOTICE] switch_core_state_machine.c:315 sofia/internal/106@pbx.probemo.jennyme.co.za has executed the last dialplan instruction, hanging up.
  292. 2017-05-16 11:41:26.367338 [NOTICE] switch_core_state_machine.c:317 Hangup sofia/internal/106@pbx.probemo.jennyme.co.za [CS_EXECUTE] [NORMAL_CLEARING]
  293. 2017-05-16 11:41:26.367338 [DEBUG] switch_channel.c:3244 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [KILL]
  294. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [BREAK]
  295. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/106@pbx.probemo.jennyme.co.za) State EXECUTE going to sleep
  296. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/106@pbx.probemo.jennyme.co.za) Running State Change CS_HANGUP
  297. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/106@pbx.probemo.jennyme.co.za) Callstate Change RINGING -> HANGUP
  298. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/106@pbx.probemo.jennyme.co.za) State HANGUP
  299. 2017-05-16 11:41:26.367338 [DEBUG] mod_sofia.c:407 sofia/internal/106@pbx.probemo.jennyme.co.za Overriding SIP cause 480 with 503 from the other leg
  300. 2017-05-16 11:41:26.367338 [DEBUG] mod_sofia.c:413 Channel sofia/internal/106@pbx.probemo.jennyme.co.za hanging up, cause: NORMAL_CLEARING
  301. 2017-05-16 11:41:26.367338 [DEBUG] mod_sofia.c:549 Responding to INVITE with: 503
  302. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:60 sofia/internal/106@pbx.probemo.jennyme.co.za Standard HANGUP, cause: NORMAL_CLEARING
  303. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/106@pbx.probemo.jennyme.co.za) State HANGUP going to sleep
  304. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/106@pbx.probemo.jennyme.co.za) State Change CS_HANGUP -> CS_REPORTING
  305. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/106@pbx.probemo.jennyme.co.za [BREAK]
  306. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/106@pbx.probemo.jennyme.co.za) Running State Change CS_REPORTING
  307. 2017-05-16 11:41:26.367338 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/106@pbx.probemo.jennyme.co.za) State REPORTING
  308.