.=======================================================================================================. | _ _ ____ _ ____ | | / \ _ __ _ __ _ _ __ _| | / ___| |_ _ ___ / ___|___ _ __ | | / _ \ | '_ \| '_ \| | | |/ _` | | | | | | | | |/ _ \ | / _ \| '_ \ | | / ___ \| | | | | | | |_| | (_| | | | |___| | |_| | __/ |__| (_) | | | | | | /_/ \_\_| |_|_| |_|\__,_|\__,_|_| \____|_|\__,_|\___|\____\___/|_| |_| | | | | ____ _____ ____ ____ __ | | | _ \_ _/ ___| / ___|___ _ __ / _| ___ _ __ ___ _ __ ___ ___ | | | |_) || || | | | / _ \| '_ \| |_ / _ \ '__/ _ \ '_ \ / __/ _ \ | | | _ < | || |___ | |__| (_) | | | | _| __/ | | __/ | | | (_| __/ | | |_| \_\|_| \____| \____\___/|_| |_|_| \___|_| \___|_| |_|\___\___| | | | | ____ _ ____ | | / ___| |_ _ ___ / ___|___ _ __ ___ ___ _ __ ___ | | | | | | | | |/ _ \ | / _ \| '_ \ / __/ _ \| '_ ` _ \ | | | |___| | |_| | __/ |__| (_) | | | | _ | (_| (_) | | | | | | | | \____|_|\__,_|\___|\____\___/|_| |_| (_) \___\___/|_| |_| |_| | | | .=======================================================================================================. 2021-07-13 19:35:28.450298 100.00% [INFO] switch_core.c:2553 FreeSWITCH Version 20.21.4-dev-5719~a57114eb17~64bit (a57114eb17 64bit) FreeSWITCH Started Max Sessions [1000] Session Rate [30] SQL [Enabled] 2021-07-13 19:35:28.450298 100.00% [CONSOLE] switch_core.c:2561 [This app Best viewed at 160x60 or more..] freeswitch@LAPTOPANDY> 2021-07-13 19:35:28.530293 100.00% [WARNING] switch_time.c:744 Increasing global timer resolution to 10ms to handle interval 10 sofia global siptrace on +OK Global siptrace on freeswitch@LAPTOPANDY> console loglevel debug +OK console log level set to DEBUG freeswitch@LAPTOPANDY> recv 596 bytes from udp/[192.168.88.116]:5050 at 16:35:45.162403: ------------------------------------------------------------------------ INVITE sip:service@192.168.88.114:5080 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.116:5050;branch=z9hG4bK-8038-1-0 From: sipp ;tag=1 To: sut Call-ID: 1-8038@192.168.88.116 CSeq: 1 INVITE Contact: sip:sipp@192.168.88.116:5050 Max-Forwards: 70 Subject: Performance Test Content-Type: application/sdp Content-Length: 206 v=0 o=user1 53655765 2353687637 IN IP4 192.168.88.116 s=- c=IN IP4 192.168.88.116 t=0 0 m=audio 6000 RTP/AVP 8 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11,16 a=sendonly 2021-07-13 19:35:45.159904 95.02% [NOTICE] switch_channel.c:1123 New Channel sofia/external/sipp@192.168.88.116:5050 [dca897b1-1740-4d7d-8657-813505d83c73] 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:581 (sofia/external/sipp@192.168.88.116:5050) Running State Change CS_NEW (Cur 1 Tot 1) 2021-07-13 19:35:45.159904 95.02% [INFO] sofia.c:10396 sofia/external/sipp@192.168.88.116:5050 receiving invite from 192.168.88.116:5050 version: 20.21.4 a57114eb17 64bit call-id: 1-8038@192.168.88.116 2021-07-13 19:35:45.159904 95.02% [DEBUG] sofia.c:7435 Channel sofia/external/sipp@192.168.88.116:5050 entering state [received][100] 2021-07-13 19:35:45.159904 95.02% [DEBUG] sofia.c:7445 Remote SDP: v=0 o=user1 53655765 2353687637 IN IP4 192.168.88.116 s=- c=IN IP4 192.168.88.116 t=0 0 m=audio 6000 RTP/AVP 8 a=rtpmap:8 PCMA/8000 a=sendonly 2021-07-13 19:35:45.159904 95.02% [DEBUG] sofia.c:7848 (sofia/external/sipp@192.168.88.116:5050) State Change CS_NEW -> CS_INIT 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:600 (sofia/external/sipp@192.168.88.116:5050) State NEW 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:581 (sofia/external/sipp@192.168.88.116:5050) Running State Change CS_INIT (Cur 1 Tot 1) 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:624 (sofia/external/sipp@192.168.88.116:5050) State INIT 2021-07-13 19:35:45.159904 95.02% [DEBUG] mod_sofia.c:97 sofia/external/sipp@192.168.88.116:5050 SOFIA INIT 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:40 sofia/external/sipp@192.168.88.116:5050 Standard INIT 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:48 (sofia/external/sipp@192.168.88.116:5050) State Change CS_INIT -> CS_ROUTING 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:624 (sofia/external/sipp@192.168.88.116:5050) State INIT going to sleep 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:581 (sofia/external/sipp@192.168.88.116:5050) Running State Change CS_ROUTING (Cur 1 Tot 1) 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_channel.c:2380 (sofia/external/sipp@192.168.88.116:5050) Callstate Change DOWN -> RINGING 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:640 (sofia/external/sipp@192.168.88.116:5050) State ROUTING 2021-07-13 19:35:45.159904 95.02% [DEBUG] mod_sofia.c:158 sofia/external/sipp@192.168.88.116:5050 SOFIA ROUTING 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:230 sofia/external/sipp@192.168.88.116:5050 Standard ROUTING 2021-07-13 19:35:45.159904 95.02% [INFO] mod_dialplan_xml.c:639 Processing sipp ->service in context public send 311 bytes to udp/[192.168.88.116]:5050 at 16:35:45.167886: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.88.116:5050;branch=z9hG4bK-8038-1-0 From: sipp ;tag=1 To: sut Call-ID: 1-8038@192.168.88.116 CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Content-Length: 0 Dialplan: sofia/external/sipp@192.168.88.116:5050 parsing [public->SIPREC] continue=false Dialplan: sofia/external/sipp@192.168.88.116:5050 Regex (PASS) [SIPREC] destination_number(service) =~ /^service$/ break=on-false Dialplan: sofia/external/sipp@192.168.88.116:5050 Action answer() Dialplan: sofia/external/sipp@192.168.88.116:5050 Action set(park_after_bridge=true) Dialplan: sofia/external/sipp@192.168.88.116:5050 Action park() 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:281 (sofia/external/sipp@192.168.88.116:5050) State Change CS_ROUTING -> CS_EXECUTE 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:640 (sofia/external/sipp@192.168.88.116:5050) State ROUTING going to sleep 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:581 (sofia/external/sipp@192.168.88.116:5050) Running State Change CS_EXECUTE (Cur 1 Tot 1) 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:647 (sofia/external/sipp@192.168.88.116:5050) State EXECUTE 2021-07-13 19:35:45.159904 95.02% [DEBUG] mod_sofia.c:213 sofia/external/sipp@192.168.88.116:5050 SOFIA EXECUTE 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_state_machine.c:323 sofia/external/sipp@192.168.88.116:5050 Standard EXECUTE EXECUTE [depth=0] sofia/external/sipp@192.168.88.116:5050 answer() 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_media.c:5648 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_media.c:5648 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_media.c:5648 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_media.c:5648 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_media.c:5703 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_media.c:3868 Set Codec sofia/external/sipp@192.168.88.116:5050 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_codec.c:111 sofia/external/sipp@192.168.88.116:5050 Original read codec set to PCMA:8 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_media.c:5942 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event. 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_media.c:5971 sofia/external/sipp@192.168.88.116:5050 Set 2833 dtmf send payload to 101 recv payload to 101 2021-07-13 19:35:45.159904 95.02% [DEBUG] switch_core_media.c:8792 AUDIO RTP [sofia/external/sipp@192.168.88.116:5050] 192.168.88.114 port 29490 -> 192.168.88.116 port 6000 codec: 8 ms: 20 2021-07-13 19:35:45.170886 95.02% [DEBUG] switch_rtp.c:4572 Starting timer [soft] 160 bytes per 20ms 2021-07-13 19:35:45.170886 95.02% [DEBUG] switch_core_media.c:9106 sofia/external/sipp@192.168.88.116:5050 Set 2833 dtmf send payload to 101 2021-07-13 19:35:45.170886 95.02% [DEBUG] switch_core_media.c:9113 sofia/external/sipp@192.168.88.116:5050 Set 2833 dtmf receive payload to 101 2021-07-13 19:35:45.170886 95.02% [DEBUG] switch_core_media.c:9136 sofia/external/sipp@192.168.88.116:5050 Set rtp dtmf delay to 40 2021-07-13 19:35:45.170886 95.02% [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/sipp@192.168.88.116:5050! 2021-07-13 19:35:45.170886 95.02% [DEBUG] switch_channel.c:3622 (sofia/external/sipp@192.168.88.116:5050) Callstate Change RINGING -> EARLY 2021-07-13 19:35:45.170886 95.02% [DEBUG] switch_core_media.c:8774 Audio params are unchanged for sofia/external/sipp@192.168.88.116:5050. 2021-07-13 19:35:45.170886 95.02% [DEBUG] mod_sofia.c:913 Local SDP sofia/external/sipp@192.168.88.116:5050: v=0 o=FreeSWITCH 1626164655 1626164656 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 29490 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=recvonly 2021-07-13 19:35:45.170886 95.02% [NOTICE] mod_dptools.c:1419 Channel [sofia/external/sipp@192.168.88.116:5050] has been answered send 969 bytes to udp/[192.168.88.116]:5050 at 16:35:45.173892: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.116:5050;branch=z9hG4bK-8038-1-0 From: sipp ;tag=1 To: sut ;tag=KrD268pQv17Ne Call-ID: 1-8038@192.168.88.116 CSeq: 1 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit 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-Type: application/sdp Content-Disposition: session Content-Length: 236 Remote-Party-ID: "service" ;party=calling;privacy=off;screen=no v=0 o=FreeSWITCH 1626164655 1626164656 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 29490 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=recvonly a=ptime:20 2021-07-13 19:35:45.170886 95.02% [DEBUG] switch_channel.c:3950 (sofia/external/sipp@192.168.88.116:5050) Callstate Change EARLY -> ACTIVE 2021-07-13 19:35:45.170886 95.02% [DEBUG] sofia.c:7435 Channel sofia/external/sipp@192.168.88.116:5050 entering state [completed][200] EXECUTE [depth=0] sofia/external/sipp@192.168.88.116:5050 set(park_after_bridge=true) 2021-07-13 19:35:45.170886 95.02% [DEBUG] mod_dptools.c:1685 SET sofia/external/sipp@192.168.88.116:5050 [park_after_bridge]=[true] EXECUTE [depth=0] sofia/external/sipp@192.168.88.116:5050 park() recv 367 bytes from udp/[192.168.88.116]:5050 at 16:35:45.176885: ------------------------------------------------------------------------ ACK sip:service@192.168.88.114:5080 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.116:5050;branch=z9hG4bK-8038-1-4 From: sipp ;tag=1 To: sut ;tag=KrD268pQv17Ne Call-ID: 1-8038@192.168.88.116 CSeq: 1 ACK Contact: sip:sipp@192.168.88.116:5050 Max-Forwards: 70 Subject: Performance Test Content-Length: 0 2021-07-13 19:35:45.179889 95.02% [DEBUG] sofia.c:7435 Channel sofia/external/sipp@192.168.88.116:5050 entering state [ready][200] freeswitch@LAPTOPANDY> show channels uuid,direction,created,created_epoch,name,state,cid_name,cid_num,ip_addr,dest,application,application_data,dialplan,context,read_codec,read_rate,read_bit_rate,write_codec,write_rate,write_bit_rate,secure,hostname,presence_id,presence_data,accountcode,callstate,callee_name,callee_num,callee_direction,call_uuid,sent_callee_name,sent_callee_num,initial_cid_name,initial_cid_num,initial_ip_addr,initial_dest,initial_dialplan,initial_context dca897b1-1740-4d7d-8657-813505d83c73,inbound,2021-07-13 19:35:45,1626194145,sofia/external/sipp@192.168.88.116:5050,CS_EXECUTE,sipp,sipp,192.168.88.116,service,park,,XML,public,PCMA,8000,64000,PCMA,8000,64000,,LAPTOPANDY,,,,ACTIVE,,,,,,,sipp,sipp,192.168.88.116,service,XML,public 1 total. freeswitch@LAPTOPANDY> uuid_hold dca897b1-1740-4d7d-8657-813505d83c73 +OK Success 2021-07-13 19:36:01.640194 87.21% [DEBUG] switch_channel.c:1975 (sofia/external/sipp@192.168.88.116:5050) Callstate Change ACTIVE -> HELD 2021-07-13 19:36:01.640194 87.21% [INFO] sofia_glue.c:1651 sofia/external/sipp@192.168.88.116:5050 sending invite call-id: (null) 2021-07-13 19:36:01.640194 87.21% [DEBUG] sofia_glue.c:1654 sofia/external/sipp@192.168.88.116:5050 sending invite version: 20.21.4 a57114eb17 64bit Local SDP: v=0 o=FreeSWITCH 1626164655 1626164657 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 29490 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=recvonly send 910 bytes to udp/[192.168.88.116]:5050 at 16:36:01.644148: ------------------------------------------------------------------------ INVITE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 236 X-FS-Support: update_display,send_info v=0 o=FreeSWITCH 1626164655 1626164657 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 29490 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=inactive a=ptime:20 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:01.647148: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 freeswitch@LAPTOPANDY> 2021-07-13 19:36:01.680146 87.21% [DEBUG] sofia.c:7435 Channel sofia/external/sipp@192.168.88.116:5050 entering state [calling][0] recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:02.151826: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 send 910 bytes to udp/[192.168.88.116]:5050 at 16:36:02.644547: ------------------------------------------------------------------------ INVITE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 236 X-FS-Support: update_display,send_info v=0 o=FreeSWITCH 1626164655 1626164657 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 29490 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=inactive a=ptime:20 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:02.648519: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:03.154710: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 send 910 bytes to udp/[192.168.88.116]:5050 at 16:36:04.645019: ------------------------------------------------------------------------ INVITE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 236 X-FS-Support: update_display,send_info v=0 o=FreeSWITCH 1626164655 1626164657 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 29490 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=inactive a=ptime:20 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:04.647021: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:05.161585: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 freeswitch@LAPTOPANDY> uuid_hold dca897b1-1740-4d7d-8657-813505d83c73 +OK Success 2021-07-13 19:36:06.780065 83.93% [INFO] sofia_glue.c:1651 sofia/external/sipp@192.168.88.116:5050 sending invite call-id: (null) 2021-07-13 19:36:06.780065 83.93% [DEBUG] sofia_glue.c:1654 sofia/external/sipp@192.168.88.116:5050 sending invite version: 20.21.4 a57114eb17 64bit Local SDP: v=0 o=FreeSWITCH 1626164655 1626164658 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 29490 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=recvonly freeswitch@LAPTOPANDY> send 910 bytes to udp/[192.168.88.116]:5050 at 16:36:08.645627: ------------------------------------------------------------------------ INVITE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 236 X-FS-Support: update_display,send_info v=0 o=FreeSWITCH 1626164655 1626164657 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 29490 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=inactive a=ptime:20 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:08.647627: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:09.163448: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:13.169635: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 send 910 bytes to udp/[192.168.88.116]:5050 at 16:36:16.646028: ------------------------------------------------------------------------ INVITE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 236 X-FS-Support: update_display,send_info v=0 o=FreeSWITCH 1626164655 1626164657 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 29490 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=inactive a=ptime:20 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:16.651031: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:17.171715: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:21.176358: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:25.179392: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:29.184347: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 send 910 bytes to udp/[192.168.88.116]:5050 at 16:36:32.646329: ------------------------------------------------------------------------ INVITE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 236 X-FS-Support: update_display,send_info v=0 o=FreeSWITCH 1626164655 1626164657 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 29490 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=inactive a=ptime:20 recv 348 bytes from udp/[192.168.88.116]:5050 at 16:36:32.649326: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKKgymZZ1pveK3B From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543288 INVITE Contact: sip:sipp@192.168.88.116:5050 Content-Type: application/sdp Content-Length: 221 recv 361 bytes from udp/[192.168.88.116]:5050 at 16:36:33.187783: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.116:5050;branch=z9hG4bK-8038-1--1 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Max-Forwards: 70 Contact: Content-Length: 0 send 471 bytes to udp/[192.168.88.116]:5050 at 16:36:33.187783: ------------------------------------------------------------------------ SIP/2.0 481 Call Does Not Exist Via: SIP/2.0/UDP 192.168.88.116:5050;branch=z9hG4bK-8038-1--1 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 send 565 bytes to udp/[192.168.88.116]:5050 at 16:36:33.645093: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKmSQD1tjtSQ9NQ Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 2021-07-13 19:36:33.650096 69.60% [DEBUG] sofia.c:7435 Channel sofia/external/sipp@192.168.88.116:5050 entering state [terminating][408] 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_channel.c:2160 (sofia/external/sipp@192.168.88.116:5050) Callstate Change HELD -> UNHELD 2021-07-13 19:36:33.650096 69.60% [NOTICE] sofia.c:8674 Hangup sofia/external/sipp@192.168.88.116:5050 [CS_EXECUTE] [RECOVERY_ON_TIMER_EXPIRE] 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_session.c:2983 sofia/external/sipp@192.168.88.116:5050 skip receive message [PHONE_EVENT] (channel is hungup already) 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:647 (sofia/external/sipp@192.168.88.116:5050) State EXECUTE going to sleep 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:581 (sofia/external/sipp@192.168.88.116:5050) Running State Change CS_HANGUP (Cur 1 Tot 1) 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:844 (sofia/external/sipp@192.168.88.116:5050) Callstate Change UNHELD -> HANGUP 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:846 (sofia/external/sipp@192.168.88.116:5050) State HANGUP 2021-07-13 19:36:33.650096 69.60% [DEBUG] mod_sofia.c:468 Channel sofia/external/sipp@192.168.88.116:5050 hanging up, cause: RECOVERY_ON_TIMER_EXPIRE 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:59 sofia/external/sipp@192.168.88.116:5050 Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:846 (sofia/external/sipp@192.168.88.116:5050) State HANGUP going to sleep 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:616 (sofia/external/sipp@192.168.88.116:5050) State Change CS_HANGUP -> CS_REPORTING 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:581 (sofia/external/sipp@192.168.88.116:5050) Running State Change CS_REPORTING (Cur 1 Tot 1) 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:932 (sofia/external/sipp@192.168.88.116:5050) State REPORTING 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:168 sofia/external/sipp@192.168.88.116:5050 Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:932 (sofia/external/sipp@192.168.88.116:5050) State REPORTING going to sleep 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:607 (sofia/external/sipp@192.168.88.116:5050) State Change CS_REPORTING -> CS_DESTROY 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_session.c:1753 Session 1 (sofia/external/sipp@192.168.88.116:5050) Locked, Waiting on external entities 2021-07-13 19:36:33.650096 69.60% [NOTICE] switch_core_session.c:1771 Session 1 (sofia/external/sipp@192.168.88.116:5050) Ended 2021-07-13 19:36:33.650096 69.60% [NOTICE] switch_core_session.c:1775 Close Channel sofia/external/sipp@192.168.88.116:5050 [CS_DESTROY] 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:735 (sofia/external/sipp@192.168.88.116:5050) Running State Change CS_DESTROY (Cur 0 Tot 1) 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:745 (sofia/external/sipp@192.168.88.116:5050) State DESTROY 2021-07-13 19:36:33.650096 69.60% [DEBUG] mod_sofia.c:379 sofia/external/sipp@192.168.88.116:5050 SOFIA DESTROY 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:175 sofia/external/sipp@192.168.88.116:5050 Standard DESTROY 2021-07-13 19:36:33.650096 69.60% [DEBUG] switch_core_state_machine.c:745 (sofia/external/sipp@192.168.88.116:5050) State DESTROY going to sleep send 565 bytes to udp/[192.168.88.116]:5050 at 16:36:34.645333: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKmSQD1tjtSQ9NQ Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 send 565 bytes to udp/[192.168.88.116]:5050 at 16:36:36.646240: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKmSQD1tjtSQ9NQ Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 send 565 bytes to udp/[192.168.88.116]:5050 at 16:36:40.646760: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKmSQD1tjtSQ9NQ Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 send 565 bytes to udp/[192.168.88.116]:5050 at 16:36:44.647540: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKmSQD1tjtSQ9NQ Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 send 565 bytes to udp/[192.168.88.116]:5050 at 16:36:48.648155: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKmSQD1tjtSQ9NQ Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 send 565 bytes to udp/[192.168.88.116]:5050 at 16:36:52.648422: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKmSQD1tjtSQ9NQ Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 send 565 bytes to udp/[192.168.88.116]:5050 at 16:36:56.648772: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKmSQD1tjtSQ9NQ Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 send 565 bytes to udp/[192.168.88.116]:5050 at 16:37:00.649119: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKmSQD1tjtSQ9NQ Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 send 565 bytes to udp/[192.168.88.116]:5050 at 16:37:04.649756: ------------------------------------------------------------------------ BYE sip:sipp@192.168.88.116:5050 SIP/2.0 Via: SIP/2.0/UDP 192.168.88.114:5080;rport;branch=z9hG4bKmSQD1tjtSQ9NQ Max-Forwards: 70 From: sut ;tag=KrD268pQv17Ne To: sipp ;tag=1 Call-ID: 1-8038@192.168.88.116 CSeq: 38543289 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/20.21.4-dev-5719~a57114eb17~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0