1. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
3. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
4. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
5. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/587@vpbx-stpeters.bitco.co.za G729/8000 20 ms 160 samples 8000 bits 1 channels
6. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_codec.c:111 sofia/internal/587@vpbx-stpeters.bitco.co.za Original read codec set to G729:18
7. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
8. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_media.c:5909 sofia/internal/587@vpbx-stpeters.bitco.co.za Set 2833 dtmf send payload to 101 recv payload to 101
9. 2020-12-15 11:19:04.267495 [DEBUG] sofia.c:7759 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State Change CS_NEW -> CS_INIT
10. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/587@vpbx-stpeters.bitco.co.za) Running State Change CS_INIT (Cur 1 Tot 173)
11. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State INIT
12. 2020-12-15 11:19:04.267495 [DEBUG] mod_sofia.c:93 sofia/internal/587@vpbx-stpeters.bitco.co.za SOFIA INIT
13. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_state_machine.c:40 sofia/internal/587@vpbx-stpeters.bitco.co.za Standard INIT
14. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State Change CS_INIT -> CS_ROUTING
15. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State INIT going to sleep
16. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/587@vpbx-stpeters.bitco.co.za) Running State Change CS_ROUTING (Cur 1 Tot 173)
17. 2020-12-15 11:19:04.267495 [DEBUG] switch_channel.c:2332 (sofia/internal/587@vpbx-stpeters.bitco.co.za) Callstate Change DOWN -> RINGING
18. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State ROUTING
19. 2020-12-15 11:19:04.267495 [DEBUG] mod_sofia.c:154 sofia/internal/587@vpbx-stpeters.bitco.co.za SOFIA ROUTING
20. 2020-12-15 11:19:04.267495 [DEBUG] switch_core_state_machine.c:236 sofia/internal/587@vpbx-stpeters.bitco.co.za Standard ROUTING
21. 2020-12-15 11:19:04.267495 [INFO] mod_dialplan_xml.c:637 Processing 587 <587>->*08 in context vpbx-stpeters.bitco.co.za
22. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za parsing [vpbx-stpeters.bitco.co.za->user_exists] continue=true
23. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
24. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [user_exists] () =~ // break=on-false
25. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
26. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za set(user_exists=false)
27. 2020-12-15 11:19:04.287593 [DEBUG] mod_dptools.c:1672 SET sofia/internal/587@vpbx-stpeters.bitco.co.za [user_exists]=[false]
28. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
29. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za set(from_user_exists=true)
30. 2020-12-15 11:19:04.287593 [DEBUG] mod_dptools.c:1672 SET sofia/internal/587@vpbx-stpeters.bitco.co.za [from_user_exists]=[true]
31. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
32. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za parsing [vpbx-stpeters.bitco.co.za->caller-details] continue=true
33. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
34. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Action set(caller_destination=${destination_number}) INLINE
35. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za set(caller_destination=*08)
36. 2020-12-15 11:19:04.287593 [DEBUG] mod_dptools.c:1672 SET sofia/internal/587@vpbx-stpeters.bitco.co.za [caller_destination]=[*08]
37. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Action set(caller_id_name=${caller_id_name}) INLINE
38. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za set(caller_id_name=587)
39. 2020-12-15 11:19:04.287593 [DEBUG] mod_dptools.c:1672 SET sofia/internal/587@vpbx-stpeters.bitco.co.za [caller_id_name]=[587]
40. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Action set(caller_id_number=${caller_id_number})
41. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za parsing [vpbx-stpeters.bitco.co.za->call-direction] continue=true
42. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
43. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za ANTI-Action export(call_direction=local) INLINE
44. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za export(call_direction=local)
45. 2020-12-15 11:19:04.287593 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[local]
46. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za parsing [vpbx-stpeters.bitco.co.za->global-variables] continue=true
47. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [global-variables] () =~ // break=on-false
48. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
49. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za parsing [vpbx-stpeters.bitco.co.za->domain-variables] continue=true
50. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [domain-variables] () =~ // break=on-false
51. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Action export(origination_callee_id_name=${destination_number})
52. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za parsing [vpbx-stpeters.bitco.co.za->is_loopback] continue=true
53. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
54. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za parsing [vpbx-stpeters.bitco.co.za->user_record] continue=true
55. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
56. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
57. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
58. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
59. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
60. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
61. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
62. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
63. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
64. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
65. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
66. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
67. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
68. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za set(from_user_record=)
69. 2020-12-15 11:19:04.287593 [DEBUG] mod_dptools.c:1672 SET sofia/internal/587@vpbx-stpeters.bitco.co.za [from_user_record]=[UNDEF]
70. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
71. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
72. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
73. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${call_direction}(local) =~ /^inbound$/ break=never
74. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
75. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
76. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${call_direction}(local) =~ /^outbound$/ break=never
77. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
78. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
79. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [user_record] ${call_direction}(local) =~ /^local$/ break=never
80. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
81. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
82. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za parsing [vpbx-stpeters.bitco.co.za->redial] continue=true
83. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (FAIL) [redial] destination_number(*08) =~ /^(redial|\*870)$/ break=on-true
84. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [redial] () =~ // break=never
85. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
86. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za parsing [vpbx-stpeters.bitco.co.za->speed_dial] continue=false
87. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Regex (PASS) [speed_dial] destination_number(*08) =~ /^\*0(.*)$/ break=on-false
88. Dialplan: sofia/internal/587@vpbx-stpeters.bitco.co.za Action lua(app.lua speed_dial 8)
89. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State Change CS_ROUTING -> CS_EXECUTE
90. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State ROUTING going to sleep
91. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/587@vpbx-stpeters.bitco.co.za) Running State Change CS_EXECUTE (Cur 1 Tot 173)
92. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State EXECUTE
93. 2020-12-15 11:19:04.287593 [DEBUG] mod_sofia.c:209 sofia/internal/587@vpbx-stpeters.bitco.co.za SOFIA EXECUTE
94. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:329 sofia/internal/587@vpbx-stpeters.bitco.co.za Standard EXECUTE
95. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za set(caller_id_number=587)
96. 2020-12-15 11:19:04.287593 [DEBUG] mod_dptools.c:1672 SET sofia/internal/587@vpbx-stpeters.bitco.co.za [caller_id_number]=[587]
97. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za set(RFC2822_DATE=Tue, 15 Dec 2020 11:19:04 +0200)
98. 2020-12-15 11:19:04.287593 [DEBUG] mod_dptools.c:1672 SET sofia/internal/587@vpbx-stpeters.bitco.co.za [RFC2822_DATE]=[Tue, 15 Dec 2020 11:19:04 +0200]
99. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za export(origination_callee_id_name=*08)
100. 2020-12-15 11:19:04.287593 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [origination_callee_id_name]=[*08]
101. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za hash(insert/vpbx-stpeters.bitco.co.za-last_dial/587/*08)
102. EXECUTE [depth=0] sofia/internal/587@vpbx-stpeters.bitco.co.za lua(app.lua speed_dial 8)
103. 2020-12-15 11:19:04.287593 [WARNING] switch_cpp.cpp:1447 [app:dialplan:outbound:speed_dial] can not find number: 8 in domain: vpbx-stpeters.bitco.co.za
104. 2020-12-15 11:19:04.287593 [DEBUG] switch_cpp.cpp:1191 sofia/internal/587@vpbx-stpeters.bitco.co.za destroy/unlink session from object
105. 2020-12-15 11:19:04.287593 [NOTICE] switch_core_state_machine.c:386 sofia/internal/587@vpbx-stpeters.bitco.co.za has executed the last dialplan instruction, hanging up.
106. 2020-12-15 11:19:04.287593 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/587@vpbx-stpeters.bitco.co.za [CS_EXECUTE] [NORMAL_CLEARING]
107. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State EXECUTE going to sleep
108. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/587@vpbx-stpeters.bitco.co.za) Running State Change CS_HANGUP (Cur 1 Tot 173)
109. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/587@vpbx-stpeters.bitco.co.za) Callstate Change RINGING -> HANGUP
110. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State HANGUP
111. 2020-12-15 11:19:04.287593 [DEBUG] mod_sofia.c:453 Channel sofia/internal/587@vpbx-stpeters.bitco.co.za hanging up, cause: NORMAL_CLEARING
112. 2020-12-15 11:19:04.287593 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
113. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:60 sofia/internal/587@vpbx-stpeters.bitco.co.za Standard HANGUP, cause: NORMAL_CLEARING
114. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State HANGUP going to sleep
115. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State Change CS_HANGUP -> CS_REPORTING
116. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/587@vpbx-stpeters.bitco.co.za) Running State Change CS_REPORTING (Cur 1 Tot 173)
117. 2020-12-15 11:19:04.287593 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State REPORTING
118. 2020-12-15 11:19:04.327480 [DEBUG] switch_core_state_machine.c:174 sofia/internal/587@vpbx-stpeters.bitco.co.za Standard REPORTING, cause: NORMAL_CLEARING
119. 2020-12-15 11:19:04.327480 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State REPORTING going to sleep
120. 2020-12-15 11:19:04.327480 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State Change CS_REPORTING -> CS_DESTROY
121. 2020-12-15 11:19:04.327480 [DEBUG] switch_core_session.c:1726 Session 173 (sofia/internal/587@vpbx-stpeters.bitco.co.za) Locked, Waiting on external entities
122. 2020-12-15 11:19:04.327480 [NOTICE] switch_core_session.c:1744 Session 173 (sofia/internal/587@vpbx-stpeters.bitco.co.za) Ended
123. 2020-12-15 11:19:04.327480 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/587@vpbx-stpeters.bitco.co.za [CS_DESTROY]
124. 2020-12-15 11:19:04.327480 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/587@vpbx-stpeters.bitco.co.za) Running State Change CS_DESTROY (Cur 0 Tot 173)
125. 2020-12-15 11:19:04.327480 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State DESTROY
126. 2020-12-15 11:19:04.327480 [DEBUG] mod_sofia.c:364 sofia/internal/587@vpbx-stpeters.bitco.co.za SOFIA DESTROY
127. 2020-12-15 11:19:04.327480 [DEBUG] switch_core_state_machine.c:181 sofia/internal/587@vpbx-stpeters.bitco.co.za Standard DESTROY
128. 2020-12-15 11:19:04.327480 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/587@vpbx-stpeters.bitco.co.za) State DESTROY going to sleep
129. 2020-12-15 11:19:06.567492 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'internal' for [287@vpbx-stpeters.bitco.co.za] from ip 10.4.4.230
130. 2020-12-15 11:19:06.567492 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'internal' for [283@vpbx-stpeters.bitco.co.za] from ip 10.4.4.230
131. 2020-12-15 11:19:06.567492 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'internal' for [282@vpbx-stpeters.bitco.co.za] from ip 10.4.4.230
132.