I press *33102 (*33 for eavesdrop and 102 for the ext) it asks for the pin, I enter the pin, then the call terminates
Any ideas on why this is? I can see in the logs below that it is executing the dial plan but I don't see why the dial plan is wrong? I have attached a copy of the dial plan
2020-01-29 13:02:23.277419 [NOTICE] mod_dptools.c:1406 Channel [sofia/internal/105@abn3.npusa.us] has been answered
2020-01-29 13:02:23.277419 [DEBUG] switch_channel.c:3865 (sofia/internal/105@abn3.npusa.us) Callstate Change EARLY -> ACTIVE
2020-01-29 13:02:23.277419 [DEBUG] sofia.c:7301 Channel sofia/internal/105@abn3.npusa.us entering state [completed][200]
EXECUTE [depth=0] sofia/internal/105@abn3.npusa.us set(pin_number=5409)
2020-01-29 13:02:23.277419 [DEBUG] mod_dptools.c:1672 SET sofia/internal/105@abn3.npusa.us [pin_number]=[5409]
EXECUTE [depth=0] sofia/internal/105@abn3.npusa.us lua(eavesdrop.lua 602)
2020-01-29 13:02:23.277419 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@16000hz 1 channels 20ms
2020-01-29 13:02:23.277419 [DEBUG] switch_ivr_async.c:1641 No silence detection configured; assuming start of speech
2020-01-29 13:02:23.357428 [DEBUG] sofia.c:7301 Channel sofia/internal/105@abn3.npusa.us entering state [ready][200]
2020-01-29 13:02:23.437421 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed.
2020-01-29 13:02:23.437421 [DEBUG] switch_core_io.c:448 Setting BUG Codec G722:9
2020-01-29 13:02:26.037417 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/ivr/ivr-please_enter_pin_followed_by_pound.wav
2020-01-29 13:02:26.497419 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF 5:1520
2020-01-29 13:02:26.497419 [INFO] switch_channel.c:522 RECV DTMF 5:1520
2020-01-29 13:02:26.918135 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF 4:800
2020-01-29 13:02:26.918135 [INFO] switch_channel.c:522 RECV DTMF 4:800
2020-01-29 13:02:27.457415 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF 0:1200
2020-01-29 13:02:27.457415 [INFO] switch_channel.c:522 RECV DTMF 0:1200
2020-01-29 13:02:27.837420 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF 9:640
2020-01-29 13:02:27.837420 [INFO] switch_channel.c:522 RECV DTMF 9:640
2020-01-29 13:02:28.317417 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF #:640
2020-01-29 13:02:28.317417 [INFO] switch_channel.c:522 RECV DTMF #:640
2020-01-29 13:02:28.317417 [NOTICE] switch_cpp.cpp:1443 [eavesdrop] pin is correct
2020-01-29 13:02:28.317417 [DEBUG] switch_cpp.cpp:1187 sofia/internal/105@abn3.npusa.us destroy/unlink session from object
2020-01-29 13:02:28.317417 [NOTICE] switch_core_state_machine.c:386 sofia/internal/105@abn3.npusa.us has executed the last dialplan instruction, hanging up.
2020-01-29 13:02:28.317417 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/105@abn3.npusa.us [CS_EXECUTE] [NORMAL_CLEARING]
2020-01-29 13:02:28.317417 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/105@abn3.npusa.us) State EXECUTE going to sleep
2020-01-29 13:02:28.317417 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/105@abn3.npusa.us) Running State Change CS_HANGUP (Cur 1 Tot 164)
2020-01-29 13:02:28.317417 [DEBUG] switch_ivr_async.c:1446 Stop recording file /var/lib/freeswitch/recordings/abn3.npusa.us/archive/2020/Jan/29/d9071de3-1aa5-4c51-8607-e796daf1b750.wav
2020-01-29 13:02:28.337415 [DEBUG] switch_ivr_async.c:1521 Channel is hung up
2020-01-29 13:02:28.337415 [DEBUG] switch_core_media_bug.c:1295 Removing BUG from sofia/internal/105@abn3.npusa.us
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/105@abn3.npusa.us) Callstate Change ACTIVE -> HANGUP
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/105@abn3.npusa.us) State HANGUP
2020-01-29 13:02:28.337415 [DEBUG] mod_sofia.c:453 Channel sofia/internal/105@abn3.npusa.us hanging up, cause: NORMAL_CLEARING
2020-01-29 13:02:28.337415 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/105@abn3.npusa.us
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:60 sofia/internal/105@abn3.npusa.us Standard HANGUP, cause: NORMAL_CLEARING
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/105@abn3.npusa.us) State HANGUP going to sleep
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/105@abn3.npusa.us) State Change CS_HANGUP -> CS_REPORTING
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/105@abn3.npusa.us) Running State Change CS_REPORTING (Cur 1 Tot 164)
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/105@abn3.npusa.us) State REPORTING
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:174 sofia/internal/105@abn3.npusa.us Standard REPORTING, cause: NORMAL_CLEARING
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/105@abn3.npusa.us) State REPORTING going to sleep
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/105@abn3.npusa.us) State Change CS_REPORTING -> CS_DESTROY
2020-01-29 13:02:28.377417 [DEBUG] switch_core_session.c:1726 Session 164 (sofia/internal/105@abn3.npusa.us) Locked, Waiting on external entities
2020-01-29 13:02:28.377417 [NOTICE] switch_core_session.c:1744 Session 164 (sofia/internal/105@abn3.npusa.us) Ended
2020-01-29 13:02:28.377417 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/105@abn3.npusa.us [CS_DESTROY]
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/105@abn3.npusa.us) Running State Change CS_DESTROY (Cur 0 Tot 164)
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/105@abn3.npusa.us) State DESTROY
2020-01-29 13:02:28.377417 [DEBUG] mod_sofia.c:364 sofia/internal/105@abn3.npusa.us SOFIA DESTROY
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:181 sofia/internal/105@abn3.npusa.us Standard DESTROY
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/105@abn3.npusa.us) State DESTROY going to sleep
Any ideas on why this is? I can see in the logs below that it is executing the dial plan but I don't see why the dial plan is wrong? I have attached a copy of the dial plan
2020-01-29 13:02:23.277419 [NOTICE] mod_dptools.c:1406 Channel [sofia/internal/105@abn3.npusa.us] has been answered
2020-01-29 13:02:23.277419 [DEBUG] switch_channel.c:3865 (sofia/internal/105@abn3.npusa.us) Callstate Change EARLY -> ACTIVE
2020-01-29 13:02:23.277419 [DEBUG] sofia.c:7301 Channel sofia/internal/105@abn3.npusa.us entering state [completed][200]
EXECUTE [depth=0] sofia/internal/105@abn3.npusa.us set(pin_number=5409)
2020-01-29 13:02:23.277419 [DEBUG] mod_dptools.c:1672 SET sofia/internal/105@abn3.npusa.us [pin_number]=[5409]
EXECUTE [depth=0] sofia/internal/105@abn3.npusa.us lua(eavesdrop.lua 602)
2020-01-29 13:02:23.277419 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@16000hz 1 channels 20ms
2020-01-29 13:02:23.277419 [DEBUG] switch_ivr_async.c:1641 No silence detection configured; assuming start of speech
2020-01-29 13:02:23.357428 [DEBUG] sofia.c:7301 Channel sofia/internal/105@abn3.npusa.us entering state [ready][200]
2020-01-29 13:02:23.437421 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed.
2020-01-29 13:02:23.437421 [DEBUG] switch_core_io.c:448 Setting BUG Codec G722:9
2020-01-29 13:02:26.037417 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/ivr/ivr-please_enter_pin_followed_by_pound.wav
2020-01-29 13:02:26.497419 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF 5:1520
2020-01-29 13:02:26.497419 [INFO] switch_channel.c:522 RECV DTMF 5:1520
2020-01-29 13:02:26.918135 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF 4:800
2020-01-29 13:02:26.918135 [INFO] switch_channel.c:522 RECV DTMF 4:800
2020-01-29 13:02:27.457415 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF 0:1200
2020-01-29 13:02:27.457415 [INFO] switch_channel.c:522 RECV DTMF 0:1200
2020-01-29 13:02:27.837420 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF 9:640
2020-01-29 13:02:27.837420 [INFO] switch_channel.c:522 RECV DTMF 9:640
2020-01-29 13:02:28.317417 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF #:640
2020-01-29 13:02:28.317417 [INFO] switch_channel.c:522 RECV DTMF #:640
2020-01-29 13:02:28.317417 [NOTICE] switch_cpp.cpp:1443 [eavesdrop] pin is correct
2020-01-29 13:02:28.317417 [DEBUG] switch_cpp.cpp:1187 sofia/internal/105@abn3.npusa.us destroy/unlink session from object
2020-01-29 13:02:28.317417 [NOTICE] switch_core_state_machine.c:386 sofia/internal/105@abn3.npusa.us has executed the last dialplan instruction, hanging up.
2020-01-29 13:02:28.317417 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/105@abn3.npusa.us [CS_EXECUTE] [NORMAL_CLEARING]
2020-01-29 13:02:28.317417 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/105@abn3.npusa.us) State EXECUTE going to sleep
2020-01-29 13:02:28.317417 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/105@abn3.npusa.us) Running State Change CS_HANGUP (Cur 1 Tot 164)
2020-01-29 13:02:28.317417 [DEBUG] switch_ivr_async.c:1446 Stop recording file /var/lib/freeswitch/recordings/abn3.npusa.us/archive/2020/Jan/29/d9071de3-1aa5-4c51-8607-e796daf1b750.wav
2020-01-29 13:02:28.337415 [DEBUG] switch_ivr_async.c:1521 Channel is hung up
2020-01-29 13:02:28.337415 [DEBUG] switch_core_media_bug.c:1295 Removing BUG from sofia/internal/105@abn3.npusa.us
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/105@abn3.npusa.us) Callstate Change ACTIVE -> HANGUP
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/105@abn3.npusa.us) State HANGUP
2020-01-29 13:02:28.337415 [DEBUG] mod_sofia.c:453 Channel sofia/internal/105@abn3.npusa.us hanging up, cause: NORMAL_CLEARING
2020-01-29 13:02:28.337415 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/105@abn3.npusa.us
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:60 sofia/internal/105@abn3.npusa.us Standard HANGUP, cause: NORMAL_CLEARING
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/105@abn3.npusa.us) State HANGUP going to sleep
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/105@abn3.npusa.us) State Change CS_HANGUP -> CS_REPORTING
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/105@abn3.npusa.us) Running State Change CS_REPORTING (Cur 1 Tot 164)
2020-01-29 13:02:28.337415 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/105@abn3.npusa.us) State REPORTING
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:174 sofia/internal/105@abn3.npusa.us Standard REPORTING, cause: NORMAL_CLEARING
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/105@abn3.npusa.us) State REPORTING going to sleep
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/105@abn3.npusa.us) State Change CS_REPORTING -> CS_DESTROY
2020-01-29 13:02:28.377417 [DEBUG] switch_core_session.c:1726 Session 164 (sofia/internal/105@abn3.npusa.us) Locked, Waiting on external entities
2020-01-29 13:02:28.377417 [NOTICE] switch_core_session.c:1744 Session 164 (sofia/internal/105@abn3.npusa.us) Ended
2020-01-29 13:02:28.377417 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/105@abn3.npusa.us [CS_DESTROY]
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/105@abn3.npusa.us) Running State Change CS_DESTROY (Cur 0 Tot 164)
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/105@abn3.npusa.us) State DESTROY
2020-01-29 13:02:28.377417 [DEBUG] mod_sofia.c:364 sofia/internal/105@abn3.npusa.us SOFIA DESTROY
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:181 sofia/internal/105@abn3.npusa.us Standard DESTROY
2020-01-29 13:02:28.377417 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/105@abn3.npusa.us) State DESTROY going to sleep