v=0 o=FreeSWITCH 1628062263 1628062265 IN IP4 89.46.223.194 s=FreeSWITCH c=IN IP4 89.46.223.194 t=0 0 m=audio 18248 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2021-08-04 08:35:13.360650 [NOTICE] switch_ivr_bridge.c:623 Channel [sofia/default/498008001050@193.109.216.112] has been answered 2021-08-04 08:35:13.360650 [DEBUG] sofia.c:7084 Channel sofia/default/498008001050@193.109.216.112 entering state [completed][200] EXECUTE sofia/default/498008001050@193.109.216.112 record_session(/var/lib/freeswitch/recordings/6817b6ee-f520-11eb-bea5-b1a3947a3cab.wav) 2021-08-04 08:35:13.380656 [DEBUG] switch_ivr_async.c:1500 No silence detection configured; assuming start of speech 2021-08-04 08:35:13.380656 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/default/498008001050@193.109.216.112 2021-08-04 08:35:13.380656 [DEBUG] switch_channel.c:3773 (sofia/default/498008001050@193.109.216.112) Callstate Change EARLY -> ACTIVE 2021-08-04 08:35:13.380656 [DEBUG] switch_ivr_async.c:1500 No silence detection configured; assuming start of speech 2021-08-04 08:35:13.400655 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. 2021-08-04 08:35:13.400655 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8 2021-08-04 08:35:13.420650 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. 2021-08-04 08:35:13.420650 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8 2021-08-04 08:35:13.420650 [DEBUG] sofia.c:7084 Channel sofia/default/498008001050@193.109.216.112 entering state [ready][200] 2021-08-04 08:35:14.920651 [DEBUG] switch_rtp.c:7534 RTP RECV DTMF 6:960 2021-08-04 08:35:14.920651 [INFO] switch_channel.c:515 RECV DTMF 6:960 2021-08-04 08:35:15.640645 [DEBUG] switch_rtp.c:7534 RTP RECV DTMF 6:960 2021-08-04 08:35:15.640645 [INFO] switch_channel.c:515 RECV DTMF 6:960 2021-08-04 08:35:16.320655 [DEBUG] switch_rtp.c:7534 RTP RECV DTMF 6:960 2021-08-04 08:35:16.320655 [INFO] switch_channel.c:515 RECV DTMF 6:960 2021-08-04 08:35:16.620641 [NOTICE] sofia.c:1012 Hangup sofia/default/498008001050@193.109.216.112 [CS_EXECUTE] [NORMAL_CLEARING] 2021-08-04 08:35:16.620641 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/498008001050@193.109.216.112] 2021-08-04 08:35:16.640640 [DEBUG] switch_ivr_bridge.c:706 sofia/default/498008001050@193.109.216.112 ending bridge by request from write function 2021-08-04 08:35:16.640640 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/4915759322375] 2021-08-04 08:35:16.640640 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/default/4915759322375 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:653 (sofia/default/4915759322375) State EXCHANGE_MEDIA going to sleep 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:584 (sofia/default/4915759322375) Running State Change CS_HANGUP (Cur 15 Tot 1047378) 2021-08-04 08:35:16.640640 [DEBUG] switch_ivr_async.c:1316 Stop recording file /var/lib/freeswitch/recordings/6817b6ee-f520-11eb-bea5-b1a3947a3cab.wav 2021-08-04 08:35:16.640640 [DEBUG] switch_ivr_bridge.c:1712 sofia/default/4915759322375 skip receive message [UNBRIDGE] (channel is hungup already) 2021-08-04 08:35:16.640640 [DEBUG] switch_ivr_bridge.c:1715 sofia/default/498008001050@193.109.216.112 skip receive message [UNBRIDGE] (channel is hungup already) 2021-08-04 08:35:16.640640 [DEBUG] switch_core_session.c:2815 sofia/default/498008001050@193.109.216.112 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:650 (sofia/default/498008001050@193.109.216.112) State EXECUTE going to sleep 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:584 (sofia/default/498008001050@193.109.216.112) Running State Change CS_HANGUP (Cur 15 Tot 1047378) 2021-08-04 08:35:16.640640 [DEBUG] switch_ivr_async.c:1316 Stop recording file /var/lib/freeswitch/recordings/6817b6ee-f520-11eb-bea5-b1a3947a3cab.wav 2021-08-04 08:35:16.640640 [DEBUG] switch_ivr_async.c:1380 Channel is hung up 2021-08-04 08:35:16.640640 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/498008001050@193.109.216.112 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:850 (sofia/default/498008001050@193.109.216.112) Callstate Change ACTIVE -> HANGUP 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:852 (sofia/default/498008001050@193.109.216.112) State HANGUP 2021-08-04 08:35:16.640640 [DEBUG] mod_sofia.c:438 Channel sofia/default/498008001050@193.109.216.112 hanging up, cause: NORMAL_CLEARING 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:60 sofia/default/498008001050@193.109.216.112 Standard HANGUP, cause: NORMAL_CLEARING 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:852 (sofia/default/498008001050@193.109.216.112) State HANGUP going to sleep 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:619 (sofia/default/498008001050@193.109.216.112) State Change CS_HANGUP -> CS_REPORTING 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:584 (sofia/default/498008001050@193.109.216.112) Running State Change CS_REPORTING (Cur 15 Tot 1047378) 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:938 (sofia/default/498008001050@193.109.216.112) State REPORTING 2021-08-04 08:35:16.640640 [INFO] mod_json_cdr.c:271 Process [6817b6ee-f520-11eb-bea5-b1a3947a3cab.cdr.json] 2021-08-04 08:35:16.640640 [DEBUG] switch_ivr_async.c:1380 Channel is hung up 2021-08-04 08:35:16.640640 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/4915759322375 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:850 (sofia/default/4915759322375) Callstate Change ACTIVE -> HANGUP 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:852 (sofia/default/4915759322375) State HANGUP 2021-08-04 08:35:16.640640 [DEBUG] mod_sofia.c:432 sofia/default/4915759322375 Overriding SIP cause 480 with 200 from the other leg 2021-08-04 08:35:16.640640 [DEBUG] mod_sofia.c:438 Channel sofia/default/4915759322375 hanging up, cause: NORMAL_CLEARING 2021-08-04 08:35:16.640640 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/4915759322375 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:60 sofia/default/4915759322375 Standard HANGUP, cause: NORMAL_CLEARING 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:852 (sofia/default/4915759322375) State HANGUP going to sleep 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:619 (sofia/default/4915759322375) State Change CS_HANGUP -> CS_REPORTING 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:584 (sofia/default/4915759322375) Running State Change CS_REPORTING (Cur 15 Tot 1047378) 2021-08-04 08:35:16.640640 [DEBUG] switch_core_state_machine.c:938 (sofia/default/4915759322375) State REPORTING 2021-08-04 08:35:16.640640 [INFO] mod_json_cdr.c:271 Process [681dfe0a-f520-11eb-becb-b1a3947a3cab.cdr.json] 2021-08-04 08:35:16.660644 [DEBUG] switch_core_state_machine.c:174 sofia/default/4915759322375 Standard REPORTING, cause: NORMAL_CLEARING 2021-08-04 08:35:16.660644 [DEBUG] switch_core_state_machine.c:938 (sofia/default/4915759322375) State REPORTING going to sleep 2021-08-04 08:35:16.660644 [DEBUG] switch_core_state_machine.c:610 (sofia/default/4915759322375) State Change CS_REPORTING -> CS_DESTROY 2021-08-04 08:35:16.660644 [DEBUG] switch_core_session.c:1665 Session 1047378 (sofia/default/4915759322375) Locked, Waiting on external entities 2021-08-04 08:35:16.660644 [NOTICE] switch_core_session.c:1683 Session 1047378 (sofia/default/4915759322375) Ended 2021-08-04 08:35:16.660644 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/4915759322375 [CS_DESTROY] 2021-08-04 08:35:16.660644 [DEBUG] switch_core_state_machine.c:741 (sofia/default/4915759322375) Running State Change CS_DESTROY (Cur 14 Tot 1047378) 2021-08-04 08:35:16.660644 [DEBUG] switch_core_state_machine.c:751 (sofia/default/4915759322375) State DESTROY 2021-08-04 08:35:16.660644 [DEBUG] mod_sofia.c:343 sofia/default/4915759322375 SOFIA DESTROY 2021-08-04 08:35:16.660644 [DEBUG] switch_core_state_machine.c:181 sofia/default/4915759322375 Standard DESTROY 2021-08-04 08:35:16.660644 [DEBUG] switch_core_state_machine.c:751 (sofia/default/4915759322375) State DESTROY going to sleep 2021-08-04 08:35:16.720652 [DEBUG] switch_core_state_machine.c:174 sofia/default/498008001050@193.109.216.112 Standard REPORTING, cause: NORMAL_CLEARING 2021-08-04 08:35:16.720652 [DEBUG] switch_core_state_machine.c:938 (sofia/default/498008001050@193.109.216.112) State REPORTING going to sleep 2021-08-04 08:35:16.720652 [DEBUG] switch_core_state_machine.c:610 (sofia/default/498008001050@193.109.216.112) State Change CS_REPORTING -> CS_DESTROY 2021-08-04 08:35:16.720652 [DEBUG] switch_core_session.c:1665 Session 1047377 (sofia/default/498008001050@193.109.216.112) Locked, Waiting on external entities 2021-08-04 08:35:16.720652 [NOTICE] switch_core_session.c:1683 Session 1047377 (sofia/default/498008001050@193.109.216.112) Ended 2021-08-04 08:35:16.720652 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/498008001050@193.109.216.112 [CS_DESTROY] 2021-08-04 08:35:16.720652 [DEBUG] switch_core_state_machine.c:741 (sofia/default/498008001050@193.109.216.112) Running State Change CS_DESTROY (Cur 13 Tot 1047378) 2021-08-04 08:35:16.720652 [DEBUG] switch_core_state_machine.c:751 (sofia/default/498008001050@193.109.216.112) State DESTROY 2021-08-04 08:35:16.720652 [DEBUG] mod_sofia.c:343 sofia/default/498008001050@193.109.216.112 SOFIA DESTROY 2021-08-04 08:35:16.720652 [DEBUG] switch_core_state_machine.c:181 sofia/default/498008001050@193.109.216.112 Standard DESTROY 2021-08-04 08:35:16.720652 [DEBUG] switch_core_state_machine.c:751 (sofia/default/498008001050@193.109.216.112) State DESTROY going to sleep 2021-08-04 08:35:16.880644 [DEBUG] switch_scheduler.c:144 Deleting task 77372 switch_ivr_schedule_hangup (681dfe0a-f520-11eb-becb-b1a3947a3cab) 2021-08-04 08:35:17.020644 [DEBUG] switch_rtp.c:7534 RTP RECV DTMF 6:960 2021-08-04 08:35:17.020644 [INFO] switch_channel.c:515 RECV DTMF 6:960 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:1693 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:1740 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2021-08-04 08:35:17.020644 [DEBUG] switch_core_codec.c:223 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com Push codec L16:100 2021-08-04 08:35:17.020644 [ERR] switch_core_video.c:2267 This function is not available, libpng not installed 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:128 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com binding '0' to 'mute' 2021-08-04 08:35:17.020644 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf' 2021-08-04 08:35:17.020644 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f6a5aeb1400 data: 0x7f69a4043fd8 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:128 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com binding '1' to 'vol talk dn' 2021-08-04 08:35:17.020644 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f6a5aeb1400 data: 0x7f69a4044008 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:128 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com binding '2' to 'vol talk up' 2021-08-04 08:35:17.020644 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f6a5aeb1400 data: 0x7f69a4044038 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:128 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com binding '3' to 'vol talk zero' 2021-08-04 08:35:17.020644 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f6a5aeb1400 data: 0x7f69a4044068 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:128 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com binding '4' to 'vol listen dn' 2021-08-04 08:35:17.020644 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f6a5aeb1400 data: 0x7f69a4044098 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:128 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com binding '5' to 'vol listen zero' 2021-08-04 08:35:17.020644 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f6a5aeb1400 data: 0x7f69a40440c8 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:128 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com binding '6' to 'vol listen up' 2021-08-04 08:35:17.020644 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f6a5aeb1400 data: 0x7f69a40440f8 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:128 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com binding '7' to 'energy dn' 2021-08-04 08:35:17.020644 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f6a5aeb1400 data: 0x7f69a4044128 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:128 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com binding '8' to 'energy equ' 2021-08-04 08:35:17.020644 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f6a5aeb1400 data: 0x7f69a4044158 2021-08-04 08:35:17.020644 [DEBUG] conference_member.c:128 sofia/default/4345433960@Lirontest11138.newsip.israelnumber.com binding '9' to 'energy up' 2021-08-04 08:35:17.020644 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f6a5aeb1400 data: 0x7f69a4044188 2021-08-04 08:35:17.020644 [DEBUG] conference_loop.c:1142 Setup timer soft success interval: 20 samples: 160 from codec PCMA 2021-08-04 08:35:18.440722 [NOTICE] switch_channel.c:1104 New Channel sofia/default/86@89.46.223.194 [6d2e755a-f520-11eb-bed7-b1a3947a3cab] 2021-08-04 08:35:18.440722 [DEBUG] switch_core_state_machine.c:584 (sofia/default/86@89.46.223.194) Running State Change CS_NEW (Cur 14 Tot 1047379) 2021-08-04 08:35:18.440722 [DEBUG] sofia.c:9873 sofia/default/86@89.46.223.194 receiving invite from 91.132.58.82:64218 version: 1.6.20 64bit 2021-08-04 08:35:18.440722 [DEBUG] sofia.c:10044 IP 91.132.58.82 Rejected by acl "default". Falling back to Digest auth. 2021-08-04 08:35:18.440722 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [66601112074174013@89.46.223.194] from ip 91.132.58.82 2021-08-04 08:35:18.440722 [DEBUG] switch_core_state_machine.c:603 (sofia/default/86@89.46.223.194) State NEW 2021-08-04 08:35:18.440722 [DEBUG] sofia.c:2334 detaching session 6d2e755a-f520-11eb-bed7-b1a3947a3cab