"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 [[email protected]:1] Set(“SIP/235-0000000d”, “__RINGTIMER=15”) in new stack
2356 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:1] Macro(“SIP/235-0000000d”, “user-callerid,”) in new stack
2359 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:2] Set(“SIP/235-0000000d”, “AMPUSER=235”) in new stack
2361 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:4] Set(“SIP/235-0000000d”, “HOTDESKEXTEN=235”) in new stack
2363 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:5] Set(“SIP/235-0000000d”, “HOTDESKCALL=0”) in new stack
2364 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:8] GotoIf(“SIP/235-0000000d”, “0?report”) in new stack
2367 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:10] Set(“SIP/235-0000000d”, “AMPUSER=”) in new stack
2369 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:11] GotoIf(“SIP/235-0000000d”, “0?limit”) in new stack
2370 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:12] Set(“SIP/235-0000000d”, “AMPUSERCIDNAME=”) in new stack
2371 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:25] GotoIf(“SIP/235-0000000d”, “0?continue”) in new stack
2378 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:27] Set(“SIP/235-0000000d”, “__TTL=64”) in new stack
2380 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:46] GotoIf(“SIP/235-0000000d”, “0?cnum”) in new stack
2385 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“SIP/235-0000000d”, “RingGroupMethod=none”) in new stack
2389 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:3] Set(“SIP/235-0000000d”, “__EXTTOCALL=236”) in new stack
2390 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:4] Set(“SIP/235-0000000d”, “__PICKUPMARK=236”) in new stack
2391 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:5] Set(“SIP/235-0000000d”, “RT=15”) in new stack
2392 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:7] ExecIf(“SIP/235-0000000d”, “0?MacroExit()”) in new stack
2394 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]ten-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 [[email protected]:9] ExecIf(“SIP/235-0000000d”, “0?MacroExit()”) in new stack
2396 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] GotoIf(“SIP/235-0000000d”, “0?initialized”) in new stack
2418 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:3] Set(“SIP/235-0000000d”, “NOW=1603299232”) in new stack
2420 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:4] Set(“SIP/235-0000000d”, “__DAY=21”) in new stack
2421 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:5] Set(“SIP/235-0000000d”, “__MONTH=10”) in new stack
2422 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:6] Set(“SIP/235-0000000d”, “__YEAR=2020”) in new stack
2423 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:8] Set(“SIP/235-0000000d”, “__FROMEXTEN=235”) in new stack
2425 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:10] NoOp(“SIP/235-0000000d”, “Recordings initialized”) in new stack
2427 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“SIP/235-0000000d”, “CALLTYPE=internal”) in new stack
2436 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:4] Set(“SIP/235-0000000d”, “CALLEE=”) in new stack
2438 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:6] GotoIf(“SIP/235-0000000d”, “0?callee”) in new stack
2440 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:13] Set(“SIP/235-0000000d”, “RECMODE=”) in new stack
2443 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:3] Return(“SIP/235-0000000d”, “”) in new stack
2450 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:17] Return(“SIP/235-0000000d”, “”) in new stack
2451 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“SIP/235-0000000d”, “DEXTEN=236”) in new stack
2453 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:4] Set(“SIP/235-0000000d”, “DIALSTATUS_CW=”) in new stack
2456 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:10] GotoIf(“SIP/235-0000000d”, “0?nodial”) in new stack
2461 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:11] GotoIf(“SIP/235-0000000d”, “0?continue”) in new stack
2462 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:12] Set(“SIP/235-0000000d”, “EXTHASCW=”) in new stack
2463 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:27] GotoIf(“SIP/235-0000000d”, “0?nodial”) in new stack
2472 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“SIP/235-0000000d”, “DSTRING=”) in new stack
2474 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:2] Set(“SIP/235-0000000d”, “DEVICES=”) in new stack
2475 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/235-0000000d”, “1?Return()”) in new stack
2476 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:61] NoOp(“SIP/235-0000000d”, “”) in new stack
2479 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:64] MacroExit(“SIP/235-0000000d”, “”) in new stack
2482 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:23] Set(“SIP/235-0000000d”, “DIALSTATUS=NOANSWER”) in new stack
2486 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:24] ExecIf(“SIP/235-0000000d”, “0?MacroExit()”) in new stack
2487 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“SIP/235-0000000d”, “AMPUSER=235”) in new stack
2492 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:4] Set(“SIP/235-0000000d”, “HOTDESKEXTEN=235”) in new stack
2494 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:5] Set(“SIP/235-0000000d”, “HOTDESKCALL=0”) in new stack
2495 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:8] GotoIf(“SIP/235-0000000d”, “0?report”) in new stack
2498 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:10] Set(“SIP/235-0000000d”, “AMPUSER=”) in new stack
2500 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:11] GotoIf(“SIP/235-0000000d”, “0?limit”) in new stack
2501 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:12] Set(“SIP/235-0000000d”, “AMPUSERCIDNAME=”) in new stack
2502 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:46] GotoIf(“SIP/235-0000000d”, “0?cnum”) in new stack
2513 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“SIP/235-0000000d”, “VMGAIN=”) in new stack
2517 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:3] Macro(“SIP/235-0000000d”, “blkvm-check,”) in new stack
2518 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:1] Set(“SIP/235-0000000d”, “GOSUB_RETVAL=”) in new stack
2519 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:3] MacroExit(“SIP/235-0000000d”, “”) in new stack
2521 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“SIP/235-0000000d”, “__EXTTOCALL=236”) in new stack
2524 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:3] Set(“SIP/235-0000000d”, “MEXTEN=236”) in new stack
2526 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:4] Set(“SIP/235-0000000d”, “MMODE=NOANSWER”) in new stack
2527 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:5] Set(“SIP/235-0000000d”, “RETVM=”) in new stack
2528 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:6] Set(“SIP/235-0000000d”, “MODE=unavail”) in new stack
2529 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“SIP/235-0000000d”, “VMCONTEXT=default”) in new stack
2531 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:300] NoOp(“SIP/235-0000000d”, “”) in new stack
2534 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:8] Set(“SIP/235-0000000d”, “MODE=unavail”) in new stack
2535 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] Set(“SIP/235-0000000d”, “VMCONTEXT=default”) in new stack
2546 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]:300] NoOp(“SIP/235-0000000d”, “”) in new stack
2549 [2020-10-21 16:53:52] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]:2] VoiceMail(“SIP/235-0000000d”, “[email protected],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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] Macro(“SIP/235-0000000d”, “hangupcall,”) in new stack
2562 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:4] NoOp(“SIP/235-0000000d”, " montior file= ") in new stack
2566 [2020-10-21 16:53:55] VERBOSE[19012][C-0000000b] pbx.c: Executing [[email protected]: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 [[email protected]: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]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]
[email protected][email protected]

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 [[email protected]:1] Set(“SIP/235-00000000”, “__RINGTIMER=15”) in new stack
4854 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:1] Macro(“SIP/235-00000000”, “user-callerid,”) in new stack
4859 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:2] Set(“SIP/235-00000000”, “AMPUSER=235”) in new stack
4861 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:4] Set(“SIP/235-00000000”, “HOTDESKEXTEN=235”) in new stack
4863 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:5] Set(“SIP/235-00000000”, “HOTDESKCALL=0”) in new stack
4864 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:8] GotoIf(“SIP/235-00000000”, “0?report”) in new stack
4867 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:10] Set(“SIP/235-00000000”, “AMPUSER=”) in new stack
4869 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:11] GotoIf(“SIP/235-00000000”, “0?limit”) in new stack
4870 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:12] Set(“SIP/235-00000000”, “AMPUSERCIDNAME=”) in new stack
4871 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:25] GotoIf(“SIP/235-00000000”, “0?continue”) in new stack
4878 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:27] Set(“SIP/235-00000000”, “__TTL=64”) in new stack
4880 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:46] GotoIf(“SIP/235-00000000”, “0?cnum”) in new stack
4885 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“SIP/235-00000000”, “RingGroupMethod=none”) in new stack
4889 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:3] Set(“SIP/235-00000000”, “__EXTTOCALL=236”) in new stack
4890 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:4] Set(“SIP/235-00000000”, “__PICKUPMARK=236”) in new stack
4891 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:5] Set(“SIP/235-00000000”, “RT=15”) in new stack
4892 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:7] ExecIf(“SIP/235-00000000”, “0?MacroExit()”) in new stack
4894 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:9] ExecIf(“SIP/235-00000000”, “0?MacroExit()”) in new stack
4896 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] GotoIf(“SIP/235-00000000”, “0?initialized”) in new stack
4918 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:3] Set(“SIP/235-00000000”, “NOW=1603966387”) in new stack
4920 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:4] Set(“SIP/235-00000000”, “__DAY=29”) in new stack
4921 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:5] Set(“SIP/235-00000000”, “__MONTH=10”) in new stack
4922 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:6] Set(“SIP/235-00000000”, “__YEAR=2020”) in new stack
4923 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:8] Set(“SIP/235-00000000”, “__FROMEXTEN=235”) in new stack
4925 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:10] NoOp(“SIP/235-00000000”, “Recordings initialized”) in new stack
4927 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“SIP/235-00000000”, “CALLTYPE=internal”) in new stack
4936 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:4] Set(“SIP/235-00000000”, “CALLEE=”) in new stack
4938 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:6] GotoIf(“SIP/235-00000000”, “0?callee”) in new stack
4940 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:13] Set(“SIP/235-00000000”, “RECMODE=”) in new stack
4943 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:3] Return(“SIP/235-00000000”, “”) in new stack
4950 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:17] Return(“SIP/235-00000000”, “”) in new stack
4951 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“SIP/235-00000000”, “DEXTEN=236”) in new stack
4953 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:4] Set(“SIP/235-00000000”, “DIALSTATUS_CW=”) in new stack
4956 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:10] GotoIf(“SIP/235-00000000”, “0?nodial”) in new stack
4961 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:11] GotoIf(“SIP/235-00000000”, “0?continue”) in new stack
4962 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:12] Set(“SIP/235-00000000”, “EXTHASCW=”) in new stack
4963 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:27] GotoIf(“SIP/235-00000000”, “0?nodial”) in new stack
4972 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“SIP/235-00000000”, “DSTRING=”) in new stack
4974 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:2] Set(“SIP/235-00000000”, “DEVICES=”) in new stack
4975 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/235-00000000”, “1?Return()”) in new stack
4976 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:61] NoOp(“SIP/235-00000000”, “”) in new stack
4979 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:64] MacroExit(“SIP/235-00000000”, “”) in new stack
4982 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:23] Set(“SIP/235-00000000”, “DIALSTATUS=NOANSWER”) in new stack
4986 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:24] ExecIf(“SIP/235-00000000”, “0?MacroExit()”) in new stack
4987 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“SIP/235-00000000”, “AMPUSER=235”) in new stack
4992 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:4] Set(“SIP/235-00000000”, “HOTDESKEXTEN=235”) in new stack
4994 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:5] Set(“SIP/235-00000000”, “HOTDESKCALL=0”) in new stack
4995 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:8] GotoIf(“SIP/235-00000000”, “0?report”) in new stack
4998 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:10] Set(“SIP/235-00000000”, “AMPUSER=”) in new stack
5000 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:11] GotoIf(“SIP/235-00000000”, “0?limit”) in new stack
5001 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:12] Set(“SIP/235-00000000”, “AMPUSERCIDNAME=”) in new stack
5002 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:46] GotoIf(“SIP/235-00000000”, “0?cnum”) in new stack
5013 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:2] Set(“SIP/235-00000000”, “VMGAIN=”) in new stack
5017 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:3] Macro(“SIP/235-00000000”, “blkvm-check,”) in new stack
5018 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:1] Set(“SIP/235-00000000”, “GOSUB_RETVAL=”) in new stack
5019 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:3] MacroExit(“SIP/235-00000000”, “”) in new stack
5021 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“SIP/235-00000000”, “__EXTTOCALL=236”) in new stack
5024 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:3] Set(“SIP/235-00000000”, “MEXTEN=236”) in new stack
5026 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:4] Set(“SIP/235-00000000”, “MMODE=NOANSWER”) in new stack
5027 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:5] Set(“SIP/235-00000000”, “RETVM=”) in new stack
5028 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:6] Set(“SIP/235-00000000”, “MODE=unavail”) in new stack
5029 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:1] Set(“SIP/235-00000000”, “VMCONTEXT=default”) in new stack
5031 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]:300] NoOp(“SIP/235-00000000”, “”) in new stack
5034 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:8] Set(“SIP/235-00000000”, “MODE=unavail”) in new stack
5035 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] Set(“SIP/235-00000000”, “VMCONTEXT=default”) in new stack
5046 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]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 [[email protected]:300] NoOp(“SIP/235-00000000”, “”) in new stack
5049 [2020-10-29 10:13:07] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]:2] VoiceMail(“SIP/235-00000000”, “[email protected],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 [[email protected]:1] Macro(“SIP/235-00000000”, “hangupcall,”) in new stack
5060 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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 [[email protected]:4] NoOp(“SIP/235-00000000”, " montior file= ") in new stack
5064 [2020-10-29 10:13:18] VERBOSE[3653][C-00000001] pbx.c: Executing [[email protected]: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 [[email protected]: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.