f8173180-bbdf-4790-9767-2bf5e9244e35 EXECUTE [depth=0] sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 export(hostname=sip7) f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:55.252591 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [hostname]=[sip7] f8173180-bbdf-4790-9767-2bf5e9244e35 EXECUTE [depth=0] sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 set(rtp_payload_number=101) f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:55.252591 [DEBUG] mod_dptools.c:1672 SET sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 [rtp_payload_number]=[101] f8173180-bbdf-4790-9767-2bf5e9244e35 EXECUTE [depth=0] sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 set(zrtp_secure_media=false) f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:55.252591 [DEBUG] mod_dptools.c:1672 SET sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 [zrtp_secure_media]=[false] f8173180-bbdf-4790-9767-2bf5e9244e35 EXECUTE [depth=0] sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 answer() f8173180-bbdf-4790-9767-2bf5e9244e35 EXECUTE [depth=0] sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 bind_digit_action(default,*,exec:transfer,ivr_7636_class) f8173180-bbdf-4790-9767-2bf5e9244e35 EXECUTE [depth=0] sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 ivr(7636_class) f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:55.252591 [DEBUG] switch_ivr_menu.c:486 Executing IVR menu 7636_class f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:55.252591 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:58.512596 [DEBUG] switch_rtp.c:8004 RTP RECV DTMF 4:2240 f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:58.512596 [DEBUG] mod_dptools.c:132 sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 Digit NOT match binding [4] f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:58.512596 [INFO] switch_channel.c:522 RECV DTMF 4:2000 f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:58.512596 [DEBUG] switch_channel.c:618 sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 Queue dtmf f8173180-bbdf-4790-9767-2bf5e9244e35 digit=4 ms=250 samples=2000 f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:58.512596 [DEBUG] switch_ivr_play_say.c:1933 done playing file file_string:///usr/share/freeswitch/sounds/hotlines/7636_2021/00.mp3 f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:15:58.512596 [DEBUG] switch_ivr_menu.c:378 waiting for 1/2 digits t/o 2500 **2.5 second timeout then it execute the extension** f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:01.032614 [DEBUG] switch_ivr_menu.c:425 digits 4 f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:01.032614 [DEBUG] switch_ivr_menu.c:580 IVR action on menu 7636_class matched 4 param 7636_class_4_require_pin f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:01.032614 [DEBUG] switch_ivr_menu.c:584 switch_ivr_menu_execute todo=[1] f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:01.032614 [DEBUG] switch_ivr_menu.c:486 Executing IVR menu 7636_class_4_require_pin f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:01.032614 [DEBUG] switch_ivr_play_say.c:1488 Codec Activated L16@8000hz 1 channels 20ms f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:04.072597 [DEBUG] switch_ivr_play_say.c:1933 done playing file file_string:///usr/share/freeswitch/sounds/hotlines/7636_2021/enter-pin.mp3 f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:04.072597 [DEBUG] switch_ivr_menu.c:378 waiting for 6/6 digits t/o 10000 f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [NOTICE] sofia.c:1089 Hangup sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 [CS_EXECUTE] [NORMAL_CLEARING] f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_ivr_menu.c:425 digits f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_ivr_menu.c:664 IVR menu 7636_class_4_require_pin no input detected f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_ivr_menu.c:676 exit-sound voicemail/vm-goodbye.wav f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_core_session.c:2905 sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_core_state_machine.c:651 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) State EXECUTE going to sleep f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_core_state_machine.c:585 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) Running State Change CS_HANGUP (Cur 15 Tot 677418) f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_core_state_machine.c:848 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) Callstate Change ACTIVE -> HANGUP f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_core_state_machine.c:850 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) State HANGUP f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] mod_sofia.c:453 Channel sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 hanging up, cause: NORMAL_CLEARING f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_core_state_machine.c:60 sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 Standard HANGUP, cause: NORMAL_CLEARING f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_core_state_machine.c:850 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) State HANGUP going to sleep f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_core_state_machine.c:620 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) State Change CS_HANGUP -> CS_REPORTING f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_core_state_machine.c:585 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) Running State Change CS_REPORTING (Cur 15 Tot 677418) f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.052590 [DEBUG] switch_core_state_machine.c:936 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) State REPORTING f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [DEBUG] switch_core_state_machine.c:174 sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 Standard REPORTING, cause: NORMAL_CLEARING f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [DEBUG] switch_core_state_machine.c:936 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) State REPORTING going to sleep f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [DEBUG] switch_core_state_machine.c:611 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) State Change CS_REPORTING -> CS_DESTROY f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [DEBUG] switch_core_session.c:1726 Session 677417 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) Locked, Waiting on external entities f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [NOTICE] switch_core_session.c:1744 Session 677417 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) Ended f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 [CS_DESTROY] f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [DEBUG] switch_core_state_machine.c:739 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) Running State Change CS_DESTROY (Cur 14 Tot 677418) f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [DEBUG] switch_core_state_machine.c:749 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) State DESTROY f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [DEBUG] mod_sofia.c:364 sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 SOFIA DESTROY f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [DEBUG] switch_core_state_machine.c:181 sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061 Standard DESTROY f8173180-bbdf-4790-9767-2bf5e9244e35 2021-08-18 09:16:05.272592 [DEBUG] switch_core_state_machine.c:749 (sofia/external/$WORKING-CALLER@$REMOTE_MEDIA:5061) State DESTROY going to sleep