recv 1350 bytes from udp/[10.20.20.240]:5060 at 17:47:49.899193: ------------------------------------------------------------------------ INVITE sip:1867@voip.com SIP/2.0 Via: SIP/2.0/UDP 10.20.20.240;branch=z9hG4bKbeaa.1aa6cdfb87aafb647f15f3a905d7effa.0 From: "1185" ;tag=1frDZcbu2 To: sip:1867@voip.com CSeq: 21 INVITE Call-ID: IlnORofF1G Max-Forwards: 69 Supported: replaces, outbound, gruu, path, record-aware Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE Content-Type: application/sdp Content-Length: 624 User-Agent: Linphone-Desktop/5.1.2 (DESKTOP-CH53FMF) windows/10 Qt/5.15.2 LinphoneSDK/5.2.97 Contact: v=0 o=1185 1689 2891 IN IP4 10.20.0.242 s=Talk c=IN IP4 10.20.0.242 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=record:off m=audio 32354 RTP/AVP 96 97 98 0 8 18 101 99 100 a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 speex/16000 a=fmtp:97 vbr=on a=rtpmap:98 speex/8000 a=fmtp:98 vbr=on a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:99 telephone-event/16000 a=rtpmap:100 telephone-event/8000 a=sendrecv a=rtcp:32355 2023-09-01 17:47:49.885020 98.87% [NOTICE] switch_channel.c:1142 New Channel sofia/sbc/1185@voip.com [634b5d75-efe3-4632-967b-60436cf93e3f] 2023-09-01 17:47:49.885020 98.87% [DEBUG] switch_core_state_machine.c:581 (sofia/sbc/1185@voip.com) Running State Change CS_NEW (Cur 1 Tot 1) 2023-09-01 17:47:49.885020 98.87% [INFO] sofia.c:10459 sofia/sbc/1185@voip.com receiving invite from 10.20.20.240:5060 version: 1.10.10-release 64bit call-id: IlnORofF1G 2023-09-01 17:47:49.885020 98.87% [DEBUG] sofia.c:10553 verifying acl "sbc-acl" for ip/port 10.20.20.240:0. 2023-09-01 17:47:49.885020 98.87% [DEBUG] sofia.c:7493 Channel sofia/sbc/1185@voip.com entering state [received][100] 2023-09-01 17:47:49.885020 98.87% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=1185 1689 2891 IN IP4 10.20.0.242 s=Talk c=IN IP4 10.20.0.242 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics a=record:off m=audio 32354 RTP/AVP 96 97 98 0 8 18 101 99 100 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 speex/16000 a=fmtp:97 vbr=on a=rtpmap:98 speex/8000 a=fmtp:98 vbr=on a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:99 telephone-event/16000 a=rtpmap:100 telephone-event/8000 a=rtcp-fb:* trr-int 1000 a=rtcp-fb:* ccm tmmbr a=rtcp:32355 2023-09-01 17:47:49.885020 98.87% [DEBUG] sofia.c:7906 (sofia/sbc/1185@voip.com) State Change CS_NEW -> CS_INIT 2023-09-01 17:47:49.885020 98.87% [DEBUG] switch_core_state_machine.c:600 (sofia/sbc/1185@voip.com) State NEW 2023-09-01 17:47:49.885020 98.87% [DEBUG] switch_core_state_machine.c:581 (sofia/sbc/1185@voip.com) Running State Change CS_INIT (Cur 1 Tot 1) 2023-09-01 17:47:49.885020 98.87% [DEBUG] switch_core_state_machine.c:624 (sofia/sbc/1185@voip.com) State INIT 2023-09-01 17:47:49.885020 98.87% [DEBUG] mod_sofia.c:97 sofia/sbc/1185@voip.com SOFIA INIT 2023-09-01 17:47:49.885020 98.87% [DEBUG] switch_core_state_machine.c:40 sofia/sbc/1185@voip.com Standard INIT 2023-09-01 17:47:49.885020 98.87% [DEBUG] switch_core_state_machine.c:48 (sofia/sbc/1185@voip.com) State Change CS_INIT -> CS_ROUTING 2023-09-01 17:47:49.885020 98.87% [DEBUG] switch_core_state_machine.c:624 (sofia/sbc/1185@voip.com) State INIT going to sleep 2023-09-01 17:47:49.885020 98.87% [DEBUG] switch_core_state_machine.c:581 (sofia/sbc/1185@voip.com) Running State Change CS_ROUTING (Cur 1 Tot 1) 2023-09-01 17:47:49.885020 98.87% [DEBUG] switch_channel.c:2399 (sofia/sbc/1185@voip.com) Callstate Change DOWN -> RINGING 2023-09-01 17:47:49.885020 98.87% [DEBUG] switch_core_state_machine.c:640 (sofia/sbc/1185@voip.com) State ROUTING send 279 bytes to udp/[10.20.20.240]:5060 at 17:47:49.902143: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.20.20.240;branch=z9hG4bKbeaa.1aa6cdfb87aafb647f15f3a905d7effa.0 From: "1185" ;tag=1frDZcbu2 To: sip:1867@voip.com Call-ID: IlnORofF1G CSeq: 21 INVITE User-Agent: DevTelRealm Content-Length: 0 EXECUTE [depth=0] sofia/sbc/1185@voip.com bridge(sofia/sbc/1867@voip.com;fs_path=sip:10.20.20.240:5060) 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables 2023-09-01 17:47:50.104986 98.87% [NOTICE] switch_channel.c:1142 New Channel sofia/sbc/1867@voip.com [862e159a-7a63-4a10-8e43-dc80731bf105] 2023-09-01 17:47:50.104986 98.87% [DEBUG] mod_sofia.c:5110 (sofia/sbc/1867@voip.com) State Change CS_NEW -> CS_INIT 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:581 (sofia/sbc/1867@voip.com) Running State Change CS_INIT (Cur 2 Tot 2) 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:624 (sofia/sbc/1867@voip.com) State INIT 2023-09-01 17:47:50.104986 98.87% [DEBUG] mod_sofia.c:97 sofia/sbc/1867@voip.com SOFIA INIT 2023-09-01 17:47:50.104986 98.87% [DEBUG] sofia_glue.c:1628 sip:10.20.20.240:5060 Setting proxy route to sofia/sbc/1867@voip.com 2023-09-01 17:47:50.104986 98.87% [INFO] sofia_glue.c:1659 sofia/sbc/1867@voip.com sending invite call-id: (null) 2023-09-01 17:47:50.104986 98.87% [DEBUG] sofia_glue.c:1662 sofia/sbc/1867@voip.com sending invite version: 1.10.10-release 64bit Local SDP: v=0 o=DevRealm 1693564648 1693564649 IN IP4 10.20.20.241 s=DevRealm c=IN IP4 10.20.20.241 t=0 0 m=audio 25822 RTP/AVP 102 0 8 103 104 101 13 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-15 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtpmap:104 CN/48000 a=rtpmap:13 CN/8000 a=ptime:20 a=sendrecv 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:40 sofia/sbc/1867@voip.com Standard INIT 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:48 (sofia/sbc/1867@voip.com) State Change CS_INIT -> CS_ROUTING 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:624 (sofia/sbc/1867@voip.com) State INIT going to sleep 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:581 (sofia/sbc/1867@voip.com) Running State Change CS_ROUTING (Cur 2 Tot 2) 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:640 (sofia/sbc/1867@voip.com) State ROUTING 2023-09-01 17:47:50.104986 98.87% [DEBUG] mod_sofia.c:158 sofia/sbc/1867@voip.com SOFIA ROUTING 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_ivr_originate.c:67 (sofia/sbc/1867@voip.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:640 (sofia/sbc/1867@voip.com) State ROUTING going to sleep 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:581 (sofia/sbc/1867@voip.com) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 2) 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:659 (sofia/sbc/1867@voip.com) State CONSUME_MEDIA 2023-09-01 17:47:50.104986 98.87% [DEBUG] switch_core_state_machine.c:659 (sofia/sbc/1867@voip.com) State CONSUME_MEDIA going to sleep send 1309 bytes to udp/[10.20.20.240]:5060 at 17:47:50.115106: ------------------------------------------------------------------------ INVITE sip:1867@voip.com SIP/2.0 Via: SIP/2.0/UDP 10.20.20.241:5090;rport;branch=z9hG4bKee4SFmUaetgHB Route: Max-Forwards: 67 From: "1185" ;tag=ScSZmZeHHBryg To: Call-ID: 8288ec67-c392-123c-d1a8-0243e4a483ce CSeq: 72241443 INVITE Contact: User-Agent: DevRealm Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 516 Remote-Party-ID: "1185" ;party=calling;screen=yes;privacy=off v=0 o=DevRealm 1693564648 1693564649 IN IP4 10.20.20.241 s=DevRealm c=IN IP4 10.20.20.241 t=0 0 m=audio 25822 RTP/AVP 102 0 8 103 104 101 13 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-15 a=rtpmap:104 CN/48000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtpmap:13 CN/8000 a=ptime:20 2023-09-01 17:47:50.104986 98.87% [DEBUG] sofia.c:7493 Channel sofia/sbc/1867@voip.com entering state [calling][0] recv 353 bytes from udp/[10.20.20.240]:5060 at 17:47:50.116728: ------------------------------------------------------------------------ SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 10.20.20.241:5090;rport=5090;branch=z9hG4bKee4SFmUaetgHB;received=10.20.20.241 From: "1185" ;tag=ScSZmZeHHBryg To: Call-ID: 8288ec67-c392-123c-d1a8-0243e4a483ce CSeq: 72241443 INVITE Server: Dev Kamailio Content-Length: 0 recv 638 bytes from udp/[10.20.20.240]:5060 at 17:47:50.396595: ------------------------------------------------------------------------ SIP/2.0 180 Ringing To: ;tag=cf25622f From: "1185" ;tag=ScSZmZeHHBryg Call-ID: 8288ec67-c392-123c-d1a8-0243e4a483ce CSeq: 72241443 INVITE Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE Supported: replaces, norefersub, extended-refer, timer, sec-agree, outbound, path, X-cisco-serviceuri User-Agent: Z 5.6.1 v2.10.19.9 Allow-Events: presence, kpml, talk, as-feature-event Content-Length: 0 Via: SIP/2.0/UDP 10.20.20.241:5090;received=10.20.20.241;rport=5090;branch=z9hG4bKee4SFmUaetgHB Contact: 2023-09-01 17:47:50.385023 98.30% [DEBUG] sofia.c:7493 Channel sofia/sbc/1867@voip.com entering state [proceeding][180] 2023-09-01 17:47:50.385023 98.30% [NOTICE] sofia.c:7604 Ring-Ready sofia/sbc/1867@voip.com! 2023-09-01 17:47:50.385023 98.30% [DEBUG] switch_channel.c:3513 (sofia/sbc/1867@voip.com) Callstate Change DOWN -> RINGING 2023-09-01 17:47:50.405000 98.30% [NOTICE] mod_sofia.c:2514 Ring-Ready sofia/sbc/1185@voip.com! send 547 bytes to udp/[10.20.20.240]:5060 at 17:47:50.414967: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.20.20.240;branch=z9hG4bKbeaa.1aa6cdfb87aafb647f15f3a905d7effa.0 From: "1185" ;tag=1frDZcbu2 To: ;tag=r3Z6j4XDm21BN Call-ID: IlnORofF1G CSeq: 21 INVITE Contact: User-Agent: DevRealm Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Content-Length: 0 2023-09-01 17:47:50.405000 98.30% [DEBUG] sofia.c:7493 Channel sofia/sbc/1185@voip.com entering state [early][180] 2023-09-01 17:47:50.405000 98.30% [NOTICE] switch_ivr_originate.c:568 Ring Ready sofia/sbc/1185@voip.com! recv 362 bytes from udp/[10.20.20.240]:5060 at 17:47:51.572165: ------------------------------------------------------------------------ OPTIONS sip:10.20.20.241:5090 SIP/2.0 Via: SIP/2.0/UDP 10.20.20.240;branch=z9hG4bK8dcb.a428c176000000000000000000000000.0 To: From: ;tag=9b0a4d1038262ff449114b015b78e7e2-2938f5e8 CSeq: 10 OPTIONS Call-ID: 0bf53aae3b4949a0-501002@10.20.0.240 Max-Forwards: 70 Content-Length: 0 User-Agent: Dev Kamailio send 571 bytes to udp/[10.20.20.240]:5060 at 17:47:51.572466: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.20.20.240;branch=z9hG4bK8dcb.a428c176000000000000000000000000.0 From: ;tag=9b0a4d1038262ff449114b015b78e7e2-2938f5e8 To: ;tag=tNjrptZmemeHc Call-ID: 0bf53aae3b4949a0-501002@10.20.0.240 CSeq: 10 OPTIONS Contact: User-Agent: DevRealm Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Content-Length: 0 recv 1084 bytes from udp/[10.20.20.240]:5060 at 17:47:56.435365: ------------------------------------------------------------------------ SIP/2.0 200 OK To: ;tag=cf25622f From: "1185" ;tag=ScSZmZeHHBryg Call-ID: 8288ec67-c392-123c-d1a8-0243e4a483ce CSeq: 72241443 INVITE Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE Content-Type: application/sdp Supported: replaces, norefersub, extended-refer, timer, sec-agree, outbound, path, X-cisco-serviceuri User-Agent: Z 5.6.1 v2.10.19.9 Allow-Events: presence, kpml, talk, as-feature-event Content-Length: 397 Via: SIP/2.0/UDP 10.20.20.241:5090;received=10.20.20.241;rport=5090;branch=z9hG4bKee4SFmUaetgHB X-Encryption: false Contact: v=0 o=Z 0 1485743060 IN IP4 10.20.0.242 s=Z c=IN IP4 10.20.0.242 t=0 0 m=audio 32392 RTP/AVP 102 0 8 103 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 sprop-maxcapturerate=16000; minptime=10; useinbandfec=1 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrecv a=rtcp:32393 a=ptime:20 2023-09-01 17:47:56.424983 98.23% [DEBUG] sofia.c:7493 Channel sofia/sbc/1867@voip.com entering state [completing][200] 2023-09-01 17:47:56.424983 98.23% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=Z 0 1485743060 IN IP4 10.20.0.242 s=Z c=IN IP4 10.20.0.242 t=0 0 m=audio 32392 RTP/AVP 102 0 8 103 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 sprop-maxcapturerate=16000; minptime=10; useinbandfec=1 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtcp:32393 a=ptime:20 send 378 bytes to udp/[10.20.20.240]:5060 at 17:47:56.437218: ------------------------------------------------------------------------ ACK sip:atpsh-64935774-7a503-65@10.20.20.240 SIP/2.0 Via: SIP/2.0/UDP 10.20.20.241:5090;rport;branch=z9hG4bKFQXjHFceB363p Max-Forwards: 70 From: "1185" ;tag=ScSZmZeHHBryg To: ;tag=cf25622f Call-ID: 8288ec67-c392-123c-d1a8-0243e4a483ce CSeq: 72241443 ACK Contact: Content-Length: 0 2023-09-01 17:47:56.424983 98.23% [DEBUG] sofia.c:7493 Channel sofia/sbc/1867@voip.com entering state [ready][200] 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:2] 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5569 Audio Codec Compare [opus:116:48000:20:0:2] is saved as a near-match 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [opus:102:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [opus:102:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:2] 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:2] 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 103@48000 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:3734 Set Codec sofia/sbc/1867@voip.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_codec.c:111 sofia/sbc/1867@voip.com Original read codec set to PCMU:0 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:5856 sofia/sbc/1867@voip.com Set 2833 dtmf send payload to 101 recv payload to 101 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/sbc/1867@voip.com] 10.20.20.241 port 25822 -> 10.20.0.242 port 32392 codec: 0 ms: 20 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:8880 Activating RTCP PORT 32393 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_rtp.c:4705 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 32393 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_rtp.c:2533 Setting RTCP remote addr to 10.20.0.242:32393 2 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:8972 sofia/sbc/1867@voip.com Set 2833 dtmf send payload to 101 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:8979 sofia/sbc/1867@voip.com Set 2833 dtmf receive payload to 101 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_core_media.c:9002 sofia/sbc/1867@voip.com Set rtp dtmf delay to 40 2023-09-01 17:47:56.424983 98.23% [NOTICE] sofia.c:8680 Channel [sofia/sbc/1867@voip.com] has been answered 2023-09-01 17:47:56.424983 98.23% [DEBUG] switch_channel.c:3912 (sofia/sbc/1867@voip.com) Callstate Change RINGING -> ACTIVE 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_ivr_originate.c:451 Setting codec string on sofia/sbc/1185@voip.com to PCMU@8000h@20i 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [opus:96:48000:20:0:1]/[PCMU:0:8000:20:64000:1] 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:98:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@48000 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:3734 Set Codec sofia/sbc/1185@voip.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_codec.c:111 sofia/sbc/1185@voip.com Original read codec set to PCMU:0 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 100@8000 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:5856 sofia/sbc/1185@voip.com Set 2833 dtmf send payload to 100 recv payload to 100 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/sbc/1185@voip.com] 10.20.20.241 port 19762 -> 10.20.0.242 port 32354 codec: 0 ms: 20 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:8880 Activating RTCP PORT 32355 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_rtp.c:4705 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 32355 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_rtp.c:2533 Setting RTCP remote addr to 10.20.0.242:32355 2 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:8972 sofia/sbc/1185@voip.com Set 2833 dtmf send payload to 100 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:8979 sofia/sbc/1185@voip.com Set 2833 dtmf receive payload to 100 2023-09-01 17:47:56.444981 98.23% [DEBUG] switch_core_media.c:9002 sofia/sbc/1185@voip.com Set rtp dtmf delay to 40 2023-09-01 17:47:56.444981 98.23% [NOTICE] sofia_media.c:90 Pre-Answer sofia/sbc/1185@voip.com! 2023-09-01 17:47:56.464996 98.23% [DEBUG] switch_channel.c:3585 (sofia/sbc/1185@voip.com) Callstate Change RINGING -> EARLY 2023-09-01 17:47:56.464996 98.23% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/sbc/1185@voip.com. 2023-09-01 17:47:56.464996 98.23% [DEBUG] mod_sofia.c:914 Local SDP sofia/sbc/1185@voip.com: v=0 o=DevRealm 1693570714 1693570715 IN IP4 10.20.20.241 s=DevRealm c=IN IP4 10.20.20.241 t=0 0 m=audio 19762 RTP/AVP 0 100 a=rtpmap:0 PCMU/8000 a=rtpmap:100 telephone-event/8000 a=fmtp:100 0-15 a=ptime:20 a=sendrecv a=rtcp:19763 IN IP4 10.20.20.241 2023-09-01 17:47:56.464996 98.23% [NOTICE] switch_ivr_originate.c:3851 Channel [sofia/sbc/1185@voip.com] has been answered send 867 bytes to udp/[10.20.20.240]:5060 at 17:47:56.465985: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.20.20.240;branch=z9hG4bKbeaa.1aa6cdfb87aafb647f15f3a905d7effa.0 From: "1185" ;tag=1frDZcbu2 To: ;tag=r3Z6j4XDm21BN Call-ID: IlnORofF1G CSeq: 21 INVITE Contact: User-Agent: DevRealm Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 266 X-Encryption: false v=0 o=DevRealm 1693570714 1693570715 IN IP4 10.20.20.241 s=DevRealm c=IN IP4 10.20.20.241 t=0 0 m=audio 19762 RTP/AVP 0 100 a=rtpmap:0 PCMU/8000 a=rtpmap:100 telephone-event/8000 a=fmtp:100 0-15 a=ptime:20 a=rtcp:19763 IN IP4 10.20.20.241