I have try a very simple dialplan
[from-dahdi]
exten => 1000,1,Dial(SIP/1001,10,t,m)
exten => 1000,2,Voicemail(1001@interni)
exten => 1000,3,Hangup
exten => 1006,1,Dial(dahdi/g15,10,t,m)
exten => 1006,2,Voicemail(1006@interni)
exten => 1006,3,Hangup
exten => 7500,1,VoicemailMain(@interni)
exten => 600,1,Answer()
exten => 600,2,Playback(demo-echotest) ; Let them know what
exten => 600,3,Echo() ; Do the echo test
exten => 600,4,Playback(demo-echodone) ; Let them know it
exten => 600,5,Hangup()
Try increase the debug
core set debug 5
core set verbose 5
This is the result
[Dec 14 13:02:08] DEBUG[2695]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:08] DEBUG[2696]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:08] DEBUG[2690]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 3
[Dec 14 13:02:08] DEBUG[2690]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 4
[Dec 14 13:02:09] DEBUG[2694]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:09] DEBUG[2690]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 2
[Dec 14 13:02:09] DEBUG[2692]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:09] DEBUG[2693]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:09] DEBUG[2690]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 0
[Dec 14 13:02:09] DEBUG[2690]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 1
[Dec 14 13:02:19] DEBUG[2729]: res_pjsip_registrar.c:1286 check_expiration_thread: Woke up at 1671019339 Interval: 30
[Dec 14 13:02:19] DEBUG[2729]: res_pjsip_registrar.c:1293 check_expiration_thread: Expiring 0 contacts
[Dec 14 13:02:31] DEBUG[2751]: chan_dahdi.c:11745 do_monitor: Monitor doohicky got event Ring/Answered on channel 14
[Dec 14 13:02:31] DEBUG[2751]: sig_analog.c:3683 analog_handle_init_event: channel (14) - signaling (2) - event (ANALOG_EVENT_RINGOFFHOOK)
[Dec 14 13:02:31] DEBUG[2751][C-00000001]: stasis.c:575 stasis_topic_create_with_detail: Creating topic. name: channel:1671019351.0, detail:
[Dec 14 13:02:31] DEBUG[2751][C-00000001]: stasis.c:609 stasis_topic_create_with_detail: Topic 'channel:1671019351.0': 0x7f11fc001600 created
[Dec 14 13:02:31] DEBUG[2751][C-00000001]: stasis.c:575 stasis_topic_create_with_detail: Creating topic. name: cache:4/channel:1671019351.0, detail:
[Dec 14 13:02:31] DEBUG[2751][C-00000001]: stasis.c:609 stasis_topic_create_with_detail: Topic 'cache:4/channel:1671019351.0': 0x7f11fc004060 created
[Dec 14 13:02:31] DEBUG[2751][C-00000001]: channel.c:989 __ast_channel_alloc_ap: Channel 0x7f11fc0018b0 'DAHDI/14-1' allocated
[Dec 14 13:02:31] DEBUG[2751][C-00000001]: dsp.c:507 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[Dec 14 13:02:31] DEBUG[2751][C-00000001]: dsp.c:507 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[Dec 14 13:02:31] DEBUG[2702]: devicestate.c:361 _ast_device_state: No provider found, checking channel drivers for DAHDI - 14
[Dec 14 13:02:31] DEBUG[2702]: devicestate.c:466 do_state_change: Changing state for DAHDI/14 - state 2 (In use)
[Dec 14 13:02:31] DEBUG[2763]: sig_analog.c:1736 __analog_ss_thread: __analog_ss_thread 14
-- Starting simple switch on 'DAHDI/14-1'
[Dec 14 13:02:33] DEBUG[2763][C-00000001]: sig_analog.c:3561 analog_exception: analog_exception 14
[Dec 14 13:02:33] DEBUG[2763][C-00000001]: sig_analog.c:3653 analog_exception: Exception on 36, channel 14
[Dec 14 13:02:33] DEBUG[2763][C-00000001]: sig_analog.c:2698 __analog_handle_event: __analog_handle_event 14
[Dec 14 13:02:33] DEBUG[2763][C-00000001]: sig_analog.c:2725 __analog_handle_event: Got event ANALOG_EVENT_PULSE_START(10) on channel 14 (index 0)
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:3561 analog_exception: analog_exception 14
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:3653 analog_exception: Exception on 36, channel 14
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:2698 __analog_handle_event: __analog_handle_event 14
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:2725 __analog_handle_event: Got event UNKNOWN/OTHER(65588) on channel 14 (index 0)
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:2729 __analog_handle_event: Detected pulse digit '4'
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:1568 analog_handle_dtmf: End DTMF digit: 0x34 '4' on DAHDI/14-1
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: chan_dahdi.c:1654 my_handle_dtmf: End DTMF digit: 0x34 '4' on DAHDI/14-1
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:2112 __analog_ss_thread: waitfordigit returned '4' (52), timeout = 0
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:2356 __analog_ss_thread: Can't match 4 from '1008' in context from-dahdi
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: channel.c:2565 ast_hangup: Channel 0x7f11fc0018b0 'DAHDI/14-1' hanging up. Refs: 2
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: chan_dahdi.c:6001 dahdi_hangup: dahdi_hangup(DAHDI/14-1)
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:1256 analog_hangup: analog_hangup 14
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:1278 analog_hangup: Hangup: channel: 14 index = 0, normal = 1, callwait = 0, thirdcall = 0
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: chan_dahdi.c:6594 dahdi_setoption: Set option TONE VERIFY, mode: OFF(0) on DAHDI/14-1
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: chan_dahdi.c:6604 dahdi_setoption: Set option TDD MODE, value: OFF(0) on DAHDI/14-1
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: sig_analog.c:764 analog_update_conf: Updated conferencing on 14, with 0 conference users
-- Hanging up on 'DAHDI/14-1'
-- Hungup 'DAHDI/14-1'
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: channel.c:2211 ast_channel_destructor: Channel 0x7f11fc0018b0 'DAHDI/14-1' destroying
[Dec 14 13:02:34] DEBUG[2710]: cdr.c:1468 cdr_object_finalize: Finalized CDR for DAHDI/14-1 - start 1671019351.146574 answer 0.000000 end 1671019354.019709 dur 2.873 bill 1671019354.019 dispo FAILED
[Dec 14 13:02:34] DEBUG[2710]: cdr.c:3541 post_cdr: Skipping CDR for DAHDI/14-1 since we weren't answered
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: stasis.c:437 topic_dtor: Destroying topic. name: cache:4/channel:1671019351.0, detail:
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: stasis.c:446 topic_dtor: Topic 'cache:4/channel:1671019351.0': 0x7f11fc004060 destroyed
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: stasis.c:437 topic_dtor: Destroying topic. name: channel:1671019351.0, detail:
[Dec 14 13:02:34] DEBUG[2763][C-00000001]: stasis.c:446 topic_dtor: Topic 'channel:1671019351.0': 0x7f11fc001600 destroyed
[Dec 14 13:02:34] DEBUG[2702]: devicestate.c:361 _ast_device_state: No provider found, checking channel drivers for DAHDI - 14
[Dec 14 13:02:34] DEBUG[2702]: devicestate.c:466 do_state_change: Changing state for DAHDI/14 - state 0 (Unknown)
[Dec 14 13:02:34] DEBUG[2751]: chan_dahdi.c:11745 do_monitor: Monitor doohicky got event Hook Transition Complete on channel 14
[Dec 14 13:02:34] DEBUG[2751]: sig_analog.c:3683 analog_handle_init_event: channel (14) - signaling (2) - event (ANALOG_EVENT_HOOKCOMPLETE)
[Dec 14 13:02:35] DEBUG[2751]: chan_dahdi.c:11745 do_monitor: Monitor doohicky got event Pulse Start on channel 14
[Dec 14 13:02:35] DEBUG[2751]: sig_analog.c:3683 analog_handle_init_event: channel (14) - signaling (2) - event (ANALOG_EVENT_PULSE_START)
[Dec 14 13:02:36] DEBUG[2751]: chan_dahdi.c:11745 do_monitor: Monitor doohicky got event Event 65591 on channel 14
[Dec 14 13:02:36] DEBUG[2751]: sig_analog.c:3683 analog_handle_init_event: channel (14) - signaling (2) - event (UNKNOWN/OTHER)
[Dec 14 13:02:37] DEBUG[2751]: chan_dahdi.c:11745 do_monitor: Monitor doohicky got event Pulse Start on channel 14
[Dec 14 13:02:37] DEBUG[2751]: sig_analog.c:3683 analog_handle_init_event: channel (14) - signaling (2) - event (ANALOG_EVENT_PULSE_START)
[Dec 14 13:02:38] DEBUG[2751]: chan_dahdi.c:11745 do_monitor: Monitor doohicky got event Event 65591 on channel 14
[Dec 14 13:02:38] DEBUG[2751]: sig_analog.c:3683 analog_handle_init_event: channel (14) - signaling (2) - event (UNKNOWN/OTHER)
[Dec 14 13:02:41] DEBUG[2751]: chan_dahdi.c:11745 do_monitor: Monitor doohicky got event On hook on channel 14
[Dec 14 13:02:41] DEBUG[2751]: sig_analog.c:3683 analog_handle_init_event: channel (14) - signaling (2) - event (ANALOG_EVENT_ONHOOK)
[Dec 14 13:02:49] DEBUG[2719]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:49] DEBUG[2717]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:49] DEBUG[2718]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:49] DEBUG[2712]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 9
[Dec 14 13:02:49] DEBUG[2712]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 7
[Dec 14 13:02:49] DEBUG[2712]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 8
[Dec 14 13:02:49] DEBUG[2729]: res_pjsip_registrar.c:1286 check_expiration_thread: Woke up at 1671019369 Interval: 30
[Dec 14 13:02:49] DEBUG[2729]: res_pjsip_registrar.c:1293 check_expiration_thread: Expiring 0 contacts
[Dec 14 13:02:49] DEBUG[2720]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:49] DEBUG[2691]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 10
[Dec 14 13:02:49] DEBUG[2715]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:49] DEBUG[2716]: threadpool.c:1169 worker_idle: Worker thread idle timeout reached. Dying.
[Dec 14 13:02:49] DEBUG[2712]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 5
[Dec 14 13:02:49] DEBUG[2712]: threadpool.c:1028 worker_thread_destroy: Destroying worker thread 6