1. span style="color: cornflowerblue; font-weight: bold;"> dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [NOTICE] switch_channel.c:1118 New Channel sofia/external/100@hpbx043.telecomsvc.com [dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a]
2. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_NEW (Cur 1 Tot 99)
3. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] sofia.c:10280 sofia/external/100@hpbx043.telecomsvc.com receiving invite from 72.53.173.29:5060 version: 1.10.5-release git 25569c1 2020-08-18 18:51:21Z 64bit
4. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [received][100]
5. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] sofia.c:7336 Remote SDP:
6. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a v=0
7. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a o=- 1 1 IN IP4 72.53.173.29
8. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a s=SIP call
9. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a c=IN IP4 72.53.173.29
10. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a t=0 0
11. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=sendrecv
12. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a m=audio 60342 RTP/AVP 9 0 18 8 101
13. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=rtpmap:9 G722/8000
14. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=rtpmap:0 PCMU/8000
15. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=rtpmap:18 G729/8000
16. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=fmtp:18 annexb=no
17. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=rtpmap:8 PCMA/8000
18. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=rtpmap:101 telephone-event/8000
19. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=ptime:20
20. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a
21. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] sofia.c:7739 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_NEW -> CS_INIT
22. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:604 (sofia/external/100@hpbx043.telecomsvc.com) State NEW
23. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_INIT (Cur 1 Tot 99)
24. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:628 (sofia/external/100@hpbx043.telecomsvc.com) State INIT
25. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] mod_sofia.c:93 sofia/external/100@hpbx043.telecomsvc.com SOFIA INIT
26. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:40 sofia/external/100@hpbx043.telecomsvc.com Standard INIT
27. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:48 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_INIT -> CS_ROUTING
28. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:628 (sofia/external/100@hpbx043.telecomsvc.com) State INIT going to sleep
29. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_ROUTING (Cur 1 Tot 99)
30. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_channel.c:2332 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change DOWN -> RINGING
31. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:644 (sofia/external/100@hpbx043.telecomsvc.com) State ROUTING
32. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] mod_sofia.c:154 sofia/external/100@hpbx043.telecomsvc.com SOFIA ROUTING
33. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:236 sofia/external/100@hpbx043.telecomsvc.com Standard ROUTING
34. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [INFO] mod_dialplan_xml.c:637 Processing Jim Concannon <100>->5031 in context public
35. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->unloop] continue=false
36. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
37. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
38. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->outside_call] continue=true
39. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Absolute Condition [outside_call]
40. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(outside_call=true)
41. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
42. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action log(CRIT ***** naked in public from ${sip_from_user} to ${destination_number} ***** )
43. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action log(CRIT ***** caller_name = ${caller_id_name}; ICLID = ${caller_id_number} ***** )
44. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->from Consumer Celluloid] continue=true
45. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [from Consumer Celluloid] ${caller_id_number}(100) =~ /^(19542352653)$/ break=never
46. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->junk block] continue=false
47. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${sip_to_user}(65749684) =~ /obi_fjh/ break=never
48. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_name}(Jim Concannon) =~ /^V\d+/ break=on-true
49. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_name}(Jim Concannon) =~ /[Pp]olice[Oo]fficer\d+/ break=on-true
50. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(923008355903)$/ break=on-true
51. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(14156358962)$/ break=on-true
52. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(17542193980)$/ break=on-true
53. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(13012881384)$/ break=on-true
54. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(1408689\d{4})$/ break=on-true
55. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->callcentric_did] continue=false
56. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [callcentric_did] ${sip_to_user}(65749684) =~ /^(12023809008)$/ break=on-true
57. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->Jim Concannon] continue=false
58. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (PASS) [Jim Concannon] ${destination_number}(5031) =~ /^(5031)$/ break=never
59. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (PASS) [Jim Concannon] ${sip_from_user}(100) =~ /^(100)$/ break=on-true
60. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action log(CRIT ***** JIM CONCANNON EXT100 from ${sip_from_user} to ${destination_number} ***** )
61. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(ringback=${us-ring})
62. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(call_timeout=48)
63. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(hangup_after_bridge=true)
64. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(continue_on_fail=true)
65. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action bridge(${sofia_contact(user/2104)},${sofia_contact(user/2107)},${sofia_contact(user/2103)},${sofia_contact(user/2105)}:_:{sip_cid_type=rpid,ignore_early_media=true}sofia/gateway/callcentric/19542352653)
66. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:287 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_ROUTING -> CS_EXECUTE
67. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:644 (sofia/external/100@hpbx043.telecomsvc.com) State ROUTING going to sleep
68. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_EXECUTE (Cur 1 Tot 99)
69. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:651 (sofia/external/100@hpbx043.telecomsvc.com) State EXECUTE
70. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] mod_sofia.c:209 sofia/external/100@hpbx043.telecomsvc.com SOFIA EXECUTE
71. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:329 sofia/external/100@hpbx043.telecomsvc.com Standard EXECUTE
72. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(outside_call=true)
73. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [outside_call]=[true]
74. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com export(RFC2822_DATE=Mon, 30 Jan 2023 11:10:30 -0500)
75. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 30 Jan 2023 11:10:30 -0500]
76. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com log(CRIT ***** naked in public from 100 to 5031 ***** )
77. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [CRIT] mod_dptools.c:1866 ***** naked in public from 100 to 5031 *****
78. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com log(CRIT ***** caller_name = Jim Concannon; ICLID = 100 ***** )
79. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [CRIT] mod_dptools.c:1866 ***** caller_name = Jim Concannon; ICLID = 100 *****
80. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com log(CRIT ***** JIM CONCANNON EXT100 from 100 to 5031 ***** )
81. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [CRIT] mod_dptools.c:1866 ***** JIM CONCANNON EXT100 from 100 to 5031 *****
82. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(ringback=%(2000,4000,440,480))
83. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [ringback]=[%(2000,4000,440,480)]
84. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(call_timeout=48)
85. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [call_timeout]=[48]
86. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(hangup_after_bridge=true)
87. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [hangup_after_bridge]=[true]
88. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(continue_on_fail=true)
89. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [continue_on_fail]=[true]
90. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com bridge(error/user_not_registered,sofia/internal/sip:2107@192.168.65.94,error/user_not_registered,error/user_not_registered:_:{sip_cid_type=rpid,ignore_early_media=true}sofia/gateway/callcentric/19542352653)
91. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_channel.c:1264 sofia/external/100@hpbx043.telecomsvc.com EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 30 Jan 2023 11:10:30 -0500] to event
92. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_ivr_originate.c:1618 Parsing ultra-global variables
93. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [INFO] switch_ivr_originate.c:1684 Sending early media
94. 2023-01-30 11:10:30.623782 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
95. 2023-01-30 11:10:30.623782 [NOTICE] switch_ivr_originate.c:2999 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
96. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/2107@192.168.65.94 [a86f7630-ef31-4acb-bfa1-d2018a0cc34d]
97. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
98. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
99. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
100. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] mod_sofia.c:5089 (sofia/internal/2107@192.168.65.94) State Change CS_NEW -> CS_INIT
101. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
102. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
103. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
104. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
105. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
106. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
107. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
108. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
109. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
110. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
111. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
112. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
113. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/100@hpbx043.telecomsvc.com G722/8000 20 ms 160 samples 64000 bits 1 channels
114. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_codec.c:111 sofia/external/100@hpbx043.telecomsvc.com Original read codec set to G722:9
115. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_INIT (Cur 2 Tot 100)
116. 2023-01-30 11:10:30.623782 [NOTICE] switch_ivr_originate.c:2999 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
117. 2023-01-30 11:10:30.623782 [NOTICE] switch_ivr_originate.c:2999 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
118. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
119. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:5911 sofia/external/100@hpbx043.telecomsvc.com Set 2833 dtmf send payload to 101 recv payload to 101
120. 2023-01-30 11:10:30.623782 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
121. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/100@hpbx043.telecomsvc.com] 192.168.65.20 port 18462 -> 72.53.173.29 port 60342 codec: 9 ms: 20
122. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2107@192.168.65.94) State INIT
123. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] mod_sofia.c:93 sofia/internal/2107@192.168.65.94 SOFIA INIT
124. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [NOTICE] switch_channel.c:1118 New Channel sofia/external/19542352653 [6ca9d392-578d-42e1-8617-229c015e29f5]
125. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] mod_sofia.c:5089 (sofia/external/19542352653) State Change CS_NEW -> CS_INIT
126. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] sofia_glue.c:1618 sofia/internal/2107@192.168.65.94 sending invite version: 1.10.5-release git 25569c1 2020-08-18 18:51:21Z 64bit
127. a86f7630-ef31-4acb-bfa1-d2018a0cc34d Local SDP:
128. a86f7630-ef31-4acb-bfa1-d2018a0cc34d v=0
129. a86f7630-ef31-4acb-bfa1-d2018a0cc34d o=FreeSWITCH 1675068826 1675068827 IN IP4 192.168.65.20
130. a86f7630-ef31-4acb-bfa1-d2018a0cc34d s=FreeSWITCH
131. a86f7630-ef31-4acb-bfa1-d2018a0cc34d c=IN IP4 192.168.65.20
132. a86f7630-ef31-4acb-bfa1-d2018a0cc34d t=0 0
133. a86f7630-ef31-4acb-bfa1-d2018a0cc34d m=audio 26204 RTP/AVP 0 102 9 101 103
134. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=rtpmap:0 PCMU/8000
135. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=rtpmap:102 opus/48000/2
136. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
137. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=rtpmap:9 G722/8000
138. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=rtpmap:101 telephone-event/8000
139. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=fmtp:101 0-16
140. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=rtpmap:103 telephone-event/48000
141. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=fmtp:103 0-16
142. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=ptime:20
143. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=sendrecv
144. a86f7630-ef31-4acb-bfa1-d2018a0cc34d
145. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2107@192.168.65.94 Standard INIT
146. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2107@192.168.65.94) State Change CS_INIT -> CS_ROUTING
147. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2107@192.168.65.94) State INIT going to sleep
148. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
149. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_INIT (Cur 3 Tot 101)
150. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_ROUTING (Cur 3 Tot 101)
151. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:628 (sofia/external/19542352653) State INIT
152. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] mod_sofia.c:93 sofia/external/19542352653 SOFIA INIT
153. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2107@192.168.65.94) State ROUTING
154. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] mod_sofia.c:154 sofia/internal/2107@192.168.65.94 SOFIA ROUTING
155. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/2107@192.168.65.94) State Change CS_ROUTING -> CS_CONSUME_MEDIA
156. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2107@192.168.65.94) State ROUTING going to sleep
157. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 101)
158. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [calling][0]
159. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/2107@192.168.65.94) State CONSUME_MEDIA
160. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] sofia_glue.c:1618 sofia/external/19542352653 sending invite version: 1.10.5-release git 25569c1 2020-08-18 18:51:21Z 64bit
161. 6ca9d392-578d-42e1-8617-229c015e29f5 Local SDP:
162. 6ca9d392-578d-42e1-8617-229c015e29f5 v=0
163. 6ca9d392-578d-42e1-8617-229c015e29f5 o=FreeSWITCH 1675064688 1675064689 IN IP4 192.168.65.20
164. 6ca9d392-578d-42e1-8617-229c015e29f5 s=FreeSWITCH
165. 6ca9d392-578d-42e1-8617-229c015e29f5 c=IN IP4 192.168.65.20
166. 6ca9d392-578d-42e1-8617-229c015e29f5 t=0 0
167. 6ca9d392-578d-42e1-8617-229c015e29f5 m=audio 30342 RTP/AVP 0 9 101
168. 6ca9d392-578d-42e1-8617-229c015e29f5 a=rtpmap:0 PCMU/8000
169. 6ca9d392-578d-42e1-8617-229c015e29f5 a=rtpmap:9 G722/8000
170. 6ca9d392-578d-42e1-8617-229c015e29f5 a=rtpmap:101 telephone-event/8000
171. 6ca9d392-578d-42e1-8617-229c015e29f5 a=fmtp:101 0-16
172. 6ca9d392-578d-42e1-8617-229c015e29f5 a=ptime:20
173. 6ca9d392-578d-42e1-8617-229c015e29f5 a=sendrecv
174. 6ca9d392-578d-42e1-8617-229c015e29f5
175. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/2107@192.168.65.94) State CONSUME_MEDIA going to sleep
176. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:40 sofia/external/19542352653 Standard INIT
177. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:48 (sofia/external/19542352653) State Change CS_INIT -> CS_ROUTING
178. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:628 (sofia/external/19542352653) State INIT going to sleep
179. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_ROUTING (Cur 3 Tot 101)
180. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:644 (sofia/external/19542352653) State ROUTING
181. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] mod_sofia.c:154 sofia/external/19542352653 SOFIA ROUTING
182. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_ivr_originate.c:67 (sofia/external/19542352653) State Change CS_ROUTING -> CS_CONSUME_MEDIA
183. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:644 (sofia/external/19542352653) State ROUTING going to sleep
184. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 101)
185. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:663 (sofia/external/19542352653) State CONSUME_MEDIA
186. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] switch_core_state_machine.c:663 (sofia/external/19542352653) State CONSUME_MEDIA going to sleep
187. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.623782 [DEBUG] sofia.c:7326 Channel sofia/external/19542352653 entering state [calling][0]
188. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:8977 sofia/external/100@hpbx043.telecomsvc.com Set 2833 dtmf send payload to 101
189. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:8984 sofia/external/100@hpbx043.telecomsvc.com Set 2833 dtmf receive payload to 101
190. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:9007 sofia/external/100@hpbx043.telecomsvc.com Set rtp dtmf delay to 40
191. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/100@hpbx043.telecomsvc.com!
192. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_channel.c:3565 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change RINGING -> EARLY
193. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/external/100@hpbx043.telecomsvc.com.
194. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.623782 [DEBUG] mod_sofia.c:2593 Ring SDP:
195. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a v=0
196. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a o=FreeSWITCH 1675076568 1675076569 IN IP4 192.168.65.20
197. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a s=FreeSWITCH
198. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a c=IN IP4 192.168.65.20
199. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a t=0 0
200. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a m=audio 18462 RTP/AVP 9 101
201. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=rtpmap:9 G722/8000
202. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=rtpmap:101 telephone-event/8000
203. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=fmtp:101 0-16
204. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=ptime:20
205. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=sendrecv
206. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a
207. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.643781 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [early][183]
208. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:30.663728 [DEBUG] sofia.c:7326 Channel sofia/external/19542352653 entering state [calling][0]
209. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.703673 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [proceeding][180]
210. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.703673 [NOTICE] sofia.c:7437 Ring-Ready sofia/internal/2107@192.168.65.94!
211. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:30.703673 [DEBUG] switch_channel.c:3437 (sofia/internal/2107@192.168.65.94) Callstate Change DOWN -> RINGING
212. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:30.863783 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
213. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [completing][200]
214. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] sofia.c:7336 Remote SDP:
215. a86f7630-ef31-4acb-bfa1-d2018a0cc34d v=0
216. a86f7630-ef31-4acb-bfa1-d2018a0cc34d o=- 1675095033 1675095033 IN IP4 192.168.65.94
217. a86f7630-ef31-4acb-bfa1-d2018a0cc34d s=Polycom IP Phone
218. a86f7630-ef31-4acb-bfa1-d2018a0cc34d c=IN IP4 192.168.65.94
219. a86f7630-ef31-4acb-bfa1-d2018a0cc34d t=0 0
220. a86f7630-ef31-4acb-bfa1-d2018a0cc34d m=audio 2294 RTP/AVP 0 101
221. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=rtpmap:0 PCMU/8000
222. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=rtpmap:101 telephone-event/8000
223. a86f7630-ef31-4acb-bfa1-d2018a0cc34d
224. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [ready][200]
225. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
226. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
227. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
228. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
229. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
230. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:3839 Set Codec sofia/internal/2107@192.168.65.94 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
231. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_codec.c:111 sofia/internal/2107@192.168.65.94 Original read codec set to PCMU:0
232. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
233. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:5911 sofia/internal/2107@192.168.65.94 Set 2833 dtmf send payload to 101 recv payload to 101
234. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/internal/2107@192.168.65.94] 192.168.65.20 port 26204 -> 192.168.65.94 port 2294 codec: 0 ms: 20
235. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
236. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:8977 sofia/internal/2107@192.168.65.94 Set 2833 dtmf send payload to 101
237. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:8984 sofia/internal/2107@192.168.65.94 Set 2833 dtmf receive payload to 101
238. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_core_media.c:9007 sofia/internal/2107@192.168.65.94 Set rtp dtmf delay to 40
239. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [NOTICE] sofia.c:8504 Channel [sofia/internal/2107@192.168.65.94] has been answered
240. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.523676 [DEBUG] switch_channel.c:3865 (sofia/internal/2107@192.168.65.94) Callstate Change RINGING -> ACTIVE
241. 2023-01-30 11:10:33.523676 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/internal/2107@192.168.65.94]
242. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [NOTICE] switch_ivr_originate.c:3759 Hangup sofia/external/19542352653 [CS_CONSUME_MEDIA] [LOSE_RACE]
243. 2023-01-30 11:10:33.563675 [DEBUG] switch_ivr_originate.c:3984 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
244. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_HANGUP (Cur 3 Tot 101)
245. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:848 (sofia/external/19542352653) Callstate Change DOWN -> HANGUP
246. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:850 (sofia/external/19542352653) State HANGUP
247. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] mod_sofia.c:453 Channel sofia/external/19542352653 hanging up, cause: LOSE_RACE
248. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/external/19542352653
249. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:60 sofia/external/19542352653 Standard HANGUP, cause: LOSE_RACE
250. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:850 (sofia/external/19542352653) State HANGUP going to sleep
251. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:620 (sofia/external/19542352653) State Change CS_HANGUP -> CS_REPORTING
252. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_REPORTING (Cur 3 Tot 101)
253. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:936 (sofia/external/19542352653) State REPORTING
254. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:174 sofia/external/19542352653 Standard REPORTING, cause: LOSE_RACE
255. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:936 (sofia/external/19542352653) State REPORTING going to sleep
256. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:611 (sofia/external/19542352653) State Change CS_REPORTING -> CS_DESTROY
257. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_session.c:1726 Session 101 (sofia/external/19542352653) Locked, Waiting on external entities
258. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [NOTICE] switch_core_session.c:1744 Session 101 (sofia/external/19542352653) Ended
259. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/19542352653 [CS_DESTROY]
260. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:739 (sofia/external/19542352653) Running State Change CS_DESTROY (Cur 2 Tot 101)
261. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:749 (sofia/external/19542352653) State DESTROY
262. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] mod_sofia.c:364 sofia/external/19542352653 SOFIA DESTROY
263. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:181 sofia/external/19542352653 Standard DESTROY
264. 6ca9d392-578d-42e1-8617-229c015e29f5 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:749 (sofia/external/19542352653) State DESTROY going to sleep
265. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:33.563675 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/external/100@hpbx043.telecomsvc.com.
266. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:33.563675 [DEBUG] mod_sofia.c:898 Local SDP sofia/external/100@hpbx043.telecomsvc.com:
267. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a v=0
268. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a o=FreeSWITCH 1675076568 1675076570 IN IP4 192.168.65.20
269. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a s=FreeSWITCH
270. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a c=IN IP4 192.168.65.20
271. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a t=0 0
272. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a m=audio 18462 RTP/AVP 9 101
273. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=rtpmap:9 G722/8000
274. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=rtpmap:101 telephone-event/8000
275. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=fmtp:101 0-16
276. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=ptime:20
277. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a a=sendrecv
278. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a
279. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:33.563675 [NOTICE] switch_ivr_bridge.c:1670 Channel [sofia/external/100@hpbx043.telecomsvc.com] has been answered
280. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:33.563675 [DEBUG] switch_channel.c:3865 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change EARLY -> ACTIVE
281. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:33.563675 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [completed][200]
282. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.563675 [DEBUG] switch_ivr_bridge.c:1793 (sofia/internal/2107@192.168.65.94) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
283. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 101)
284. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.563675 [DEBUG] switch_core_state_machine.c:654 (sofia/internal/2107@192.168.65.94) State EXCHANGE_MEDIA
285. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.563675 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
286. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.603674 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
287. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:33.603674 [NOTICE] switch_core_media.c:15845 Activating write resampler
288. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:33.603674 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
289. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.603674 [NOTICE] switch_core_media.c:15845 Activating write resampler
290. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:10:33.623675 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [ready][200]
291. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [calling][0]
292. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [ready][200]
293. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] sofia.c:7333 Duplicate SDP
294. a86f7630-ef31-4acb-bfa1-d2018a0cc34d v=0
295. a86f7630-ef31-4acb-bfa1-d2018a0cc34d o=- 1675095033 1675095033 IN IP4 192.168.65.94
296. a86f7630-ef31-4acb-bfa1-d2018a0cc34d s=Polycom IP Phone
297. a86f7630-ef31-4acb-bfa1-d2018a0cc34d c=IN IP4 192.168.65.94
298. a86f7630-ef31-4acb-bfa1-d2018a0cc34d t=0 0
299. a86f7630-ef31-4acb-bfa1-d2018a0cc34d m=audio 2294 RTP/AVP 0 101
300. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=rtpmap:0 PCMU/8000
301. a86f7630-ef31-4acb-bfa1-d2018a0cc34d a=rtpmap:101 telephone-event/8000
302. a86f7630-ef31-4acb-bfa1-d2018a0cc34d
303. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
304. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
305. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
306. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
307. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
308. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
309. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] switch_core_media.c:5911 sofia/internal/2107@192.168.65.94 Set 2833 dtmf send payload to 101 recv payload to 101
310. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] sofia.c:8345 Processing updated SDP
311. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:10:33.663675 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/internal/2107@192.168.65.94.
312. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.043657 [NOTICE] sofia.c:1089 Hangup sofia/external/100@hpbx043.telecomsvc.com [CS_EXECUTE] [NORMAL_CLEARING]
313. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.043657 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/external/100@hpbx043.telecomsvc.com]
314. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_ivr_bridge.c:820 sofia/external/100@hpbx043.telecomsvc.com ending bridge by request from write function
315. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/internal/2107@192.168.65.94]
316. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/internal/2107@192.168.65.94 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
317. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:654 (sofia/internal/2107@192.168.65.94) State EXCHANGE_MEDIA going to sleep
318. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_HANGUP (Cur 2 Tot 101)
319. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/2107@192.168.65.94) Callstate Change ACTIVE -> HANGUP
320. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2107@192.168.65.94) State HANGUP
321. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] mod_sofia.c:447 sofia/internal/2107@192.168.65.94 Overriding SIP cause 480 with 200 from the other leg
322. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] mod_sofia.c:453 Channel sofia/internal/2107@192.168.65.94 hanging up, cause: NORMAL_CLEARING
323. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/2107@192.168.65.94
324. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2107@192.168.65.94 Standard HANGUP, cause: NORMAL_CLEARING
325. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2107@192.168.65.94) State HANGUP going to sleep
326. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/2107@192.168.65.94) State Change CS_HANGUP -> CS_REPORTING
327. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_REPORTING (Cur 2 Tot 101)
328. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2107@192.168.65.94) State REPORTING
329. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2107@192.168.65.94 Standard REPORTING, cause: NORMAL_CLEARING
330. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2107@192.168.65.94) State REPORTING going to sleep
331. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/2107@192.168.65.94) State Change CS_REPORTING -> CS_DESTROY
332. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_session.c:1726 Session 100 (sofia/internal/2107@192.168.65.94) Locked, Waiting on external entities
333. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_ivr_bridge.c:1895 sofia/external/100@hpbx043.telecomsvc.com skip receive message [UNBRIDGE] (channel is hungup already)
334. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [NOTICE] switch_core_session.c:1744 Session 100 (sofia/internal/2107@192.168.65.94) Ended
335. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/2107@192.168.65.94 [CS_DESTROY]
336. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/2107@192.168.65.94) Running State Change CS_DESTROY (Cur 1 Tot 101)
337. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_session.c:2905 sofia/external/100@hpbx043.telecomsvc.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
338. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/2107@192.168.65.94) State DESTROY
339. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] mod_sofia.c:364 sofia/internal/2107@192.168.65.94 SOFIA DESTROY
340. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:651 (sofia/external/100@hpbx043.telecomsvc.com) State EXECUTE going to sleep
341. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:181 sofia/internal/2107@192.168.65.94 Standard DESTROY
342. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_HANGUP (Cur 1 Tot 101)
343. a86f7630-ef31-4acb-bfa1-d2018a0cc34d 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/2107@192.168.65.94) State DESTROY going to sleep
344. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:848 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change ACTIVE -> HANGUP
345. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:850 (sofia/external/100@hpbx043.telecomsvc.com) State HANGUP
346. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] mod_sofia.c:453 Channel sofia/external/100@hpbx043.telecomsvc.com hanging up, cause: NORMAL_CLEARING
347. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:60 sofia/external/100@hpbx043.telecomsvc.com Standard HANGUP, cause: NORMAL_CLEARING
348. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:850 (sofia/external/100@hpbx043.telecomsvc.com) State HANGUP going to sleep
349. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:620 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_HANGUP -> CS_REPORTING
350. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_REPORTING (Cur 1 Tot 101)
351. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:936 (sofia/external/100@hpbx043.telecomsvc.com) State REPORTING
352. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:174 sofia/external/100@hpbx043.telecomsvc.com Standard REPORTING, cause: NORMAL_CLEARING
353. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:936 (sofia/external/100@hpbx043.telecomsvc.com) State REPORTING going to sleep
354. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:611 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_REPORTING -> CS_DESTROY
355. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_session.c:1726 Session 99 (sofia/external/100@hpbx043.telecomsvc.com) Locked, Waiting on external entities
356. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [NOTICE] switch_core_session.c:1744 Session 99 (sofia/external/100@hpbx043.telecomsvc.com) Ended
357. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/100@hpbx043.telecomsvc.com [CS_DESTROY]
358. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:739 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_DESTROY (Cur 0 Tot 101)
359. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:749 (sofia/external/100@hpbx043.telecomsvc.com) State DESTROY
360. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] mod_sofia.c:364 sofia/external/100@hpbx043.telecomsvc.com SOFIA DESTROY
361. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:181 sofia/external/100@hpbx043.telecomsvc.com Standard DESTROY
362. dd5f9059-8bfe-4b53-b762-d1c52ecdcb7a 2023-01-30 11:13:51.063658 [DEBUG] switch_core_state_machine.c:749 (sofia/external/100@hpbx043.telecomsvc.com) State DESTROY going to sleep
363. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [NOTICE] switch_channel.c:1118 New Channel sofia/external/100@hpbx043.telecomsvc.com [75685b3c-6d46-4a25-a1dd-79b325dddcc8]
364. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_NEW (Cur 1 Tot 102)
365. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] sofia.c:10280 sofia/external/100@hpbx043.telecomsvc.com receiving invite from 72.53.173.29:5060 version: 1.10.5-release git 25569c1 2020-08-18 18:51:21Z 64bit
366. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [received][100]
367. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] sofia.c:7336 Remote SDP:
368. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 v=0
369. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 o=- 1 1 IN IP4 72.53.173.29
370. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 s=SIP call
371. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 c=IN IP4 72.53.173.29
372. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 t=0 0
373. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=sendrecv
374. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 m=audio 36186 RTP/AVP 9 0 18 8 101
375. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=rtpmap:9 G722/8000
376. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=rtpmap:0 PCMU/8000
377. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=rtpmap:18 G729/8000
378. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=fmtp:18 annexb=no
379. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=rtpmap:8 PCMA/8000
380. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=rtpmap:101 telephone-event/8000
381. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=ptime:20
382. 75685b3c-6d46-4a25-a1dd-79b325dddcc8
383. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] sofia.c:7739 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_NEW -> CS_INIT
384. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:604 (sofia/external/100@hpbx043.telecomsvc.com) State NEW
385. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_INIT (Cur 1 Tot 102)
386. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:628 (sofia/external/100@hpbx043.telecomsvc.com) State INIT
387. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] mod_sofia.c:93 sofia/external/100@hpbx043.telecomsvc.com SOFIA INIT
388. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:40 sofia/external/100@hpbx043.telecomsvc.com Standard INIT
389. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:48 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_INIT -> CS_ROUTING
390. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:628 (sofia/external/100@hpbx043.telecomsvc.com) State INIT going to sleep
391. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_ROUTING (Cur 1 Tot 102)
392. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_channel.c:2332 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change DOWN -> RINGING
393. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:644 (sofia/external/100@hpbx043.telecomsvc.com) State ROUTING
394. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] mod_sofia.c:154 sofia/external/100@hpbx043.telecomsvc.com SOFIA ROUTING
395. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:236 sofia/external/100@hpbx043.telecomsvc.com Standard ROUTING
396. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [INFO] mod_dialplan_xml.c:637 Processing Jim Concannon <100>->5031 in context public
397. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->unloop] continue=false
398. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
399. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
400. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->outside_call] continue=true
401. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Absolute Condition [outside_call]
402. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(outside_call=true)
403. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
404. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action log(CRIT ***** naked in public from ${sip_from_user} to ${destination_number} ***** )
405. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action log(CRIT ***** caller_name = ${caller_id_name}; ICLID = ${caller_id_number} ***** )
406. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->from Consumer Celluloid] continue=true
407. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [from Consumer Celluloid] ${caller_id_number}(100) =~ /^(19542352653)$/ break=never
408. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->junk block] continue=false
409. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${sip_to_user}(65749684) =~ /obi_fjh/ break=never
410. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_name}(Jim Concannon) =~ /^V\d+/ break=on-true
411. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_name}(Jim Concannon) =~ /[Pp]olice[Oo]fficer\d+/ break=on-true
412. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(923008355903)$/ break=on-true
413. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(14156358962)$/ break=on-true
414. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(17542193980)$/ break=on-true
415. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(13012881384)$/ break=on-true
416. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(1408689\d{4})$/ break=on-true
417. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->callcentric_did] continue=false
418. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [callcentric_did] ${sip_to_user}(65749684) =~ /^(12023809008)$/ break=on-true
419. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->Jim Concannon] continue=false
420. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (PASS) [Jim Concannon] ${destination_number}(5031) =~ /^(5031)$/ break=never
421. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (PASS) [Jim Concannon] ${sip_from_user}(100) =~ /^(100)$/ break=on-true
422. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action log(CRIT ***** JIM CONCANNON EXT100 from ${sip_from_user} to ${destination_number} ***** )
423. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(ringback=${us-ring})
424. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(call_timeout=48)
425. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(hangup_after_bridge=true)
426. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(continue_on_fail=true)
427. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action bridge(${sofia_contact(user/2104)},${sofia_contact(user/2107)},${sofia_contact(user/2103)},${sofia_contact(user/2105)}:_:{sip_cid_type=rpid,ignore_early_media=true}sofia/gateway/callcentric/19542352653)
428. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:287 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_ROUTING -> CS_EXECUTE
429. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:644 (sofia/external/100@hpbx043.telecomsvc.com) State ROUTING going to sleep
430. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_EXECUTE (Cur 1 Tot 102)
431. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:651 (sofia/external/100@hpbx043.telecomsvc.com) State EXECUTE
432. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] mod_sofia.c:209 sofia/external/100@hpbx043.telecomsvc.com SOFIA EXECUTE
433. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:329 sofia/external/100@hpbx043.telecomsvc.com Standard EXECUTE
434. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(outside_call=true)
435. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [outside_call]=[true]
436. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com export(RFC2822_DATE=Mon, 30 Jan 2023 11:14:57 -0500)
437. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 30 Jan 2023 11:14:57 -0500]
438. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com log(CRIT ***** naked in public from 100 to 5031 ***** )
439. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [CRIT] mod_dptools.c:1866 ***** naked in public from 100 to 5031 *****
440. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com log(CRIT ***** caller_name = Jim Concannon; ICLID = 100 ***** )
441. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [CRIT] mod_dptools.c:1866 ***** caller_name = Jim Concannon; ICLID = 100 *****
442. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com log(CRIT ***** JIM CONCANNON EXT100 from 100 to 5031 ***** )
443. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [CRIT] mod_dptools.c:1866 ***** JIM CONCANNON EXT100 from 100 to 5031 *****
444. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(ringback=%(2000,4000,440,480))
445. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [ringback]=[%(2000,4000,440,480)]
446. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(call_timeout=48)
447. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [call_timeout]=[48]
448. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(hangup_after_bridge=true)
449. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [hangup_after_bridge]=[true]
450. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(continue_on_fail=true)
451. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [continue_on_fail]=[true]
452. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com bridge(error/user_not_registered,sofia/internal/sip:2107@192.168.65.94,error/user_not_registered,error/user_not_registered:_:{sip_cid_type=rpid,ignore_early_media=true}sofia/gateway/callcentric/19542352653)
453. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_channel.c:1264 sofia/external/100@hpbx043.telecomsvc.com EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 30 Jan 2023 11:14:57 -0500] to event
454. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_ivr_originate.c:1618 Parsing ultra-global variables
455. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [INFO] switch_ivr_originate.c:1684 Sending early media
456. 2023-01-30 11:14:57.623686 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
457. 2023-01-30 11:14:57.623686 [NOTICE] switch_ivr_originate.c:2999 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
458. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
459. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
460. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
461. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
462. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
463. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
464. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
465. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
466. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
467. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
468. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
469. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/2107@192.168.65.94 [590e91be-1fb9-4c34-a412-38e47f03c303]
470. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
471. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
472. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] mod_sofia.c:5089 (sofia/internal/2107@192.168.65.94) State Change CS_NEW -> CS_INIT
473. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
474. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
475. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/100@hpbx043.telecomsvc.com G722/8000 20 ms 160 samples 64000 bits 1 channels
476. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_codec.c:111 sofia/external/100@hpbx043.telecomsvc.com Original read codec set to G722:9
477. 2023-01-30 11:14:57.623686 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
478. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
479. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:5911 sofia/external/100@hpbx043.telecomsvc.com Set 2833 dtmf send payload to 101 recv payload to 101
480. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/100@hpbx043.telecomsvc.com] 192.168.65.20 port 22260 -> 72.53.173.29 port 36186 codec: 9 ms: 20
481. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [NOTICE] switch_channel.c:1118 New Channel sofia/external/19542352653 [b93b9181-3327-44c0-8ba3-b23da75ee807]
482. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] mod_sofia.c:5089 (sofia/external/19542352653) State Change CS_NEW -> CS_INIT
483. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_INIT (Cur 3 Tot 104)
484. 2023-01-30 11:14:57.623686 [NOTICE] switch_ivr_originate.c:2999 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
485. 2023-01-30 11:14:57.623686 [NOTICE] switch_ivr_originate.c:2999 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
486. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2107@192.168.65.94) State INIT
487. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] mod_sofia.c:93 sofia/internal/2107@192.168.65.94 SOFIA INIT
488. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
489. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_INIT (Cur 3 Tot 104)
490. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:628 (sofia/external/19542352653) State INIT
491. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] mod_sofia.c:93 sofia/external/19542352653 SOFIA INIT
492. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] sofia_glue.c:1618 sofia/internal/2107@192.168.65.94 sending invite version: 1.10.5-release git 25569c1 2020-08-18 18:51:21Z 64bit
493. 590e91be-1fb9-4c34-a412-38e47f03c303 Local SDP:
494. 590e91be-1fb9-4c34-a412-38e47f03c303 v=0
495. 590e91be-1fb9-4c34-a412-38e47f03c303 o=FreeSWITCH 1675074811 1675074812 IN IP4 192.168.65.20
496. 590e91be-1fb9-4c34-a412-38e47f03c303 s=FreeSWITCH
497. 590e91be-1fb9-4c34-a412-38e47f03c303 c=IN IP4 192.168.65.20
498. 590e91be-1fb9-4c34-a412-38e47f03c303 t=0 0
499. 590e91be-1fb9-4c34-a412-38e47f03c303 m=audio 20486 RTP/AVP 0 102 9 101 103
500. 590e91be-1fb9-4c34-a412-38e47f03c303 a=rtpmap:0 PCMU/8000
501. 590e91be-1fb9-4c34-a412-38e47f03c303 a=rtpmap:102 opus/48000/2
502. 590e91be-1fb9-4c34-a412-38e47f03c303 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
503. 590e91be-1fb9-4c34-a412-38e47f03c303 a=rtpmap:9 G722/8000
504. 590e91be-1fb9-4c34-a412-38e47f03c303 a=rtpmap:101 telephone-event/8000
505. 590e91be-1fb9-4c34-a412-38e47f03c303 a=fmtp:101 0-16
506. 590e91be-1fb9-4c34-a412-38e47f03c303 a=rtpmap:103 telephone-event/48000
507. 590e91be-1fb9-4c34-a412-38e47f03c303 a=fmtp:103 0-16
508. 590e91be-1fb9-4c34-a412-38e47f03c303 a=ptime:20
509. 590e91be-1fb9-4c34-a412-38e47f03c303 a=sendrecv
510. 590e91be-1fb9-4c34-a412-38e47f03c303
511. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2107@192.168.65.94 Standard INIT
512. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2107@192.168.65.94) State Change CS_INIT -> CS_ROUTING
513. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2107@192.168.65.94) State INIT going to sleep
514. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_ROUTING (Cur 3 Tot 104)
515. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2107@192.168.65.94) State ROUTING
516. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] mod_sofia.c:154 sofia/internal/2107@192.168.65.94 SOFIA ROUTING
517. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/2107@192.168.65.94) State Change CS_ROUTING -> CS_CONSUME_MEDIA
518. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2107@192.168.65.94) State ROUTING going to sleep
519. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 104)
520. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] sofia_glue.c:1618 sofia/external/19542352653 sending invite version: 1.10.5-release git 25569c1 2020-08-18 18:51:21Z 64bit
521. b93b9181-3327-44c0-8ba3-b23da75ee807 Local SDP:
522. b93b9181-3327-44c0-8ba3-b23da75ee807 v=0
523. b93b9181-3327-44c0-8ba3-b23da75ee807 o=FreeSWITCH 1675067845 1675067846 IN IP4 192.168.65.20
524. b93b9181-3327-44c0-8ba3-b23da75ee807 s=FreeSWITCH
525. b93b9181-3327-44c0-8ba3-b23da75ee807 c=IN IP4 192.168.65.20
526. b93b9181-3327-44c0-8ba3-b23da75ee807 t=0 0
527. b93b9181-3327-44c0-8ba3-b23da75ee807 m=audio 27452 RTP/AVP 0 9 101
528. b93b9181-3327-44c0-8ba3-b23da75ee807 a=rtpmap:0 PCMU/8000
529. b93b9181-3327-44c0-8ba3-b23da75ee807 a=rtpmap:9 G722/8000
530. b93b9181-3327-44c0-8ba3-b23da75ee807 a=rtpmap:101 telephone-event/8000
531. b93b9181-3327-44c0-8ba3-b23da75ee807 a=fmtp:101 0-16
532. b93b9181-3327-44c0-8ba3-b23da75ee807 a=ptime:20
533. b93b9181-3327-44c0-8ba3-b23da75ee807 a=sendrecv
534. b93b9181-3327-44c0-8ba3-b23da75ee807
535. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/2107@192.168.65.94) State CONSUME_MEDIA
536. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/2107@192.168.65.94) State CONSUME_MEDIA going to sleep
537. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:40 sofia/external/19542352653 Standard INIT
538. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:48 (sofia/external/19542352653) State Change CS_INIT -> CS_ROUTING
539. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:628 (sofia/external/19542352653) State INIT going to sleep
540. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.623686 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [calling][0]
541. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_ROUTING (Cur 3 Tot 104)
542. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:644 (sofia/external/19542352653) State ROUTING
543. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] mod_sofia.c:154 sofia/external/19542352653 SOFIA ROUTING
544. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_ivr_originate.c:67 (sofia/external/19542352653) State Change CS_ROUTING -> CS_CONSUME_MEDIA
545. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:644 (sofia/external/19542352653) State ROUTING going to sleep
546. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 104)
547. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] sofia.c:7326 Channel sofia/external/19542352653 entering state [calling][0]
548. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:663 (sofia/external/19542352653) State CONSUME_MEDIA
549. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.623686 [DEBUG] switch_core_state_machine.c:663 (sofia/external/19542352653) State CONSUME_MEDIA going to sleep
550. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:8977 sofia/external/100@hpbx043.telecomsvc.com Set 2833 dtmf send payload to 101
551. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:8984 sofia/external/100@hpbx043.telecomsvc.com Set 2833 dtmf receive payload to 101
552. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:9007 sofia/external/100@hpbx043.telecomsvc.com Set rtp dtmf delay to 40
553. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/100@hpbx043.telecomsvc.com!
554. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_channel.c:3565 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change RINGING -> EARLY
555. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/external/100@hpbx043.telecomsvc.com.
556. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.623686 [DEBUG] mod_sofia.c:2593 Ring SDP:
557. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 v=0
558. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 o=FreeSWITCH 1675073037 1675073038 IN IP4 192.168.65.20
559. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 s=FreeSWITCH
560. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 c=IN IP4 192.168.65.20
561. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 t=0 0
562. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 m=audio 22260 RTP/AVP 9 101
563. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=rtpmap:9 G722/8000
564. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=rtpmap:101 telephone-event/8000
565. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=fmtp:101 0-16
566. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=ptime:20
567. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=sendrecv
568. 75685b3c-6d46-4a25-a1dd-79b325dddcc8
569. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.643688 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [early][183]
570. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:57.663688 [DEBUG] sofia.c:7326 Channel sofia/external/19542352653 entering state [calling][0]
571. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.703688 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [proceeding][180]
572. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.703688 [NOTICE] sofia.c:7437 Ring-Ready sofia/internal/2107@192.168.65.94!
573. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:57.703688 [DEBUG] switch_channel.c:3437 (sofia/internal/2107@192.168.65.94) Callstate Change DOWN -> RINGING
574. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:57.943687 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
575. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [completing][200]
576. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] sofia.c:7336 Remote SDP:
577. 590e91be-1fb9-4c34-a412-38e47f03c303 v=0
578. 590e91be-1fb9-4c34-a412-38e47f03c303 o=- 1675095298 1675095298 IN IP4 192.168.65.94
579. 590e91be-1fb9-4c34-a412-38e47f03c303 s=Polycom IP Phone
580. 590e91be-1fb9-4c34-a412-38e47f03c303 c=IN IP4 192.168.65.94
581. 590e91be-1fb9-4c34-a412-38e47f03c303 t=0 0
582. 590e91be-1fb9-4c34-a412-38e47f03c303 m=audio 2298 RTP/AVP 0 101
583. 590e91be-1fb9-4c34-a412-38e47f03c303 a=rtpmap:0 PCMU/8000
584. 590e91be-1fb9-4c34-a412-38e47f03c303 a=rtpmap:101 telephone-event/8000
585. 590e91be-1fb9-4c34-a412-38e47f03c303
586. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [ready][200]
587. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
588. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
589. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
590. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
591. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
592. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:3839 Set Codec sofia/internal/2107@192.168.65.94 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
593. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_codec.c:111 sofia/internal/2107@192.168.65.94 Original read codec set to PCMU:0
594. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
595. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:5911 sofia/internal/2107@192.168.65.94 Set 2833 dtmf send payload to 101 recv payload to 101
596. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/internal/2107@192.168.65.94] 192.168.65.20 port 20486 -> 192.168.65.94 port 2298 codec: 0 ms: 20
597. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
598. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:8977 sofia/internal/2107@192.168.65.94 Set 2833 dtmf send payload to 101
599. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:8984 sofia/internal/2107@192.168.65.94 Set 2833 dtmf receive payload to 101
600. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_core_media.c:9007 sofia/internal/2107@192.168.65.94 Set rtp dtmf delay to 40
601. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [NOTICE] sofia.c:8504 Channel [sofia/internal/2107@192.168.65.94] has been answered
602. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.763689 [DEBUG] switch_channel.c:3865 (sofia/internal/2107@192.168.65.94) Callstate Change RINGING -> ACTIVE
603. 2023-01-30 11:14:58.763689 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/internal/2107@192.168.65.94]
604. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [NOTICE] switch_ivr_originate.c:3759 Hangup sofia/external/19542352653 [CS_CONSUME_MEDIA] [LOSE_RACE]
605. 2023-01-30 11:14:58.783687 [DEBUG] switch_ivr_originate.c:3984 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
606. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_HANGUP (Cur 3 Tot 104)
607. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:848 (sofia/external/19542352653) Callstate Change DOWN -> HANGUP
608. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:850 (sofia/external/19542352653) State HANGUP
609. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] mod_sofia.c:453 Channel sofia/external/19542352653 hanging up, cause: LOSE_RACE
610. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/external/19542352653
611. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:60 sofia/external/19542352653 Standard HANGUP, cause: LOSE_RACE
612. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:850 (sofia/external/19542352653) State HANGUP going to sleep
613. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:620 (sofia/external/19542352653) State Change CS_HANGUP -> CS_REPORTING
614. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_REPORTING (Cur 3 Tot 104)
615. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:936 (sofia/external/19542352653) State REPORTING
616. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:174 sofia/external/19542352653 Standard REPORTING, cause: LOSE_RACE
617. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:936 (sofia/external/19542352653) State REPORTING going to sleep
618. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:611 (sofia/external/19542352653) State Change CS_REPORTING -> CS_DESTROY
619. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_session.c:1726 Session 104 (sofia/external/19542352653) Locked, Waiting on external entities
620. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [NOTICE] switch_core_session.c:1744 Session 104 (sofia/external/19542352653) Ended
621. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/19542352653 [CS_DESTROY]
622. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:739 (sofia/external/19542352653) Running State Change CS_DESTROY (Cur 2 Tot 104)
623. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:749 (sofia/external/19542352653) State DESTROY
624. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] mod_sofia.c:364 sofia/external/19542352653 SOFIA DESTROY
625. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:181 sofia/external/19542352653 Standard DESTROY
626. b93b9181-3327-44c0-8ba3-b23da75ee807 2023-01-30 11:14:58.783687 [DEBUG] switch_core_state_machine.c:749 (sofia/external/19542352653) State DESTROY going to sleep
627. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:58.803664 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/external/100@hpbx043.telecomsvc.com.
628. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:58.803664 [DEBUG] mod_sofia.c:898 Local SDP sofia/external/100@hpbx043.telecomsvc.com:
629. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 v=0
630. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 o=FreeSWITCH 1675073037 1675073039 IN IP4 192.168.65.20
631. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 s=FreeSWITCH
632. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 c=IN IP4 192.168.65.20
633. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 t=0 0
634. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 m=audio 22260 RTP/AVP 9 101
635. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=rtpmap:9 G722/8000
636. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=rtpmap:101 telephone-event/8000
637. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=fmtp:101 0-16
638. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=ptime:20
639. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 a=sendrecv
640. 75685b3c-6d46-4a25-a1dd-79b325dddcc8
641. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:58.803664 [NOTICE] switch_ivr_bridge.c:1670 Channel [sofia/external/100@hpbx043.telecomsvc.com] has been answered
642. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:58.803664 [DEBUG] switch_channel.c:3865 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change EARLY -> ACTIVE
643. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:58.803664 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [completed][200]
644. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.803664 [DEBUG] switch_ivr_bridge.c:1793 (sofia/internal/2107@192.168.65.94) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
645. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.803664 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 104)
646. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.803664 [DEBUG] switch_core_state_machine.c:654 (sofia/internal/2107@192.168.65.94) State EXCHANGE_MEDIA
647. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.803664 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
648. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.843688 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
649. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:58.843688 [NOTICE] switch_core_media.c:15845 Activating write resampler
650. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:58.863688 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [ready][200]
651. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [calling][0]
652. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [ready][200]
653. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] sofia.c:7333 Duplicate SDP
654. 590e91be-1fb9-4c34-a412-38e47f03c303 v=0
655. 590e91be-1fb9-4c34-a412-38e47f03c303 o=- 1675095298 1675095298 IN IP4 192.168.65.94
656. 590e91be-1fb9-4c34-a412-38e47f03c303 s=Polycom IP Phone
657. 590e91be-1fb9-4c34-a412-38e47f03c303 c=IN IP4 192.168.65.94
658. 590e91be-1fb9-4c34-a412-38e47f03c303 t=0 0
659. 590e91be-1fb9-4c34-a412-38e47f03c303 m=audio 2298 RTP/AVP 0 101
660. 590e91be-1fb9-4c34-a412-38e47f03c303 a=rtpmap:0 PCMU/8000
661. 590e91be-1fb9-4c34-a412-38e47f03c303 a=rtpmap:101 telephone-event/8000
662. 590e91be-1fb9-4c34-a412-38e47f03c303
663. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
664. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
665. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
666. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
667. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
668. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
669. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] switch_core_media.c:5911 sofia/internal/2107@192.168.65.94 Set 2833 dtmf send payload to 101 recv payload to 101
670. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] sofia.c:8345 Processing updated SDP
671. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/internal/2107@192.168.65.94.
672. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:14:58.903687 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
673. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:14:58.903687 [NOTICE] switch_core_media.c:15845 Activating write resampler
674. 2023-01-30 11:40:33.763688 [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=299 [select nonce,last_nc from sip_authentication where nonce='add10586-9ced-4964-884c-304bad5b12ef' and last_nc < 283734]
675. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.043688 [NOTICE] sofia.c:1089 Hangup sofia/external/100@hpbx043.telecomsvc.com [CS_EXECUTE] [NORMAL_CLEARING]
676. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.043688 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/external/100@hpbx043.telecomsvc.com]
677. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_ivr_bridge.c:820 sofia/external/100@hpbx043.telecomsvc.com ending bridge by request from write function
678. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/internal/2107@192.168.65.94]
679. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/internal/2107@192.168.65.94 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
680. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:654 (sofia/internal/2107@192.168.65.94) State EXCHANGE_MEDIA going to sleep
681. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_HANGUP (Cur 2 Tot 104)
682. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_ivr_bridge.c:1892 sofia/internal/2107@192.168.65.94 skip receive message [UNBRIDGE] (channel is hungup already)
683. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_ivr_bridge.c:1895 sofia/external/100@hpbx043.telecomsvc.com skip receive message [UNBRIDGE] (channel is hungup already)
684. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/2107@192.168.65.94) Callstate Change ACTIVE -> HANGUP
685. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_session.c:2905 sofia/external/100@hpbx043.telecomsvc.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
686. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:651 (sofia/external/100@hpbx043.telecomsvc.com) State EXECUTE going to sleep
687. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_HANGUP (Cur 2 Tot 104)
688. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2107@192.168.65.94) State HANGUP
689. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] mod_sofia.c:447 sofia/internal/2107@192.168.65.94 Overriding SIP cause 480 with 200 from the other leg
690. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] mod_sofia.c:453 Channel sofia/internal/2107@192.168.65.94 hanging up, cause: NORMAL_CLEARING
691. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:848 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change ACTIVE -> HANGUP
692. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:850 (sofia/external/100@hpbx043.telecomsvc.com) State HANGUP
693. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] mod_sofia.c:453 Channel sofia/external/100@hpbx043.telecomsvc.com hanging up, cause: NORMAL_CLEARING
694. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:60 sofia/external/100@hpbx043.telecomsvc.com Standard HANGUP, cause: NORMAL_CLEARING
695. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:850 (sofia/external/100@hpbx043.telecomsvc.com) State HANGUP going to sleep
696. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:620 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_HANGUP -> CS_REPORTING
697. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_REPORTING (Cur 2 Tot 104)
698. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:936 (sofia/external/100@hpbx043.telecomsvc.com) State REPORTING
699. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:174 sofia/external/100@hpbx043.telecomsvc.com Standard REPORTING, cause: NORMAL_CLEARING
700. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:936 (sofia/external/100@hpbx043.telecomsvc.com) State REPORTING going to sleep
701. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:611 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_REPORTING -> CS_DESTROY
702. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_session.c:1726 Session 102 (sofia/external/100@hpbx043.telecomsvc.com) Locked, Waiting on external entities
703. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [NOTICE] switch_core_session.c:1744 Session 102 (sofia/external/100@hpbx043.telecomsvc.com) Ended
704. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/100@hpbx043.telecomsvc.com [CS_DESTROY]
705. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:739 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_DESTROY (Cur 1 Tot 104)
706. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:749 (sofia/external/100@hpbx043.telecomsvc.com) State DESTROY
707. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] mod_sofia.c:364 sofia/external/100@hpbx043.telecomsvc.com SOFIA DESTROY
708. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:181 sofia/external/100@hpbx043.telecomsvc.com Standard DESTROY
709. 75685b3c-6d46-4a25-a1dd-79b325dddcc8 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:749 (sofia/external/100@hpbx043.telecomsvc.com) State DESTROY going to sleep
710. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/2107@192.168.65.94
711. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2107@192.168.65.94 Standard HANGUP, cause: NORMAL_CLEARING
712. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2107@192.168.65.94) State HANGUP going to sleep
713. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/2107@192.168.65.94) State Change CS_HANGUP -> CS_REPORTING
714. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_REPORTING (Cur 1 Tot 104)
715. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2107@192.168.65.94) State REPORTING
716. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2107@192.168.65.94 Standard REPORTING, cause: NORMAL_CLEARING
717. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2107@192.168.65.94) State REPORTING going to sleep
718. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/2107@192.168.65.94) State Change CS_REPORTING -> CS_DESTROY
719. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_session.c:1726 Session 103 (sofia/internal/2107@192.168.65.94) Locked, Waiting on external entities
720. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [NOTICE] switch_core_session.c:1744 Session 103 (sofia/internal/2107@192.168.65.94) Ended
721. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/2107@192.168.65.94 [CS_DESTROY]
722. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/2107@192.168.65.94) Running State Change CS_DESTROY (Cur 0 Tot 104)
723. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/2107@192.168.65.94) State DESTROY
724. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] mod_sofia.c:364 sofia/internal/2107@192.168.65.94 SOFIA DESTROY
725. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:181 sofia/internal/2107@192.168.65.94 Standard DESTROY
726. 590e91be-1fb9-4c34-a412-38e47f03c303 2023-01-30 11:47:18.063698 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/2107@192.168.65.94) State DESTROY going to sleep
727. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [NOTICE] switch_channel.c:1118 New Channel sofia/external/100@hpbx043.telecomsvc.com [062ca2cd-d581-43d5-baf0-102d69b63cd7]
728. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_NEW (Cur 1 Tot 105)
729. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] sofia.c:10280 sofia/external/100@hpbx043.telecomsvc.com receiving invite from 72.53.173.29:5060 version: 1.10.5-release git 25569c1 2020-08-18 18:51:21Z 64bit
730. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [received][100]
731. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] sofia.c:7336 Remote SDP:
732. 062ca2cd-d581-43d5-baf0-102d69b63cd7 v=0
733. 062ca2cd-d581-43d5-baf0-102d69b63cd7 o=- 1 1 IN IP4 72.53.173.29
734. 062ca2cd-d581-43d5-baf0-102d69b63cd7 s=SIP call
735. 062ca2cd-d581-43d5-baf0-102d69b63cd7 c=IN IP4 72.53.173.29
736. 062ca2cd-d581-43d5-baf0-102d69b63cd7 t=0 0
737. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=sendrecv
738. 062ca2cd-d581-43d5-baf0-102d69b63cd7 m=audio 35950 RTP/AVP 9 0 18 8 101
739. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=rtpmap:9 G722/8000
740. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=rtpmap:0 PCMU/8000
741. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=rtpmap:18 G729/8000
742. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=fmtp:18 annexb=no
743. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=rtpmap:8 PCMA/8000
744. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=rtpmap:101 telephone-event/8000
745. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=ptime:20
746. 062ca2cd-d581-43d5-baf0-102d69b63cd7
747. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] sofia.c:7739 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_NEW -> CS_INIT
748. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:604 (sofia/external/100@hpbx043.telecomsvc.com) State NEW
749. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_INIT (Cur 1 Tot 105)
750. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:628 (sofia/external/100@hpbx043.telecomsvc.com) State INIT
751. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] mod_sofia.c:93 sofia/external/100@hpbx043.telecomsvc.com SOFIA INIT
752. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:40 sofia/external/100@hpbx043.telecomsvc.com Standard INIT
753. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:48 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_INIT -> CS_ROUTING
754. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:628 (sofia/external/100@hpbx043.telecomsvc.com) State INIT going to sleep
755. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_ROUTING (Cur 1 Tot 105)
756. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_channel.c:2332 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change DOWN -> RINGING
757. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:644 (sofia/external/100@hpbx043.telecomsvc.com) State ROUTING
758. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] mod_sofia.c:154 sofia/external/100@hpbx043.telecomsvc.com SOFIA ROUTING
759. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:236 sofia/external/100@hpbx043.telecomsvc.com Standard ROUTING
760. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [INFO] mod_dialplan_xml.c:637 Processing Jim Concannon <100>->5031 in context public
761. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->unloop] continue=false
762. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
763. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
764. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->outside_call] continue=true
765. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Absolute Condition [outside_call]
766. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(outside_call=true)
767. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
768. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action log(CRIT ***** naked in public from ${sip_from_user} to ${destination_number} ***** )
769. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action log(CRIT ***** caller_name = ${caller_id_name}; ICLID = ${caller_id_number} ***** )
770. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->from Consumer Celluloid] continue=true
771. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [from Consumer Celluloid] ${caller_id_number}(100) =~ /^(19542352653)$/ break=never
772. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->junk block] continue=false
773. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${sip_to_user}(65749684) =~ /obi_fjh/ break=never
774. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_name}(Jim Concannon) =~ /^V\d+/ break=on-true
775. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_name}(Jim Concannon) =~ /[Pp]olice[Oo]fficer\d+/ break=on-true
776. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(923008355903)$/ break=on-true
777. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(14156358962)$/ break=on-true
778. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(17542193980)$/ break=on-true
779. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(13012881384)$/ break=on-true
780. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [junk block] ${caller_id_number}(100) =~ /^(1408689\d{4})$/ break=on-true
781. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->callcentric_did] continue=false
782. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (FAIL) [callcentric_did] ${sip_to_user}(65749684) =~ /^(12023809008)$/ break=on-true
783. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com parsing [public->Jim Concannon] continue=false
784. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (PASS) [Jim Concannon] ${destination_number}(5031) =~ /^(5031)$/ break=never
785. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Regex (PASS) [Jim Concannon] ${sip_from_user}(100) =~ /^(100)$/ break=on-true
786. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action log(CRIT ***** JIM CONCANNON EXT100 from ${sip_from_user} to ${destination_number} ***** )
787. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(ringback=${us-ring})
788. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(call_timeout=48)
789. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(hangup_after_bridge=true)
790. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action set(continue_on_fail=true)
791. 062ca2cd-d581-43d5-baf0-102d69b63cd7 Dialplan: sofia/external/100@hpbx043.telecomsvc.com Action bridge(${sofia_contact(user/2104)},${sofia_contact(user/2107)},${sofia_contact(user/2103)},${sofia_contact(user/2105)}:_:{sip_cid_type=rpid,ignore_early_media=true}sofia/gateway/callcentric/19542352653)
792. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:287 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_ROUTING -> CS_EXECUTE
793. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:644 (sofia/external/100@hpbx043.telecomsvc.com) State ROUTING going to sleep
794. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_EXECUTE (Cur 1 Tot 105)
795. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:651 (sofia/external/100@hpbx043.telecomsvc.com) State EXECUTE
796. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] mod_sofia.c:209 sofia/external/100@hpbx043.telecomsvc.com SOFIA EXECUTE
797. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:329 sofia/external/100@hpbx043.telecomsvc.com Standard EXECUTE
798. 062ca2cd-d581-43d5-baf0-102d69b63cd7 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(outside_call=true)
799. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [outside_call]=[true]
800. 062ca2cd-d581-43d5-baf0-102d69b63cd7 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com export(RFC2822_DATE=Mon, 30 Jan 2023 16:07:15 -0500)
801. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 30 Jan 2023 16:07:15 -0500]
802. 062ca2cd-d581-43d5-baf0-102d69b63cd7 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com log(CRIT ***** naked in public from 100 to 5031 ***** )
803. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [CRIT] mod_dptools.c:1866 ***** naked in public from 100 to 5031 *****
804. 062ca2cd-d581-43d5-baf0-102d69b63cd7 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com log(CRIT ***** caller_name = Jim Concannon; ICLID = 100 ***** )
805. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [CRIT] mod_dptools.c:1866 ***** caller_name = Jim Concannon; ICLID = 100 *****
806. 062ca2cd-d581-43d5-baf0-102d69b63cd7 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com log(CRIT ***** JIM CONCANNON EXT100 from 100 to 5031 ***** )
807. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [CRIT] mod_dptools.c:1866 ***** JIM CONCANNON EXT100 from 100 to 5031 *****
808. 062ca2cd-d581-43d5-baf0-102d69b63cd7 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(ringback=%(2000,4000,440,480))
809. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [ringback]=[%(2000,4000,440,480)]
810. 062ca2cd-d581-43d5-baf0-102d69b63cd7 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(call_timeout=48)
811. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [call_timeout]=[48]
812. 062ca2cd-d581-43d5-baf0-102d69b63cd7 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(hangup_after_bridge=true)
813. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [hangup_after_bridge]=[true]
814. 062ca2cd-d581-43d5-baf0-102d69b63cd7 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com set(continue_on_fail=true)
815. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] mod_dptools.c:1672 SET sofia/external/100@hpbx043.telecomsvc.com [continue_on_fail]=[true]
816. 062ca2cd-d581-43d5-baf0-102d69b63cd7 EXECUTE [depth=0] sofia/external/100@hpbx043.telecomsvc.com bridge(error/user_not_registered,sofia/internal/sip:2107@192.168.65.94,error/user_not_registered,error/user_not_registered:_:{sip_cid_type=rpid,ignore_early_media=true}sofia/gateway/callcentric/19542352653)
817. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_channel.c:1264 sofia/external/100@hpbx043.telecomsvc.com EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 30 Jan 2023 16:07:15 -0500] to event
818. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_ivr_originate.c:1618 Parsing ultra-global variables
819. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [INFO] switch_ivr_originate.c:1684 Sending early media
820. 2023-01-30 16:07:15.223660 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
821. 2023-01-30 16:07:15.223660 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
822. 2023-01-30 16:07:15.223660 [NOTICE] switch_ivr_originate.c:2999 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
823. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
824. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
825. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
826. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
827. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
828. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
829. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
830. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
831. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
832. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
833. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
834. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
835. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/2107@192.168.65.94 [2026466d-1dfe-4183-8e19-25e4c3fc8898]
836. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
837. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
838. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
839. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] mod_sofia.c:5089 (sofia/internal/2107@192.168.65.94) State Change CS_NEW -> CS_INIT
840. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/100@hpbx043.telecomsvc.com G722/8000 20 ms 160 samples 64000 bits 1 channels
841. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_codec.c:111 sofia/external/100@hpbx043.telecomsvc.com Original read codec set to G722:9
842. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [NOTICE] switch_channel.c:1118 New Channel sofia/external/19542352653 [e5d72229-ce5d-4c7d-984f-7a3641541932]
843. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] mod_sofia.c:5089 (sofia/external/19542352653) State Change CS_NEW -> CS_INIT
844. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
845. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:5911 sofia/external/100@hpbx043.telecomsvc.com Set 2833 dtmf send payload to 101 recv payload to 101
846. 2023-01-30 16:07:15.223660 [NOTICE] switch_ivr_originate.c:2999 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
847. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/100@hpbx043.telecomsvc.com] 192.168.65.20 port 17144 -> 72.53.173.29 port 35950 codec: 9 ms: 20
848. 2023-01-30 16:07:15.223660 [NOTICE] switch_ivr_originate.c:2999 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
849. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_INIT (Cur 3 Tot 107)
850. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_INIT (Cur 3 Tot 107)
851. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:628 (sofia/external/19542352653) State INIT
852. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] mod_sofia.c:93 sofia/external/19542352653 SOFIA INIT
853. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2107@192.168.65.94) State INIT
854. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] mod_sofia.c:93 sofia/internal/2107@192.168.65.94 SOFIA INIT
855. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
856. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] sofia_glue.c:1618 sofia/internal/2107@192.168.65.94 sending invite version: 1.10.5-release git 25569c1 2020-08-18 18:51:21Z 64bit
857. 2026466d-1dfe-4183-8e19-25e4c3fc8898 Local SDP:
858. 2026466d-1dfe-4183-8e19-25e4c3fc8898 v=0
859. 2026466d-1dfe-4183-8e19-25e4c3fc8898 o=FreeSWITCH 1675090321 1675090322 IN IP4 192.168.65.20
860. 2026466d-1dfe-4183-8e19-25e4c3fc8898 s=FreeSWITCH
861. 2026466d-1dfe-4183-8e19-25e4c3fc8898 c=IN IP4 192.168.65.20
862. 2026466d-1dfe-4183-8e19-25e4c3fc8898 t=0 0
863. 2026466d-1dfe-4183-8e19-25e4c3fc8898 m=audio 22514 RTP/AVP 0 102 9 101 103
864. 2026466d-1dfe-4183-8e19-25e4c3fc8898 a=rtpmap:0 PCMU/8000
865. 2026466d-1dfe-4183-8e19-25e4c3fc8898 a=rtpmap:102 opus/48000/2
866. 2026466d-1dfe-4183-8e19-25e4c3fc8898 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
867. 2026466d-1dfe-4183-8e19-25e4c3fc8898 a=rtpmap:9 G722/8000
868. 2026466d-1dfe-4183-8e19-25e4c3fc8898 a=rtpmap:101 telephone-event/8000
869. 2026466d-1dfe-4183-8e19-25e4c3fc8898 a=fmtp:101 0-16
870. 2026466d-1dfe-4183-8e19-25e4c3fc8898 a=rtpmap:103 telephone-event/48000
871. 2026466d-1dfe-4183-8e19-25e4c3fc8898 a=fmtp:103 0-16
872. 2026466d-1dfe-4183-8e19-25e4c3fc8898 a=ptime:20
873. 2026466d-1dfe-4183-8e19-25e4c3fc8898 a=sendrecv
874. 2026466d-1dfe-4183-8e19-25e4c3fc8898
875. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2107@192.168.65.94 Standard INIT
876. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2107@192.168.65.94) State Change CS_INIT -> CS_ROUTING
877. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2107@192.168.65.94) State INIT going to sleep
878. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] sofia_glue.c:1618 sofia/external/19542352653 sending invite version: 1.10.5-release git 25569c1 2020-08-18 18:51:21Z 64bit
879. e5d72229-ce5d-4c7d-984f-7a3641541932 Local SDP:
880. e5d72229-ce5d-4c7d-984f-7a3641541932 v=0
881. e5d72229-ce5d-4c7d-984f-7a3641541932 o=FreeSWITCH 1675088047 1675088048 IN IP4 192.168.65.20
882. e5d72229-ce5d-4c7d-984f-7a3641541932 s=FreeSWITCH
883. e5d72229-ce5d-4c7d-984f-7a3641541932 c=IN IP4 192.168.65.20
884. e5d72229-ce5d-4c7d-984f-7a3641541932 t=0 0
885. e5d72229-ce5d-4c7d-984f-7a3641541932 m=audio 24788 RTP/AVP 0 9 101
886. e5d72229-ce5d-4c7d-984f-7a3641541932 a=rtpmap:0 PCMU/8000
887. e5d72229-ce5d-4c7d-984f-7a3641541932 a=rtpmap:9 G722/8000
888. e5d72229-ce5d-4c7d-984f-7a3641541932 a=rtpmap:101 telephone-event/8000
889. e5d72229-ce5d-4c7d-984f-7a3641541932 a=fmtp:101 0-16
890. e5d72229-ce5d-4c7d-984f-7a3641541932 a=ptime:20
891. e5d72229-ce5d-4c7d-984f-7a3641541932 a=sendrecv
892. e5d72229-ce5d-4c7d-984f-7a3641541932
893. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_ROUTING (Cur 3 Tot 107)
894. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2107@192.168.65.94) State ROUTING
895. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] mod_sofia.c:154 sofia/internal/2107@192.168.65.94 SOFIA ROUTING
896. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/2107@192.168.65.94) State Change CS_ROUTING -> CS_CONSUME_MEDIA
897. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2107@192.168.65.94) State ROUTING going to sleep
898. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 107)
899. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:40 sofia/external/19542352653 Standard INIT
900. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:48 (sofia/external/19542352653) State Change CS_INIT -> CS_ROUTING
901. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:628 (sofia/external/19542352653) State INIT going to sleep
902. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/2107@192.168.65.94) State CONSUME_MEDIA
903. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/2107@192.168.65.94) State CONSUME_MEDIA going to sleep
904. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.223660 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [calling][0]
905. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_ROUTING (Cur 3 Tot 107)
906. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] sofia.c:7326 Channel sofia/external/19542352653 entering state [calling][0]
907. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:644 (sofia/external/19542352653) State ROUTING
908. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] mod_sofia.c:154 sofia/external/19542352653 SOFIA ROUTING
909. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_ivr_originate.c:67 (sofia/external/19542352653) State Change CS_ROUTING -> CS_CONSUME_MEDIA
910. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:644 (sofia/external/19542352653) State ROUTING going to sleep
911. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 107)
912. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:663 (sofia/external/19542352653) State CONSUME_MEDIA
913. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.223660 [DEBUG] switch_core_state_machine.c:663 (sofia/external/19542352653) State CONSUME_MEDIA going to sleep
914. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:8977 sofia/external/100@hpbx043.telecomsvc.com Set 2833 dtmf send payload to 101
915. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:8984 sofia/external/100@hpbx043.telecomsvc.com Set 2833 dtmf receive payload to 101
916. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:9007 sofia/external/100@hpbx043.telecomsvc.com Set rtp dtmf delay to 40
917. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/100@hpbx043.telecomsvc.com!
918. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_channel.c:3565 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change RINGING -> EARLY
919. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/external/100@hpbx043.telecomsvc.com.
920. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.223660 [DEBUG] mod_sofia.c:2593 Ring SDP:
921. 062ca2cd-d581-43d5-baf0-102d69b63cd7 v=0
922. 062ca2cd-d581-43d5-baf0-102d69b63cd7 o=FreeSWITCH 1675095691 1675095692 IN IP4 192.168.65.20
923. 062ca2cd-d581-43d5-baf0-102d69b63cd7 s=FreeSWITCH
924. 062ca2cd-d581-43d5-baf0-102d69b63cd7 c=IN IP4 192.168.65.20
925. 062ca2cd-d581-43d5-baf0-102d69b63cd7 t=0 0
926. 062ca2cd-d581-43d5-baf0-102d69b63cd7 m=audio 17144 RTP/AVP 9 101
927. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=rtpmap:9 G722/8000
928. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=rtpmap:101 telephone-event/8000
929. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=fmtp:101 0-16
930. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=ptime:20
931. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=sendrecv
932. 062ca2cd-d581-43d5-baf0-102d69b63cd7
933. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.243658 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [early][183]
934. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:15.303659 [DEBUG] sofia.c:7326 Channel sofia/external/19542352653 entering state [calling][0]
935. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.303659 [DEBUG] sofia.c:7326 Channel sofia/internal/2107@192.168.65.94 entering state [proceeding][180]
936. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.303659 [NOTICE] sofia.c:7437 Ring-Ready sofia/internal/2107@192.168.65.94!
937. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:15.303659 [DEBUG] switch_channel.c:3437 (sofia/internal/2107@192.168.65.94) Callstate Change DOWN -> RINGING
938. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:15.483661 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
939. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] sofia.c:7326 Channel sofia/external/19542352653 entering state [proceeding][183]
940. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] sofia.c:7336 Remote SDP:
941. e5d72229-ce5d-4c7d-984f-7a3641541932 v=0
942. e5d72229-ce5d-4c7d-984f-7a3641541932 o=CCSBC 1675081024 1675081025 IN IP4 199.87.144.67
943. e5d72229-ce5d-4c7d-984f-7a3641541932 s=CCSBC
944. e5d72229-ce5d-4c7d-984f-7a3641541932 c=IN IP4 199.87.144.67
945. e5d72229-ce5d-4c7d-984f-7a3641541932 t=0 0
946. e5d72229-ce5d-4c7d-984f-7a3641541932 m=audio 53774 RTP/AVP 0 101
947. e5d72229-ce5d-4c7d-984f-7a3641541932 a=rtpmap:0 PCMU/8000
948. e5d72229-ce5d-4c7d-984f-7a3641541932 a=rtpmap:101 telephone-event/8000
949. e5d72229-ce5d-4c7d-984f-7a3641541932 a=fmtp:101 0-15
950. e5d72229-ce5d-4c7d-984f-7a3641541932 a=ptime:20
951. e5d72229-ce5d-4c7d-984f-7a3641541932 a=silenceSupp:off - - - -
952. e5d72229-ce5d-4c7d-984f-7a3641541932 a=setup:actpass
953. e5d72229-ce5d-4c7d-984f-7a3641541932
954. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [NOTICE] sofia.c:7339 Pre-Answer sofia/external/19542352653!
955. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_channel.c:3565 (sofia/external/19542352653) Callstate Change DOWN -> EARLY
956. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
957. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
958. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
959. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
960. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/19542352653 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
961. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_codec.c:111 sofia/external/19542352653 Original read codec set to PCMU:0
962. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
963. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:5911 sofia/external/19542352653 Set 2833 dtmf send payload to 101 recv payload to 101
964. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/19542352653] 192.168.65.20 port 24788 -> 199.87.144.67 port 53774 codec: 0 ms: 20
965. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
966. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:8977 sofia/external/19542352653 Set 2833 dtmf send payload to 101
967. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:8984 sofia/external/19542352653 Set 2833 dtmf receive payload to 101
968. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:16.823661 [DEBUG] switch_core_media.c:9007 sofia/external/19542352653 Set rtp dtmf delay to 40
969. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:17.323657 [DEBUG] sofia.c:7326 Channel sofia/external/19542352653 entering state [proceeding][183]
970. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:17.323657 [DEBUG] sofia.c:7333 Duplicate SDP
971. e5d72229-ce5d-4c7d-984f-7a3641541932 v=0
972. e5d72229-ce5d-4c7d-984f-7a3641541932 o=CCSBC 1675081024 1675081025 IN IP4 199.87.144.67
973. e5d72229-ce5d-4c7d-984f-7a3641541932 s=CCSBC
974. e5d72229-ce5d-4c7d-984f-7a3641541932 c=IN IP4 199.87.144.67
975. e5d72229-ce5d-4c7d-984f-7a3641541932 t=0 0
976. e5d72229-ce5d-4c7d-984f-7a3641541932 m=audio 53774 RTP/AVP 0 101
977. e5d72229-ce5d-4c7d-984f-7a3641541932 a=rtpmap:0 PCMU/8000
978. e5d72229-ce5d-4c7d-984f-7a3641541932 a=rtpmap:101 telephone-event/8000
979. e5d72229-ce5d-4c7d-984f-7a3641541932 a=fmtp:101 0-15
980. e5d72229-ce5d-4c7d-984f-7a3641541932 a=ptime:20
981. e5d72229-ce5d-4c7d-984f-7a3641541932 a=silenceSupp:off - - - -
982. e5d72229-ce5d-4c7d-984f-7a3641541932 a=setup:actpass
983. e5d72229-ce5d-4c7d-984f-7a3641541932
984. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:17.323657 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
985. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:17.323657 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
986. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:17.323657 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
987. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:17.323657 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
988. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:17.323657 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
989. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:17.323657 [DEBUG] switch_core_media.c:5911 sofia/external/19542352653 Set 2833 dtmf send payload to 101 recv payload to 101
990. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:17.323657 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/external/19542352653.
991. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.003656 [DEBUG] sofia.c:7326 Channel sofia/external/19542352653 entering state [completing][200]
992. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.003656 [DEBUG] sofia.c:7333 Duplicate SDP
993. e5d72229-ce5d-4c7d-984f-7a3641541932 v=0
994. e5d72229-ce5d-4c7d-984f-7a3641541932 o=CCSBC 1675081024 1675081025 IN IP4 199.87.144.67
995. e5d72229-ce5d-4c7d-984f-7a3641541932 s=CCSBC
996. e5d72229-ce5d-4c7d-984f-7a3641541932 c=IN IP4 199.87.144.67
997. e5d72229-ce5d-4c7d-984f-7a3641541932 t=0 0
998. e5d72229-ce5d-4c7d-984f-7a3641541932 m=audio 53774 RTP/AVP 0 101
999. e5d72229-ce5d-4c7d-984f-7a3641541932 a=rtpmap:0 PCMU/8000
1000. e5d72229-ce5d-4c7d-984f-7a3641541932 a=rtpmap:101 telephone-event/8000
1001. e5d72229-ce5d-4c7d-984f-7a3641541932 a=fmtp:101 0-15
1002. e5d72229-ce5d-4c7d-984f-7a3641541932 a=ptime:20
1003. e5d72229-ce5d-4c7d-984f-7a3641541932 a=silenceSupp:off - - - -
1004. e5d72229-ce5d-4c7d-984f-7a3641541932 a=setup:actpass
1005. e5d72229-ce5d-4c7d-984f-7a3641541932
1006. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.003656 [DEBUG] sofia.c:7326 Channel sofia/external/19542352653 entering state [ready][200]
1007. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.003656 [NOTICE] sofia.c:8445 Channel [sofia/external/19542352653] has been answered
1008. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.003656 [DEBUG] switch_channel.c:3865 (sofia/external/19542352653) Callstate Change EARLY -> ACTIVE
1009. 2023-01-30 16:07:18.003656 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/external/19542352653]
1010. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [NOTICE] switch_ivr_originate.c:3759 Hangup sofia/internal/2107@192.168.65.94 [CS_CONSUME_MEDIA] [LOSE_RACE]
1011. 2023-01-30 16:07:18.023657 [DEBUG] switch_ivr_originate.c:3984 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]
1012. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_HANGUP (Cur 3 Tot 107)
1013. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/2107@192.168.65.94) Callstate Change RINGING -> HANGUP
1014. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2107@192.168.65.94) State HANGUP
1015. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] mod_sofia.c:453 Channel sofia/internal/2107@192.168.65.94 hanging up, cause: LOSE_RACE
1016. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/internal/2107@192.168.65.94
1017. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2107@192.168.65.94 Standard HANGUP, cause: LOSE_RACE
1018. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2107@192.168.65.94) State HANGUP going to sleep
1019. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/2107@192.168.65.94) State Change CS_HANGUP -> CS_REPORTING
1020. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2107@192.168.65.94) Running State Change CS_REPORTING (Cur 3 Tot 107)
1021. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2107@192.168.65.94) State REPORTING
1022. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2107@192.168.65.94 Standard REPORTING, cause: LOSE_RACE
1023. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2107@192.168.65.94) State REPORTING going to sleep
1024. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/2107@192.168.65.94) State Change CS_REPORTING -> CS_DESTROY
1025. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_session.c:1726 Session 106 (sofia/internal/2107@192.168.65.94) Locked, Waiting on external entities
1026. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [NOTICE] switch_core_session.c:1744 Session 106 (sofia/internal/2107@192.168.65.94) Ended
1027. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/2107@192.168.65.94 [CS_DESTROY]
1028. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/2107@192.168.65.94) Running State Change CS_DESTROY (Cur 2 Tot 107)
1029. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/2107@192.168.65.94) State DESTROY
1030. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] mod_sofia.c:364 sofia/internal/2107@192.168.65.94 SOFIA DESTROY
1031. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:181 sofia/internal/2107@192.168.65.94 Standard DESTROY
1032. 2026466d-1dfe-4183-8e19-25e4c3fc8898 2023-01-30 16:07:18.023657 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/2107@192.168.65.94) State DESTROY going to sleep
1033. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:18.043657 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for sofia/external/100@hpbx043.telecomsvc.com.
1034. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:18.043657 [DEBUG] mod_sofia.c:898 Local SDP sofia/external/100@hpbx043.telecomsvc.com:
1035. 062ca2cd-d581-43d5-baf0-102d69b63cd7 v=0
1036. 062ca2cd-d581-43d5-baf0-102d69b63cd7 o=FreeSWITCH 1675095691 1675095693 IN IP4 192.168.65.20
1037. 062ca2cd-d581-43d5-baf0-102d69b63cd7 s=FreeSWITCH
1038. 062ca2cd-d581-43d5-baf0-102d69b63cd7 c=IN IP4 192.168.65.20
1039. 062ca2cd-d581-43d5-baf0-102d69b63cd7 t=0 0
1040. 062ca2cd-d581-43d5-baf0-102d69b63cd7 m=audio 17144 RTP/AVP 9 101
1041. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=rtpmap:9 G722/8000
1042. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=rtpmap:101 telephone-event/8000
1043. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=fmtp:101 0-16
1044. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=ptime:20
1045. 062ca2cd-d581-43d5-baf0-102d69b63cd7 a=sendrecv
1046. 062ca2cd-d581-43d5-baf0-102d69b63cd7
1047. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:18.043657 [NOTICE] switch_ivr_bridge.c:1670 Channel [sofia/external/100@hpbx043.telecomsvc.com] has been answered
1048. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:18.043657 [DEBUG] switch_channel.c:3865 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change EARLY -> ACTIVE
1049. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:18.043657 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [completed][200]
1050. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.043657 [DEBUG] switch_ivr_bridge.c:1793 (sofia/external/19542352653) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
1051. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.043657 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 107)
1052. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.043657 [DEBUG] switch_core_state_machine.c:654 (sofia/external/19542352653) State EXCHANGE_MEDIA
1053. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.043657 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
1054. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.063657 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
1055. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:18.063657 [NOTICE] switch_core_media.c:15845 Activating write resampler
1056. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:18.083657 [DEBUG] sofia.c:7326 Channel sofia/external/100@hpbx043.telecomsvc.com entering state [ready][200]
1057. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:18.103656 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
1058. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:18.103656 [NOTICE] switch_core_media.c:15845 Activating write resampler
1059. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [NOTICE] sofia.c:1089 Hangup sofia/external/19542352653 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
1060. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/external/19542352653]
1061. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:654 (sofia/external/19542352653) State EXCHANGE_MEDIA going to sleep
1062. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_HANGUP (Cur 2 Tot 107)
1063. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:848 (sofia/external/19542352653) Callstate Change ACTIVE -> HANGUP
1064. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:850 (sofia/external/19542352653) State HANGUP
1065. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] mod_sofia.c:453 Channel sofia/external/19542352653 hanging up, cause: NORMAL_CLEARING
1066. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:60 sofia/external/19542352653 Standard HANGUP, cause: NORMAL_CLEARING
1067. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:850 (sofia/external/19542352653) State HANGUP going to sleep
1068. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:620 (sofia/external/19542352653) State Change CS_HANGUP -> CS_REPORTING
1069. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:585 (sofia/external/19542352653) Running State Change CS_REPORTING (Cur 2 Tot 107)
1070. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:936 (sofia/external/19542352653) State REPORTING
1071. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:174 sofia/external/19542352653 Standard REPORTING, cause: NORMAL_CLEARING
1072. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:936 (sofia/external/19542352653) State REPORTING going to sleep
1073. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:611 (sofia/external/19542352653) State Change CS_REPORTING -> CS_DESTROY
1074. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_session.c:1726 Session 107 (sofia/external/19542352653) Locked, Waiting on external entities
1075. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_ivr_bridge.c:820 sofia/external/19542352653 ending bridge by request from write function
1076. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/external/100@hpbx043.telecomsvc.com]
1077. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [NOTICE] switch_ivr_bridge.c:1944 Hangup sofia/external/100@hpbx043.telecomsvc.com [CS_EXECUTE] [NORMAL_CLEARING]
1078. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [NOTICE] switch_core_session.c:1744 Session 107 (sofia/external/19542352653) Ended
1079. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/19542352653 [CS_DESTROY]
1080. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_session.c:2905 sofia/external/100@hpbx043.telecomsvc.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
1081. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:651 (sofia/external/100@hpbx043.telecomsvc.com) State EXECUTE going to sleep
1082. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_HANGUP (Cur 1 Tot 107)
1083. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:739 (sofia/external/19542352653) Running State Change CS_DESTROY (Cur 1 Tot 107)
1084. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:749 (sofia/external/19542352653) State DESTROY
1085. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] mod_sofia.c:364 sofia/external/19542352653 SOFIA DESTROY
1086. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:848 (sofia/external/100@hpbx043.telecomsvc.com) Callstate Change ACTIVE -> HANGUP
1087. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:181 sofia/external/19542352653 Standard DESTROY
1088. e5d72229-ce5d-4c7d-984f-7a3641541932 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:749 (sofia/external/19542352653) State DESTROY going to sleep
1089. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:850 (sofia/external/100@hpbx043.telecomsvc.com) State HANGUP
1090. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] mod_sofia.c:447 sofia/external/100@hpbx043.telecomsvc.com Overriding SIP cause 480 with 200 from the other leg
1091. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] mod_sofia.c:453 Channel sofia/external/100@hpbx043.telecomsvc.com hanging up, cause: NORMAL_CLEARING
1092. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/external/100@hpbx043.telecomsvc.com
1093. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:60 sofia/external/100@hpbx043.telecomsvc.com Standard HANGUP, cause: NORMAL_CLEARING
1094. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:850 (sofia/external/100@hpbx043.telecomsvc.com) State HANGUP going to sleep
1095. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:620 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_HANGUP -> CS_REPORTING
1096. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:585 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_REPORTING (Cur 1 Tot 107)
1097. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:936 (sofia/external/100@hpbx043.telecomsvc.com) State REPORTING
1098. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:174 sofia/external/100@hpbx043.telecomsvc.com Standard REPORTING, cause: NORMAL_CLEARING
1099. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:936 (sofia/external/100@hpbx043.telecomsvc.com) State REPORTING going to sleep
1100. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:611 (sofia/external/100@hpbx043.telecomsvc.com) State Change CS_REPORTING -> CS_DESTROY
1101. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_session.c:1726 Session 105 (sofia/external/100@hpbx043.telecomsvc.com) Locked, Waiting on external entities
1102. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [NOTICE] switch_core_session.c:1744 Session 105 (sofia/external/100@hpbx043.telecomsvc.com) Ended
1103. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/100@hpbx043.telecomsvc.com [CS_DESTROY]
1104. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:739 (sofia/external/100@hpbx043.telecomsvc.com) Running State Change CS_DESTROY (Cur 0 Tot 107)
1105. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:749 (sofia/external/100@hpbx043.telecomsvc.com) State DESTROY
1106. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] mod_sofia.c:364 sofia/external/100@hpbx043.telecomsvc.com SOFIA DESTROY
1107. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:181 sofia/external/100@hpbx043.telecomsvc.com Standard DESTROY
1108. 062ca2cd-d581-43d5-baf0-102d69b63cd7 2023-01-30 16:07:26.303661 [DEBUG] switch_core_state_machine.c:749 (sofia/external/100@hpbx043.telecomsvc.com) State DESTROY going to sleep
1109.