Here is the complete call log
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:1] Set("PJSIP/LocalPhone_Trunk-0000001c", "__DIRECTION=INBOUND") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:2] Gosub("PJSIP/LocalPhone_Trunk-0000001c", "sub-record-check,s,1(in,s,dontcare)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "0?initialized") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/LocalPhone_Trunk-0000001c", "__REC_STATUS=INITIALIZED") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/LocalPhone_Trunk-0000001c", "NOW=1666005008") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/LocalPhone_Trunk-0000001c", "__DAY=17") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/LocalPhone_Trunk-0000001c", "__MONTH=10") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/LocalPhone_Trunk-0000001c", "__YEAR=2022") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/LocalPhone_Trunk-0000001c", "__TIMESTR=20221017-131008") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/LocalPhone_Trunk-0000001c", "__FROMEXTEN=unknown") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/LocalPhone_Trunk-0000001c", "__MON_FMT=wav") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "Recordings initialized") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "0?Set(ARG3=dontcare)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/LocalPhone_Trunk-0000001c", "REC_POLICY_MODE_SAVE=") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "0?Set(REC_STATUS=NO)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "2?checkaction") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx_builtins.c: Goto (sub-record-check,s,17)
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "1?sub-record-check,in,1") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx_builtins.c: Goto (sub-record-check,in,1)
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [in@sub-record-check:1] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "Inbound Recording Check to s") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [in@sub-record-check:2] Set("PJSIP/LocalPhone_Trunk-0000001c", "FROMEXTEN=unknown") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [in@sub-record-check:3] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "11?Set(FROMEXTEN=32486xxxxxx)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [in@sub-record-check:4] Gosub("PJSIP/LocalPhone_Trunk-0000001c", "recordcheck,1(dontcare,in,s)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "Starting recording check against dontcare") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/LocalPhone_Trunk-0000001c", "dontcare") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/LocalPhone_Trunk-0000001c", "") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [in@sub-record-check:5] Return("PJSIP/LocalPhone_Trunk-0000001c", "") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:3] Set("PJSIP/LocalPhone_Trunk-0000001c", "CHANNEL(tonezone)=be") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:4] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "1?Set(__FROM_DID=s)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:5] Set("PJSIP/LocalPhone_Trunk-0000001c", "returnhere=1") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:6] Gosub("PJSIP/LocalPhone_Trunk-0000001c", "app-blacklist-check,s,1()") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "0?blacklisted") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@app-blacklist-check:2] Set("PJSIP/LocalPhone_Trunk-0000001c", "CALLED_BLACKLIST=1") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@app-blacklist-check:3] Return("PJSIP/LocalPhone_Trunk-0000001c", "") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:7] Set("PJSIP/LocalPhone_Trunk-0000001c", "CDR(did)=s") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:8] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "0?") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:9] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "0 ?Set(CALLERID(name)=32486xxxxxx)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:10] Set("PJSIP/LocalPhone_Trunk-0000001c", "__MOHCLASS=") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:11] Set("PJSIP/LocalPhone_Trunk-0000001c", "__REVERSAL_REJECT=FALSE") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:12] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "1?post-reverse-charge") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx_builtins.c: Goto (from-pstn,s,14)
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:14] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:15] Set("PJSIP/LocalPhone_Trunk-0000001c", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:16] Set("PJSIP/LocalPhone_Trunk-0000001c", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:17] Set("PJSIP/LocalPhone_Trunk-0000001c", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:18] Set("PJSIP/LocalPhone_Trunk-0000001c", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:19] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "CallerID Entry Point") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:20] Set("PJSIP/LocalPhone_Trunk-0000001c", "__CRM_DIRECTION=INBOUND") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:21] Set("PJSIP/LocalPhone_Trunk-0000001c", "__CRM_SOURCE=32486xxxxxx") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:22] Set("PJSIP/LocalPhone_Trunk-0000001c", "__CRM_LINKEDID=1666005008.28") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:23] AGI("PJSIP/LocalPhone_Trunk-0000001c", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] res_agi.c: <PJSIP/LocalPhone_Trunk-0000001c>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:24] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@from-pstn:25] Goto("PJSIP/LocalPhone_Trunk-0000001c", "timeconditions,2,1") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx_builtins.c: Goto (timeconditions,2,1)
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [2@timeconditions:1] Set("PJSIP/LocalPhone_Trunk-0000001c", "DB(TC/2/INUSESTATE)=INUSE") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [2@timeconditions:2] Set("PJSIP/LocalPhone_Trunk-0000001c", "DB(TC/2/NOT_INUSESTATE)=NOT_INUSE") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [2@timeconditions:3] AGI("PJSIP/LocalPhone_Trunk-0000001c", "agi://127.0.0.1/calendar.agi,calendar,goto,73073b42-e45f-47be-a36d-65277eec2ab3,default,dHJ1ZXN0YXRl,ZmFsc2VzdGF0ZQ==") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] res_agi.c: agi://127.0.0.1/calendar.agi,calendar,goto,73073b42-e45f-47be-a36d-65277eec2ab3,default,dHJ1ZXN0YXRl,ZmFsc2VzdGF0ZQ==: Checking Calendar
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] res_agi.c: agi://127.0.0.1/calendar.agi,calendar,goto,73073b42-e45f-47be-a36d-65277eec2ab3,default,dHJ1ZXN0YXRl,ZmFsc2VzdGF0ZQ==: Calendar: False
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] res_agi.c: agi://127.0.0.1/calendar.agi,calendar,goto,73073b42-e45f-47be-a36d-65277eec2ab3,default,dHJ1ZXN0YXRl,ZmFsc2VzdGF0ZQ==: Goto:falsestate
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] res_agi.c: AGI Script Executing Application: (Goto) Options: (falsestate)
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx_builtins.c: Goto (timeconditions,2,4)
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] res_agi.c: <PJSIP/LocalPhone_Trunk-0000001c>AGI Script agi://127.0.0.1/calendar.agi completed, returning 0
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [2@timeconditions:4] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "0?truegoto") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [2@timeconditions:5] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "0?Set(DB(TC/2)=)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [2@timeconditions:6] Set("PJSIP/LocalPhone_Trunk-0000001c", "DEVICE_STATE(Custom:TC2)=INUSE") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [2@timeconditions:7] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [2@timeconditions:8] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "1?timeconditions,1,1") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx_builtins.c: Goto (timeconditions,1,1)
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [1@timeconditions:1] Set("PJSIP/LocalPhone_Trunk-0000001c", "DB(TC/1/INUSESTATE)=INUSE") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [1@timeconditions:2] Set("PJSIP/LocalPhone_Trunk-0000001c", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [1@timeconditions:3] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "TIMENOW: 13:10,Mon,17,Oct") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [1@timeconditions:4] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "TIMEMATCHED: TRUE") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [1@timeconditions:5] GotoIfTime("PJSIP/LocalPhone_Trunk-0000001c", "09:00-18:00,mon-fri,*,*?truestate") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx_builtins.c: Goto (timeconditions,1,14)
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [1@timeconditions:14] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "0?falsegoto") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [1@timeconditions:15] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "0?Set(DB(TC/1)=)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [1@timeconditions:16] Set("PJSIP/LocalPhone_Trunk-0000001c", "DEVICE_STATE(Custom:TC1)=NOT_INUSE") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [1@timeconditions:17] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [1@timeconditions:18] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "1?app-announcement-4,s,1") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx_builtins.c: Goto (app-announcement-4,s,1)
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@app-announcement-4:1] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "0?begin") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@app-announcement-4:2] Answer("PJSIP/LocalPhone_Trunk-0000001c", "") in new stack
[2022-10-17 13:10:08] VERBOSE[2769][C-00000010] pbx.c: Executing [s@app-announcement-4:3] Wait("PJSIP/LocalPhone_Trunk-0000001c", "1") in new stack
[2022-10-17 13:10:09] VERBOSE[2769][C-00000010] pbx.c: Executing [s@app-announcement-4:4] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "Playing announcement Welkom Bericht IVR") in new stack
[2022-10-17 13:10:09] VERBOSE[2769][C-00000010] pbx.c: Executing [s@app-announcement-4:5] Playback("PJSIP/LocalPhone_Trunk-0000001c", "custom/main_ivr,noanswer") in new stack
[2022-10-17 13:10:09] VERBOSE[2769][C-00000010] file.c: <PJSIP/LocalPhone_Trunk-0000001c> Playing 'custom/main_ivr.slin' (language 'nl')
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] app_stack.c: PJSIP/LocalPhone_Trunk-0000001c Internal Gosub(crm-hangup,s,1) start
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "Sending Hangup to CRM") in new stack
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "HANGUP CAUSE: 16") in new stack
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/LocalPhone_Trunk-0000001c", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/LocalPhone_Trunk-0000001c", "MASTER CHANNEL: 1666005008.28 = 1666005008.28") in new stack
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/LocalPhone_Trunk-0000001c", "0?return") in new stack
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/LocalPhone_Trunk-0000001c", "__CRM_HANGUP=1") in new stack
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/LocalPhone_Trunk-0000001c", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] res_agi.c: <PJSIP/LocalPhone_Trunk-0000001c>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/LocalPhone_Trunk-0000001c", "") in new stack
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] app_stack.c: Spawn extension (app-announcement-4, s, 5) exited non-zero on 'PJSIP/LocalPhone_Trunk-0000001c'
[2022-10-17 13:10:12] VERBOSE[2769][C-00000010] app_stack.c: PJSIP/LocalPhone_Trunk-0000001c Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=