t 2021-03-03 08:58:09.049677 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables 2021-03-03 08:58:09.049677 [NOTICE] switch_channel.c:1118 New Channel sofia/default/3302@192.168.0.108:5060 [404b71b4-cea5-4276-93c4-26312dc4ea76] 2021-03-03 08:58:09.049677 [DEBUG] mod_sofia.c:5089 (sofia/default/3302@192.168.0.108:5060) State Change CS_NEW -> CS_INIT 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_INIT (Cur 7 Tot 235075) 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:628 (sofia/default/3302@192.168.0.108:5060) State INIT 2021-03-03 08:58:09.049677 [DEBUG] mod_sofia.c:93 sofia/default/3302@192.168.0.108:5060 SOFIA INIT 2021-03-03 08:58:09.049677 [DEBUG] sofia_glue.c:1587 sip:3302@212.139.8.138:3580 Setting proxy route to sofia/default/3302@192.168.0.108:5060 2021-03-03 08:58:09.049677 [DEBUG] sofia_glue.c:1618 sofia/default/3302@192.168.0.108:5060 sending invite version: 1.10.2 -release-14-f7bdd3845a 64bit Local SDP: v=0 o=FreeSWITCH 1614733081 1614733082 IN IP4 178.62.70.78 s=FreeSWITCH c=IN IP4 178.62.70.78 t=0 0 m=audio 28808 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:40 sofia/default/3302@192.168.0.108:5060 Standard INIT 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:48 (sofia/default/3302@192.168.0.108:5060) State Change CS_INIT -> CS_ROUTING 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:628 (sofia/default/3302@192.168.0.108:5060) State INIT going to sleep 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_ROUTING (Cur 7 Tot 235075) 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:644 (sofia/default/3302@192.168.0.108:5060) State ROUTING 2021-03-03 08:58:09.049677 [DEBUG] mod_sofia.c:154 sofia/default/3302@192.168.0.108:5060 SOFIA ROUTING 2021-03-03 08:58:09.049677 [DEBUG] switch_ivr_originate.c:67 (sofia/default/3302@192.168.0.108:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:644 (sofia/default/3302@192.168.0.108:5060) State ROUTING going to sleep 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_CONSUME_MEDIA (Cur 7 Tot 235075) 2021-03-03 08:58:09.049677 [DEBUG] sofia.c:7301 Channel sofia/default/3302@192.168.0.108:5060 entering state [calling][0] 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:663 (sofia/default/3302@192.168.0.108:5060) State CONSUME_MEDIA 2021-03-03 08:58:09.049677 [DEBUG] switch_core_state_machine.c:663 (sofia/default/3302@192.168.0.108:5060) State CONSUME_MEDIA going to sleep 2021-03-03 08:58:09.069757 [DEBUG] sofia.c:7301 Channel sofia/default/3301@192.168.0.100:5060 entering state [proceeding][180] 2021-03-03 08:58:09.069757 [NOTICE] sofia.c:7412 Ring-Ready sofia/default/3301@192.168.0.100:5060! 2021-03-03 08:58:09.069757 [DEBUG] switch_channel.c:3437 (sofia/default/3301@192.168.0.100:5060) Callstate Change DOWN -> RINGING 2021-03-03 08:58:09.089796 [NOTICE] mod_sofia.c:2498 Ring-Ready sofia/default/447903334940@simwood.com! 2021-03-03 08:58:09.089796 [DEBUG] sofia.c:7301 Channel sofia/default/447903334940@simwood.com entering state [early][180] 2021-03-03 08:58:09.089796 [NOTICE] switch_ivr_originate.c:553 Ring Ready sofia/default/447903334940@simwood.com! 2021-03-03 08:58:09.249763 [DEBUG] sofia.c:7301 Channel sofia/default/3307@154.113.86.204:61749 entering state [proceeding][180] 2021-03-03 08:58:09.249763 [NOTICE] sofia.c:7412 Ring-Ready sofia/default/3307@154.113.86.204:61749! 2021-03-03 08:58:09.249763 [DEBUG] switch_channel.c:3437 (sofia/default/3307@154.113.86.204:61749) Callstate Change DOWN -> RINGING 2021-03-03 08:58:12.849683 [WARNING] sofia_reg.c:1793 SIP auth challenge (REGISTER) on sofia profile 'default' for [7706@Rajni179.sipp.switchboard.co.uk] from ip 188.240.171.6 2021-03-03 08:58:15.429801 [WARNING] switch_core_state_machine.c:688 1f3b2245-9e37-431e-9876-226d4e9a2f0e sofia/default/56001@178.62.70.78 Abandoned 2021-03-03 08:58:15.429801 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/56001@178.62.70.78 [CS_NEW] [WRONG_CALL_STATE] 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:585 (sofia/default/56001@178.62.70.78) Running State Change CS_HANGUP (Cur 7 Tot 235075) 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:848 (sofia/default/56001@178.62.70.78) Callstate Change DOWN -> HANGUP 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:850 (sofia/default/56001@178.62.70.78) State HANGUP 2021-03-03 08:58:15.429801 [DEBUG] mod_sofia.c:453 Channel sofia/default/56001@178.62.70.78 hanging up, cause: WRONG_CALL_STATE 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:60 sofia/default/56001@178.62.70.78 Standard HANGUP, cause: WRONG_CALL_STATE 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:850 (sofia/default/56001@178.62.70.78) State HANGUP going to sleep 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:620 (sofia/default/56001@178.62.70.78) State Change CS_HANGUP -> CS_REPORTING 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:585 (sofia/default/56001@178.62.70.78) Running State Change CS_REPORTING (Cur 7 Tot 235075) 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:936 (sofia/default/56001@178.62.70.78) State REPORTING 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:174 sofia/default/56001@178.62.70.78 Standard REPORTING, cause: WRONG_CALL_STATE 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:936 (sofia/default/56001@178.62.70.78) State REPORTING going to sleep 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:611 (sofia/default/56001@178.62.70.78) State Change CS_REPORTING -> CS_DESTROY 2021-03-03 08:58:15.429801 [DEBUG] switch_core_session.c:1726 Session 235069 (sofia/default/56001@178.62.70.78) Locked, Waiting on external entities 2021-03-03 08:58:15.429801 [NOTICE] switch_core_session.c:1744 Session 235069 (sofia/default/56001@178.62.70.78) Ended 2021-03-03 08:58:15.429801 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/56001@178.62.70.78 [CS_DESTROY] 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:739 (sofia/default/56001@178.62.70.78) Running State Change CS_DESTROY (Cur 6 Tot 235075) 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:749 (sofia/default/56001@178.62.70.78) State DESTROY 2021-03-03 08:58:15.429801 [DEBUG] mod_sofia.c:364 sofia/default/56001@178.62.70.78 SOFIA DESTROY 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:181 sofia/default/56001@178.62.70.78 Standard DESTROY 2021-03-03 08:58:15.429801 [DEBUG] switch_core_state_machine.c:749 (sofia/default/56001@178.62.70.78) State DESTROY going to sleep 2021-03-03 08:58:23.649744 [DEBUG] sofia.c:7301 Channel sofia/default/3307@154.113.86.204:61749 entering state [completing][200] 2021-03-03 08:58:23.649744 [DEBUG] sofia.c:7311 Remote SDP: v=0 o=3cxVCE 48281295 52157625 IN IP4 154.113.86.204 s=3cxVCE Audio Call c=IN IP4 154.113.86.204 t=0 0 m=audio 40006 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 2021-03-03 08:58:23.649744 [DEBUG] sofia.c:7301 Channel sofia/default/3307@154.113.86.204:61749 entering state [ready][200] 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/3307@154.113.86.204:61749 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2021-03-03 08:58:23.649744 [DEBUG] switch_core_codec.c:111 sofia/default/3307@154.113.86.204:61749 Original read codec set to PCMA:8 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:5909 sofia/default/3307@154.113.86.204:61749 Set 2833 dtmf send payload to 101 recv payload to 101 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/3307@154.113.86.204:61749] 178.62.70.78 port 17120 -> 154.113.86.204 port 40006 codec: 8 ms: 20 2021-03-03 08:58:23.649744 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:8973 sofia/default/3307@154.113.86.204:61749 Set 2833 dtmf send payload to 101 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:8980 sofia/default/3307@154.113.86.204:61749 Set 2833 dtmf receive payload to 101 2021-03-03 08:58:23.649744 [DEBUG] switch_core_media.c:9003 sofia/default/3307@154.113.86.204:61749 Set rtp dtmf delay to 40 2021-03-03 08:58:23.649744 [NOTICE] sofia.c:8479 Channel [sofia/default/3307@154.113.86.204:61749] has been answered 2021-03-03 08:58:23.649744 [DEBUG] switch_channel.c:3865 (sofia/default/3307@154.113.86.204:61749) Callstate Change RINGING -> ACTIVE EXECUTE [depth=0] sofia/default/3307@154.113.86.204:61749 lua(/usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua) 2021-03-03 08:58:23.669723 [ERR] mod_lua.cpp:202 cannot open /usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua: No such file or directory 2021-03-03 08:58:23.669723 [DEBUG] switch_cpp.cpp:1187 sofia/default/3307@154.113.86.204:61749 destroy/unlink session from object 2021-03-03 08:58:23.689697 [NOTICE] switch_ivr_originate.c:3716 Hangup sofia/default/3301@192.168.0.100:5060 [CS_CONSUME_MEDIA] [LOSE_RACE] 2021-03-03 08:58:23.689697 [NOTICE] switch_ivr_originate.c:3716 Hangup sofia/default/3303@192.168.0.106:5074 [CS_CONSUME_MEDIA] [LOSE_RACE] 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3301@192.168.0.100:5060) Running State Change CS_HANGUP (Cur 6 Tot 235075) 2021-03-03 08:58:23.689697 [NOTICE] switch_ivr_originate.c:3716 Hangup sofia/default/3304@192.168.0.110:5060 [CS_CONSUME_MEDIA] [LOSE_RACE] 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:848 (sofia/default/3301@192.168.0.100:5060) Callstate Change RINGING -> HANGUP 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3303@192.168.0.106:5074) Running State Change CS_HANGUP (Cur 6 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3301@192.168.0.100:5060) State HANGUP 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:453 Channel sofia/default/3301@192.168.0.100:5060 hanging up, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [NOTICE] switch_ivr_originate.c:3716 Hangup sofia/default/3302@192.168.0.108:5060 [CS_CONSUME_MEDIA] [LOSE_RACE] 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3304@192.168.0.110:5060) Running State Change CS_HANGUP (Cur 6 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:848 (sofia/default/3303@192.168.0.106:5074) Callstate Change DOWN -> HANGUP 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_HANGUP (Cur 6 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3303@192.168.0.106:5074) State HANGUP 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:453 Channel sofia/default/3303@192.168.0.106:5074 hanging up, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:848 (sofia/default/3304@192.168.0.110:5060) Callstate Change DOWN -> HANGUP 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:848 (sofia/default/3302@192.168.0.108:5060) Callstate Change DOWN -> HANGUP 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3304@192.168.0.110:5060) State HANGUP 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:453 Channel sofia/default/3304@192.168.0.110:5060 hanging up, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3302@192.168.0.108:5060) State HANGUP 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:453 Channel sofia/default/3302@192.168.0.108:5060 hanging up, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/3301@192.168.0.100:5060 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:60 sofia/default/3301@192.168.0.100:5060 Standard HANGUP, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3301@192.168.0.100:5060) State HANGUP going to sleep 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:620 (sofia/default/3301@192.168.0.100:5060) State Change CS_HANGUP -> CS_REPORTING 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3301@192.168.0.100:5060) Running State Change CS_REPORTING (Cur 6 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3301@192.168.0.100:5060) State REPORTING 2021-03-03 08:58:23.689697 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/447903334940@simwood.com] 178.62.70.78 port 27396 -> 178.22.139.45 port 17370 codec: 8 ms: 20 2021-03-03 08:58:23.689697 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/3303@192.168.0.106:5074 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:60 sofia/default/3303@192.168.0.106:5074 Standard HANGUP, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3303@192.168.0.106:5074) State HANGUP going to sleep 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:620 (sofia/default/3303@192.168.0.106:5074) State Change CS_HANGUP -> CS_REPORTING 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3303@192.168.0.106:5074) Running State Change CS_REPORTING (Cur 6 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3303@192.168.0.106:5074) State REPORTING 2021-03-03 08:58:23.689697 [INFO] mod_json_cdr.c:271 Process [5f0629f5-2d26-4eac-a26c-702e8cf26429.cdr.json] 2021-03-03 08:58:23.689697 [INFO] mod_json_cdr.c:271 Process [5ac3dffa-1cda-4966-ad56-a58779541e1b.cdr.json] 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/3302@192.168.0.108:5060 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:60 sofia/default/3302@192.168.0.108:5060 Standard HANGUP, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3302@192.168.0.108:5060) State HANGUP going to sleep 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:620 (sofia/default/3302@192.168.0.108:5060) State Change CS_HANGUP -> CS_REPORTING 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_REPORTING (Cur 6 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3302@192.168.0.108:5060) State REPORTING 2021-03-03 08:58:23.689697 [INFO] mod_json_cdr.c:271 Process [404b71b4-cea5-4276-93c4-26312dc4ea76.cdr.json] 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:521 Sending CANCEL to sofia/default/3304@192.168.0.110:5060 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:60 sofia/default/3304@192.168.0.110:5060 Standard HANGUP, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:850 (sofia/default/3304@192.168.0.110:5060) State HANGUP going to sleep 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:620 (sofia/default/3304@192.168.0.110:5060) State Change CS_HANGUP -> CS_REPORTING 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3304@192.168.0.110:5060) Running State Change CS_REPORTING (Cur 6 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3304@192.168.0.110:5060) State REPORTING 2021-03-03 08:58:23.689697 [INFO] mod_json_cdr.c:271 Process [b37cb6a9-c9f4-441f-af99-7f48216f734f.cdr.json] 2021-03-03 08:58:23.689697 [DEBUG] switch_core_media.c:8881 Activating RTCP PORT 17371 2021-03-03 08:58:23.689697 [DEBUG] switch_rtp.c:4806 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 17371 2021-03-03 08:58:23.689697 [DEBUG] switch_rtp.c:2627 Setting RTCP remote addr to 178.22.139.45:17371 2 2021-03-03 08:58:23.689697 [DEBUG] switch_core_media.c:8973 sofia/default/447903334940@simwood.com Set 2833 dtmf send payload to 100 2021-03-03 08:58:23.689697 [DEBUG] switch_core_media.c:8980 sofia/default/447903334940@simwood.com Set 2833 dtmf receive payload to 100 2021-03-03 08:58:23.689697 [DEBUG] switch_core_media.c:9003 sofia/default/447903334940@simwood.com Set rtp dtmf delay to 40 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/447903334940@simwood.com: v=0 o=FreeSWITCH 1614734507 1614734508 IN IP4 178.62.70.78 s=FreeSWITCH c=IN IP4 178.62.70.78 t=0 0 m=audio 27396 RTP/AVP 8 100 a=rtpmap:8 PCMA/8000 a=rtpmap:100 telephone-event/8000 a=fmtp:100 0-16 a=ptime:20 a=sendrecv a=rtcp:27397 IN IP4 178.62.70.78 2021-03-03 08:58:23.689697 [NOTICE] switch_ivr_originate.c:3751 Channel [sofia/default/447903334940@simwood.com] has been answered 2021-03-03 08:58:23.689697 [DEBUG] switch_channel.c:3865 (sofia/default/447903334940@simwood.com) Callstate Change RINGING -> ACTIVE 2021-03-03 08:58:23.689697 [DEBUG] sofia.c:7301 Channel sofia/default/447903334940@simwood.com entering state [completed][200] 2021-03-03 08:58:23.689697 [DEBUG] switch_ivr_originate.c:3809 Originate Resulted in Success: [sofia/default/3307@154.113.86.204:61749] 2021-03-03 08:58:23.689697 [DEBUG] switch_ivr_bridge.c:1796 (sofia/default/3307@154.113.86.204:61749) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:585 (sofia/default/3307@154.113.86.204:61749) Running State Change CS_EXCHANGE_MEDIA (Cur 6 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:654 (sofia/default/3307@154.113.86.204:61749) State EXCHANGE_MEDIA 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:174 sofia/default/3301@192.168.0.100:5060 Standard REPORTING, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3301@192.168.0.100:5060) State REPORTING going to sleep 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:611 (sofia/default/3301@192.168.0.100:5060) State Change CS_REPORTING -> CS_DESTROY 2021-03-03 08:58:23.689697 [DEBUG] switch_core_session.c:1726 Session 235072 (sofia/default/3301@192.168.0.100:5060) Locked, Waiting on external entities 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1744 Session 235072 (sofia/default/3301@192.168.0.100:5060) Ended 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/3301@192.168.0.100:5060 [CS_DESTROY] 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:739 (sofia/default/3301@192.168.0.100:5060) Running State Change CS_DESTROY (Cur 5 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3301@192.168.0.100:5060) State DESTROY 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:364 sofia/default/3301@192.168.0.100:5060 SOFIA DESTROY 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:181 sofia/default/3301@192.168.0.100:5060 Standard DESTROY 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3301@192.168.0.100:5060) State DESTROY going to sleep 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:174 sofia/default/3304@192.168.0.110:5060 Standard REPORTING, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:174 sofia/default/3303@192.168.0.106:5074 Standard REPORTING, cause: LOSE_RACE 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3304@192.168.0.110:5060) State REPORTING going to sleep 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3303@192.168.0.106:5074) State REPORTING going to sleep 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:611 (sofia/default/3304@192.168.0.110:5060) State Change CS_REPORTING -> CS_DESTROY 2021-03-03 08:58:23.689697 [DEBUG] switch_core_session.c:1726 Session 235074 (sofia/default/3304@192.168.0.110:5060) Locked, Waiting on external entities 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:611 (sofia/default/3303@192.168.0.106:5074) State Change CS_REPORTING -> CS_DESTROY 2021-03-03 08:58:23.689697 [DEBUG] switch_core_session.c:1726 Session 235073 (sofia/default/3303@192.168.0.106:5074) Locked, Waiting on external entities 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1744 Session 235074 (sofia/default/3304@192.168.0.110:5060) Ended 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1744 Session 235073 (sofia/default/3303@192.168.0.106:5074) Ended 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/3304@192.168.0.110:5060 [CS_DESTROY] 2021-03-03 08:58:23.689697 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/3303@192.168.0.106:5074 [CS_DESTROY] 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:739 (sofia/default/3304@192.168.0.110:5060) Running State Change CS_DESTROY (Cur 3 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:739 (sofia/default/3303@192.168.0.106:5074) Running State Change CS_DESTROY (Cur 3 Tot 235075) 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3303@192.168.0.106:5074) State DESTROY 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:364 sofia/default/3303@192.168.0.106:5074 SOFIA DESTROY 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:181 sofia/default/3303@192.168.0.106:5074 Standard DESTROY 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3303@192.168.0.106:5074) State DESTROY going to sleep 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3304@192.168.0.110:5060) State DESTROY 2021-03-03 08:58:23.689697 [DEBUG] mod_sofia.c:364 sofia/default/3304@192.168.0.110:5060 SOFIA DESTROY 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:181 sofia/default/3304@192.168.0.110:5060 Standard DESTROY 2021-03-03 08:58:23.689697 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3304@192.168.0.110:5060) State DESTROY going to sleep 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:174 sofia/default/3302@192.168.0.108:5060 Standard REPORTING, cause: LOSE_RACE 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:936 (sofia/default/3302@192.168.0.108:5060) State REPORTING going to sleep 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:611 (sofia/default/3302@192.168.0.108:5060) State Change CS_REPORTING -> CS_DESTROY 2021-03-03 08:58:23.709748 [DEBUG] switch_core_session.c:1726 Session 235075 (sofia/default/3302@192.168.0.108:5060) Locked, Waiting on external entities 2021-03-03 08:58:23.709748 [NOTICE] switch_core_session.c:1744 Session 235075 (sofia/default/3302@192.168.0.108:5060) Ended 2021-03-03 08:58:23.709748 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/3302@192.168.0.108:5060 [CS_DESTROY] 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:739 (sofia/default/3302@192.168.0.108:5060) Running State Change CS_DESTROY (Cur 2 Tot 235075) 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3302@192.168.0.108:5060) State DESTROY 2021-03-03 08:58:23.709748 [DEBUG] mod_sofia.c:364 sofia/default/3302@192.168.0.108:5060 SOFIA DESTROY 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:181 sofia/default/3302@192.168.0.108:5060 Standard DESTROY 2021-03-03 08:58:23.709748 [DEBUG] switch_core_state_machine.c:749 (sofia/default/3302@192.168.0.108:5060) State DESTROY going to sleep 2021-03-03 08:58:23.869757 [DEBUG] switch_ivr_bridge.c:658 sofia/default/3307@154.113.86.204:61749 Bridge execute app sched_hangup(+6000 normal_clearing) 2021-03-03 08:58:23.889748 [DEBUG] switch_ivr.c:632 sofia/default/3307@154.113.86.204:61749 Command Execute [depth=0] sched_hangup(+6000 normal_clearing) EXECUTE [depth=0] sofia/default/3307@154.113.86.204:61749 sched_hangup(+6000 normal_clearing) 2021-03-03 08:58:23.889748 [DEBUG] switch_scheduler.c:252 Added task 406 switch_ivr_schedule_hangup (e632e3bf-a87f-4a41-9b7e-65ec265c437d) to run at 1614767903 2021-03-03 08:58:23.929752 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed. 2021-03-03 08:58:24.209760 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed. 2021-03-03 08:58:24.229751 [DEBUG] switch_rtp.c:1912 rtcp_stats_init: audio ssrc[233912089] base_seq[64275] 2021-03-03 08:58:24.529749 [DEBUG] switch_rtp.c:7118 Correct audio RTCP ip/port confirmed. 2021-03-03 08:58:24.709757 [DEBUG] sofia.c:7301 Channel sofia/default/447903334940@simwood.com entering state [ready][200] 2021-03-03 08:58:25.269761 [NOTICE] switch_channel.c:1118 New Channel sofia/default/9142@178.62.70.78 [e7f0bad7-312a-4ea6-b62c-264ce4447f70] 2021-03-03 08:58:25.269761 [DEBUG] switch_core_state_machine.c:585 (sofia/default/9142@178.62.70.78) Running State Change CS_NEW (Cur 3 Tot 235076) 2021-03-03 08:58:25.269761 [DEBUG] sofia.c:10255 sofia/default/9142@178.62.70.78 receiving invite from 193.107.216.2:57622 version: 1.10.2 -release-14-f7bdd3845a 64bit 2021-03-03 08:58:25.269761 [DEBUG] sofia.c:10349 verifying acl "default" for ip/port 193.107.216.2:0. 2021-03-03 08:58:25.269761 [WARNING] sofia_reg.c:1793 SIP auth challenge (INVITE) on sofia profile 'default' for [700441769772003@178.62.70.78] from ip 193.107.216.2 2021-03-03 08:58:25.269761 [DEBUG] switch_core_state_machine.c:604 (sofia/default/9142@178.62.70.78) State NEW 2021-03-03 08:58:25.269761 [DEBUG] sofia.c:2434 detaching session e7f0bad7-312a-4ea6-b62c-264ce4447f70 2021-03-03 08:58:35.349765 [WARNING] switch_core_state_machine.c:688 e7f0bad7-312a-4ea6-b62c-264ce4447f70 sofia/default/9142@178.62.70.78 Abandoned 2021-03-03 08:58:35.349765 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/9142@178.62.70.78 [CS_NEW] [WRONG_CALL_STATE] 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:585 (sofia/default/9142@178.62.70.78) Running State Change CS_HANGUP (Cur 3 Tot 235076) 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:848 (sofia/default/9142@178.62.70.78) Callstate Change DOWN -> HANGUP 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:850 (sofia/default/9142@178.62.70.78) State HANGUP 2021-03-03 08:58:35.349765 [DEBUG] mod_sofia.c:453 Channel sofia/default/9142@178.62.70.78 hanging up, cause: WRONG_CALL_STATE 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:60 sofia/default/9142@178.62.70.78 Standard HANGUP, cause: WRONG_CALL_STATE 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:850 (sofia/default/9142@178.62.70.78) State HANGUP going to sleep 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:620 (sofia/default/9142@178.62.70.78) State Change CS_HANGUP -> CS_REPORTING 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:585 (sofia/default/9142@178.62.70.78) Running State Change CS_REPORTING (Cur 3 Tot 235076) 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:936 (sofia/default/9142@178.62.70.78) State REPORTING 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:174 sofia/default/9142@178.62.70.78 Standard REPORTING, cause: WRONG_CALL_STATE 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:936 (sofia/default/9142@178.62.70.78) State REPORTING going to sleep 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:611 (sofia/default/9142@178.62.70.78) State Change CS_REPORTING -> CS_DESTROY 2021-03-03 08:58:35.349765 [DEBUG] switch_core_session.c:1726 Session 235076 (sofia/default/9142@178.62.70.78) Locked, Waiting on external entities 2021-03-03 08:58:35.349765 [NOTICE] switch_core_session.c:1744 Session 235076 (sofia/default/9142@178.62.70.78) Ended 2021-03-03 08:58:35.349765 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/9142@178.62.70.78 [CS_DESTROY] 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:739 (sofia/default/9142@178.62.70.78) Running State Change CS_DESTROY (Cur 2 Tot 235076) 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:749 (sofia/default/9142@178.62.70.78) State DESTROY 2021-03-03 08:58:35.349765 [DEBUG] mod_sofia.c:364 sofia/default/9142@178.62.70.78 SOFIA DESTROY 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:181 sofia/default/9142@178.62.70.78 Standard DESTROY 2021-03-03 08:58:35.349765 [DEBUG] switch_core_state_machine.c:749 (sofia/default/9142@178.62.70.78) State DESTROY going to sleep