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