Incoming calls straight to voicemail on warm spare

FreePBX Configuration
1

Incoming calls work fine on primary pbx. When I direct calls to the warm spare (data populated via warm spare restore process in backup module) for testing purposes they go straight to voicemail. Asterisk log shows this error during the call at about the point it should start ringing the extension:

[2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.

Here’s the log excerpt (note the DID was replaced with 1234567890 and logs were cutoff after VM initiated so I wouldn’t hit the character limit in this post):

21858 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:1] Set(SIP/DR_Orig-00000001, __DIRECTION=INBOUND) in new stack
21859 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:2] Gosub(SIP/DR_Orig-00000001, sub-record-check,s,1(in,+11234567890,dontcare)) in new stack
21860 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:1] GotoIf(SIP/DR_Orig-00000001, 0?initialized) in new stack
21861 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:2] Set(SIP/DR_Orig-00000001, __REC_STATUS=INITIALIZED) in new stack
21862 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:3] Set(SIP/DR_Orig-00000001, NOW=1681928593) in new stack
21863 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:4] Set(SIP/DR_Orig-00000001, __DAY=19) in new stack
21864 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:5] Set(SIP/DR_Orig-00000001, __MONTH=04) in new stack
21865 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:6] Set(SIP/DR_Orig-00000001, __YEAR=2023) in new stack
21866 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:7] Set(SIP/DR_Orig-00000001, __TIMESTR=20230419-112313) in new stack
21867 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:8] Set(SIP/DR_Orig-00000001, __FROMEXTEN=unknown) in new stack
21868 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:9] Set(SIP/DR_Orig-00000001, __MON_FMT=wav) in new stack
21869 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:10] NoOp(SIP/DR_Orig-00000001, Recordings initialized) in new stack
21870 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:11] ExecIf(SIP/DR_Orig-00000001, 0?Set(ARG3=dontcare)) in new stack
21871 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:12] Set(SIP/DR_Orig-00000001, REC_POLICY_MODE_SAVE=) in new stack
21872 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:13] ExecIf(SIP/DR_Orig-00000001, 0?Set(REC_STATUS=NO)) in new stack
21873 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:14] GotoIf(SIP/DR_Orig-00000001, 2?checkaction) in new stack
21874 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (sub-record-check,s,17)
21875 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:17] GotoIf(SIP/DR_Orig-00000001, 1?sub-record-check,in,1) in new stack
21876 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (sub-record-check,in,1)
21877 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [in@sub-record-check:1] NoOp(SIP/DR_Orig-00000001, Inbound Recording Check to +11234567890) in new stack
21878 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [in@sub-record-check:2] Set(SIP/DR_Orig-00000001, FROMEXTEN=unknown) in new stack
21879 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [in@sub-record-check:3] ExecIf(SIP/DR_Orig-00000001, 11?Set(FROMEXTEN=18056893684)) in new stack
21880 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [in@sub-record-check:4] Gosub(SIP/DR_Orig-00000001, recordcheck,1(dontcare,in,+11234567890)) in new stack
21881 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(SIP/DR_Orig-00000001, Starting recording check against dontcare) in new stack
21882 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(SIP/DR_Orig-00000001, dontcare) in new stack
21883 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
21884 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:3] Return(SIP/DR_Orig-00000001, ) in new stack
21885 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [in@sub-record-check:5] Return(SIP/DR_Orig-00000001, ) in new stack
21886 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:3] Set(SIP/DR_Orig-00000001, CHANNEL(tonezone)=us) in new stack
21887 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:4] Set(SIP/DR_Orig-00000001, __FROM_DID=+11234567890) in new stack
21888 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:5] Set(SIP/DR_Orig-00000001, returnhere=1) in new stack
21889 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:6] Gosub(SIP/DR_Orig-00000001, app-blacklist-check,s,1()) in new stack
21890 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@app-blacklist-check:1] GotoIf(SIP/DR_Orig-00000001, 0?blacklisted) in new stack
21891 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@app-blacklist-check:2] Set(SIP/DR_Orig-00000001, CALLED_BLACKLIST=1) in new stack
21892 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@app-blacklist-check:3] Return(SIP/DR_Orig-00000001, ) in new stack
21893 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:7] Set(SIP/DR_Orig-00000001, CDR(did)=+11234567890) in new stack
21894 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:8] GotoIf(SIP/DR_Orig-00000001, 0?) in new stack
21895 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:9] ExecIf(SIP/DR_Orig-00000001, 1 ?Set(CALLERID(name)=18056893684)) in new stack
21896 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:10] Set(SIP/DR_Orig-00000001, __MOHCLASS=) in new stack
21897 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:11] Set(SIP/DR_Orig-00000001, __REVERSAL_REJECT=FALSE) in new stack
21898 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:12] GotoIf(SIP/DR_Orig-00000001, 1?post-reverse-charge) in new stack
21899 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (from-trunk,+11234567890,14)
21900 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:14] NoOp(SIP/DR_Orig-00000001, ) in new stack
21901 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:15] Set(SIP/DR_Orig-00000001, __CALLINGNAMEPRES_SV=allowed_not_screened) in new stack
21902 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:16] Set(SIP/DR_Orig-00000001, __CALLINGNUMPRES_SV=allowed_not_screened) in new stack
21903 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:17] Set(SIP/DR_Orig-00000001, CALLERID(name-pres)=allowed_not_screened) in new stack
21904 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:18] Set(SIP/DR_Orig-00000001, CALLERID(num-pres)=allowed_not_screened) in new stack
21905 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:19] NoOp(SIP/DR_Orig-00000001, CallerID Entry Point) in new stack
21906 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [+11234567890@from-trunk:20] Goto(SIP/DR_Orig-00000001, from-did-direct,987654,1) in new stack
21907 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (from-did-direct,987654,1)
21908 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [987654@from-did-direct:1] GotoIf(SIP/DR_Orig-00000001, 1?ext-local,987654,1:followme-check,987654,1) in new stack
21909 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (ext-local,987654,1)
21910 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [987654@ext-local:1] Set(SIP/DR_Orig-00000001, __RINGTIMER=15) in new stack
21911 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [987654@ext-local:2] ExecIf(SIP/DR_Orig-00000001, 0?Set(__CWIGNORE=)) in new stack
21912 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [987654@ext-local:3] Macro(SIP/DR_Orig-00000001, exten-vm,987654,987654,0,0,0) in new stack
21913 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:1] Macro(SIP/DR_Orig-00000001, user-callerid,) in new stack
21914 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:1] Set(SIP/DR_Orig-00000001, TOUCH_MONITOR=1681928593.1) in new stack
21915 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:2] Set(SIP/DR_Orig-00000001, CHANCONTEXT=) in new stack
21916 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:3] Set(SIP/DR_Orig-00000001, CHANCONTEXT=) in new stack
21917 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:4] Set(SIP/DR_Orig-00000001, CHANEXTENCONTEXT=DR_Orig-00000001) in new stack
21918 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:5] Set(SIP/DR_Orig-00000001, CHANEXTEN=DR_Orig-00000001) in new stack
21919 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:6] Set(SIP/DR_Orig-00000001, CALLERID(number)=18056893684) in new stack
21920 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:7] Set(SIP/DR_Orig-00000001, AMPUSER=18056893684) in new stack
21921 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:8] Set(SIP/DR_Orig-00000001, HOTDESCKCHAN=DR_Orig-00000001) in new stack
21922 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:9] Set(SIP/DR_Orig-00000001, HOTDESKEXTEN=DR_Orig) in new stack
21923 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:10] Set(SIP/DR_Orig-00000001, HOTDESKCALL=0) in new stack
21924 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:11] ExecIf(SIP/DR_Orig-00000001, 0?Set(HOTDESKCALL=1)) in new stack
21925 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:12] ExecIf(SIP/DR_Orig-00000001, 0?Set(CALLERID(name)=)) in new stack
21926 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(SIP/DR_Orig-00000001, 0?report) in new stack
21927 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(SIP/DR_Orig-00000001, 1?Set(REALCALLERIDNUM=18056893684)) in new stack
21928 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:15] Set(SIP/DR_Orig-00000001, AMPUSER=) in new stack
21929 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:16] GotoIf(SIP/DR_Orig-00000001, 0?limit) in new stack
21930 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:17] Set(SIP/DR_Orig-00000001, AMPUSERCIDNAME=) in new stack
21931 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:18] ExecIf(SIP/DR_Orig-00000001, 0?Set(__CIDMASQUERADING=TRUE)) in new stack
21932 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:19] GotoIf(SIP/DR_Orig-00000001, 1?report) in new stack
21933 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,28)
21934 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:28] NoOp(SIP/DR_Orig-00000001, Macro Depth is 2) in new stack
21935 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:29] GotoIf(SIP/DR_Orig-00000001, 1?report2:macroerror) in new stack
21936 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,30)
21937 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:30] GotoIf(SIP/DR_Orig-00000001, 0?continue) in new stack
21938 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:31] ExecIf(SIP/DR_Orig-00000001, 1?Set(__CALLEE_ACCOUNCODE=)) in new stack
21939 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:32] Set(SIP/DR_Orig-00000001, __TTL=64) in new stack
21940 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:33] GotoIf(SIP/DR_Orig-00000001, 1?continue) in new stack
21941 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,49)
21942 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:49] Set(SIP/DR_Orig-00000001, CALLERID(number)=18056893684) in new stack
21943 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:50] Set(SIP/DR_Orig-00000001, CALLERID(name)=18056893684) in new stack
21944 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:51] GotoIf(SIP/DR_Orig-00000001, 0?cnum) in new stack
21945 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:52] Set(SIP/DR_Orig-00000001, CDR(cnam)=18056893684) in new stack
21946 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:53] Set(SIP/DR_Orig-00000001, CDR(cnum)=18056893684) in new stack
21947 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-user-callerid:54] Set(SIP/DR_Orig-00000001, CHANNEL(language)=en) in new stack
21948 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:2] Set(SIP/DR_Orig-00000001, RingGroupMethod=none) in new stack
21949 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:3] Set(SIP/DR_Orig-00000001, __EXTTOCALL=987654) in new stack
21950 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:4] Set(SIP/DR_Orig-00000001, __PICKUPMARK=987654) in new stack
21951 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:5] Set(SIP/DR_Orig-00000001, RT=15) in new stack
21952 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:6] ExecIf(SIP/DR_Orig-00000001, 0?Macro(vm,987654,DIRECTDIAL,)) in new stack
21953 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:7] ExecIf(SIP/DR_Orig-00000001, 0?MacroExit()) in new stack
21954 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21955 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:8] ExecIf(SIP/DR_Orig-00000001, 0?Macro(vm,987654,DIRECTDIAL,)) in new stack
21956 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21957 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21958 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:9] ExecIf(SIP/DR_Orig-00000001, 0?MacroExit()) in new stack
21959 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21960 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21961 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:10] ExecIf(SIP/DR_Orig-00000001, 0?ChanSpy(PJSIP/987654,q)) in new stack
21962 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21963 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21964 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:11] ExecIf(SIP/DR_Orig-00000001, 0?MacroExit()) in new stack
21965 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21966 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:12] ExecIf(SIP/DR_Orig-00000001, 0?ChanSpy(PJSIP/987654,q)) in new stack
21967 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:13] ExecIf(SIP/DR_Orig-00000001, 0?MacroExit()) in new stack
21968 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21969 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:14] ExecIf(SIP/DR_Orig-00000001, 0?Gosub(ext-intercom,*80987654,1())) in new stack
21970 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21971 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21972 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:15] ExecIf(SIP/DR_Orig-00000001, 0?MacroExit()) in new stack
21973 [2023-04-19 11:23:13] ERROR[8504][C-00000003] res_pjsip_header_funcs.c: This function requires a PJSIP channel.
21974 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:16] ExecIf(SIP/DR_Orig-00000001, 0?Gosub(ext-intercom,*80987654,1())) in new stack
21975 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:17] ExecIf(SIP/DR_Orig-00000001, 0?MacroExit()) in new stack
21976 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:18] Gosub(SIP/DR_Orig-00000001, sub-record-check,s,1(exten,987654,dontcare)) in new stack
21977 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:1] GotoIf(SIP/DR_Orig-00000001, 11?initialized) in new stack
21978 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (sub-record-check,s,10)
21979 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:10] NoOp(SIP/DR_Orig-00000001, Recordings initialized) in new stack
21980 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:11] ExecIf(SIP/DR_Orig-00000001, 0?Set(ARG3=dontcare)) in new stack
21981 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:12] Set(SIP/DR_Orig-00000001, REC_POLICY_MODE_SAVE=) in new stack
21982 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:13] ExecIf(SIP/DR_Orig-00000001, 0?Set(REC_STATUS=NO)) in new stack
21983 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:14] GotoIf(SIP/DR_Orig-00000001, 5?checkaction) in new stack
21984 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (sub-record-check,s,17)
21985 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [s@sub-record-check:17] GotoIf(SIP/DR_Orig-00000001, 1?sub-record-check,exten,1) in new stack
21986 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (sub-record-check,exten,1)
21987 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [exten@sub-record-check:1] NoOp(SIP/DR_Orig-00000001, Exten Recording Check between 18056893684 and 987654) in new stack
21988 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [exten@sub-record-check:2] Set(SIP/DR_Orig-00000001, CALLTYPE=external) in new stack
21989 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [exten@sub-record-check:3] ExecIf(SIP/DR_Orig-00000001, 0?Set(CALLTYPE=)) in new stack
21990 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [exten@sub-record-check:4] Set(SIP/DR_Orig-00000001, CALLEE=dontcare) in new stack
21991 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [exten@sub-record-check:5] ExecIf(SIP/DR_Orig-00000001, 0?Set(CALLEE=dontcare)) in new stack
21992 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [exten@sub-record-check:6] GotoIf(SIP/DR_Orig-00000001, 1?callee) in new stack
21993 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (sub-record-check,exten,11)
21994 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [exten@sub-record-check:11] Gosub(SIP/DR_Orig-00000001, recordcheck,1(dontcare,external,987654)) in new stack
21995 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(SIP/DR_Orig-00000001, Starting recording check against dontcare) in new stack
21996 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(SIP/DR_Orig-00000001, dontcare) in new stack
21997 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
21998 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:3] Return(SIP/DR_Orig-00000001, ) in new stack
21999 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [exten@sub-record-check:12] Return(SIP/DR_Orig-00000001, ) in new stack
22000 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:19] GotoIf(SIP/DR_Orig-00000001, 1?macrodial) in new stack
22001 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (macro-exten-vm,s,25)
22002 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:25] GosubIf(SIP/DR_Orig-00000001, 0?clrheader,1()) in new stack
22003 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-exten-vm:26] Macro(SIP/DR_Orig-00000001, dial-one,15,Ttr,987654) in new stack
22004 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:1] Set(SIP/DR_Orig-00000001, DEXTEN=987654) in new stack
22005 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:2] ExecIf(SIP/DR_Orig-00000001, 0?Set(__EXTTOCALL=987654)) in new stack
22006 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:3] Set(SIP/DR_Orig-00000001, DIALSTATUS_CW=) in new stack
22007 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:4] GosubIf(SIP/DR_Orig-00000001, 0?screen,1()) in new stack
22008 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:5] GosubIf(SIP/DR_Orig-00000001, 0?cf,1()) in new stack
22009 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:6] GotoIf(SIP/DR_Orig-00000001, 1?skip1) in new stack
22010 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (macro-dial-one,s,9)
22011 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:9] GotoIf(SIP/DR_Orig-00000001, 0?nodial) in new stack
22012 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:10] GotoIf(SIP/DR_Orig-00000001, 0?continue) in new stack
22013 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:11] Set(SIP/DR_Orig-00000001, EXTHASCW=ENABLED) in new stack
22014 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:12] GotoIf(SIP/DR_Orig-00000001, 0?next1:cwinusebusy) in new stack
22015 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (macro-dial-one,s,24)
22016 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:24] GotoIf(SIP/DR_Orig-00000001, 0?next3:continue) in new stack
22017 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (macro-dial-one,s,26)
22018 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:26] GotoIf(SIP/DR_Orig-00000001, 0?nodial) in new stack
22019 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:27] GosubIf(SIP/DR_Orig-00000001, 1?dstring,1():dlocal,1()) in new stack
22020 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [dstring@macro-dial-one:1] Set(SIP/DR_Orig-00000001, DSTRING=) in new stack
22021 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [dstring@macro-dial-one:2] Set(SIP/DR_Orig-00000001, DEVICES=) in new stack
22022 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(SIP/DR_Orig-00000001, 1?Return()) in new stack
22023 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:28] GotoIf(SIP/DR_Orig-00000001, 1?nodial) in new stack
22024 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx_builtins.c: Goto (macro-dial-one,s,60)
22025 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [s@macro-dial-one:60] NoOp(SIP/DR_Orig-00000001, ) in new stack
2

It appears the backup is not including outbound routes and is missing the some (not all) details from my PJSIP trunks. Exclude trunks is disabled in the backup’s settings. Disable Remote trunks is not enabled either. Looks like a bug.

3

I think I can confirm that this is a bug. I started a case with Sangoma last week with the same symptoms.

4

Thanks - can you link here?

I have some deployments on older versions of the backup module that appear unaffected. Looks like it may have been introduced with the 16.0.63 version of backup and restore.

5

Don’t think I can, this is a support case that’s behind their portal under my profile. I can keep you posted here though.

6

This isn’t a real error. It simply means your outgoing channel isn’t using chan_pjsip. Given your incoming channel is using chan_sip, it is likely the outgoing one is doing so as well.

It is an error as far as Asterisk is concerned, but FreePBX doesn’t check the channel type and simply blindly tries both the chan_sip and chan_pjsip methods of adding SIP headers

7

@dobrosavljevic did Sangoma provide any instructions to unhose the PBX while we wait for a fix? Manually fixing the trunks and outbound routes didn’t solve the issue for me.

8

Not really as our warm spare isn’t meant to handle actual calls. We have it in place the primary unit fails.

9

Can you get it to handle calls? Mine don’t due to this issue. My only option is to clone the primary and deploy it to the warm spare then fix the network settings.

10

I don’t need to spend the time as we know something is broken with the restore to a warm spare and we don’t need the warm spare to handle calls. I really don’t care about getting calls through it at this point until the warm spare restores start working again.

11

Gotten word over the weekend that core version 16.0.68.15 will address issues with warm spare backup module. Wasn’t told when it’ll be released but it seems that others have reported issues with it as well and development is aware and working on it.

1 Like
12

This appears to be fixed in the recently released updates.

13

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