Hi,
I’m in the process of migrating an old phone system (Elastix 2.3.0) to FreePBX 15. I used the FreePBX Distro Conversion Tool for the migration and it seems to have worked (I can see all trunks, extensions, etc…).
But I can’t make internal calls on the new system (external calls work however). I’m getting a voice saying “I’m-sorry, an-error-has-occurred”. So I would like to identify this error.
Here is the Asterisk log when I try for example to call extension 236 from extension 235:
2355 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [236@Non-Tharpa:1] Set(“SIP/235-0000000d”, “__RINGTIMER=15”) in new stack
2356 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [236@Non-Tharpa:2] ExecIf(“SIP/235-0000000d”, “0?Set(__CWIGNORE=)”) in new stack
2357 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [236@Non-Tharpa:3] Macro(“SIP/235-0000000d”, “exten-vm,236,236,0,0,0”) in new stack
2358 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:1] Macro(“SIP/235-0000000d”, “user-callerid,”) in new stack
2359 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/235-0000000d”, “TOUCH_MONITOR=1603299232.13”) in new stack
2360 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/235-0000000d”, “AMPUSER=235”) in new stack
2361 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:3] Set(“SIP/235-0000000d”, “HOTDESCKCHAN=235-0000000d”) in new stack
2362 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:4] Set(“SIP/235-0000000d”, “HOTDESKEXTEN=235”) in new stack
2363 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/235-0000000d”, “HOTDESKCALL=0”) in new stack
2364 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:6] ExecIf(“SIP/235-0000000d”, “0?Set(HOTDESKCALL=1)”) in new stack
2365 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:7] ExecIf(“SIP/235-0000000d”, “0?Set(CALLERID(name)=)”) in new stack
2366 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/235-0000000d”, “0?report”) in new stack
2367 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:9] ExecIf(“SIP/235-0000000d”, “1?Set(REALCALLERIDNUM=235)”) in new stack
2368 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/235-0000000d”, “AMPUSER=”) in new stack
2369 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:11] GotoIf(“SIP/235-0000000d”, “0?limit”) in new stack
2370 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:12] Set(“SIP/235-0000000d”, “AMPUSERCIDNAME=”) in new stack
2371 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/235-0000000d”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
2372 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“SIP/235-0000000d”, “1?report”) in new stack
2373 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,23)
2374 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:23] NoOp(“SIP/235-0000000d”, “Macro Depth is 2”) in new stack
2375 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:24] GotoIf(“SIP/235-0000000d”, “1?report2:macroerror”) in new stack
2376 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,25)
2377 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:25] GotoIf(“SIP/235-0000000d”, “0?continue”) in new stack
2378 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:26] ExecIf(“SIP/235-0000000d”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
2379 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:27] Set(“SIP/235-0000000d”, “__TTL=64”) in new stack
2380 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:28] GotoIf(“SIP/235-0000000d”, “1?continue”) in new stack
2381 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,44)
2382 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:44] Set(“SIP/235-0000000d”, “CALLERID(number)=235”) in new stack
2383 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:45] Set(“SIP/235-0000000d”, “CALLERID(name)=Building Manager”) in new stack
2384 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:46] GotoIf(“SIP/235-0000000d”, “0?cnum”) in new stack
2385 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:47] Set(“SIP/235-0000000d”, “CDR(cnam)=Building Manager”) in new stack
2386 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:48] Set(“SIP/235-0000000d”, “CDR(cnum)=235”) in new stack
2387 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:49] Set(“SIP/235-0000000d”, “CHANNEL(language)=en”) in new stack
2388 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:2] Set(“SIP/235-0000000d”, “RingGroupMethod=none”) in new stack
2389 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:3] Set(“SIP/235-0000000d”, “__EXTTOCALL=236”) in new stack
2390 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:4] Set(“SIP/235-0000000d”, “__PICKUPMARK=236”) in new stack
2391 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:5] Set(“SIP/235-0000000d”, “RT=15”) in new stack
2392 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:6] ExecIf(“SIP/235-0000000d”, “0?Macro(vm,236,DIRECTDIAL,)”) in new stack
2393 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:7] ExecIf(“SIP/235-0000000d”, “0?MacroExit()”) in new stack
2394 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:8] ExecIf(“SIP/235-0000000d”, “0?Gosub(ext-intercom,nointercom236,1())”) in new stack
2395 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:9] ExecIf(“SIP/235-0000000d”, “0?MacroExit()”) in new stack
2396 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:10] ExecIf(“SIP/235-0000000d”, “0?ChanSpy(,q)”) in new stack
2397 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:11] ExecIf(“SIP/235-0000000d”, “0?MacroExit()”) in new stack
2398 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2399 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:12] ExecIf(“SIP/235-0000000d”, “0?Macro(vm,236,DIRECTDIAL,)”) in new stack
2400 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2401 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2402 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:13] ExecIf(“SIP/235-0000000d”, “0?MacroExit()”) in new stack
2403 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2404 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2405 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:14] ExecIf(“SIP/235-0000000d”, “0?Gosub(ext-intercom,nointercom236,1())”) in new stack
2406 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2407 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2408 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:15] ExecIf(“SIP/235-0000000d”, “0?MacroExit()”) in new stack
2409 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2410 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2411 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:16] ExecIf(“SIP/235-0000000d”, “0?ChanSpy(,q)”) in new stack
2412 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2413 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2414 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:17] ExecIf(“SIP/235-0000000d”, “0?MacroExit()”) in new stack
2415 [2020-10-21 16:53:52] ERROR[19012][C-0000000b] pbx_functions.c: Function PJSIP_HEADER not registered
2416 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:18] Gosub(“SIP/235-0000000d”, “sub-record-check,s,1(exten,236,dontcare)”) in new stack
2417 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/235-0000000d”, “0?initialized”) in new stack
2418 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/235-0000000d”, “__REC_STATUS=INITIALIZED”) in new stack
2419 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/235-0000000d”, “NOW=1603299232”) in new stack
2420 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/235-0000000d”, “__DAY=21”) in new stack
2421 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/235-0000000d”, “__MONTH=10”) in new stack
2422 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/235-0000000d”, “__YEAR=2020”) in new stack
2423 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/235-0000000d”, “__TIMESTR=20201021-165352”) in new stack
2424 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/235-0000000d”, “__FROMEXTEN=235”) in new stack
2425 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/235-0000000d”, “__MON_FMT=wav”) in new stack
2426 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/235-0000000d”, “Recordings initialized”) in new stack
2427 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/235-0000000d”, “0?Set(ARG3=dontcare)”) in new stack
2428 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/235-0000000d”, “REC_POLICY_MODE_SAVE=”) in new stack
2429 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/235-0000000d”, “0?Set(REC_STATUS=NO)”) in new stack
2430 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/235-0000000d”, “5?checkaction”) in new stack
2431 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (sub-record-check,s,17)
2432 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/235-0000000d”, “1?sub-record-check,exten,1”) in new stack
2433 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (sub-record-check,exten,1)
2434 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:1] NoOp(“SIP/235-0000000d”, “Exten Recording Check between 235 and 236”) in new stack
2435 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:2] Set(“SIP/235-0000000d”, “CALLTYPE=internal”) in new stack
2436 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“SIP/235-0000000d”, “0?Set(CALLTYPE=)”) in new stack
2437 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:4] Set(“SIP/235-0000000d”, “CALLEE=”) in new stack
2438 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“SIP/235-0000000d”, “1?Set(CALLEE=dontcare)”) in new stack
2439 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“SIP/235-0000000d”, “0?callee”) in new stack
2440 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:7] GotoIf(“SIP/235-0000000d”, “1?caller”) in new stack
2441 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (sub-record-check,exten,13)
2442 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:13] Set(“SIP/235-0000000d”, “RECMODE=”) in new stack
2443 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:14] ExecIf(“SIP/235-0000000d”, “1?Set(RECMODE=dontcare)”) in new stack
2444 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:15] ExecIf(“SIP/235-0000000d”, “1?Set(RECMODE=dontcare)”) in new stack
2445 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:16] Gosub(“SIP/235-0000000d”, “recordcheck,1(dontcare,internal,236)”) in new stack
2446 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/235-0000000d”, “Starting recording check against dontcare”) in new stack
2447 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/235-0000000d”, “dontcare”) in new stack
2448 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
2449 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/235-0000000d”, “”) in new stack
2450 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exten@sub-record-check:17] Return(“SIP/235-0000000d”, “”) in new stack
2451 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:19] Macro(“SIP/235-0000000d”, “dial-one,15,HhTtr,236”) in new stack
2452 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:1] Set(“SIP/235-0000000d”, “DEXTEN=236”) in new stack
2453 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:2] Set(“SIP/235-0000000d”, “__CRM_SOURCE=235”) in new stack
2454 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:3] ExecIf(“SIP/235-0000000d”, “0?Set(__EXTTOCALL=236)”) in new stack
2455 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:4] Set(“SIP/235-0000000d”, “DIALSTATUS_CW=”) in new stack
2456 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:5] GosubIf(“SIP/235-0000000d”, “0?screen,1()”) in new stack
2457 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:6] GosubIf(“SIP/235-0000000d”, “0?cf,1()”) in new stack
2458 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:7] GotoIf(“SIP/235-0000000d”, “1?skip1”) in new stack
2459 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-dial-one,s,10)
2460 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:10] GotoIf(“SIP/235-0000000d”, “0?nodial”) in new stack
2461 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:11] GotoIf(“SIP/235-0000000d”, “0?continue”) in new stack
2462 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:12] Set(“SIP/235-0000000d”, “EXTHASCW=”) in new stack
2463 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:13] GotoIf(“SIP/235-0000000d”, “1?next1:cwinusebusy”) in new stack
2464 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-dial-one,s,14)
2465 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:14] GotoIf(“SIP/235-0000000d”, “0?docfu:skip3”) in new stack
2466 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-dial-one,s,18)
2467 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:18] GotoIf(“SIP/235-0000000d”, “1?next2:continue”) in new stack
2468 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-dial-one,s,19)
2469 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:19] GotoIf(“SIP/235-0000000d”, “1?continue”) in new stack
2470 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-dial-one,s,27)
2471 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:27] GotoIf(“SIP/235-0000000d”, “0?nodial”) in new stack
2472 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:28] GosubIf(“SIP/235-0000000d”, “1?dstring,1():dlocal,1()”) in new stack
2473 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:1] Set(“SIP/235-0000000d”, “DSTRING=”) in new stack
2474 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:2] Set(“SIP/235-0000000d”, “DEVICES=”) in new stack
2475 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“SIP/235-0000000d”, “1?Return()”) in new stack
2476 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:29] GotoIf(“SIP/235-0000000d”, “1?nodial”) in new stack
2477 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-dial-one,s,61)
2478 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:61] NoOp(“SIP/235-0000000d”, “”) in new stack
2479 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:62] ExecIf(“SIP/235-0000000d”, “1?Set(DIALSTATUS=NOANSWER)”) in new stack
2480 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:63] NoOp(“SIP/235-0000000d”, “Returned from dial-one with nothing to call and DIALSTATUS: NOANSWER”) in new stack
2481 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-dial-one:64] MacroExit(“SIP/235-0000000d”, “”) in new stack
2482 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:20] Set(“SIP/235-0000000d”, “SV_DIALSTATUS=NOANSWER”) in new stack
2483 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:21] GosubIf(“SIP/235-0000000d”, “0?docfu,1()”) in new stack
2484 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:22] GosubIf(“SIP/235-0000000d”, “0?docfb,1()”) in new stack
2485 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:23] Set(“SIP/235-0000000d”, “DIALSTATUS=NOANSWER”) in new stack
2486 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:24] ExecIf(“SIP/235-0000000d”, “0?MacroExit()”) in new stack
2487 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:25] GotoIf(“SIP/235-0000000d”, “0?s-NOANSWER,1”) in new stack
2488 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-exten-vm:26] Macro(“SIP/235-0000000d”, “vm,236,NOANSWER,”) in new stack
2489 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-vm:1] Macro(“SIP/235-0000000d”, “user-callerid,SKIPTTL”) in new stack
2490 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/235-0000000d”, “TOUCH_MONITOR=1603299232.13”) in new stack
2491 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/235-0000000d”, “AMPUSER=235”) in new stack
2492 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:3] Set(“SIP/235-0000000d”, “HOTDESCKCHAN=235-0000000d”) in new stack
2493 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:4] Set(“SIP/235-0000000d”, “HOTDESKEXTEN=235”) in new stack
2494 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/235-0000000d”, “HOTDESKCALL=0”) in new stack
2495 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:6] ExecIf(“SIP/235-0000000d”, “0?Set(HOTDESKCALL=1)”) in new stack
2496 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:7] ExecIf(“SIP/235-0000000d”, “0?Set(CALLERID(name)=)”) in new stack
2497 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/235-0000000d”, “0?report”) in new stack
2498 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:9] ExecIf(“SIP/235-0000000d”, “0?Set(REALCALLERIDNUM=235)”) in new stack
2499 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/235-0000000d”, “AMPUSER=”) in new stack
2500 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:11] GotoIf(“SIP/235-0000000d”, “0?limit”) in new stack
2501 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:12] Set(“SIP/235-0000000d”, “AMPUSERCIDNAME=”) in new stack
2502 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/235-0000000d”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
2503 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“SIP/235-0000000d”, “1?report”) in new stack
2504 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,23)
2505 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:23] NoOp(“SIP/235-0000000d”, “Macro Depth is 3”) in new stack
2506 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:24] GotoIf(“SIP/235-0000000d”, “1?report2:macroerror”) in new stack
2507 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,25)
2508 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:25] GotoIf(“SIP/235-0000000d”, “1?continue”) in new stack
2509 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,44)
2510 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:44] Set(“SIP/235-0000000d”, “CALLERID(number)=235”) in new stack
2511 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:45] Set(“SIP/235-0000000d”, “CALLERID(name)=Building Manager”) in new stack
2512 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:46] GotoIf(“SIP/235-0000000d”, “0?cnum”) in new stack
2513 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:47] Set(“SIP/235-0000000d”, “CDR(cnam)=Building Manager”) in new stack
2514 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:48] Set(“SIP/235-0000000d”, “CDR(cnum)=235”) in new stack
2515 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-user-callerid:49] Set(“SIP/235-0000000d”, “CHANNEL(language)=en”) in new stack
2516 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-vm:2] Set(“SIP/235-0000000d”, “VMGAIN=”) in new stack
2517 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-vm:3] Macro(“SIP/235-0000000d”, “blkvm-check,”) in new stack
2518 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-blkvm-check:1] Set(“SIP/235-0000000d”, “GOSUB_RETVAL=”) in new stack
2519 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf(“SIP/235-0000000d”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
2520 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit(“SIP/235-0000000d”, “”) in new stack
2521 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-vm:4] GotoIf(“SIP/235-0000000d”, “1?vmx,1”) in new stack
2522 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-vm,vmx,1)
2523 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:1] Set(“SIP/235-0000000d”, “__EXTTOCALL=236”) in new stack
2524 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:2] Set(“SIP/235-0000000d”, “__CRM_VOICEMAIL=236”) in new stack
2525 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:3] Set(“SIP/235-0000000d”, “MEXTEN=236”) in new stack
2526 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:4] Set(“SIP/235-0000000d”, “MMODE=NOANSWER”) in new stack
2527 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:5] Set(“SIP/235-0000000d”, “RETVM=”) in new stack
2528 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:6] Set(“SIP/235-0000000d”, “MODE=unavail”) in new stack
2529 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:7] Macro(“SIP/235-0000000d”, “get-vmcontext,236”) in new stack
2530 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“SIP/235-0000000d”, “VMCONTEXT=default”) in new stack
2531 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/235-0000000d”, “0?200:300”) in new stack
2532 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
2533 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“SIP/235-0000000d”, “”) in new stack
2534 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:8] Set(“SIP/235-0000000d”, “MODE=unavail”) in new stack
2535 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:9] NoOp(“SIP/235-0000000d”, “MODE IS: unavail”) in new stack
2536 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:10] GotoIf(“SIP/235-0000000d”, “1?chknomsg”) in new stack
2537 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-vm,vmx,12)
2538 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:12] GotoIf(“SIP/235-0000000d”, “0?s-NOANSWER,1”) in new stack
2539 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:13] GotoIf(“SIP/235-0000000d”, “1?notdirect”) in new stack
2540 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-vm,vmx,15)
2541 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:15] NoOp(“SIP/235-0000000d”, "Checking if ext 236 is enabled: ") in new stack
2542 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [vmx@macro-vm:16] GotoIf(“SIP/235-0000000d”, “1?s-NOANSWER,1”) in new stack
2543 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-vm,s-NOANSWER,1)
2544 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s-NOANSWER@macro-vm:1] Macro(“SIP/235-0000000d”, “get-vmcontext,236”) in new stack
2545 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“SIP/235-0000000d”, “VMCONTEXT=default”) in new stack
2546 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/235-0000000d”, “0?200:300”) in new stack
2547 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
2548 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“SIP/235-0000000d”, “”) in new stack
2549 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail(“SIP/235-0000000d”, “236@default,u”) in new stack
2550 [2020-10-21 16:53:52] WARNING[19012][C-0000000b] app_voicemail.c: No entry in voicemail config file for ‘236’
2551 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [s-NOANSWER@macro-vm:3] Goto(“SIP/235-0000000d”, “exit-FAILED,1”) in new stack
2552 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-vm,exit-FAILED,1)
2553 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [exit-FAILED@macro-vm:1] Playback(“SIP/235-0000000d”, “im-sorry&an-error-has-occurred”) in new stack
2554 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] file.c: <SIP/235-0000000d> Playing ‘im-sorry.ulaw’ (language ‘en’)
2555 [2020-10-21 16:53:53] VERBOSE[19012][C-0000000b] file.c: <SIP/235-0000000d> Playing ‘an-error-has-occurred.ulaw’ (language ‘en’)
2556 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Executing [exit-FAILED@macro-vm:2] GotoIf(“SIP/235-0000000d”, “0?exit-RETURN,1”) in new stack
2557 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Executing [exit-FAILED@macro-vm:3] Hangup(“SIP/235-0000000d”, “”) in new stack
2558 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] app_macro.c: Spawn extension (macro-vm, exit-FAILED, 3) exited non-zero on ‘SIP/235-0000000d’ in macro ‘vm’
2559 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] app_macro.c: Spawn extension (macro-exten-vm, s, 26) exited non-zero on ‘SIP/235-0000000d’ in macro ‘exten-vm’
2560 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Spawn extension (Non-Tharpa, 236, 3) exited non-zero on ‘SIP/235-0000000d’
2561 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Executing [h@Non-Tharpa:1] Macro(“SIP/235-0000000d”, “hangupcall,”) in new stack
2562 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/235-0000000d”, “1?theend”) in new stack
2563 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-hangupcall,s,3)
2564 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/235-0000000d”, “0?Set(CDR(recordingfile)=)”) in new stack
2565 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/235-0000000d”, " montior file= ") in new stack
2566 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“SIP/235-0000000d”, “1?skipagi”) in new stack
2567 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx_builtins.c: Goto (macro-hangupcall,s,7)
2568 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“SIP/235-0000000d”, “”) in new stack
2569 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘SIP/235-0000000d’ in macro ‘hangupcall’
2570 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Spawn extension (Non-Tharpa, h, 1) exited non-zero on ‘SIP/235-0000000d’
Can someone help me to identify the error?