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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:3] Return(SIP/DR_Orig-00000001, ) in new stack
21885 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]:5] Return(SIP/DR_Orig-00000001, ) in new stack
21886 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:3] Return(SIP/DR_Orig-00000001, ) in new stack
21893 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]: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 [[email protected]:8] GotoIf(SIP/DR_Orig-00000001, 0?) in new stack
21895 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]: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 [[email protected]:10] Set(SIP/DR_Orig-00000001, __MOHCLASS=) in new stack
21897 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:14] NoOp(SIP/DR_Orig-00000001, ) in new stack
21901 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(SIP/DR_Orig-00000001, CHANCONTEXT=) in new stack
21916 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]:3] Set(SIP/DR_Orig-00000001, CHANCONTEXT=) in new stack
21917 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:15] Set(SIP/DR_Orig-00000001, AMPUSER=) in new stack
21929 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]: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 [[email protected]:17] Set(SIP/DR_Orig-00000001, AMPUSERCIDNAME=) in new stack
21931 [2023-04-19 11:23:13] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:3] Return(SIP/DR_Orig-00000001, ) in new stack
21999 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]:12] Return(SIP/DR_Orig-00000001, ) in new stack
22000 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] Set(SIP/DR_Orig-00000001, DSTRING=) in new stack
22021 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]:2] Set(SIP/DR_Orig-00000001, DEVICES=) in new stack
22022 [2023-04-19 11:23:14] VERBOSE[8504][C-00000003] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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.