Wake Up Call added but phone doesn't ring

athoopen · December 26, 2024, 3:24pm

All,
I (newby in this space) am challenged by the Wake Up Call Module (15.0.5.5) I can issue a wake up call from my mobile phone (using Linphone). It also gets listed in the FreePBX UI, but it doesn’t ring my mobile phone.
It does an attempt but some how it fails. Below you’ll find the full trace (wakeup call is set for 15:15).
Anybody any tricks or tips to get my phone “wake me up”?

TIA
Arjan

PS: Removed data in trace to save space

[15:14:38] VERBOSE[27289] res_pjsip_registrar.c: Added contact 'sip:[email protected]:52729;pn-prid=B80E73C93CB73D058859268FEA41CA67686374B4C3172E8C6143958253E3ACF6:voip&24F3ADA36C0A8395262AC49F2FA4A92BE2DD2E78CB903CFB4EE587CE811616A8:remote;pn-provider=apns;pn-param=ABCD1234.org.linphone.phone.voip&remote;pn-silent=1;pn-timeout=0;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-groupchat-str=GC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf' to AOR '301' with expiration of 3600 seconds
[15:14:39] VERBOSE[27289] res_pjsip_registrar.c: Removed contact 'sip:[email protected]:63162;pn-prid=B80E73C93CB73D058859268FEA41CA67686374B4C3172E8C6143958253E3ACF6:voip&24F3ADA36C0A8395262AC49F2FA4A92BE2DD2E78CB903CFB4EE587CE811616A8:remote;pn-provider=apns;pn-param=ABCD1234.org.linphone.phone.voip&remote;pn-silent=1;pn-timeout=0;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-groupchat-str=GC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf' from AOR '301' due to remove existing
[15:14:39] VERBOSE[27289] res_pjsip/pjsip_options.c: Contact 301/sip:[email protected]:63162;pn-prid=B80E73C93CB73D058859268FEA41CA67686374B4C3172E8C6143958253E3ACF6:voip&24F3ADA36C0A8395262AC49F2FA4A92BE2DD2E78CB903CFB4EE587CE811616A8:remote;pn-provider=apns;pn-param=ABCD1234.org.linphone.phone.voip&remote;pn-silent=1;pn-timeout=0;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-groupchat-str=GC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf has been deleted
[15:14:39] VERBOSE[27289] res_pjsip/pjsip_configuration.c: Endpoint 301 is now Unreachable
[15:14:39] VERBOSE[27289] res_pjsip/pjsip_configuration.c: Endpoint 301 is now Reachable
[15:14:39] VERBOSE[27289] res_pjsip/pjsip_options.c: Contact 301/sip:[email protected]:52729;pn-prid=B80E73C93CB73D058859268FEA41CA67686374B4C3172E8C6143958253E3ACF6:voip&24F3ADA36C0A8395262AC49F2FA4A92BE2DD2E78CB903CFB4EE587CE811616A8:remote;pn-provider=apns;pn-param=ABCD1234.org.linphone.phone.voip&remote;pn-silent=1;pn-timeout=0;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-groupchat-str=GC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf is now Reachable.  RTT: 68.882 msec
[15:14:39] WARNING[19632] res_pjsip_pubsub.c: No registered publish handler for event presence from 301
[15:14:39] NOTICE[27289] res_pjsip_exten_state.c: Endpoint '301' state subscription failed: Extension 'rls' does not exist in context 'from-internal' or has no associated hint
[15:15:00] VERBOSE[25504] pbx_spool.c: Attempting call on Local/301@originate-skipvm for application AGI(wakeconfirm.php) (Retry 1)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [301@originate-skipvm:1] Macro("Local/301@originate-skipvm-00000005;2", "blkvm-set,") in new stack
[15:15:00] VERBOSE[25504] dial.c: Called 301@originate-skipvm
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-blkvm-set:1] ExecIf("Local/301@originate-skipvm-00000005;2", "1?Set(__BLKVM_CHANNEL=Local/301@originate-skipvm-00000005;2)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-blkvm-set:2] Set("Local/301@originate-skipvm-00000005;2", "SHARED(BLKVM,Local/301@originate-skipvm-00000005;2)=TRUE") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-blkvm-set:3] Set("Local/301@originate-skipvm-00000005;2", "GOSUB_RETVAL=TRUE") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-blkvm-set:4] MacroExit("Local/301@originate-skipvm-00000005;2", "") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [301@originate-skipvm:2] Goto("Local/301@originate-skipvm-00000005;2", "from-internal,301,1") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (from-internal,301,1)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [301@from-internal:1] Set("Local/301@originate-skipvm-00000005;2", "__RINGTIMER=15") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [301@from-internal:2] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(__CWIGNORE=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [301@from-internal:3] Macro("Local/301@originate-skipvm-00000005;2", "exten-vm,301,301,0,0,0") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-exten-vm:1] Macro("Local/301@originate-skipvm-00000005;2", "user-callerid,") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/301@originate-skipvm-00000005;2", "TOUCH_MONITOR=1735222500.70") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/301@originate-skipvm-00000005;2", "CHANCONTEXT=originate-skipvm-00000005;2") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:3] Set("Local/301@originate-skipvm-00000005;2", "CHANCONTEXT=originate") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:4] Set("Local/301@originate-skipvm-00000005;2", "CHANEXTENCONTEXT=301@originate-skipvm-00000005;2") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/301@originate-skipvm-00000005;2", "CHANEXTEN=301") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:6] Set("Local/301@originate-skipvm-00000005;2", "CALLERID(number)=*68") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/301@originate-skipvm-00000005;2", "AMPUSER=*68") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:8] Set("Local/301@originate-skipvm-00000005;2", "HOTDESCKCHAN=301@originate-skipvm-00000005;2") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:9] Set("Local/301@originate-skipvm-00000005;2", "HOTDESKEXTEN=301@originate") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:10] Set("Local/301@originate-skipvm-00000005;2", "HOTDESKCALL=0") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(HOTDESKCALL=1)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(CALLERID(name)=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("Local/301@originate-skipvm-00000005;2", "0?report") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("Local/301@originate-skipvm-00000005;2", "1?Set(REALCALLERIDNUM=*68)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:15] Set("Local/301@originate-skipvm-00000005;2", "AMPUSER=") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("Local/301@originate-skipvm-00000005;2", "0?limit") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:17] Set("Local/301@originate-skipvm-00000005;2", "AMPUSERCIDNAME=") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("Local/301@originate-skipvm-00000005;2", "1?report") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (macro-user-callerid,s,27)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:27] NoOp("Local/301@originate-skipvm-00000005;2", "Macro Depth is 2") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:28] GotoIf("Local/301@originate-skipvm-00000005;2", "1?report2:macroerror") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("Local/301@originate-skipvm-00000005;2", "0?continue") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:30] Set("Local/301@originate-skipvm-00000005;2", "__TTL=64") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("Local/301@originate-skipvm-00000005;2", "1?continue") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (macro-user-callerid,s,47)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:47] Set("Local/301@originate-skipvm-00000005;2", "CALLERID(number)=*68") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:48] Set("Local/301@originate-skipvm-00000005;2", "CALLERID(name)=Wake Up Calls") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:49] GotoIf("Local/301@originate-skipvm-00000005;2", "0?cnum") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:50] Set("Local/301@originate-skipvm-00000005;2", "CDR(cnam)=Wake Up Calls") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:51] Set("Local/301@originate-skipvm-00000005;2", "CDR(cnum)=*68") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-user-callerid:52] Set("Local/301@originate-skipvm-00000005;2", "CHANNEL(language)=nl") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-exten-vm:2] Set("Local/301@originate-skipvm-00000005;2", "RingGroupMethod=none") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-exten-vm:3] Set("Local/301@originate-skipvm-00000005;2", "__EXTTOCALL=301") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-exten-vm:4] Set("Local/301@originate-skipvm-00000005;2", "__PICKUPMARK=301") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-exten-vm:5] Set("Local/301@originate-skipvm-00000005;2", "RT=15") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-exten-vm:6] Gosub("Local/301@originate-skipvm-00000005;2", "sub-record-check,s,1(exten,301,dontcare)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:1] GotoIf("Local/301@originate-skipvm-00000005;2", "0?initialized") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:2] Set("Local/301@originate-skipvm-00000005;2", "__REC_STATUS=INITIALIZED") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:3] Set("Local/301@originate-skipvm-00000005;2", "NOW=1735222500") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:4] Set("Local/301@originate-skipvm-00000005;2", "__DAY=26") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:5] Set("Local/301@originate-skipvm-00000005;2", "__MONTH=12") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:6] Set("Local/301@originate-skipvm-00000005;2", "__YEAR=2024") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:7] Set("Local/301@originate-skipvm-00000005;2", "__TIMESTR=20241226-151500") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:8] Set("Local/301@originate-skipvm-00000005;2", "__FROMEXTEN=*68") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:9] Set("Local/301@originate-skipvm-00000005;2", "__MON_FMT=wav") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:10] NoOp("Local/301@originate-skipvm-00000005;2", "Recordings initialized") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:11] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(ARG3=dontcare)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:12] Set("Local/301@originate-skipvm-00000005;2", "REC_POLICY_MODE_SAVE=") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:13] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(REC_STATUS=NO)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:14] GotoIf("Local/301@originate-skipvm-00000005;2", "5?checkaction") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (sub-record-check,s,17)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@sub-record-check:17] GotoIf("Local/301@originate-skipvm-00000005;2", "1?sub-record-check,exten,1") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (sub-record-check,exten,1)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:1] NoOp("Local/301@originate-skipvm-00000005;2", "Exten Recording Check between *68 and 301") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:2] Set("Local/301@originate-skipvm-00000005;2", "CALLTYPE=internal") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:3] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(CALLTYPE=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:4] Set("Local/301@originate-skipvm-00000005;2", "CALLEE=dontcare") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:5] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(CALLEE=dontcare)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:6] GotoIf("Local/301@originate-skipvm-00000005;2", "0?callee") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:7] GotoIf("Local/301@originate-skipvm-00000005;2", "1?caller") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (sub-record-check,exten,13)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:13] Set("Local/301@originate-skipvm-00000005;2", "RECMODE=") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:14] Set("Local/301@originate-skipvm-00000005;2", "CALLERRECMODE=") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:15] Set("Local/301@originate-skipvm-00000005;2", "CALEERECMODE=dontcare") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:16] GotoIf("Local/301@originate-skipvm-00000005;2", "0?processnormal") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:17] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(RECMODE=dontcare)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:18] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(RECMODE=dontcare)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:19] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(RECMODE=dontcare)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:20] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(RECMODE=dontcare)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:21] ExecIf("Local/301@originate-skipvm-00000005;2", "1?Set(RECMODE=dontcare)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:22] ExecIf("Local/301@originate-skipvm-00000005;2", "1?Set(RECMODE=dontcare)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:23] Gosub("Local/301@originate-skipvm-00000005;2", "recordcheck,1(dontcare,internal,301)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Local/301@originate-skipvm-00000005;2", "Starting recording check against dontcare") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Local/301@originate-skipvm-00000005;2", "dontcare") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Local/301@originate-skipvm-00000005;2", "") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [exten@sub-record-check:24] Return("Local/301@originate-skipvm-00000005;2", "") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-exten-vm:7] GotoIf("Local/301@originate-skipvm-00000005;2", "1?macrodial") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (macro-exten-vm,s,13)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-exten-vm:13] GosubIf("Local/301@originate-skipvm-00000005;2", "0?clrheader,1()") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-exten-vm:14] Macro("Local/301@originate-skipvm-00000005;2", "dial-one,15,HhTtr,301") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:1] Set("Local/301@originate-skipvm-00000005;2", "DEXTEN=301") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:2] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(__EXTTOCALL=301)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:3] Set("Local/301@originate-skipvm-00000005;2", "DIALSTATUS_CW=") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:4] GosubIf("Local/301@originate-skipvm-00000005;2", "0?screen,1()") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:5] GosubIf("Local/301@originate-skipvm-00000005;2", "0?cf,1()") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:6] GotoIf("Local/301@originate-skipvm-00000005;2", "1?skip1") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (macro-dial-one,s,9)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:9] GotoIf("Local/301@originate-skipvm-00000005;2", "0?nodial") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:10] GotoIf("Local/301@originate-skipvm-00000005;2", "0?continue") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:11] Set("Local/301@originate-skipvm-00000005;2", "EXTHASCW=ENABLED") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:12] GotoIf("Local/301@originate-skipvm-00000005;2", "0?next1:cwinusebusy") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (macro-dial-one,s,24)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:24] GotoIf("Local/301@originate-skipvm-00000005;2", "0?next3:continue") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (macro-dial-one,s,26)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:26] GotoIf("Local/301@originate-skipvm-00000005;2", "0?nodial") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:27] GosubIf("Local/301@originate-skipvm-00000005;2", "1?dstring,1():dlocal,1()") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:1] Set("Local/301@originate-skipvm-00000005;2", "DSTRING=") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:2] Set("Local/301@originate-skipvm-00000005;2", "DEVICES=301") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Return()") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(DEVICES=01)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:5] Set("Local/301@originate-skipvm-00000005;2", "LOOPCNT=1") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:6] Set("Local/301@originate-skipvm-00000005;2", "ITER=1") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:7] Set("Local/301@originate-skipvm-00000005;2", "THISDIAL=PJSIP/301") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf("Local/301@originate-skipvm-00000005;2", "0?docheck") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:9] NoOp("Local/301@originate-skipvm-00000005;2", "Debug: Found PJSIP Destination PJSIP/301") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:10] GotoIf("Local/301@originate-skipvm-00000005;2", "0?doset") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:11] NoOp("Local/301@originate-skipvm-00000005;2", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:12] Set("Local/301@originate-skipvm-00000005;2", "THISDIAL=PJSIP/301/sip:[email protected]:52729;pn-prid=B80E73C93CB73D058859268FEA41CA67686374B4C3172E8C6143958253E3ACF6:voip&24F3ADA36C0A8395262AC49F2FA4A92BE2DD2E78CB903CFB4EE587CE811616A8:remote;pn-provider=apns;pn-param=ABCD1234.org.linphone.phone.voip&remote;pn-silent=1;pn-timeout=0;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-groupchat-str=GC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:13] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(DIALSTATUS=CHANUNAVAIL)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("Local/301@originate-skipvm-00000005;2", "0?skipset") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:15] Set("Local/301@originate-skipvm-00000005;2", "DSTRING=PJSIP/301/sip:[email protected]:52729;pn-prid=B80E73C93CB73D058859268FEA41CA67686374B4C3172E8C6143958253E3ACF6:voip&24F3ADA36C0A8395262AC49F2FA4A92BE2DD2E78CB903CFB4EE587CE811616A8:remote;pn-provider=apns;pn-param=ABCD1234.org.linphone.phone.voip&remote;pn-silent=1;pn-timeout=0;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-groupchat-str=GC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf&") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:16] Set("Local/301@originate-skipvm-00000005;2", "ITER=2") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("Local/301@originate-skipvm-00000005;2", "0?begin") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Return()") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:19] Set("Local/301@originate-skipvm-00000005;2", "DSTRING=PJSIP/301/sip:[email protected]:52729;pn-prid=B80E73C93CB73D058859268FEA41CA67686374B4C3172E8C6143958253E3ACF6:voip&24F3ADA36C0A8395262AC49F2FA4A92BE2DD2E78CB903CFB4EE587CE811616A8:remote;pn-provider=apns;pn-param=ABCD1234.org.linphone.phone.voip&remote;pn-silent=1;pn-timeout=0;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-groupchat-str=GC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [dstring@macro-dial-one:20] Return("Local/301@originate-skipvm-00000005;2", "") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:28] GotoIf("Local/301@originate-skipvm-00000005;2", "0?nodial") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:29] GotoIf("Local/301@originate-skipvm-00000005;2", "0?skiptrace") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:30] GosubIf("Local/301@originate-skipvm-00000005;2", "0?ctset,1():ctclear,1()") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [ctclear@macro-dial-one:1] NoOp("Local/301@originate-skipvm-00000005;2", "Deleting: CALLTRACE/301 ") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [ctclear@macro-dial-one:2] Return("Local/301@originate-skipvm-00000005;2", "") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:31] Set("Local/301@originate-skipvm-00000005;2", "D_OPTIONS=HhTtr") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:32] GosubIf("Local/301@originate-skipvm-00000005;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:33] NoOp("Local/301@originate-skipvm-00000005;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:34] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(ALERT_INFO=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:35] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(ALERT_INFO=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:36] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(ALERT_INFO=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:37] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:38] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:39] GosubIf("Local/301@originate-skipvm-00000005;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:40] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(CHANNEL(musicclass)=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:41] GosubIf("Local/301@originate-skipvm-00000005;2", "0?qwait,1()") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:42] Set("Local/301@originate-skipvm-00000005;2", "__CWIGNORE=") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:43] Set("Local/301@originate-skipvm-00000005;2", "__KEEPCID=TRUE") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:44] GotoIf("Local/301@originate-skipvm-00000005;2", "0?usegoto,1") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:45] GotoIf("Local/301@originate-skipvm-00000005;2", "1?godial") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (macro-dial-one,s,50)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:50] Macro("Local/301@originate-skipvm-00000005;2", "dialout-one-predial-hook,") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("Local/301@originate-skipvm-00000005;2", "") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:51] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(D_OPTIONS=HhtrI)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:52] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:53] NoOp("Local/301@originate-skipvm-00000005;2", "") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:54] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(D_OPTIONS=Hhtr)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:55] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(D_OPTIONS=HhTtrg)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-dial-one:56] Dial("Local/301@originate-skipvm-00000005;2", "PJSIP/301/sip:[email protected]:52729;pn-prid=B80E73C93CB73D058859268FEA41CA67686374B4C3172E8C6143958253E3ACF6:voip&24F3ADA36C0A8395262AC49F2FA4A92BE2DD2E78CB903CFB4EE587CE811616A8:remote;pn-provider=apns;pn-param=ABCD1234.org.linphone.phone.voip&remote;pn-silent=1;pn-timeout=0;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-groupchat-str=GC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf,15,HhTtrb(func-apply-sipheaders^s^1)") in new stack
[15:15:00] WARNING[25505][C-00000026] app_dial.c: Dial argument takes format (technology/resource)
[15:15:00] VERBOSE[25505][C-00000026] app_macro.c: Spawn extension (macro-dial-one, s, 56) exited non-zero on 'Local/301@originate-skipvm-00000005;2' in macro 'dial-one'
[15:15:00] VERBOSE[25505][C-00000026] app_macro.c: Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'Local/301@originate-skipvm-00000005;2' in macro 'exten-vm'
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Spawn extension (from-internal, 301, 3) exited non-zero on 'Local/301@originate-skipvm-00000005;2'
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [h@from-internal:1] Macro("Local/301@originate-skipvm-00000005;2", "hangupcall") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/301@originate-skipvm-00000005;2", "1?theend") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/301@originate-skipvm-00000005;2", "0?Set(CDR(recordingfile)=)") in new stack
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Executing [s@macro-hangupcall:4] Hangup("Local/301@originate-skipvm-00000005;2", "") in new stack
[15:15:00] VERBOSE[25505][C-00000026] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/301@originate-skipvm-00000005;2' in macro 'hangupcall'
[15:15:00] VERBOSE[25505][C-00000026] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/301@originate-skipvm-00000005;2'
[15:15:00] NOTICE[25504] pbx_spool.c: Call failed to go through, reason (0) Call Failure (not BUSY, and not NO_ANSWER, maybe Circuit busy or down?)

ashcortech · December 26, 2024, 3:53pm

well for one thing it looks like your extension is registering/unregistering a lot. I’m not familiar with Linphone at all but does it show any missed calls?

Also, are you able to make any other “normal” calls to/from the extension reliably?

david55 · December 26, 2024, 4:16pm

The contact URI, from the registration, is using an “&” character in a URI parameter. That is valid, but unfortunately conflicts with it’s use to delimit multiple destinations for the Dial() application, in Asterisk. Although you only have one contact registered, FreePBX always uses the mechanism that supports multiple contacts, for chan_pjsip, so it always includes the full URI, in the Dial string.

This should probably be considered a bug in the handling of Dial(), and PJSIP_DIAL_CONTACTS() and raised against Asterisk’s github issues.

People using raw Asterisk, who knew there was only one contact, would probably just name the endpoint, rather than using he long form, from PJSIP_DIAL_CONTACTS().

athoopen · December 28, 2024, 10:57am

Thanks for all the feedback but I was not able to get a wakeup call on Linphone on IPhone. However Linphone on Linux and VoIP phones does work. I will live with that

ashcortech · December 28, 2024, 1:09pm

then I would try a different softphone on your iPhone or since you’ve narrowed it down to what appears to be an iPhone issue, take a look at the iPhone wifi/network settings. Temporarily turn off any of the private IP settings that the iPhones implement and see if that fixes it. I know we’ve had several products we deal with that use smartphone apps (home automation systems, music systems etc) that do not like those settings and won’t perform well with them.

david55 · December 28, 2024, 1:12pm

A wakeup call to an iPhone is going to need a push service.

ashcortech · December 28, 2024, 1:17pm

how so? isn’t just an automated phone call from the pbx? (haven’t had a need for it so only played with it once and a desk phone a long time ago out of curiosity)

david55 · December 28, 2024, 1:25pm

iPhones aggressively put applications to sleep and SIP phones cannot be written so as to keep the SIP port open for incoming INVITEs. They need a PUSH server, in the cloud, to wake up the phone application.

Android is going that way, but allows you to mark specific apps as never being put to sleep.

The Linphone service may be providing the push server.

ashcortech · December 28, 2024, 1:40pm

ah… gotcha, I see what you’re saying… I know ClearlyAnywhere uses their own servers for the push so the app isn’t truly connected all the time to the pbx.

athoopen · December 28, 2024, 3:46pm

For those more technical than I, interesting reading and coding at https://github.com/balusreekanth/ios-asterisk-push