"I'm-sorry, an-error-has-occurred" for internal calls (after migration from Elastix to FreePBX)

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?

look for ‘strangeness’ in /etc/asterisk/voicemail.conf, like empty, half open or malformed [] stanzas

Looks correctly formed to me.
Here it is for double-check:
(I added a * after http because this forum prevents posting links)

[general]
emailbody=${VM_NAME},\n\nThere is a new voicemail in mailbox ${VM_MAILBOX}:\n\n\tFrom:\t${VM_CALLERID}\n\tLength:\t${VM_DUR} seconds\n\tDate:\t${VM_DATE}\n\nDial 98 to access your voicemail by phone.\nVisit http://AMPWEBADDRESS/ucp to check your voicemail with a web browser.\n
pollmailboxes=yes
pollfreq=10
forcegreetings=yes
forcename=yes
emailsubject=PBX Voicemail Notification
fromstring=PBX Phone System
charset=UTF-8
envelope=yes
operator=yes
review=no
saycid=no
sayduration=yes
searchcontexts=no
sendvoicemail=yes
tempgreetwarn=yes
usedirectory=yes
hidefromdir=no
moveheard=yes
smdienable=no
nextaftercmd=yes
emaildateformat=%A, %B %d, %Y at %r
pagersubject=PBX Voicemail Notification
pagerbody=New ${VM_DUR} long msg in box ${VM_MAILBOX}\nfrom ${VM_CALLERID}, on ${VM_DATE}
pagerfromstring=PBX Phone System
pagerdateformat=%A, %B %d, %Y at %r
pbxskip=no
attach=yes
maxgreet=60
maxlogins=3
maxmsg=100
minpassword=0
maxsecs=300
maxsilence=10
silencethreshold=128
minsecs=1
imapgreetings=yes
imapclosetimeout=60
imapopentimeout=60
imapreadtimeout=60
imapwritetimeout=60
aliasescontext=pbxaliases

[pbxaliases]
12@device=12@default
13@device=13@default
14@device=14@default
16@device=16@default
17@device=17@default
221@device=221@default
224@device=224@default
225@device=225@default
227@device=227@default
228@device=228@default
230@device=230@default
231@device=231@default
232@device=232@default
233@device=233@default
234@device=234@default
235@device=235@default
236@device=236@default
240@device=240@default
243@device=243@default
245@device=245@default
248@device=248@default
249@device=249@default
250@device=250@default
251@device=251@default
252@device=252@default
59@device=59@default
60@device=60@default

You are missing the [default] stanza completely.

Unless you have a working backup I suggest you recreate all your voicemail boxes.

1 Like

Thank you Dicko for pointing this out. I copied the [default] settings from the old Elastix system to FreePBX and it seems this solved the issue indeed, I don’t have the “I’m sorry, an error has occurred” anymore, thank you! :slight_smile:

However it looks like it was not the only issue I had, because I’m still not able to make internal calls. When I try to call 236 from 235 for example, I’m now getting “The person at extension 236 is unavailable. Please leave your message after the tone…” (see logs below). Same in the other direction (236 => 235). Any idea what the problem can be?

4851 [2020-10-29 10:13:06] VERBOSE[2458][C-00000001] netsock2.c: Using SIP RTP TOS bits 184
4852 [2020-10-29 10:13:06] VERBOSE[2458][C-00000001] netsock2.c: Using SIP RTP CoS mark 5
4853 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [236@Non-Tharpa:1] Set(“SIP/235-00000000”, “__RINGTIMER=15”) in new stack
4854 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [236@Non-Tharpa:2] ExecIf(“SIP/235-00000000”, “0?Set(__CWIGNORE=)”) in new stack
4855 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [236@Non-Tharpa:3] Macro(“SIP/235-00000000”, “exten-vm,236,236,0,0,0”) in new stack
4856 [2020-10-29 10:13:07] WARNING[3653][C-00000001] app_macro.c: Macro() is deprecated and will be removed from a future version of Asterisk.
4857 [2020-10-29 10:13:07] WARNING[3653][C-00000001] app_macro.c: Dialplan should be updated to use Gosub instead.
4858 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:1] Macro(“SIP/235-00000000”, “user-callerid,”) in new stack
4859 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/235-00000000”, “TOUCH_MONITOR=1603966386.0”) in new stack
4860 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/235-00000000”, “AMPUSER=235”) in new stack
4861 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:3] Set(“SIP/235-00000000”, “HOTDESCKCHAN=235-00000000”) in new stack
4862 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:4] Set(“SIP/235-00000000”, “HOTDESKEXTEN=235”) in new stack
4863 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/235-00000000”, “HOTDESKCALL=0”) in new stack
4864 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:6] ExecIf(“SIP/235-00000000”, “0?Set(HOTDESKCALL=1)”) in new stack
4865 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:7] ExecIf(“SIP/235-00000000”, “0?Set(CALLERID(name)=)”) in new stack
4866 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/235-00000000”, “0?report”) in new stack
4867 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:9] ExecIf(“SIP/235-00000000”, “1?Set(REALCALLERIDNUM=235)”) in new stack
4868 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/235-00000000”, “AMPUSER=”) in new stack
4869 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:11] GotoIf(“SIP/235-00000000”, “0?limit”) in new stack
4870 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:12] Set(“SIP/235-00000000”, “AMPUSERCIDNAME=”) in new stack
4871 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/235-00000000”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
4872 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“SIP/235-00000000”, “1?report”) in new stack
4873 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,23)
4874 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:23] NoOp(“SIP/235-00000000”, “Macro Depth is 2”) in new stack
4875 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:24] GotoIf(“SIP/235-00000000”, “1?report2:macroerror”) in new stack
4876 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,25)
4877 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:25] GotoIf(“SIP/235-00000000”, “0?continue”) in new stack
4878 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:26] ExecIf(“SIP/235-00000000”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
4879 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:27] Set(“SIP/235-00000000”, “__TTL=64”) in new stack
4880 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:28] GotoIf(“SIP/235-00000000”, “1?continue”) in new stack
4881 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,44)
4882 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:44] Set(“SIP/235-00000000”, “CALLERID(number)=235”) in new stack
4883 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:45] Set(“SIP/235-00000000”, “CALLERID(name)=Building Manager”) in new stack
4884 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:46] GotoIf(“SIP/235-00000000”, “0?cnum”) in new stack
4885 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:47] Set(“SIP/235-00000000”, “CDR(cnam)=Building Manager”) in new stack
4886 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:48] Set(“SIP/235-00000000”, “CDR(cnum)=235”) in new stack
4887 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:49] Set(“SIP/235-00000000”, “CHANNEL(language)=en”) in new stack
4888 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:2] Set(“SIP/235-00000000”, “RingGroupMethod=none”) in new stack
4889 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:3] Set(“SIP/235-00000000”, “__EXTTOCALL=236”) in new stack
4890 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:4] Set(“SIP/235-00000000”, “__PICKUPMARK=236”) in new stack
4891 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:5] Set(“SIP/235-00000000”, “RT=15”) in new stack
4892 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:6] ExecIf(“SIP/235-00000000”, “0?Macro(vm,236,DIRECTDIAL,)”) in new stack
4893 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:7] ExecIf(“SIP/235-00000000”, “0?MacroExit()”) in new stack
4894 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:8] ExecIf(“SIP/235-00000000”, “0?Gosub(ext-intercom,nointercom236,1())”) in new stack
4895 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:9] ExecIf(“SIP/235-00000000”, “0?MacroExit()”) in new stack
4896 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:10] ExecIf(“SIP/235-00000000”, “0?ChanSpy(,q)”) in new stack
4897 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:11] ExecIf(“SIP/235-00000000”, “0?MacroExit()”) in new stack
4898 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4899 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:12] ExecIf(“SIP/235-00000000”, “0?Macro(vm,236,DIRECTDIAL,)”) in new stack
4900 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4901 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4902 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:13] ExecIf(“SIP/235-00000000”, “0?MacroExit()”) in new stack
4903 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4904 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4905 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:14] ExecIf(“SIP/235-00000000”, “0?Gosub(ext-intercom,nointercom236,1())”) in new stack
4906 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4907 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4908 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:15] ExecIf(“SIP/235-00000000”, “0?MacroExit()”) in new stack
4909 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4910 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4911 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:16] ExecIf(“SIP/235-00000000”, “0?ChanSpy(,q)”) in new stack
4912 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4913 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4914 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:17] ExecIf(“SIP/235-00000000”, “0?MacroExit()”) in new stack
4915 [2020-10-29 10:13:07] ERROR[3653][C-00000001] pbx_functions.c: Function PJSIP_HEADER not registered
4916 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:18] Gosub(“SIP/235-00000000”, “sub-record-check,s,1(exten,236,dontcare)”) in new stack
4917 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/235-00000000”, “0?initialized”) in new stack
4918 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/235-00000000”, “__REC_STATUS=INITIALIZED”) in new stack
4919 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/235-00000000”, “NOW=1603966387”) in new stack
4920 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/235-00000000”, “__DAY=29”) in new stack
4921 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/235-00000000”, “__MONTH=10”) in new stack
4922 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/235-00000000”, “__YEAR=2020”) in new stack
4923 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/235-00000000”, “__TIMESTR=20201029-101307”) in new stack
4924 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/235-00000000”, “__FROMEXTEN=235”) in new stack
4925 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/235-00000000”, “__MON_FMT=wav”) in new stack
4926 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/235-00000000”, “Recordings initialized”) in new stack
4927 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/235-00000000”, “0?Set(ARG3=dontcare)”) in new stack
4928 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/235-00000000”, “REC_POLICY_MODE_SAVE=”) in new stack
4929 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/235-00000000”, “0?Set(REC_STATUS=NO)”) in new stack
4930 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/235-00000000”, “5?checkaction”) in new stack
4931 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (sub-record-check,s,17)
4932 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/235-00000000”, “1?sub-record-check,exten,1”) in new stack
4933 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (sub-record-check,exten,1)
4934 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:1] NoOp(“SIP/235-00000000”, “Exten Recording Check between 235 and 236”) in new stack
4935 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:2] Set(“SIP/235-00000000”, “CALLTYPE=internal”) in new stack
4936 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“SIP/235-00000000”, “0?Set(CALLTYPE=)”) in new stack
4937 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:4] Set(“SIP/235-00000000”, “CALLEE=”) in new stack
4938 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“SIP/235-00000000”, “1?Set(CALLEE=dontcare)”) in new stack
4939 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“SIP/235-00000000”, “0?callee”) in new stack
4940 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:7] GotoIf(“SIP/235-00000000”, “1?caller”) in new stack
4941 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (sub-record-check,exten,13)
4942 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:13] Set(“SIP/235-00000000”, “RECMODE=”) in new stack
4943 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:14] ExecIf(“SIP/235-00000000”, “1?Set(RECMODE=dontcare)”) in new stack
4944 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:15] ExecIf(“SIP/235-00000000”, “1?Set(RECMODE=dontcare)”) in new stack
4945 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:16] Gosub(“SIP/235-00000000”, “recordcheck,1(dontcare,internal,236)”) in new stack
4946 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/235-00000000”, “Starting recording check against dontcare”) in new stack
4947 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/235-00000000”, “dontcare”) in new stack
4948 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
4949 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/235-00000000”, “”) in new stack
4950 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [exten@sub-record-check:17] Return(“SIP/235-00000000”, “”) in new stack
4951 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:19] Macro(“SIP/235-00000000”, “dial-one,15,HhTtr,236”) in new stack
4952 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:1] Set(“SIP/235-00000000”, “DEXTEN=236”) in new stack
4953 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:2] Set(“SIP/235-00000000”, “__CRM_SOURCE=235”) in new stack
4954 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:3] ExecIf(“SIP/235-00000000”, “0?Set(__EXTTOCALL=236)”) in new stack
4955 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:4] Set(“SIP/235-00000000”, “DIALSTATUS_CW=”) in new stack
4956 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:5] GosubIf(“SIP/235-00000000”, “0?screen,1()”) in new stack
4957 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:6] GosubIf(“SIP/235-00000000”, “0?cf,1()”) in new stack
4958 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:7] GotoIf(“SIP/235-00000000”, “1?skip1”) in new stack
4959 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,10)
4960 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:10] GotoIf(“SIP/235-00000000”, “0?nodial”) in new stack
4961 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:11] GotoIf(“SIP/235-00000000”, “0?continue”) in new stack
4962 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:12] Set(“SIP/235-00000000”, “EXTHASCW=”) in new stack
4963 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:13] GotoIf(“SIP/235-00000000”, “1?next1:cwinusebusy”) in new stack
4964 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,14)
4965 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:14] GotoIf(“SIP/235-00000000”, “0?docfu:skip3”) in new stack
4966 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,18)
4967 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:18] GotoIf(“SIP/235-00000000”, “1?next2:continue”) in new stack
4968 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,19)
4969 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:19] GotoIf(“SIP/235-00000000”, “1?continue”) in new stack
4970 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,27)
4971 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:27] GotoIf(“SIP/235-00000000”, “0?nodial”) in new stack
4972 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:28] GosubIf(“SIP/235-00000000”, “1?dstring,1():dlocal,1()”) in new stack
4973 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [dstring@macro-dial-one:1] Set(“SIP/235-00000000”, “DSTRING=”) in new stack
4974 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [dstring@macro-dial-one:2] Set(“SIP/235-00000000”, “DEVICES=”) in new stack
4975 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“SIP/235-00000000”, “1?Return()”) in new stack
4976 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:29] GotoIf(“SIP/235-00000000”, “1?nodial”) in new stack
4977 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-dial-one,s,61)
4978 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:61] NoOp(“SIP/235-00000000”, “”) in new stack
4979 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:62] ExecIf(“SIP/235-00000000”, “1?Set(DIALSTATUS=NOANSWER)”) in new stack
4980 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:63] NoOp(“SIP/235-00000000”, “Returned from dial-one with nothing to call and DIALSTATUS: NOANSWER”) in new stack
4981 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-dial-one:64] MacroExit(“SIP/235-00000000”, “”) in new stack
4982 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:20] Set(“SIP/235-00000000”, “SV_DIALSTATUS=NOANSWER”) in new stack
4983 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:21] GosubIf(“SIP/235-00000000”, “0?docfu,1()”) in new stack
4984 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:22] GosubIf(“SIP/235-00000000”, “0?docfb,1()”) in new stack
4985 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:23] Set(“SIP/235-00000000”, “DIALSTATUS=NOANSWER”) in new stack
4986 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:24] ExecIf(“SIP/235-00000000”, “0?MacroExit()”) in new stack
4987 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:25] GotoIf(“SIP/235-00000000”, “0?s-NOANSWER,1”) in new stack
4988 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-exten-vm:26] Macro(“SIP/235-00000000”, “vm,236,NOANSWER,”) in new stack
4989 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-vm:1] Macro(“SIP/235-00000000”, “user-callerid,SKIPTTL”) in new stack
4990 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/235-00000000”, “TOUCH_MONITOR=1603966386.0”) in new stack
4991 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/235-00000000”, “AMPUSER=235”) in new stack
4992 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:3] Set(“SIP/235-00000000”, “HOTDESCKCHAN=235-00000000”) in new stack
4993 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:4] Set(“SIP/235-00000000”, “HOTDESKEXTEN=235”) in new stack
4994 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/235-00000000”, “HOTDESKCALL=0”) in new stack
4995 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:6] ExecIf(“SIP/235-00000000”, “0?Set(HOTDESKCALL=1)”) in new stack
4996 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:7] ExecIf(“SIP/235-00000000”, “0?Set(CALLERID(name)=)”) in new stack
4997 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/235-00000000”, “0?report”) in new stack
4998 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:9] ExecIf(“SIP/235-00000000”, “0?Set(REALCALLERIDNUM=235)”) in new stack
4999 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/235-00000000”, “AMPUSER=”) in new stack
5000 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:11] GotoIf(“SIP/235-00000000”, “0?limit”) in new stack
5001 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:12] Set(“SIP/235-00000000”, “AMPUSERCIDNAME=”) in new stack
5002 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/235-00000000”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
5003 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“SIP/235-00000000”, “1?report”) in new stack
5004 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,23)
5005 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:23] NoOp(“SIP/235-00000000”, “Macro Depth is 3”) in new stack
5006 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:24] GotoIf(“SIP/235-00000000”, “1?report2:macroerror”) in new stack
5007 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,25)
5008 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:25] GotoIf(“SIP/235-00000000”, “1?continue”) in new stack
5009 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-user-callerid,s,44)
5010 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:44] Set(“SIP/235-00000000”, “CALLERID(number)=235”) in new stack
5011 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:45] Set(“SIP/235-00000000”, “CALLERID(name)=Building Manager”) in new stack
5012 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:46] GotoIf(“SIP/235-00000000”, “0?cnum”) in new stack
5013 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:47] Set(“SIP/235-00000000”, “CDR(cnam)=Building Manager”) in new stack
5014 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:48] Set(“SIP/235-00000000”, “CDR(cnum)=235”) in new stack
5015 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-user-callerid:49] Set(“SIP/235-00000000”, “CHANNEL(language)=en”) in new stack
5016 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-vm:2] Set(“SIP/235-00000000”, “VMGAIN=”) in new stack
5017 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-vm:3] Macro(“SIP/235-00000000”, “blkvm-check,”) in new stack
5018 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-blkvm-check:1] Set(“SIP/235-00000000”, “GOSUB_RETVAL=”) in new stack
5019 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf(“SIP/235-00000000”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
5020 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit(“SIP/235-00000000”, “”) in new stack
5021 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-vm:4] GotoIf(“SIP/235-00000000”, “1?vmx,1”) in new stack
5022 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-vm,vmx,1)
5023 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:1] Set(“SIP/235-00000000”, “__EXTTOCALL=236”) in new stack
5024 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:2] Set(“SIP/235-00000000”, “__CRM_VOICEMAIL=236”) in new stack
5025 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:3] Set(“SIP/235-00000000”, “MEXTEN=236”) in new stack
5026 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:4] Set(“SIP/235-00000000”, “MMODE=NOANSWER”) in new stack
5027 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:5] Set(“SIP/235-00000000”, “RETVM=”) in new stack
5028 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:6] Set(“SIP/235-00000000”, “MODE=unavail”) in new stack
5029 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:7] Macro(“SIP/235-00000000”, “get-vmcontext,236”) in new stack
5030 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“SIP/235-00000000”, “VMCONTEXT=default”) in new stack
5031 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/235-00000000”, “0?200:300”) in new stack
5032 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
5033 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“SIP/235-00000000”, “”) in new stack
5034 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:8] Set(“SIP/235-00000000”, “MODE=unavail”) in new stack
5035 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:9] NoOp(“SIP/235-00000000”, “MODE IS: unavail”) in new stack
5036 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:10] GotoIf(“SIP/235-00000000”, “1?chknomsg”) in new stack
5037 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-vm,vmx,12)
5038 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:12] GotoIf(“SIP/235-00000000”, “0?s-NOANSWER,1”) in new stack
5039 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:13] GotoIf(“SIP/235-00000000”, “1?notdirect”) in new stack
5040 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-vm,vmx,15)
5041 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:15] NoOp(“SIP/235-00000000”, "Checking if ext 236 is enabled: ") in new stack
5042 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [vmx@macro-vm:16] GotoIf(“SIP/235-00000000”, “1?s-NOANSWER,1”) in new stack
5043 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-vm,s-NOANSWER,1)
5044 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s-NOANSWER@macro-vm:1] Macro(“SIP/235-00000000”, “get-vmcontext,236”) in new stack
5045 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“SIP/235-00000000”, “VMCONTEXT=default”) in new stack
5046 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/235-00000000”, “0?200:300”) in new stack
5047 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
5048 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“SIP/235-00000000”, “”) in new stack
5049 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail(“SIP/235-00000000”, “236@default,u”) in new stack
5050 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] file.c: <SIP/235-00000000> Playing ‘vm-theperson.ulaw’ (language ‘en’)
5051 [2020-10-29 10:13:10] VERBOSE[3653][C-00000001] file.c: <SIP/235-00000000> Playing ‘digits/2.ulaw’ (language ‘en’)
5052 [2020-10-29 10:13:10] VERBOSE[3653][C-00000001] file.c: <SIP/235-00000000> Playing ‘digits/3.ulaw’ (language ‘en’)
5053 [2020-10-29 10:13:11] VERBOSE[3653][C-00000001] file.c: <SIP/235-00000000> Playing ‘digits/6.ulaw’ (language ‘en’)
5054 [2020-10-29 10:13:12] VERBOSE[3653][C-00000001] file.c: <SIP/235-00000000> Playing ‘vm-isunavail.ulaw’ (language ‘en’)
5055 [2020-10-29 10:13:14] VERBOSE[3653][C-00000001] file.c: <SIP/235-00000000> Playing ‘vm-intro.ulaw’ (language ‘en’)
5056 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] app_macro.c: Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘SIP/235-00000000’ in macro ‘vm’
5057 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] app_macro.c: Spawn extension (macro-exten-vm, s, 26) exited non-zero on ‘SIP/235-00000000’ in macro ‘exten-vm’
5058 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx.c: Spawn extension (Non-Tharpa, 236, 3) exited non-zero on ‘SIP/235-00000000’
5059 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx.c: Executing [h@Non-Tharpa:1] Macro(“SIP/235-00000000”, “hangupcall,”) in new stack
5060 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/235-00000000”, “1?theend”) in new stack
5061 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-hangupcall,s,3)
5062 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/235-00000000”, “0?Set(CDR(recordingfile)=)”) in new stack
5063 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/235-00000000”, " montior file= ") in new stack
5064 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“SIP/235-00000000”, “1?skipagi”) in new stack
5065 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx_builtins.c: Goto (macro-hangupcall,s,7)
5066 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“SIP/235-00000000”, “”) in new stack
5067 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘SIP/235-00000000’ in macro ‘hangupcall’
5068 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx.c: Spawn extension (Non-Tharpa, h, 1) exited non-zero on ‘SIP/235-00000000’

looks like the extensions are not regisering, sometmnes caused by an incomplete sqlite3 asteriskdb, double check and re-save each extension one by one.

You can sometimes recover from AstDB corruption with

fwconsole util resetastdb
1 Like

Lorne, That was it!

I ran the command you suggested and now internal calls are working :slight_smile:

Thank you so much both of you!!

@Manjushri can you provide the backup module version pls:

fwconsole ma list | grep backup

Sure, it was version 15.0.10.38

I said “was” because I ran the online upgrade just after your fix (now it is 15.0.10.39 on my VM).

But be aware that as I said in my original post, I used the FreePBX Distro Conversion Tool (before its shutdown on 28 September) to do the migration (from Elastix 2.3.0 to FreePBX 15), not the backup & restore tool.

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