15:36:37.744689 [NOTICE] switch_channel.c:1118 New Channel
sofia/internal/22198@10.18.203.9:5060 [a56b3ec6-1a0d-469e-83da-73e46420fede]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.744689 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_NEW (Cur 1 Tot 11)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.744689 [DEBUG] sofia.c:10280
sofia/internal/22198@10.18.203.9:5060 receiving invite from 10.18.203.36:5060 version: 1.10.5 64bit
2020-08-31 15:36:37.744689 [DEBUG] sofia.c:10374 verifying acl "domains" for ip/port 10.18.203.36:0.
2020-08-31 15:36:37.754689 [DEBUG] sofia.c:2434 detaching session a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.754689 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/22198@10.18.203.9:5060) State NEW
2020-08-31 15:36:37.764690 [DEBUG] sofia.c:2544 Re-attaching to session a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.774691 [DEBUG] sofia.c:10280
sofia/internal/22198@10.18.203.9:5060 receiving invite from 10.18.203.36:5060 version: 1.10.5 64bit
2020-08-31 15:36:37.774691 [DEBUG] sofia.c:10374 verifying acl "domains" for ip/port 10.18.203.36:0.
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] sofia.c:7326 Channel
sofia/internal/22198@10.18.203.9:5060 entering state [received][100]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] sofia.c:7336 Remote SDP:
a56b3ec6-1a0d-469e-83da-73e46420fede v=0
a56b3ec6-1a0d-469e-83da-73e46420fede o=- 20151 20151 IN IP4 10.18.203.36
a56b3ec6-1a0d-469e-83da-73e46420fede s=SDP data
a56b3ec6-1a0d-469e-83da-73e46420fede c=IN IP4 10.18.203.36
a56b3ec6-1a0d-469e-83da-73e46420fede t=0 0
a56b3ec6-1a0d-469e-83da-73e46420fede m=audio 12082 RTP/AVP 9 0 8 18 101
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:9 G722/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:0 PCMU/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:8 PCMA/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:18 G729/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=fmtp:18 annexb=no
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:101 telephone-event/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=fmtp:101 0-15
a56b3ec6-1a0d-469e-83da-73e46420fede a=ptime:20
a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] sofia.c:7739 (sofia/internal/22198@10.18.203.9:5060) State Change CS_NEW -> CS_INIT
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_INIT (Cur 1 Tot 11)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/22198@10.18.203.9:5060) State INIT
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_sofia.c:93
sofia/internal/22198@10.18.203.9:5060 SOFIA INIT
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:40
sofia/internal/22198@10.18.203.9:5060 Standard INIT
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/22198@10.18.203.9:5060) State Change CS_INIT -> CS_ROUTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/22198@10.18.203.9:5060) State INIT going to sleep
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_ROUTING (Cur 1 Tot 11)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_channel.c:2332 (sofia/internal/22198@10.18.203.9:5060) Callstate Change DOWN -> RINGING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/22198@10.18.203.9:5060) State ROUTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_sofia.c:154
sofia/internal/22198@10.18.203.9:5060 SOFIA ROUTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:236
sofia/internal/22198@10.18.203.9:5060 Standard ROUTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [INFO] mod_dialplan_xml.c:637 Processing ТЕСТ <22198>->22202 in context office1
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 parsing [office1->unloop] continue=false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [unloop] true() =~ /^true$/ break=on-false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 parsing [office1->in-22198] continue=false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [in-22198] destination_number(22202) =~ /^(22198)$/ break=on-false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 parsing [office1->out-22198] continue=false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Regex (FAIL) [out-22198] destination_number(22202) =~ /^(8\d{9,14})$/ break=on-false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 parsing [office1->gsv-22198] continue=false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Regex (PASS) [gsv-22198] destination_number(22202) =~ /^(2\d{4})$/ break=on-false
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_TITLE=Recording ${destination_number} ${caller_id_number} ${strftime(%Y-%m-%d %H:%M)})
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_COPYRIGHT=(c) 2012)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_SOFTWARE=FreeSwitch)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_ARTIST=FreeSwitch)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_COMMENT=Voice recordings)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_DATE=${strftime(%Y-%m-%d %H:%M)})
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action set(RECORD_STEREO=true)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action set(media_bug_answer_req=true)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action set(ringback=${us-ring})
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action bridge(sofia/gateway/TR22198/22202)
a56b3ec6-1a0d-469e-83da-73e46420fede Dialplan:
sofia/internal/22198@10.18.203.9:5060 Action hangup()
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/22198@10.18.203.9:5060) State Change CS_ROUTING -> CS_EXECUTE
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/22198@10.18.203.9:5060) State ROUTING going to sleep
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_EXECUTE (Cur 1 Tot 11)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/22198@10.18.203.9:5060) State EXECUTE
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_sofia.c:209
sofia/internal/22198@10.18.203.9:5060 SOFIA EXECUTE
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] switch_core_state_machine.c:329
sofia/internal/22198@10.18.203.9:5060 Standard EXECUTE
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0]
sofia/internal/22198@10.18.203.9:5060 set(RECORD_TITLE=Recording 22202 22198 2020-08-31 15:36)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_dptools.c:1672 SET
sofia/internal/22198@10.18.203.9:5060 [RECORD_TITLE]=[Recording 22202 22198 2020-08-31 15:36]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0]
sofia/internal/22198@10.18.203.9:5060 set(RECORD_COPYRIGHT=(c) 2012)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_dptools.c:1672 SET
sofia/internal/22198@10.18.203.9:5060 [RECORD_COPYRIGHT]=[(c) 2012]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0]
sofia/internal/22198@10.18.203.9:5060 set(RECORD_SOFTWARE=FreeSwitch)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_dptools.c:1672 SET
sofia/internal/22198@10.18.203.9:5060 [RECORD_SOFTWARE]=[FreeSwitch]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0]
sofia/internal/22198@10.18.203.9:5060 set(RECORD_ARTIST=FreeSwitch)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_dptools.c:1672 SET
sofia/internal/22198@10.18.203.9:5060 [RECORD_ARTIST]=[FreeSwitch]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0]
sofia/internal/22198@10.18.203.9:5060 set(RECORD_COMMENT=Voice recordings)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.784691 [DEBUG] mod_dptools.c:1672 SET
sofia/internal/22198@10.18.203.9:5060 [RECORD_COMMENT]=[Voice recordings]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0]
sofia/internal/22198@10.18.203.9:5060 set(RECORD_DATE=2020-08-31 15:36)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.794692 [DEBUG] mod_dptools.c:1672 SET
sofia/internal/22198@10.18.203.9:5060 [RECORD_DATE]=[2020-08-31 15:36]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0]
sofia/internal/22198@10.18.203.9:5060 set(RECORD_STEREO=true)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.794692 [DEBUG] mod_dptools.c:1672 SET
sofia/internal/22198@10.18.203.9:5060 [RECORD_STEREO]=[true]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0]
sofia/internal/22198@10.18.203.9:5060 set(media_bug_answer_req=true)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.794692 [DEBUG] mod_dptools.c:1672 SET
sofia/internal/22198@10.18.203.9:5060 [media_bug_answer_req]=[true]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0]
sofia/internal/22198@10.18.203.9:5060 set(ringback=%(2000,4000,440,480))
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.794692 [DEBUG] mod_dptools.c:1672 SET
sofia/internal/22198@10.18.203.9:5060 [ringback]=[%(2000,4000,440,480)]
a56b3ec6-1a0d-469e-83da-73e46420fede EXECUTE [depth=0]
sofia/internal/22198@10.18.203.9:5060 bridge(sofia/gateway/TR22198/22202)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:37.794692 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.794692 [NOTICE] switch_channel.c:1118 New Channel sofia/external/22202 [d88ada98-aef6-4f37-a2c1-ca2beda10e5b]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.794692 [DEBUG] mod_sofia.c:5089 (sofia/external/22202) State Change CS_NEW -> CS_INIT
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.904698 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_INIT (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.904698 [DEBUG] switch_core_state_machine.c:628 (sofia/external/22202) State INIT
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.904698 [DEBUG] mod_sofia.c:93 sofia/external/22202 SOFIA INIT
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.904698 [DEBUG] sofia_glue.c:1618 sofia/external/22202 sending invite version: 1.10.5 64bit
d88ada98-aef6-4f37-a2c1-ca2beda10e5b Local SDP:
d88ada98-aef6-4f37-a2c1-ca2beda10e5b v=0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b o=FreeSWITCH 1598843725 1598843726 IN IP4 10.18.203.9
d88ada98-aef6-4f37-a2c1-ca2beda10e5b s=FreeSWITCH
d88ada98-aef6-4f37-a2c1-ca2beda10e5b c=IN IP4 10.18.203.9
d88ada98-aef6-4f37-a2c1-ca2beda10e5b t=0 0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b m=audio 30072 RTP/AVP 9 0 8 101
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:9 G722/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:0 PCMU/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:8 PCMA/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:101 telephone-event/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=fmtp:101 0-16
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=ptime:20
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=sendrecv
d88ada98-aef6-4f37-a2c1-ca2beda10e5b
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:40 sofia/external/22202 Standard INIT
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:48 (sofia/external/22202) State Change CS_INIT -> CS_ROUTING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:628 (sofia/external/22202) State INIT going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_ROUTING (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [calling][0]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:644 (sofia/external/22202) State ROUTING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] mod_sofia.c:154 sofia/external/22202 SOFIA ROUTING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_ivr_originate.c:67 (sofia/external/22202) State Change CS_ROUTING -> CS_CONSUME_MEDIA
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:644 (sofia/external/22202) State ROUTING going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:663 (sofia/external/22202) State CONSUME_MEDIA
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] switch_core_state_machine.c:663 (sofia/external/22202) State CONSUME_MEDIA going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:37.914699 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [calling][0]
2020-08-31 15:36:38.024705 [INFO] sofia.c:1369 sofia/external/22202 Update Callee ID to "22202" <22202>
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [proceeding][180]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] sofia.c:7336 Remote SDP:
d88ada98-aef6-4f37-a2c1-ca2beda10e5b v=0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b o=- 8157652 6646641 IN IP4 10.18.201.23
d88ada98-aef6-4f37-a2c1-ca2beda10e5b s=-
d88ada98-aef6-4f37-a2c1-ca2beda10e5b c=IN IP4 10.18.201.23
d88ada98-aef6-4f37-a2c1-ca2beda10e5b t=0 0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b m=audio 23116 RTP/AVP 8
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:8 PCMA/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=sendonly
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=ptime:20
d88ada98-aef6-4f37-a2c1-ca2beda10e5b
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [NOTICE] sofia.c:7339 Pre-Answer sofia/external/22202!
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_channel.c:3565 (sofia/external/22202) Callstate Change DOWN -> EARLY
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:3839 Set Codec sofia/external/22202 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_codec.c:111 sofia/external/22202 Original read codec set to PCMA:8
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5882 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:5911 sofia/external/22202 Set 2833 dtmf send payload to 101 recv payload to 101
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/external/22202] 10.18.203.9 port 30072 -> 10.18.201.23 port 23116 codec: 8 ms: 20
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:8977 sofia/external/22202 Set 2833 dtmf send payload to 101
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:8984 sofia/external/22202 Set 2833 dtmf receive payload to 101
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.024705 [DEBUG] switch_core_media.c:9007 sofia/external/22202 Set rtp dtmf delay to 40
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [INFO] switch_ivr_originate.c:3801 Sending early media
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:3839 Set Codec
sofia/internal/22198@10.18.203.9:5060 G722/8000 20 ms 160 samples 64000 bits 1 channels
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_codec.c:111
sofia/internal/22198@10.18.203.9:5060 Original read codec set to G722:9
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:5911
sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101 recv payload to 101
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/internal/22198@10.18.203.9:5060] 10.18.203.9 port 17408 -> 10.18.203.36 port 12082 codec: 9 ms: 20
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:8977
sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:8984
sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf receive payload to 101
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:9007
sofia/internal/22198@10.18.203.9:5060 Set rtp dtmf delay to 40
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [NOTICE] sofia_media.c:92 Pre-Answer
sofia/internal/22198@10.18.203.9:5060!
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_channel.c:3565 (sofia/internal/22198@10.18.203.9:5060) Callstate Change RINGING -> EARLY
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for
sofia/internal/22198@10.18.203.9:5060.
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] mod_sofia.c:2593 Ring SDP:
a56b3ec6-1a0d-469e-83da-73e46420fede v=0
a56b3ec6-1a0d-469e-83da-73e46420fede o=FreeSWITCH 1598856390 1598856391 IN IP4 10.18.203.9
a56b3ec6-1a0d-469e-83da-73e46420fede s=FreeSWITCH
a56b3ec6-1a0d-469e-83da-73e46420fede c=IN IP4 10.18.203.9
a56b3ec6-1a0d-469e-83da-73e46420fede t=0 0
a56b3ec6-1a0d-469e-83da-73e46420fede m=audio 17408 RTP/AVP 9 101
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:9 G722/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:101 telephone-event/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=fmtp:101 0-16
a56b3ec6-1a0d-469e-83da-73e46420fede a=ptime:20
a56b3ec6-1a0d-469e-83da-73e46420fede a=recvonly
a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] sofia.c:7326 Channel
sofia/internal/22198@10.18.203.9:5060 entering state [early][183]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.044706 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/external/22202]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.054707 [DEBUG] switch_ivr_bridge.c:1793 (sofia/external/22202) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.054707 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.054707 [DEBUG] switch_core_state_machine.c:654 (sofia/external/22202) State EXCHANGE_MEDIA
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.054707 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.084708 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.084708 [NOTICE] switch_core_media.c:15845 Activating write resampler
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:38.204715 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:38.204715 [NOTICE] switch_core_media.c:15845 Activating write resampler
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.544849 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [completing][200]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.544849 [DEBUG] sofia.c:7333 Duplicate SDP
d88ada98-aef6-4f37-a2c1-ca2beda10e5b v=0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b o=- 8157652 6646641 IN IP4 10.18.201.23
d88ada98-aef6-4f37-a2c1-ca2beda10e5b s=-
d88ada98-aef6-4f37-a2c1-ca2beda10e5b c=IN IP4 10.18.201.23
d88ada98-aef6-4f37-a2c1-ca2beda10e5b t=0 0
d88ada98-aef6-4f37-a2c1-ca2beda10e5b m=audio 23116 RTP/AVP 8
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=rtpmap:8 PCMA/8000
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=sendonly
d88ada98-aef6-4f37-a2c1-ca2beda10e5b a=ptime:20
d88ada98-aef6-4f37-a2c1-ca2beda10e5b
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.544849 [DEBUG] sofia.c:7326 Channel sofia/external/22202 entering state [ready][200]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.544849 [NOTICE] sofia.c:8445 Channel [sofia/external/22202] has been answered
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.544849 [DEBUG] switch_channel.c:3865 (sofia/external/22202) Callstate Change EARLY -> ACTIVE
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.564850 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for
sofia/internal/22198@10.18.203.9:5060.
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.564850 [DEBUG] mod_sofia.c:898 Local SDP
sofia/internal/22198@10.18.203.9:5060:
a56b3ec6-1a0d-469e-83da-73e46420fede v=0
a56b3ec6-1a0d-469e-83da-73e46420fede o=FreeSWITCH 1598856390 1598856392 IN IP4 10.18.203.9
a56b3ec6-1a0d-469e-83da-73e46420fede s=FreeSWITCH
a56b3ec6-1a0d-469e-83da-73e46420fede c=IN IP4 10.18.203.9
a56b3ec6-1a0d-469e-83da-73e46420fede t=0 0
a56b3ec6-1a0d-469e-83da-73e46420fede m=audio 17408 RTP/AVP 9 101
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:9 G722/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:101 telephone-event/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=fmtp:101 0-16
a56b3ec6-1a0d-469e-83da-73e46420fede a=ptime:20
a56b3ec6-1a0d-469e-83da-73e46420fede a=recvonly
a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.564850 [DEBUG] sofia.c:7326 Channel
sofia/internal/22198@10.18.203.9:5060 entering state [completed][200]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.564850 [NOTICE] switch_ivr_bridge.c:731 Channel [sofia/internal/22198@10.18.203.9:5060] has been answered
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.564850 [DEBUG] switch_channel.c:3865 (sofia/internal/22198@10.18.203.9:5060) Callstate Change EARLY -> ACTIVE
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.604852 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.604852 [DEBUG] sofia.c:7326 Channel
sofia/internal/22198@10.18.203.9:5060 entering state [ready][200]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.624854 [DEBUG] sofia.c:7326 Channel
sofia/internal/22198@10.18.203.9:5060 entering state [calling][0]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] sofia.c:7326 Channel
sofia/internal/22198@10.18.203.9:5060 entering state [ready][200]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] sofia.c:7336 Remote SDP:
a56b3ec6-1a0d-469e-83da-73e46420fede v=0
a56b3ec6-1a0d-469e-83da-73e46420fede o=- 20151 20153 IN IP4 10.18.203.36
a56b3ec6-1a0d-469e-83da-73e46420fede s=SDP data
a56b3ec6-1a0d-469e-83da-73e46420fede c=IN IP4 10.18.203.36
a56b3ec6-1a0d-469e-83da-73e46420fede t=0 0
a56b3ec6-1a0d-469e-83da-73e46420fede m=audio 12082 RTP/AVP 9 101
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:9 G722/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=rtpmap:101 telephone-event/8000
a56b3ec6-1a0d-469e-83da-73e46420fede a=fmtp:101 0-15
a56b3ec6-1a0d-469e-83da-73e46420fede a=sendonly
a56b3ec6-1a0d-469e-83da-73e46420fede a=ptime:20
a56b3ec6-1a0d-469e-83da-73e46420fede
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:5911
sofia/internal/22198@10.18.203.9:5060 Set 2833 dtmf send payload to 101 recv payload to 101
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] sofia.c:8345 Processing updated SDP
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:40.644855 [DEBUG] switch_core_media.c:8645 Audio params are unchanged for
sofia/internal/22198@10.18.203.9:5060.
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:40.884868 [INFO] switch_rtp.c:7719 Auto Changing audio port from 10.18.201.23:23116 to 10.18.203.19:12202
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.944615 [NOTICE] sofia.c:1089 Hangup
sofia/internal/22198@10.18.203.9:5060 [CS_EXECUTE] [NORMAL_CLEARING]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.944615 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/internal/22198@10.18.203.9:5060]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/external/22202]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/external/22202 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:654 (sofia/external/22202) State EXCHANGE_MEDIA going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_HANGUP (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:848 (sofia/external/22202) Callstate Change ACTIVE -> HANGUP
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:850 (sofia/external/22202) State HANGUP
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] mod_sofia.c:447 sofia/external/22202 Overriding SIP cause 480 with 200 from the other leg
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] mod_sofia.c:453 Channel sofia/external/22202 hanging up, cause: NORMAL_CLEARING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/external/22202
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:60 sofia/external/22202 Standard HANGUP, cause: NORMAL_CLEARING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:850 (sofia/external/22202) State HANGUP going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:620 (sofia/external/22202) State Change CS_HANGUP -> CS_REPORTING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:585 (sofia/external/22202) Running State Change CS_REPORTING (Cur 2 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:936 (sofia/external/22202) State REPORTING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:174 sofia/external/22202 Standard REPORTING, cause: NORMAL_CLEARING
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:936 (sofia/external/22202) State REPORTING going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:611 (sofia/external/22202) State Change CS_REPORTING -> CS_DESTROY
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.964617 [DEBUG] switch_core_session.c:1726 Session 12 (sofia/external/22202) Locked, Waiting on external entities
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_ivr_bridge.c:1895
sofia/internal/22198@10.18.203.9:5060 skip receive message [UNBRIDGE] (channel is hungup already)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_core_session.c:2905
sofia/internal/22198@10.18.203.9:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/22198@10.18.203.9:5060) State EXECUTE going to sleep
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_HANGUP (Cur 2 Tot 12)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/22198@10.18.203.9:5060) Callstate Change ACTIVE -> HANGUP
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/22198@10.18.203.9:5060) State HANGUP
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.964617 [DEBUG] mod_sofia.c:453 Channel
sofia/internal/22198@10.18.203.9:5060 hanging up, cause: NORMAL_CLEARING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:60
sofia/internal/22198@10.18.203.9:5060 Standard HANGUP, cause: NORMAL_CLEARING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/22198@10.18.203.9:5060) State HANGUP going to sleep
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/22198@10.18.203.9:5060) State Change CS_HANGUP -> CS_REPORTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_REPORTING (Cur 2 Tot 12)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/22198@10.18.203.9:5060) State REPORTING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:174
sofia/internal/22198@10.18.203.9:5060 Standard REPORTING, cause: NORMAL_CLEARING
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/22198@10.18.203.9:5060) State REPORTING going to sleep
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/22198@10.18.203.9:5060) State Change CS_REPORTING -> CS_DESTROY
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_session.c:1726 Session 11 (sofia/internal/22198@10.18.203.9:5060) Locked, Waiting on external entities
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [NOTICE] switch_core_session.c:1744 Session 11 (sofia/internal/22198@10.18.203.9:5060) Ended
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [NOTICE] switch_core_session.c:1748 Close Channel
sofia/internal/22198@10.18.203.9:5060 [CS_DESTROY]
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/22198@10.18.203.9:5060) Running State Change CS_DESTROY (Cur 1 Tot 12)
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/22198@10.18.203.9:5060) State DESTROY
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] mod_sofia.c:364
sofia/internal/22198@10.18.203.9:5060 SOFIA DESTROY
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:181
sofia/internal/22198@10.18.203.9:5060 Standard DESTROY
a56b3ec6-1a0d-469e-83da-73e46420fede 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/22198@10.18.203.9:5060) State DESTROY going to sleep
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [NOTICE] switch_core_session.c:1744 Session 12 (sofia/external/22202) Ended
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/22202 [CS_DESTROY]
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:739 (sofia/external/22202) Running State Change CS_DESTROY (Cur 0 Tot 12)
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:749 (sofia/external/22202) State DESTROY
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [DEBUG] mod_sofia.c:364 sofia/external/22202 SOFIA DESTROY
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:181 sofia/external/22202 Standard DESTROY
d88ada98-aef6-4f37-a2c1-ca2beda10e5b 2020-08-31 15:36:53.974617 [DEBUG] switch_core_state_machine.c:749 (sofia/external/22202) State DESTROY going to sleep