Internal call goes to voicemail, extern just hangs up

athoopen · January 1, 2025, 5:34pm

All,

Newbie could do with some help

If I internally call to extension 101 and it doesn’t get picked up, it is transfered to voicemail. However If an external call comes in to my pbx, it does gets transfered to extension 101, it rings, but if it doesn’t get picked up it is not transfered to voicemail, it just hangs up.

At the inbound routes I have set that ANY DID and ANY callerid must be routed to extension 101.
That does work, however voice mail doesn’t pick up if it’s an external call

Can any of you push me into the right direction …

Here is the log from an internal and an external call:

Remarks
-) removed date from log to preserve space
-) MY-EXT-NUMBER is my external phone number (on which you can reach me LOL)

external call to MY-EXT-NUMBER

[17:38:53] VERBOSE[8374] netsock2.c: Using SIP RTP Audio TOS bits 184
[17:38:53] VERBOSE[8374] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[17:38:53] VERBOSE[8374] netsock2.c: Using SIP RTP Audio CoS mark 5
[17:38:53] VERBOSE[19246][C-00000002] app_dial.c: Called PJSIP/101/sip:[email protected]:5060
[17:38:53] VERBOSE[19246][C-00000002] app_dial.c: Connected line update to SIP/MY-EXT-NUMBER-00000000 prevented.
[17:38:53] VERBOSE[19246][C-00000002] app_dial.c: PJSIP/101-00000002 is ringing
[17:38:53] VERBOSE[19246][C-00000002] app_dial.c: PJSIP/101-00000002 is ringing
[17:39:23] VERBOSE[19246][C-00000002] app_macro.c: Spawn extension (macro-dial-one, s, 56) exited non-zero on 'SIP/MY-EXT-NUMBER-00000000' in macro 'dial-one'
[17:39:23] VERBOSE[19246][C-00000002] app_macro.c: Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/MY-EXT-NUMBER-00000000' in macro 'exten-vm'
[17:39:23] VERBOSE[19246][C-00000002] pbx.c: Spawn extension (from-did-direct, 101, 3) exited non-zero on 'SIP/MY-EXT-NUMBER-00000000'
[17:39:23] VERBOSE[19246][C-00000002] pbx.c: Executing [h@from-did-direct:1] Macro("SIP/MY-EXT-NUMBER-00000000", "hangupcall,") in new stack
[17:39:23] VERBOSE[19246][C-00000002] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/MY-EXT-NUMBER-00000000", "1?theend") in new stack
[17:39:23] VERBOSE[19246][C-00000002] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[17:39:23] VERBOSE[19246][C-00000002] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/MY-EXT-NUMBER-00000000", "0?Set(CDR(recordingfile)=)") in new stack
[17:39:23] VERBOSE[19246][C-00000002] pbx.c: Executing [s@macro-hangupcall:4] Hangup("SIP/MY-EXT-NUMBER-00000000", "") in new stack
[17:39:23] VERBOSE[19246][C-00000002] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/MY-EXT-NUMBER-00000000' in macro 'hangupcall'
[17:39:23] VERBOSE[19246][C-00000002] pbx.c: Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/MY-EXT-NUMBER-00000000'


internal call to 101

[17:42:12] VERBOSE[8374] netsock2.c: Using SIP RTP Audio TOS bits 184
[17:42:12] VERBOSE[8374] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[17:42:12] VERBOSE[8374] netsock2.c: Using SIP RTP Audio CoS mark 5
[17:42:12] VERBOSE[19940][C-00000003] app_dial.c: Called PJSIP/101/sip:[email protected]:5060
[17:42:12] VERBOSE[19940][C-00000003] app_dial.c: PJSIP/101-00000004 is ringing
[17:42:12] VERBOSE[19940][C-00000003] app_dial.c: PJSIP/101-00000004 is ringing
[17:43:12] VERBOSE[19940][C-00000003] app_dial.c: Nobody picked up in 60000 ms
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-dial-one:57] ExecIf("PJSIP/102-00000003", "0?MacroExit()") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-dial-one:58] ExecIf("PJSIP/102-00000003", "0?Set(DIALSTATUS=)") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-dial-one:59] GosubIf("PJSIP/102-00000003", "0?s-NOANSWER,1()") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-dial-one:60] MacroExit("PJSIP/102-00000003", "") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-exten-vm:15] Set("PJSIP/102-00000003", "SV_DIALSTATUS=NOANSWER") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-exten-vm:16] GosubIf("PJSIP/102-00000003", "0?docfu,1()") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-exten-vm:17] GosubIf("PJSIP/102-00000003", "0?docfb,1()") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-exten-vm:18] Set("PJSIP/102-00000003", "DIALSTATUS=NOANSWER") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-exten-vm:19] ExecIf("PJSIP/102-00000003", "0?MacroExit()") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-exten-vm:20] GotoIf("PJSIP/102-00000003", "0?s-NOANSWER,1") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-exten-vm:21] Macro("PJSIP/102-00000003", "vm,101,NOANSWER,") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-vm:1] Macro("PJSIP/102-00000003", "user-callerid,SKIPTTL") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/102-00000003", "TOUCH_MONITOR=1735749732.4") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/102-00000003", "CHANCONTEXT=") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/102-00000003", "CHANCONTEXT=") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/102-00000003", "CHANEXTENCONTEXT=102-00000003") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/102-00000003", "CHANEXTEN=102-00000003") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/102-00000003", "CALLERID(number)=102") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/102-00000003", "AMPUSER=102") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/102-00000003", "HOTDESCKCHAN=102-00000003") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/102-00000003", "HOTDESKEXTEN=102") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/102-00000003", "HOTDESKCALL=0") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/102-00000003", "0?Set(HOTDESKCALL=1)") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/102-00000003", "0?Set(CALLERID(name)=)") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/102-00000003", "7?report") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,27)
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:27] NoOp("PJSIP/102-00000003", "Macro Depth is 3") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:28] GotoIf("PJSIP/102-00000003", "1?report2:macroerror") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("PJSIP/102-00000003", "1?continue") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,47)
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:47] Set("PJSIP/102-00000003", "CALLERID(number)=102") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/102-00000003", "CALLERID(name)=Kantoor") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:49] GotoIf("PJSIP/102-00000003", "0?cnum") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/102-00000003", "CDR(cnam)=Kantoor") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:51] Set("PJSIP/102-00000003", "CDR(cnum)=102") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/102-00000003", "CHANNEL(language)=nl") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-vm:2] Set("PJSIP/102-00000003", "VMGAIN=") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-vm:3] Macro("PJSIP/102-00000003", "blkvm-check,") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-blkvm-check:1] Set("PJSIP/102-00000003", "GOSUB_RETVAL=") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf("PJSIP/102-00000003", "0?Set(GOSUB_RETVAL=TRUE)") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit("PJSIP/102-00000003", "") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-vm:4] GotoIf("PJSIP/102-00000003", "1?vmx,1") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx_builtins.c: Goto (macro-vm,vmx,1)
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:1] Set("PJSIP/102-00000003", "MEXTEN=101") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:2] Set("PJSIP/102-00000003", "MMODE=NOANSWER") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:3] Set("PJSIP/102-00000003", "RETVM=") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:4] Set("PJSIP/102-00000003", "MODE=unavail") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:5] Macro("PJSIP/102-00000003", "get-vmcontext,101") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/102-00000003", "VMCONTEXT=default") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/102-00000003", "0?200:300") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/102-00000003", "") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:6] Set("PJSIP/102-00000003", "MODE=unavail") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:7] NoOp("PJSIP/102-00000003", "MODE IS: unavail") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:8] GotoIf("PJSIP/102-00000003", "1?chknomsg") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx_builtins.c: Goto (macro-vm,vmx,10)
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:10] GotoIf("PJSIP/102-00000003", "0?s-NOANSWER,1") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:11] GotoIf("PJSIP/102-00000003", "1?notdirect") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx_builtins.c: Goto (macro-vm,vmx,13)
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:13] NoOp("PJSIP/102-00000003", "Checking if ext 101 is enabled: ") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [vmx@macro-vm:14] GotoIf("PJSIP/102-00000003", "1?s-NOANSWER,1") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx_builtins.c: Goto (macro-vm,s-NOANSWER,1)
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s-NOANSWER@macro-vm:1] Macro("PJSIP/102-00000003", "get-vmcontext,101") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/102-00000003", "VMCONTEXT=default") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/102-00000003", "0?200:300") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/102-00000003", "") in new stack
[17:43:12] VERBOSE[19940][C-00000003] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail("PJSIP/102-00000003", "101@default,u") in new stack
[17:43:13] VERBOSE[19940][C-00000003] file.c: <PJSIP/102-00000003> Playing 'vm-theperson.ulaw' (language 'nl')
[17:43:14] VERBOSE[19940][C-00000003] file.c: <PJSIP/102-00000003> Playing 'digits/1.ulaw' (language 'nl')
[17:43:15] VERBOSE[19940][C-00000003] file.c: <PJSIP/102-00000003> Playing 'digits/0.ulaw' (language 'nl')
[17:43:15] VERBOSE[19940][C-00000003] file.c: <PJSIP/102-00000003> Playing 'digits/1.ulaw' (language 'nl')
[17:43:16] VERBOSE[19940][C-00000003] file.c: <PJSIP/102-00000003> Playing 'vm-isunavail.ulaw' (language 'nl')
[17:43:17] VERBOSE[19940][C-00000003] file.c: <PJSIP/102-00000003> Playing 'vm-intro.ulaw' (language 'nl')
[17:43:22] VERBOSE[19940][C-00000003] file.c: <PJSIP/102-00000003> Playing 'beep.ulaw' (language 'nl')
[17:43:23] VERBOSE[19940][C-00000003] app_voicemail.c: Recording the message
[17:43:23] VERBOSE[19940][C-00000003] app.c: x=0, open writing:  /var/spool/asterisk/voicemail/default/101/tmp/RIxowi format: wav, 0x74051628
[17:43:23] VERBOSE[19940][C-00000003] app.c: User hung up
[17:43:23] VERBOSE[19940][C-00000003] app_voicemail.c: Recording was 0 seconds long but needs to be at least 1 - abandoning
[17:43:23] VERBOSE[19940][C-00000003] app_macro.c: Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'PJSIP/102-00000003' in macro 'vm'
[17:43:23] VERBOSE[19940][C-00000003] app_macro.c: Spawn extension (macro-exten-vm, s, 21) exited non-zero on 'PJSIP/102-00000003' in macro 'exten-vm'
[17:43:23] VERBOSE[19940][C-00000003] pbx.c: Spawn extension (from-internal, 101, 3) exited non-zero on 'PJSIP/102-00000003'
[17:43:23] VERBOSE[19940][C-00000003] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/102-00000003", "hangupcall") in new stack
[17:43:23] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/102-00000003", "1?theend") in new stack
[17:43:23] VERBOSE[19940][C-00000003] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[17:43:23] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/102-00000003", "0?Set(CDR(recordingfile)=)") in new stack
[17:43:23] VERBOSE[19940][C-00000003] pbx.c: Executing [s@macro-hangupcall:4] Hangup("PJSIP/102-00000003", "") in new stack
[17:43:23] VERBOSE[19940][C-00000003] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/102-00000003' in macro 'hangupcall'
[17:43:23] VERBOSE[19940][C-00000003] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/102-00000003'

Stewart1 · January 1, 2025, 6:19pm

You have ext 101 set to ring for 60 seconds before going to voicemail, but the trunking provider dropped the call after 30 seconds. Possibly it went to their voicemail or a (maybe nonworking) failover destination. Or, maybe they simply don’t allow you to ring longer than 30 seconds. In the latter case, you could force answer the incoming call, or set the ring time to e.g. 25 seconds… The provider might have useful information in their log.

system · February 1, 2025, 6:19pm

This topic was automatically closed 31 days after the last reply. New replies are no longer allowed.