From Mungo Pintail, 1 Year ago, written in FreeSWITCH.
- go back
Embed
Viewing differences between and Untitled
  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.