------------------------------------------------------------------------ recv 1435 bytes from tls/[31.102.111.132]:44832 at 11:18:49.558706: ------------------------------------------------------------------------ INVITE sip:213@pbx-186.smallunix.net SIP/2.0 Via: SIP/2.0/TLS 192.168.128.158:51018;branch=z9hG4bK8boxBH49PYetiBQK;rport Contact: Max-Forwards: 70 From: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY Supported: replaces, path To: Content-Type: application/sdp Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 1 INVITE User-Agent: Acrobits Softphone Business/3.8.2 Content-Length: 860 v=0 o=- 7334585884 37217 IN IP4 172.26.170.170 s=hdabkzi c=IN IP4 192.168.128.158 t=0 0 m=audio 59416 RTP/SAVP 103 101 a=rtpmap:101 telephone-event/8000 a=rtpmap:103 opus/48000/2 a=fmtp:101 0-15 a=fmtp:103 maxplaybackrate=16000;maxaveragebitrate=24000;useinbandfec=1;usedtx=1 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:yYHBMOGW8Ltl9qsl0E7Rx+lAaUDSBRAN60y2I8tK a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:2AAmgPkHkRKzBKzgbZ1mOKsrKyuFePpPoqmG98JC a=ptime:20 a=zrtp-hash:1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 a=sendrecv m=audio 59416 RTP/AVP 103 101 a=rtpmap:101 telephone-event/8000 a=rtpmap:103 opus/48000/2 a=fmtp:101 0-15 a=fmtp:103 maxplaybackrate=16000;maxaveragebitrate=24000;useinbandfec=1;usedtx=1 a=ptime:20 a=zrtp-hash:1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 a=sendrecv ------------------------------------------------------------------------ 2018-09-20 11:18:49.558485 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/207@pbx-186.smallunix.net [265825de-d70a-4217-909d-341517d2425e] 2018-09-20 11:18:49.558485 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/207@pbx-186.smallunix.net) Running State Change CS_NEW (Cur 1 Tot 243) 2018-09-20 11:18:49.558485 [DEBUG] sofia.c:10080 sofia/internal/207@pbx-186.smallunix.net receiving invite from 31.102.111.132:44832 version: 1.8.1 -2-4f54cff 64bit 2018-09-20 11:18:49.558485 [DEBUG] switch_core_media.c:495 Found audio zrtp-hash; setting r_sdp_audio_zrtp_hash=1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 2018-09-20 11:18:49.558485 [DEBUG] sofia.c:10251 IP 31.102.111.132 Rejected by acl "domains". Falling back to Digest auth. 2018-09-20 11:18:49.558485 [WARNING] sofia_reg.c:1793 SIP auth challenge (INVITE) on sofia profile 'internal' for [213@pbx-186.smallunix.net] from ip 31.102.111.132 2018-09-20 11:18:49.558485 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/207@pbx-186.smallunix.net) State NEW send 877 bytes to tls/[31.102.111.132]:44832 at 11:18:49.561003: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/TLS 192.168.128.158:51018;branch=z9hG4bK8boxBH49PYetiBQK;rport=44832;received=31.102.111.132 From: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 To: ;tag=KmFSaS5j48USp Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 1 INVITE User-Agent: SWO-PBX-1.0 Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Proxy-Authenticate: Digest realm="pbx-186.smallunix.net", nonce="a47174b4-0e85-44e5-a9c3-6e964cd7231a", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ 2018-09-20 11:18:49.558485 [DEBUG] sofia.c:2413 detaching session 265825de-d70a-4217-909d-341517d2425e recv 414 bytes from tls/[31.102.111.132]:44832 at 11:18:49.625037: ------------------------------------------------------------------------ ACK sip:213@pbx-186.smallunix.net SIP/2.0 Via: SIP/2.0/TLS 192.168.128.158:51018;branch=z9hG4bK8boxBH49PYetiBQK;rport Max-Forwards: 70 Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 From: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 To: ;tag=KmFSaS5j48USp CSeq: 1 ACK User-Agent: Acrobits Softphone Business/3.8.2 Content-Length: 0 ------------------------------------------------------------------------ recv 1701 bytes from tls/[31.102.111.132]:44832 at 11:18:49.625210: ------------------------------------------------------------------------ INVITE sip:213@pbx-186.smallunix.net SIP/2.0 Via: SIP/2.0/TLS 192.168.128.158:51018;branch=z9hG4bKrXUOdwO6LSgwFeG6;rport Contact: Max-Forwards: 70 From: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY Supported: replaces, path To: Content-Type: application/sdp Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 2 INVITE Proxy-Authorization: Digest username="207",realm="pbx-186.smallunix.net",algorithm=MD5,uri="sip:213@pbx-186.smallunix.net",nonce="a47174b4-0e85-44e5-a9c3-6e964cd7231a",qop=auth,cnonce="W6Nl+bkl4YHe20K4yUEVcpgz",nc=00000001,response="8440ad0640ae1ed8a1d26977d71a589b" User-Agent: Acrobits Softphone Business/3.8.2 Content-Length: 860 v=0 o=- 7334585884 37217 IN IP4 172.26.170.170 s=hdabkzi c=IN IP4 192.168.128.158 t=0 0 m=audio 59416 RTP/SAVP 103 101 a=rtpmap:101 telephone-event/8000 a=rtpmap:103 opus/48000/2 a=fmtp:101 0-15 a=fmtp:103 maxplaybackrate=16000;maxaveragebitrate=24000;useinbandfec=1;usedtx=1 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:yYHBMOGW8Ltl9qsl0E7Rx+lAaUDSBRAN60y2I8tK a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:2AAmgPkHkRKzBKzgbZ1mOKsrKyuFePpPoqmG98JC a=ptime:20 a=zrtp-hash:1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 a=sendrecv m=audio 59416 RTP/AVP 103 101 a=rtpmap:101 telephone-event/8000 a=rtpmap:103 opus/48000/2 a=fmtp:101 0-15 a=fmtp:103 maxplaybackrate=16000;maxaveragebitrate=24000;useinbandfec=1;usedtx=1 a=ptime:20 a=zrtp-hash:1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 a=sendrecv ------------------------------------------------------------------------ 2018-09-20 11:18:49.618505 [DEBUG] sofia.c:2522 Re-attaching to session 265825de-d70a-4217-909d-341517d2425e 2018-09-20 11:18:49.638482 [DEBUG] sofia.c:10080 sofia/internal/207@pbx-186.smallunix.net receiving invite from 31.102.111.132:44832 version: 1.8.1 -2-4f54cff 64bit 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:495 Found audio zrtp-hash; setting r_sdp_audio_zrtp_hash=1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 2018-09-20 11:18:49.638482 [DEBUG] sofia.c:10251 IP 31.102.111.132 Rejected by acl "domains". Falling back to Digest auth. 2018-09-20 11:18:49.638482 [DEBUG] sofia.c:11388 Setting NAT mode based on nat.auto 2018-09-20 11:18:49.638482 [DEBUG] sofia.c:7291 Channel sofia/internal/207@pbx-186.smallunix.net entering state [received][100] 2018-09-20 11:18:49.638482 [DEBUG] sofia.c:7301 Remote SDP: v=0 o=- 7334585884 37217 IN IP4 172.26.170.170 s=hdabkzi c=IN IP4 192.168.128.158 t=0 0 m=audio 59416 RTP/SAVP 103 101 a=rtpmap:103 opus/48000/2 a=fmtp:103 maxplaybackrate=16000;maxaveragebitrate=24000;useinbandfec=1;usedtx=1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:yYHBMOGW8Ltl9qsl0E7Rx+lAaUDSBRAN60y2I8tK a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:2AAmgPkHkRKzBKzgbZ1mOKsrKyuFePpPoqmG98JC a=ptime:20 a=zrtp-hash:1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 m=audio 59416 RTP/AVP 103 101 a=rtpmap:103 opus/48000/2 a=fmtp:103 maxplaybackrate=16000;maxaveragebitrate=24000;useinbandfec=1;usedtx=1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=zrtp-hash:1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:495 Found audio zrtp-hash; setting r_sdp_audio_zrtp_hash=1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 2018-09-20 11:18:49.638482 [DEBUG] sofia.c:7695 (sofia/internal/207@pbx-186.smallunix.net) State Change CS_NEW -> CS_INIT 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/207@pbx-186.smallunix.net) Running State Change CS_INIT (Cur 1 Tot 243) 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/207@pbx-186.smallunix.net) State INIT 2018-09-20 11:18:49.638482 [DEBUG] mod_sofia.c:93 sofia/internal/207@pbx-186.smallunix.net SOFIA INIT 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:40 sofia/internal/207@pbx-186.smallunix.net Standard INIT 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/207@pbx-186.smallunix.net) State Change CS_INIT -> CS_ROUTING 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/207@pbx-186.smallunix.net) State INIT going to sleep 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/207@pbx-186.smallunix.net) Running State Change CS_ROUTING (Cur 1 Tot 243) 2018-09-20 11:18:49.638482 [DEBUG] switch_channel.c:2249 (sofia/internal/207@pbx-186.smallunix.net) Callstate Change DOWN -> RINGING 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/207@pbx-186.smallunix.net) State ROUTING 2018-09-20 11:18:49.638482 [DEBUG] mod_sofia.c:154 sofia/internal/207@pbx-186.smallunix.net SOFIA ROUTING 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:236 sofia/internal/207@pbx-186.smallunix.net Standard ROUTING 2018-09-20 11:18:49.638482 [INFO] mod_dialplan_xml.c:637 Processing Andrea Mazzeo <207>->213 in context pbx-186.smallunix.net send 366 bytes to tls/[31.102.111.132]:44832 at 11:18:49.644397: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/TLS 192.168.128.158:51018;branch=z9hG4bKrXUOdwO6LSgwFeG6;rport=44832;received=31.102.111.132 From: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 To: Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 2 INVITE User-Agent: SWO-PBX-1.0 Content-Length: 0 ------------------------------------------------------------------------ 2018-09-20 11:18:49.638482 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/213@192.168.129.176:50560 [7018033a-46f6-4613-bb20-e851e7a944ea] 2018-09-20 11:18:49.638482 [DEBUG] mod_sofia.c:5028 (sofia/internal/213@192.168.129.176:50560) State Change CS_NEW -> CS_INIT 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:371 Passing a-leg remote zrtp-hash (audio) to b-leg 2018-09-20 11:18:49.638482 [DEBUG] mod_sofia.c:5098 [zrtp_passthru] Setting a-leg inherit_codec=true 2018-09-20 11:18:49.638482 [DEBUG] mod_sofia.c:5101 [zrtp_passthru] Setting b-leg absolute_codec_string='mod_opus.opus@48000h@20i@2c,mod_opus.opus@48000h@20i@2c' 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/213@192.168.129.176:50560) Running State Change CS_INIT (Cur 2 Tot 244) 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/213@192.168.129.176:50560) State INIT 2018-09-20 11:18:49.638482 [DEBUG] mod_sofia.c:93 sofia/internal/213@192.168.129.176:50560 SOFIA INIT 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local audio crypto Key [1 AEAD_AES_256_GCM_8 inline:JFtFyIfqr9s0CLLhMzZM2DBij4W8ZMBwCilg3OJjLmgbjgJ8m6MTtO7ZXu0] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local video crypto Key [1 AEAD_AES_256_GCM_8 inline:Rbu9DV5+/pYb4d7ivJG4ogq1kUDv/5TBJUGpY4hM50f/phGioPye91c5yFY] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local text crypto Key [1 AEAD_AES_256_GCM_8 inline:22oUKP3r0QHQMzes0tdAJQg6jF2BizX25Hv3bRwjkDD2+tCtJ+oShfHQArA] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local audio crypto Key [2 AEAD_AES_128_GCM_8 inline:euTvSAIt4JHpKh9T3O4DpkvwCazQ5SMS8KP+YA] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local video crypto Key [2 AEAD_AES_128_GCM_8 inline:Fr4hI3aZ+ky9R7A3M+qrYpjS/vEuZ55ug7b5LA] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local text crypto Key [2 AEAD_AES_128_GCM_8 inline:8jlaH6U6DYVAgeYlX+mxDyUglONXRNuetodKTA] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local audio crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:WhULTfxLspM+mMa1h2hvrZpfCe1rz6EXRhPPx0JPXUTPNaPfnMHaXcehHKWC7g] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local video crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:j/UIv0xFR2RZqlB4JTGwl4A0yba88CZBoxui9cMmrobDoju15BRUMafiQtT/WA] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local text crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:WRJ19r8HBwnVemruwVB/i4Io5w5kghRClw7HlA5sQfMde2ooBYtBQLdW6rLabw] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local audio crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:DeT7BKdqueIdG4nGrNeWiGWUaVYguyEcm4L++6fGyKzixTLX6jo] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local video crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:M0h8DHq3YaCW3QMLfliR0DzftCsw6vd3TEhJ9QxABV/UZUk2tQw] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local text crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:8de4Q3fyynj80xWPVrbPaNPNlsex0YDZZCINcZJTKr+FfYCz5W8] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local audio crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:aVXcsQjwL27rlbVRYc+BvTFoZ6e5hpdtWGx3E4ro] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local video crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:oG85AwdGp6JVzNK5ylSRmwfwL4MeBeSPDW6ju44s] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local text crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:jZWe5iW4y1iXAFZg6ary4FUyECG774bBs1sd0k7K] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local audio crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:X3wZWjOAR8vgVriREgZTWijl5HaDXkF5XDRauP66d8FdY4u3K2kW/NKTpXEg1g] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local video crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:oTFDyJ01aTRuJXNZhuxXLKaXcFGkmThl83VybKWNkatOCkiJLeIB2bjXysWLYQ] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local text crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:xRYeprP5H5hf4gdaxP3Q27kijHpesi7LAr7BmPU5wKlIBrob1mZm6oT9J2mY0Q] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local audio crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:nMOsOBO6y8zdb9akWjbfP4E20BVcsBSxF6mqd1ezRp3oAzqkC14] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local video crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:ESPqiePeGp3gWQYenzF5KE2xOjQJPXTMGquAAWp/f+6UlEQ3DTQ] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local text crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:EHZFIjh/vaUbjuEONSeBl0dYSJSrRpeYHv3d/2VyXJi36sUSsaA] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local audio crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:hsr/O+i9JCXiZN4rM3zXLt1nYxW4MGQqmkpwoxBx] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local video crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:lxn74blOHNKJDAEC2/YJUP3mK3+WIBrHdpanBywB] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local text crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:v2W5TbZtfFLOa4IkRJEnq3N2wf3yoLHkhEJMo9hy] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local audio crypto Key [9 AES_CM_128_NULL_AUTH inline:2c+6dPgCx1iwloN9j9oBSgo9/o+4pFKQO6zGMRg4] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local video crypto Key [9 AES_CM_128_NULL_AUTH inline:mXF8x9taBY1HldtBHCfeSGnX6mYxS0oPJxV0Tovg] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:1207 Set Local text crypto Key [9 AES_CM_128_NULL_AUTH inline:1I2chO7YGs2LENlIRLXSBfEgiVyC9fo3J2c2OuDZ] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_media.c:9886 Adding audio a=zrtp-hash:1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 2018-09-20 11:18:49.638482 [DEBUG] sofia_glue.c:1268 sip:213@49.172.161.250:50560;rinstance=292D7641;transport=tls Setting proxy route to sofia/internal/213@192.168.129.176:50560 2018-09-20 11:18:49.638482 [DEBUG] sofia_glue.c:1299 sofia/internal/213@192.168.129.176:50560 sending invite version: 1.8.1 -2-4f54cff 64bit Local SDP: v=0 o=FreeSWITCH 1537413935 1537413936 IN IP4 31.102.111.134 s=FreeSWITCH c=IN IP4 31.102.111.134 t=0 0 m=audio 21194 RTP/SAVP 102 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:101 telephone-event/48000 a=fmtp:101 0-16 a=crypto:1 AEAD_AES_256_GCM_8 inline:JFtFyIfqr9s0CLLhMzZM2DBij4W8ZMBwCilg3OJjLmgbjgJ8m6MTtO7ZXu0 a=crypto:2 AEAD_AES_128_GCM_8 inline:euTvSAIt4JHpKh9T3O4DpkvwCazQ5SMS8KP+YA a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:WhULTfxLspM+mMa1h2hvrZpfCe1rz6EXRhPPx0JPXUTPNaPfnMHaXcehHKWC7g a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:DeT7BKdqueIdG4nGrNeWiGWUaVYguyEcm4L++6fGyKzixTLX6jo a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:aVXcsQjwL27rlbVRYc+BvTFoZ6e5hpdtWGx3E4ro a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:X3wZWjOAR8vgVriREgZTWijl5HaDXkF5XDRauP66d8FdY4u3K2kW/NKTpXEg1g a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:nMOsOBO6y8zdb9akWjbfP4E20BVcsBSxF6mqd1ezRp3oAzqkC14 a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:hsr/O+i9JCXiZN4rM3zXLt1nYxW4MGQqmkpwoxBx a=crypto:9 AES_CM_128_NULL_AUTH inline:2c+6dPgCx1iwloN9j9oBSgo9/o+4pFKQO6zGMRg4 a=rtpmap:13 CN/48000 a=ptime:20 a=zrtp-hash:1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 a=sendrecv 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:40 sofia/internal/213@192.168.129.176:50560 Standard INIT 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/213@192.168.129.176:50560) State Change CS_INIT -> CS_ROUTING 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/213@192.168.129.176:50560) State INIT going to sleep 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/213@192.168.129.176:50560) Running State Change CS_ROUTING (Cur 2 Tot 244) 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/213@192.168.129.176:50560) State ROUTING 2018-09-20 11:18:49.638482 [DEBUG] mod_sofia.c:154 sofia/internal/213@192.168.129.176:50560 SOFIA ROUTING 2018-09-20 11:18:49.638482 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/213@192.168.129.176:50560) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/213@192.168.129.176:50560) State ROUTING going to sleep send 2339 bytes to tls/[49.172.161.250]:50560 at 11:18:49.655563: ------------------------------------------------------------------------ INVITE sip:213@192.168.129.176:50560;rinstance=292D7641;transport=tls SIP/2.0 Via: SIP/2.0/TLS 31.102.111.134;branch=z9hG4bK5XQ58Qg22jNjr Route: ;rinstance=292D7641;transport=tls Max-Forwards: 69 From: "Andrea Mazzeo" ;tag=N61aeF7Syt8yD To: Call-ID: 068e6451-3759-1237-c4ba-000c2919b6af CSeq: 128381500 INVITE Contact: User-Agent: SWO-PBX-1.0 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 1286 X-FS-Support: update_display,send_info Remote-Party-ID: "Andrea Mazzeo" ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1537413935 1537413936 IN IP4 31.102.111.134 s=FreeSWITCH c=IN IP4 31.102.111.134 t=0 0 m=audio 21194 RTP/SAVP 102 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:101 telephone-event/48000 a=fmtp:101 0-16 a=rtpmap:13 CN/48000 a=crypto:1 AEAD_AES_256_GCM_8 inline:JFtFyIfqr9s0CLLhMzZM2DBij4W8ZMBwCilg3OJjLmgbjgJ8m6MTtO7ZXu0 a=crypto:2 AEAD_AES_128_GCM_8 inline:euTvSAIt4JHpKh9T3O4DpkvwCazQ5SMS8KP+YA a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:WhULTfxLspM+mMa1h2hvrZpfCe1rz6EXRhPPx0JPXUTPNaPfnMHaXcehHKWC7g a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:DeT7BKdqueIdG4nGrNeWiGWUaVYguyEcm4L++6fGyKzixTLX6jo a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:aVXcsQjwL27rlbVRYc+BvTFoZ6e5hpdtWGx3E4ro a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:X3wZWjOAR8vgVriREgZTWijl5HaDXkF5XDRauP66d8FdY4u3K2kW/NKTpXEg1g a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:nMOsOBO6y8zdb9akWjbfP4E20BVcsBSxF6mqd1ezRp3oAzqkC14 a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:hsr/O+i9JCXiZN4rM3zXLt1nYxW4MGQqmkpwoxBx a=crypto:9 AES_CM_128_NULL_AUTH inline:2c+6dPgCx1iwloN9j9oBSgo9/o+4pFKQO6zGMRg4 a=ptime:20 a=zrtp-hash:1.10 1C4E69454FCD1BB7AE3439803C631BEDF11215DDF0BDC2351399921DECF8EEE1 ------------------------------------------------------------------------ 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/213@192.168.129.176:50560) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 244) 2018-09-20 11:18:49.638482 [DEBUG] sofia.c:7291 Channel sofia/internal/213@192.168.129.176:50560 entering state [calling][0] 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/213@192.168.129.176:50560) State CONSUME_MEDIA 2018-09-20 11:18:49.638482 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/213@192.168.129.176:50560) State CONSUME_MEDIA going to sleep recv 354 bytes from tls/[49.172.161.250]:50560 at 11:18:49.812287: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/TLS 31.102.111.134;branch=z9hG4bK5XQ58Qg22jNjr From: "Andrea Mazzeo" ;tag=N61aeF7Syt8yD Call-ID: 068e6451-3759-1237-c4ba-000c2919b6af CSeq: 128381500 INVITE To: User-Agent: Acrobits Softphone Business/3.8.2 Content-Length: 0 ------------------------------------------------------------------------ recv 532 bytes from tls/[49.172.161.250]:50560 at 11:18:49.903792: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/TLS 31.102.111.134;branch=z9hG4bK5XQ58Qg22jNjr Contact: From: "Andrea Mazzeo" ;tag=N61aeF7Syt8yD Call-ID: 068e6451-3759-1237-c4ba-000c2919b6af CSeq: 128381500 INVITE To: ;tag=09FF027B916C5C2FCA338126B4A6840A Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY Supported: replaces, path User-Agent: Acrobits Softphone Business/3.8.2 Content-Length: 0 ------------------------------------------------------------------------ 2018-09-20 11:18:49.898470 [DEBUG] sofia.c:7291 Channel sofia/internal/213@192.168.129.176:50560 entering state [proceeding][180] 2018-09-20 11:18:49.898470 [NOTICE] sofia.c:7401 Ring-Ready sofia/internal/213@192.168.129.176:50560! 2018-09-20 11:18:49.898470 [DEBUG] switch_channel.c:3354 (sofia/internal/213@192.168.129.176:50560) Callstate Change DOWN -> RINGING 2018-09-20 11:18:49.898470 [NOTICE] mod_sofia.c:2419 Ring-Ready sofia/internal/207@pbx-186.smallunix.net! send 879 bytes to tls/[31.102.111.132]:44832 at 11:18:49.916815: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/TLS 192.168.128.158:51018;branch=z9hG4bKrXUOdwO6LSgwFeG6;rport=44832;received=31.102.111.132 From: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 To: ;tag=mX8Hcmpp1Hjcj Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 2 INVITE Contact: User-Agent: SWO-PBX-1.0 Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Length: 0 Remote-Party-ID: "Outbound Call" ;party=calling;privacy=off;screen=no ------------------------------------------------------------------------ 2018-09-20 11:18:49.898470 [DEBUG] sofia.c:7291 Channel sofia/internal/207@pbx-186.smallunix.net entering state [early][180] 2018-09-20 11:18:49.898470 [NOTICE] switch_ivr_originate.c:527 Ring Ready sofia/internal/207@pbx-186.smallunix.net! ------------------------------------------------------------------------ recv 972 bytes from tls/[49.172.161.250]:50560 at 11:18:51.504614: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/TLS 31.102.111.134;branch=z9hG4bK5XQ58Qg22jNjr Contact: From: "Andrea Mazzeo" ;tag=N61aeF7Syt8yD Call-ID: 068e6451-3759-1237-c4ba-000c2919b6af CSeq: 128381500 INVITE To: ;tag=09FF027B916C5C2FCA338126B4A6840A Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY Supported: replaces, path Content-Type: application/sdp User-Agent: Acrobits Softphone Business/3.8.2 Content-Length: 412 v=0 o=- 9845989873 23518 IN IP4 172.26.170.170 s=ocpiwdk c=IN IP4 192.168.129.176 t=0 0 m=audio 65018 RTP/SAVP 102 a=rtpmap:102 opus/48000/2 a=fmtp:102 maxplaybackrate=16000;maxaveragebitrate=30000;useinbandfec=1 a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:/t/7xNzYwWEti93oblybP9b/Vv/PW0Z/5wmEbdRl a=ptime:20 a=zrtp-hash:1.10 13F3D6124818E7CB1E3C9ED58FDFC6194163F66FBCA9FCA3E40AA8F6D26D12B0 a=sendrecv ------------------------------------------------------------------------ 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:495 Found audio zrtp-hash; setting r_sdp_audio_zrtp_hash=1.10 13F3D6124818E7CB1E3C9ED58FDFC6194163F66FBCA9FCA3E40AA8F6D26D12B0 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:371 Passing a-leg remote zrtp-hash (audio) to b-leg 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:377 Passing b-leg remote zrtp-hash (audio) to a-leg 2018-09-20 11:18:51.498484 [DEBUG] sofia.c:7291 Channel sofia/internal/213@192.168.129.176:50560 entering state [completing][200] 2018-09-20 11:18:51.498484 [DEBUG] sofia.c:7301 Remote SDP: v=0 o=- 9845989873 23518 IN IP4 172.26.170.170 s=ocpiwdk c=IN IP4 192.168.129.176 t=0 0 m=audio 65018 RTP/SAVP 102 a=rtpmap:102 opus/48000/2 a=fmtp:102 maxplaybackrate=16000;maxaveragebitrate=30000;useinbandfec=1 a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:/t/7xNzYwWEti93oblybP9b/Vv/PW0Z/5wmEbdRl a=ptime:20 a=zrtp-hash:1.10 13F3D6124818E7CB1E3C9ED58FDFC6194163F66FBCA9FCA3E40AA8F6D26D12B0 2018-09-20 11:18:51.498484 [INFO] switch_channel.c:3382 sofia/internal/213@192.168.129.176:50560 Activating ZRTP passthru mode. 2018-09-20 11:18:51.498484 [NOTICE] sofia.c:7304 Pre-Answer sofia/internal/213@192.168.129.176:50560! 2018-09-20 11:18:51.498484 [DEBUG] switch_channel.c:3482 (sofia/internal/213@192.168.129.176:50560) Callstate Change RINGING -> EARLY 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:495 Found audio zrtp-hash; setting r_sdp_audio_zrtp_hash=1.10 13F3D6124818E7CB1E3C9ED58FDFC6194163F66FBCA9FCA3E40AA8F6D26D12B0 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:371 Passing a-leg remote zrtp-hash (audio) to b-leg 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:377 Passing b-leg remote zrtp-hash (audio) to a-leg send 455 bytes to tls/[49.172.161.250]:50560 at 11:18:51.508398: ------------------------------------------------------------------------ ACK sip:213@192.168.129.176:50560;transport=tls SIP/2.0 Via: SIP/2.0/TLS 31.102.111.134;branch=z9hG4bK7FaQcej9v41QF Max-Forwards: 70 From: "Andrea Mazzeo" ;tag=N61aeF7Syt8yD To: ;tag=09FF027B916C5C2FCA338126B4A6840A Call-ID: 068e6451-3759-1237-c4ba-000c2919b6af CSeq: 128381500 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2018-09-20 11:18:51.498484 [DEBUG] sofia.c:7291 Channel sofia/internal/213@192.168.129.176:50560 entering state [ready][200] 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:495 Found audio zrtp-hash; setting r_sdp_audio_zrtp_hash=1.10 13F3D6124818E7CB1E3C9ED58FDFC6194163F66FBCA9FCA3E40AA8F6D26D12B0 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:371 Passing a-leg remote zrtp-hash (audio) to b-leg 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:377 Passing b-leg remote zrtp-hash (audio) to a-leg 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:1804 looking for crypto suite [AEAD_AES_256_GCM_8] in [5 AES_CM_128_HMAC_SHA1_80 inline:/t/7xNzYwWEti93oblybP9b/Vv/PW0Z/5wmEbdRl] 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:1804 looking for crypto suite [AEAD_AES_128_GCM_8] in [5 AES_CM_128_HMAC_SHA1_80 inline:/t/7xNzYwWEti93oblybP9b/Vv/PW0Z/5wmEbdRl] 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:1804 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [5 AES_CM_128_HMAC_SHA1_80 inline:/t/7xNzYwWEti93oblybP9b/Vv/PW0Z/5wmEbdRl] 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:1804 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [5 AES_CM_128_HMAC_SHA1_80 inline:/t/7xNzYwWEti93oblybP9b/Vv/PW0Z/5wmEbdRl] 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:1804 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [5 AES_CM_128_HMAC_SHA1_80 inline:/t/7xNzYwWEti93oblybP9b/Vv/PW0Z/5wmEbdRl] 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:1809 Found suite AES_CM_128_HMAC_SHA1_80 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:1877 Set Remote Key [5 AES_CM_128_HMAC_SHA1_80 inline:/t/7xNzYwWEti93oblybP9b/Vv/PW0Z/5wmEbdRl] 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:5470 Audio Codec Compare [opus:102:48000:20:0:1]/[opus:116:48000:20:0:2] 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:5525 Audio Codec Compare [opus:116:48000:20:0:2] ++++ is saved as a match 2018-09-20 11:18:51.498484 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps] 2018-09-20 11:18:51.498484 [DEBUG] mod_opus.c:617 Opus encoder: set bitrate to local settings [72000bps] 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:3773 Set Codec sofia/internal/213@192.168.129.176:50560 opus/48000 20 ms 960 samples 0 bits 1 channels 2018-09-20 11:18:51.498484 [DEBUG] switch_core_codec.c:111 sofia/internal/213@192.168.129.176:50560 Original read codec set to opus:116 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:5794 No 2833 in SDP. Disable 2833 dtmf and switch to INFO 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:8503 AUDIO RTP [sofia/internal/213@192.168.129.176:50560] 31.102.111.134 port 21194 -> 192.168.129.176 port 65018 codec: 102 ms: 20 2018-09-20 11:18:51.498484 [DEBUG] switch_rtp.c:4282 Starting timer [soft] 960 bytes per 20ms 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:8837 sofia/internal/213@192.168.129.176:50560 Set rtp dtmf delay to 40 2018-09-20 11:18:51.498484 [INFO] switch_rtp.c:4086 Activating audio Secure RTP SEND 2018-09-20 11:18:51.498484 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80 2018-09-20 11:18:51.498484 [INFO] switch_rtp.c:4064 Activating audio Secure RTP RECV 2018-09-20 11:18:51.498484 [INFO] switch_core_media.c:8855 Activating ZRTP PROXY MODE 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:8856 Disable NOTIMER_DURING_BRIDGE 2018-09-20 11:18:51.498484 [DEBUG] switch_core_media.c:8858 Activating audio UDPTL mode 2018-09-20 11:18:51.498484 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80 2018-09-20 11:18:51.498484 [NOTICE] sofia.c:8429 Channel [sofia/internal/213@192.168.129.176:50560] has been answered send 1394 bytes to tls/[31.102.111.132]:44832 at 11:18:51.529779: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/TLS 192.168.128.158:51018;branch=z9hG4bKrXUOdwO6LSgwFeG6;rport=44832;received=31.102.111.132 From: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 To: ;tag=mX8Hcmpp1Hjcj Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 2 INVITE Contact: User-Agent: SWO-PBX-1.0 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Session-Expires: 120;refresher=uas Content-Type: application/sdp Content-Disposition: session Content-Length: 446 Remote-Party-ID: "Outbound Call" ;party=calling;privacy=off;screen=no v=0 o=FreeSWITCH 1537411117 1537411118 IN IP4 31.102.111.134 s=FreeSWITCH c=IN IP4 31.102.111.134 t=0 0 m=audio 24014 RTP/SAVP 103 101 a=rtpmap:103 opus/48000/2 a=fmtp:103 useinbandfec=1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=zrtp-hash:1.10 13F3D6124818E7CB1E3C9ED58FDFC6194163F66FBCA9FCA3E40AA8F6D26D12B0 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:waW2eGxCtNY+qbRx/MS+yPvOAFZGeAYjZIQ58eXL m=audio 0 RTP/AVP 19 ------------------------------------------------------------------------ 2018-09-20 11:18:51.518472 [DEBUG] switch_channel.c:3781 (sofia/internal/207@pbx-186.smallunix.net) Callstate Change EARLY -> ACTIVE 2018-09-20 11:18:51.518472 [DEBUG] sofia.c:7291 Channel sofia/internal/207@pbx-186.smallunix.net entering state [completed][200] 2018-09-20 11:18:51.518472 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/213@192.168.129.176:50560] 2018-09-20 11:18:51.518472 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/213@192.168.129.176:50560] 2018-09-20 11:18:51.518472 [DEBUG] switch_ivr_bridge.c:1744 (sofia/internal/213@192.168.129.176:50560) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2018-09-20 11:18:51.518472 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/213@192.168.129.176:50560) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 244) 2018-09-20 11:18:51.518472 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/213@192.168.129.176:50560) State EXCHANGE_MEDIA 2018-09-20 11:18:51.518472 [DEBUG] mod_sofia.c:645 SOFIA EXCHANGE_MEDIA ------------------------------------------------------------------------ send 1394 bytes to tls/[31.102.111.132]:44832 at 11:18:52.030377: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/TLS 192.168.128.158:51018;branch=z9hG4bKrXUOdwO6LSgwFeG6;rport=44832;received=31.102.111.132 From: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 To: ;tag=mX8Hcmpp1Hjcj Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 2 INVITE Contact: User-Agent: SWO-PBX-1.0 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Session-Expires: 120;refresher=uas Content-Type: application/sdp Content-Disposition: session Content-Length: 446 Remote-Party-ID: "Outbound Call" ;party=calling;privacy=off;screen=no v=0 o=FreeSWITCH 1537411117 1537411118 IN IP4 31.102.111.134 s=FreeSWITCH c=IN IP4 31.102.111.134 t=0 0 m=audio 24014 RTP/SAVP 103 101 a=rtpmap:103 opus/48000/2 a=fmtp:103 useinbandfec=1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=zrtp-hash:1.10 13F3D6124818E7CB1E3C9ED58FDFC6194163F66FBCA9FCA3E40AA8F6D26D12B0 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:waW2eGxCtNY+qbRx/MS+yPvOAFZGeAYjZIQ58eXL m=audio 0 RTP/AVP 19 ------------------------------------------------------------------------ 2018-09-20 11:18:52.038459 [INFO] switch_rtp.c:7492 Auto Changing audio port from 192.168.129.176:65018 to 49.172.161.250:65018 recv 426 bytes from tls/[31.102.111.132]:44832 at 11:18:52.289637: ------------------------------------------------------------------------ ACK sip:213@31.102.111.134:5061;transport=tls SIP/2.0 Via: SIP/2.0/TLS 192.168.128.158:51018;branch=z9hG4bKoFuJF4Cb9Hd4lyTu;rport Max-Forwards: 70 To: ;tag=mX8Hcmpp1Hjcj From: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 2 ACK User-Agent: Acrobits Softphone Business/3.8.2 Content-Length: 0 ------------------------------------------------------------------------ 2018-09-20 11:18:52.278458 [DEBUG] sofia.c:7291 Channel sofia/internal/207@pbx-186.smallunix.net entering state [ready][200] recv 426 bytes from tls/[31.102.111.132]:44832 at 11:18:52.331275: ------------------------------------------------------------------------ ACK sip:213@31.102.111.134:5061;transport=tls SIP/2.0 Via: SIP/2.0/TLS 192.168.128.158:51018;branch=z9hG4bKJyzi5qUSbdZCQYMw;rport Max-Forwards: 70 To: ;tag=mX8Hcmpp1Hjcj From: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 2 ACK User-Agent: Acrobits Softphone Business/3.8.2 Content-Length: 0 ------------------------------------------------------------------------ 2018-09-20 11:18:52.338471 [INFO] switch_rtp.c:7492 Auto Changing audio port from 192.168.128.158:59416 to 31.102.111.132:10299 ------------------------------------------------------------------------ recv 595 bytes from tls/[49.172.161.250]:50560 at 11:18:57.814420: ------------------------------------------------------------------------ BYE sip:mod_sofia@31.102.111.134:5061;transport=tls SIP/2.0 Via: SIP/2.0/TLS 192.168.129.176:50560;branch=z9hG4bKJ81ubNMd5e7Vl3tS;rport Contact: Max-Forwards: 70 From: ;tag=09FF027B916C5C2FCA338126B4A6840A Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY Supported: replaces, path To: "Andrea Mazzeo" ;tag=N61aeF7Syt8yD Call-ID: 068e6451-3759-1237-c4ba-000c2919b6af CSeq: 1 BYE User-Agent: Acrobits Softphone Business/3.8.2 Content-Length: 0 ------------------------------------------------------------------------ 2018-09-20 11:18:57.798460 [NOTICE] sofia.c:1079 Hangup sofia/internal/213@192.168.129.176:50560 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] send 544 bytes to tls/[49.172.161.250]:50560 at 11:18:57.816702: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/TLS 192.168.129.176:50560;branch=z9hG4bKJ81ubNMd5e7Vl3tS;rport=50560;received=49.172.161.250 From: ;tag=09FF027B916C5C2FCA338126B4A6840A To: "Andrea Mazzeo" ;tag=N61aeF7Syt8yD Call-ID: 068e6451-3759-1237-c4ba-000c2919b6af CSeq: 1 BYE User-Agent: SWO-PBX-1.0 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2018-09-20 11:18:57.798460 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/213@192.168.129.176:50560] 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/213@192.168.129.176:50560) State EXCHANGE_MEDIA going to sleep 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/213@192.168.129.176:50560) Running State Change CS_HANGUP (Cur 2 Tot 244) 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/213@192.168.129.176:50560) Callstate Change ACTIVE -> HANGUP 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/213@192.168.129.176:50560) State HANGUP 2018-09-20 11:18:57.798460 [DEBUG] mod_sofia.c:449 Channel sofia/internal/213@192.168.129.176:50560 hanging up, cause: NORMAL_CLEARING 2018-09-20 11:18:57.798460 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/207@pbx-186.smallunix.net] 2018-09-20 11:18:57.798460 [DEBUG] switch_ivr_bridge.c:1843 sofia/internal/213@192.168.129.176:50560 skip receive message [TRANSFER] (channel is hungup already) 2018-09-20 11:18:57.798460 [NOTICE] switch_ivr_bridge.c:1895 Hangup sofia/internal/207@pbx-186.smallunix.net [CS_EXECUTE] [NORMAL_CLEARING] 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:60 sofia/internal/213@192.168.129.176:50560 Standard HANGUP, cause: NORMAL_CLEARING 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/213@192.168.129.176:50560) State HANGUP going to sleep 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/213@192.168.129.176:50560) State Change CS_HANGUP -> CS_REPORTING 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/213@192.168.129.176:50560) Running State Change CS_REPORTING (Cur 2 Tot 244) 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/213@192.168.129.176:50560) State REPORTING 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:174 sofia/internal/213@192.168.129.176:50560 Standard REPORTING, cause: NORMAL_CLEARING 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/213@192.168.129.176:50560) State REPORTING going to sleep 2018-09-20 11:18:57.798460 [DEBUG] switch_core_session.c:2885 sofia/internal/207@pbx-186.smallunix.net skip receive message [PHONE_EVENT] (channel is hungup already) 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/207@pbx-186.smallunix.net) State EXECUTE going to sleep 2018-09-20 11:18:57.798460 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/207@pbx-186.smallunix.net) Running State Change CS_HANGUP (Cur 2 Tot 244) 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/207@pbx-186.smallunix.net) Callstate Change ACTIVE -> HANGUP 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/213@192.168.129.176:50560) State Change CS_REPORTING -> CS_DESTROY 2018-09-20 11:18:57.818443 [DEBUG] switch_core_session.c:1713 Session 244 (sofia/internal/213@192.168.129.176:50560) Locked, Waiting on external entities 2018-09-20 11:18:57.818443 [NOTICE] switch_core_session.c:1731 Session 244 (sofia/internal/213@192.168.129.176:50560) Ended 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/207@pbx-186.smallunix.net) State HANGUP 2018-09-20 11:18:57.818443 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/213@192.168.129.176:50560 [CS_DESTROY] 2018-09-20 11:18:57.818443 [DEBUG] mod_sofia.c:443 sofia/internal/207@pbx-186.smallunix.net Overriding SIP cause 480 with 200 from the other leg 2018-09-20 11:18:57.818443 [DEBUG] mod_sofia.c:449 Channel sofia/internal/207@pbx-186.smallunix.net hanging up, cause: NORMAL_CLEARING 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/213@192.168.129.176:50560) Running State Change CS_DESTROY (Cur 1 Tot 244) 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/213@192.168.129.176:50560) State DESTROY 2018-09-20 11:18:57.818443 [DEBUG] mod_sofia.c:354 sofia/internal/213@192.168.129.176:50560 SOFIA DESTROY 2018-09-20 11:18:57.818443 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2018-09-20 11:18:57.818443 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2018-09-20 11:18:57.818443 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2018-09-20 11:18:57.818443 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:181 sofia/internal/213@192.168.129.176:50560 Standard DESTROY 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/213@192.168.129.176:50560) State DESTROY going to sleep 2018-09-20 11:18:57.818443 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/internal/207@pbx-186.smallunix.net 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:60 sofia/internal/207@pbx-186.smallunix.net Standard HANGUP, cause: NORMAL_CLEARING 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/207@pbx-186.smallunix.net) State HANGUP going to sleep 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/207@pbx-186.smallunix.net) State Change CS_HANGUP -> CS_REPORTING send 589 bytes to tls/[31.102.111.132]:44832 at 11:18:57.819997: ------------------------------------------------------------------------ BYE sip:207@192.168.128.158:51018;transport=tls SIP/2.0 Via: SIP/2.0/TLS 31.102.111.134;branch=z9hG4bK91v8F4KgQpeXp Max-Forwards: 70 From: ;tag=mX8Hcmpp1Hjcj To: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 128381504 BYE User-Agent: SWO-PBX-1.0 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/207@pbx-186.smallunix.net) Running State Change CS_REPORTING (Cur 1 Tot 244) Content-Length: 0 ------------------------------------------------------------------------ 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/207@pbx-186.smallunix.net) State REPORTING 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:174 sofia/internal/207@pbx-186.smallunix.net Standard REPORTING, cause: NORMAL_CLEARING 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/207@pbx-186.smallunix.net) State REPORTING going to sleep 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/207@pbx-186.smallunix.net) State Change CS_REPORTING -> CS_DESTROY 2018-09-20 11:18:57.818443 [DEBUG] switch_core_session.c:1713 Session 243 (sofia/internal/207@pbx-186.smallunix.net) Locked, Waiting on external entities 2018-09-20 11:18:57.818443 [NOTICE] switch_core_session.c:1731 Session 243 (sofia/internal/207@pbx-186.smallunix.net) Ended 2018-09-20 11:18:57.818443 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/207@pbx-186.smallunix.net [CS_DESTROY] 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/207@pbx-186.smallunix.net) Running State Change CS_DESTROY (Cur 0 Tot 244) 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/207@pbx-186.smallunix.net) State DESTROY 2018-09-20 11:18:57.818443 [DEBUG] mod_sofia.c:354 sofia/internal/207@pbx-186.smallunix.net SOFIA DESTROY 2018-09-20 11:18:57.818443 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2018-09-20 11:18:57.818443 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2018-09-20 11:18:57.818443 [DEBUG] mod_opus.c:725 Opus decoder stats: Frames[0] PLC[0] FEC[0] 2018-09-20 11:18:57.818443 [DEBUG] mod_opus.c:740 Opus encoder stats: Frames[0] Bytes encoded[0] Encoded length ms[0] Average encoded bitrate bps[0] 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:181 sofia/internal/207@pbx-186.smallunix.net Standard DESTROY 2018-09-20 11:18:57.818443 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/207@pbx-186.smallunix.net) State DESTROY going to sleep ------------------------------------------------------------------------ recv 501 bytes from tls/[31.102.111.132]:44832 at 11:19:00.095403: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/TLS 31.102.111.134;branch=z9hG4bK91v8F4KgQpeXp Contact: From: ;tag=mX8Hcmpp1Hjcj Call-ID: F4BB678B69EC1AEC3231902A3C0AEA5F28451673 CSeq: 128381504 BYE To: "Andrea Mazzeo" ;tag=45D27BDABDFA056A12161380A06BED82 Allow: OPTIONS, INVITE, ACK, REFER, CANCEL, BYE, NOTIFY Supported: replaces, path User-Agent: Acrobits Softphone Business/3.8.2 Content-Length: 0