2021-10-26 12:52:29.050821 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/2l3s73da@ppcf1t3o2201.invalid [d0dc30cc-9ae7-4c04-b1d6-4917c2d279ec] 2021-10-26 12:52:29.050821 [DEBUG] mod_sofia.c:5089 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State Change CS_NEW -> CS_INIT 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) Running State Change CS_INIT (Cur 2 Tot 33) 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State INIT 2021-10-26 12:52:29.050821 [DEBUG] mod_sofia.c:93 sofia/internal/2l3s73da@ppcf1t3o2201.invalid SOFIA INIT 2021-10-26 12:52:29.050821 [DEBUG] sofia_glue.c:1587 sip:2l3s73da@49.36.66.155:54529;transport=wss Setting proxy route to sofia/internal/2l3s73da@ppcf1t3o2201.invalid 2021-10-26 12:52:29.050821 [DEBUG] sofia_glue.c:1618 sofia/internal/2l3s73da@ppcf1t3o2201.invalid sending invite version: 1.10.2 -release 64bit Local SDP: v=0 o=FreeSWITCH 1635243287 1635243288 IN IP4 159.6X.1XX.1X s=FreeSWITCH c=IN IP4 159.6X.1XX.1X t=0 0 a=msid-semantic: WMS SUEn7PzWk1vj1UlaPB66hNYKnESNmJO5 m=audio 27462 RTP/SAVPF 9 0 8 3 101 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fingerprint:sha-256 05:2D:ED:8F:CD:13:CE:A4:E2:7E:39:E3:56:52:D4:57:FF:4C:D6:6D:A6:34:B6:C0:30:AC:F5:FE:86:0A:1A:57 a=setup:actpass a=rtcp-mux a=rtcp:27462 IN IP4 159.6X.1XX.1X a=ssrc:1166113573 cname:ujjsYJe8BAhsltt0 a=ssrc:1166113573 msid:SUEn7PzWk1vj1UlaPB66hNYKnESNmJO5 a0 a=ssrc:1166113573 mslabel:SUEn7PzWk1vj1UlaPB66hNYKnESNmJO5 a=ssrc:1166113573 label:SUEn7PzWk1vj1UlaPB66hNYKnESNmJO5a0 a=ice-ufrag:UpW23LDgflTsVyog a=ice-pwd:Hyzv7Euz4CRfVb6GqSyju1pA a=candidate:2385770488 1 udp 659136 159.6X.1XX.1X 27462 typ host generation 0 a=candidate:2385770488 2 udp 659136 159.6X.1XX.1X 27462 typ host generation 0 a=ptime:20 a=sendrecv 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2l3s73da@ppcf1t3o2201.invalid Standard INIT 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State Change CS_INIT -> CS_ROUTING 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State INIT going to sleep 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) Running State Change CS_ROUTING (Cur 2 Tot 33) 2021-10-26 12:52:29.050821 [DEBUG] sofia.c:7301 Channel sofia/internal/2l3s73da@ppcf1t3o2201.invalid entering state [calling][0] 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State ROUTING 2021-10-26 12:52:29.050821 [DEBUG] mod_sofia.c:154 sofia/internal/2l3s73da@ppcf1t3o2201.invalid SOFIA ROUTING 2021-10-26 12:52:29.050821 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State ROUTING going to sleep 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 33) 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State CONSUME_MEDIA 2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State CONSUME_MEDIA going to sleep 2021-10-26 12:52:29.270824 [DEBUG] sofia.c:7301 Channel sofia/internal/2l3s73da@ppcf1t3o2201.invalid entering state [proceeding][180] 2021-10-26 12:52:29.270824 [NOTICE] sofia.c:7412 Ring-Ready sofia/internal/2l3s73da@ppcf1t3o2201.invalid! 2021-10-26 12:52:29.270824 [DEBUG] switch_channel.c:3437 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) Callstate Change DOWN -> RINGING 2021-10-26 12:52:38.810821 [DEBUG] sofia.c:7301 Channel sofia/internal/2l3s73da@ppcf1t3o2201.invalid entering state [completing][200] 2021-10-26 12:52:38.810821 [DEBUG] sofia.c:7311 Remote SDP: v=0 o=- 5763818288033074925 2 IN IP4 127.0.0.1 s=- t=0 0 a=msid-semantic: WMS 7z7Ljk2XXKuNEXsvU3Esz2fbmVvl5URTXwlP m=audio 50131 UDP/TLS/RTP/SAVPF 9 0 8 101 c=IN IP4 49.36.66.155 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=rtcp:9 IN IP4 192.0.2.63 a=candidate:840328171 1 udp 2113937151 d26fe240-6e5f-4d34-8488-39f093b59d67.local 50131 typ host generation 0 network-cost 999 a=candidate:2430225745 1 udp 2113939711 e31f962c-3822-4856-aa21-25478692ef23.local 50132 typ host generation 0 network-cost 999 a=candidate:842163049 1 udp 1677729535 49.36.66.155 50131 typ srflx raddr 192.0.2.63 rport 0 generation 0 network-cost 999 a=ice-ufrag:eyg7 a=ice-pwd:2S4J8PnNelLFAW6kaef2ZKEh a=ice-options:trickle a=fingerprint:sha-256 1C:3D:B2:DF:8D:EB:C3:1A:57:1F:1B:FF:41:EF:56:F6:2D:D4:A3:A6:69:C8:B0:49:B4:7B:39:82:22:BA:57:3E a=setup:active a=mid:0 a=rtcp-mux a=ssrc:3396866038 cname:yLBRvE6bQhZN8vDr a=ssrc:3396866038 msid:7z7Ljk2XXKuNEXsvU3Esz2fbmVvl5URTXwlP 64ccd886-3848-44a7-bd97-e474846a0969 a=ssrc:3396866038 mslabel:7z7Ljk2XXKuNEXsvU3Esz2fbmVvl5URTXwlP a=ssrc:3396866038 label:64ccd886-3848-44a7-bd97-e474846a0969 2021-10-26 12:52:38.810821 [DEBUG] sofia.c:7301 Channel sofia/internal/2l3s73da@ppcf1t3o2201.invalid entering state [ready][200] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/2l3s73da@ppcf1t3o2201.invalid G722/8000 20 ms 160 samples 64000 bits 1 channels 2021-10-26 12:52:38.810821 [DEBUG] switch_core_codec.c:111 sofia/internal/2l3s73da@ppcf1t3o2201.invalid Original read codec set to G722:9 2021-10-26 12:52:38.810821 [WARNING] switch_core_media.c:4252 NO candidate ACL defined, Defaulting to wan.auto 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: host addr: d26fe240-6e5f-4d34-8488-39f093b59d67.local:50131 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: host addr: e31f962c-3822-4856-aa21-25478692ef23.local:50132 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: srflx addr: 49.36.66.155:50131 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4327 Searching for rtp candidate. 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4336 Choose rtp candidate, index 0, d26fe240-6e5f-4d34-8488-39f093b59d67.local:50131 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4102 sofia/internal/2l3s73da@ppcf1t3o2201.invalid choosing family v4 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4347 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute d26fe240-6e5f-4d34-8488-39f093b59d67.local:50131 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4399 setting remote audio ice addr to index 0 d26fe240-6e5f-4d34-8488-39f093b59d67.local:50131 based on candidate 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4434 Setting remote rtcp audio addr to d26fe240-6e5f-4d34-8488-39f093b59d67.local:50131 based on candidate 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5909 sofia/internal/2l3s73da@ppcf1t3o2201.invalid Set 2833 dtmf send payload to 101 recv payload to 101 2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/internal/2l3s73da@ppcf1t3o2201.invalid] 159.6X.1XX.1X port 27462 -> d26fe240-6e5f-4d34-8488-39f093b59d67.local port 50131 codec: 9 ms: 20 2021-10-26 12:52:38.810821 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms 2021-10-26 12:52:38.810821 [ERR] switch_core_media.c:9679 AUDIO RTP REPORTS ERROR: [Remote Address Error!] 2021-10-26 12:52:38.810821 [NOTICE] switch_core_media.c:9680 Hangup sofia/internal/2l3s73da@ppcf1t3o2201.invalid [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION] 2021-10-26 12:52:38.810821 [ERR] sofia.c:8481 RTP Error! 2021-10-26 12:52:38.810821 [DEBUG] sofia.c:1548 Channel is already hungup. 2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) Running State Change CS_HANGUP (Cur 2 Tot 33) 2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) Callstate Change RINGING -> HANGUP 2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State HANGUP 2021-10-26 12:52:38.810821 [DEBUG] mod_sofia.c:453 Channel sofia/internal/2l3s73da@ppcf1t3o2201.invalid hanging up, cause: INCOMPATIBLE_DESTINATION 2021-10-26 12:52:38.810821 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/2l3s73da@ppcf1t3o2201.invalid 2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2l3s73da@ppcf1t3o2201.invalid Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State HANGUP going to sleep 2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State Change CS_HANGUP -> CS_REPORTING 2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) Running State Change CS_REPORTING (Cur 2 Tot 33) 2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State REPORTING 2021-10-26 12:52:38.810821 [DEBUG] switch_ivr_originate.c:3952 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION] 2021-10-26 12:52:38.810821 [NOTICE] switch_ivr_originate.c:2955 Cannot create outgoing channel of type [user] cause: [INCOMPATIBLE_DESTINATION] 2021-10-26 12:52:38.810821 [DEBUG] switch_ivr_originate.c:3952 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION] 2021-10-26 12:52:38.830888 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2l3s73da@ppcf1t3o2201.invalid Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 2021-10-26 12:52:38.830888 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State REPORTING going to sleep 2021-10-26 12:52:38.830888 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) State Change CS_REPORTING -> CS_DESTROY 2021-10-26 12:52:38.830888 [DEBUG] switch_core_session.c:1726 Session 33 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) Locked, Waiting on external entities 2021-10-26 12:52:38.830888 [NOTICE] switch_core_session.c:1744 Session 33 (sofia/internal/2l3s73da@ppcf1t3o2201.invalid) Ended 2021-10-26 12:52:38.830888 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/2l3s73da@ppcf1t3o2201.invalid [CS_DESTROY]