+OK log level [7] 2019-03-19 14:24:10.449359 [WARNING] switch_core_state_machine.c:687 f5895082-1f90-4b0b-9faf-dd52deb1f1e7 sofia/SIP_SHRISAI_INT/1365@103.54.24.21 Abandoned 2019-03-19 14:24:10.449359 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/SIP_SHRISAI_INT/1365@103.54.24.21 [CS_NEW] [WRONG_CALL_STATE] 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:584 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) Running State Change CS_HANGUP (Cur 1 Tot 285) 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:850 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) Callstate Change DOWN -> HANGUP 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:852 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) State HANGUP 2019-03-19 14:24:10.449359 [DEBUG] mod_sofia.c:438 Channel sofia/SIP_SHRISAI_INT/1365@103.54.24.21 hanging up, cause: WRONG_CALL_STATE 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:60 sofia/SIP_SHRISAI_INT/1365@103.54.24.21 Standard HANGUP, cause: WRONG_CALL_STATE 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:852 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) State HANGUP going to sleep 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:619 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) State Change CS_HANGUP -> CS_REPORTING 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:584 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) Running State Change CS_REPORTING (Cur 1 Tot 285) 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:938 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) State REPORTING 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:174 sofia/SIP_SHRISAI_INT/1365@103.54.24.21 Standard REPORTING, cause: WRONG_CALL_STATE 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:938 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) State REPORTING going to sleep 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:610 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) State Change CS_REPORTING -> CS_DESTROY 2019-03-19 14:24:10.449359 [DEBUG] switch_core_session.c:1665 Session 285 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) Locked, Waiting on external entities 2019-03-19 14:24:10.449359 [NOTICE] switch_core_session.c:1683 Session 285 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) Ended 2019-03-19 14:24:10.449359 [NOTICE] switch_core_session.c:1687 Close Channel sofia/SIP_SHRISAI_INT/1365@103.54.24.21 [CS_DESTROY] 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:741 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) Running State Change CS_DESTROY (Cur 0 Tot 285) 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:751 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) State DESTROY 2019-03-19 14:24:10.449359 [DEBUG] mod_sofia.c:343 sofia/SIP_SHRISAI_INT/1365@103.54.24.21 SOFIA DESTROY 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:181 sofia/SIP_SHRISAI_INT/1365@103.54.24.21 Standard DESTROY 2019-03-19 14:24:10.449359 [DEBUG] switch_core_state_machine.c:751 (sofia/SIP_SHRISAI_INT/1365@103.54.24.21) State DESTROY going to sleep recv 860 bytes from udp/[10.50.180.1]:5060 at 14:24:18.036399: ------------------------------------------------------------------------ INVITE sip:61198600@10.50.180.1:5060;transport=udp;gw=GW_TTL_DEL SIP/2.0 Via: SIP/2.0/UDP 10.50.180.1:5060;branch=z9hG4bKmmkpnpj699sk0awtwmmnsczkz;Role=3;Hpt=8eb2_36;TRC=ffffffff-120 Call-ID: asbc46b1208c50a-0286-0105@10.162.193.6 From: "918144659796";tag=aa2c106-nOyZ208c50911e To: "1161198600" CSeq: 529 INVITE Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,INFO,REFER,NOTIFY,SUBSCRIBE,PRACK,UPDATE Contact: Max-Forwards: 8 Supported: 100rel User-Agent: ZTE Softswitch/1.0.0 P-Early-Media: gated Content-Length: 175 Content-Type: application/sdp v=0 o=- 630 32095 IN IP4 10.50.180.1 s=SBC call c=IN IP4 10.50.180.1 t=0 0 m=audio 47958 RTP/AVP 8 0 18 97 a=rtpmap:97 telephone-event/8000 a=fmtp:97 0-15 a=ptime:20 ------------------------------------------------------------------------ send 364 bytes to udp/[10.50.180.1]:5060 at 14:24:18.036756: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.50.180.1:5060;branch=z9hG4bKmmkpnpj699sk0awtwmmnsczkz;Role=3;Hpt=8eb2_36;TRC=ffffffff-120 From: "918144659796";tag=aa2c106-nOyZ208c50911e To: "1161198600" Call-ID: asbc46b1208c50a-0286-0105@10.162.193.6 CSeq: 529 INVITE User-Agent: SIPOne Content-Length: 0 ------------------------------------------------------------------------ 2019-03-19 14:24:18.029382 [NOTICE] switch_channel.c:1104 New Channel sofia/SIP_TTL_DEL/918144659796@10.50.180.1 [2892eae5-114f-4719-918a-118564c5b726] 2019-03-19 14:24:18.029382 [DEBUG] switch_core_state_machine.c:584 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) Running State Change CS_NEW (Cur 1 Tot 286) 2019-03-19 14:24:18.029382 [DEBUG] sofia.c:9873 sofia/SIP_TTL_DEL/918144659796@10.50.180.1 receiving invite from 10.50.180.1:5060 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit 2019-03-19 14:24:18.029382 [DEBUG] sofia.c:10044 IP 10.50.180.1 Rejected by acl "default". Falling back to Digest auth. 2019-03-19 14:24:18.029382 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'SIP_TTL_DEL' for [1161198600@10.50.180.1] from ip 10.50.180.1 2019-03-19 14:24:18.029382 [DEBUG] switch_core_state_machine.c:603 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) State NEW send 707 bytes to udp/[10.50.180.1]:5060 at 14:24:18.037986: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.50.180.1:5060;branch=z9hG4bKmmkpnpj699sk0awtwmmnsczkz;Role=3;Hpt=8eb2_36;TRC=ffffffff-120 From: "918144659796";tag=aa2c106-nOyZ208c50911e To: "1161198600" ;tag=pjg4tH0j9UUac Call-ID: asbc46b1208c50a-0286-0105@10.162.193.6 CSeq: 529 INVITE User-Agent: SIPOne Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY Supported: path, replaces Allow-Events: talk, hold, conference, refer Proxy-Authenticate: Digest realm="10.50.180.1", nonce="e440f5fb-0aad-45ad-861e-3e5b94ef3a7f", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ 2019-03-19 14:24:18.029382 [DEBUG] sofia.c:2334 detaching session 2892eae5-114f-4719-918a-118564c5b726 recv 428 bytes from udp/[10.50.180.1]:5060 at 14:24:18.062355: ------------------------------------------------------------------------ ACK sip:61198600@10.50.180.1:5060;transport=udp;gw=GW_TTL_DEL SIP/2.0 Via: SIP/2.0/UDP 10.50.180.1:5060;branch=z9hG4bKmmkpnpj699sk0awtwmmnsczkz;Role=3;Hpt=8eb2_36;TRC=ffffffff-120 Call-ID: asbc46b1208c50a-0286-0105@10.162.193.6 From: "918144659796";tag=aa2c106-nOyZ208c50911e To: "1161198600";tag=pjg4tH0j9UUac CSeq: 529 ACK Max-Forwards: 70 Content-Length: 0 ------------------------------------------------------------------------ 2019-03-19 14:24:23.409380 [NOTICE] switch_channel.c:1104 New Channel sofia/SIP_SHRISAI_INT/2118@103.54.24.21 [b6adbc86-dc03-4605-8121-eb18bb9340ac] 2019-03-19 14:24:23.409380 [DEBUG] switch_core_state_machine.c:584 (sofia/SIP_SHRISAI_INT/2118@103.54.24.21) Running State Change CS_NEW (Cur 2 Tot 287) 2019-03-19 14:24:23.429610 [DEBUG] sofia.c:9873 sofia/SIP_SHRISAI_INT/2118@103.54.24.21 receiving invite from 185.184.241.4:54237 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit 2019-03-19 14:24:23.429610 [DEBUG] sofia.c:10044 IP 185.184.241.4 Rejected by acl "default". Falling back to Digest auth. 2019-03-19 14:24:23.429610 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'SIP_SHRISAI_INT' for [001441923604021@103.54.24.21] from ip 185.184.241.4 2019-03-19 14:24:23.429610 [DEBUG] switch_core_state_machine.c:603 (sofia/SIP_SHRISAI_INT/2118@103.54.24.21) State NEW 2019-03-19 14:24:23.429610 [DEBUG] sofia.c:2334 detaching session b6adbc86-dc03-4605-8121-eb18bb9340ac 2019-03-19 14:24:28.049359 [WARNING] switch_core_state_machine.c:687 2892eae5-114f-4719-918a-118564c5b726 sofia/SIP_TTL_DEL/918144659796@10.50.180.1 Abandoned 2019-03-19 14:24:28.049359 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/SIP_TTL_DEL/918144659796@10.50.180.1 [CS_NEW] [WRONG_CALL_STATE] 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:584 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) Running State Change CS_HANGUP (Cur 2 Tot 287) 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:850 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) Callstate Change DOWN -> HANGUP 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:852 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) State HANGUP 2019-03-19 14:24:28.049359 [DEBUG] mod_sofia.c:438 Channel sofia/SIP_TTL_DEL/918144659796@10.50.180.1 hanging up, cause: WRONG_CALL_STATE 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:60 sofia/SIP_TTL_DEL/918144659796@10.50.180.1 Standard HANGUP, cause: WRONG_CALL_STATE 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:852 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) State HANGUP going to sleep 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:619 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) State Change CS_HANGUP -> CS_REPORTING 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:584 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) Running State Change CS_REPORTING (Cur 2 Tot 287) 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:938 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) State REPORTING 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:174 sofia/SIP_TTL_DEL/918144659796@10.50.180.1 Standard REPORTING, cause: WRONG_CALL_STATE 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:938 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) State REPORTING going to sleep 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:610 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) State Change CS_REPORTING -> CS_DESTROY 2019-03-19 14:24:28.049359 [DEBUG] switch_core_session.c:1665 Session 286 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) Locked, Waiting on external entities 2019-03-19 14:24:28.049359 [NOTICE] switch_core_session.c:1683 Session 286 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) Ended 2019-03-19 14:24:28.049359 [NOTICE] switch_core_session.c:1687 Close Channel sofia/SIP_TTL_DEL/918144659796@10.50.180.1 [CS_DESTROY] 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:741 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) Running State Change CS_DESTROY (Cur 1 Tot 287) 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:751 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) State DESTROY 2019-03-19 14:24:28.049359 [DEBUG] mod_sofia.c:343 sofia/SIP_TTL_DEL/918144659796@10.50.180.1 SOFIA DESTROY 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:181 sofia/SIP_TTL_DEL/918144659796@10.50.180.1 Standard DESTROY 2019-03-19 14:24:28.049359 [DEBUG] switch_core_state_machine.c:751 (sofia/SIP_TTL_DEL/918144659796@10.50.180.1) State DESTROY going to sleep