Call not reached portSIP app


(robin1196) #1

Hi,

Hope anybody can help me.
I have an IOS app that called portSIP behind extension 4000.
But the call never reached the app.
You get the message “this number is not in used”.
The logfile below shows up.

4513 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/incoming-00000caa”, “0?initialized”) in new stack
4514 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/incoming-00000caa”, “__REC_STATUS=INITIALIZED”) in new stack
4515 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/incoming-00000caa”, “NOW=1613117476”) in new stack
4516 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/incoming-00000caa”, “__DAY=12”) in new stack
4517 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/incoming-00000caa”, “__MONTH=02”) in new stack
4518 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/incoming-00000caa”, “__YEAR=2021”) in new stack
4519 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/incoming-00000caa”, “__TIMESTR=20210212-091116”) in new stack
4520 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/incoming-00000caa”, “__FROMEXTEN=unknown”) in new stack
4521 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/incoming-00000caa”, “__MON_FMT=wav”) in new stack
4522 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/incoming-00000caa”, “Recordings initialized”) in new stack
4523 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/incoming-00000caa”, “0?Set(ARG3=dontcare)”) in new stack
4524 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/incoming-00000caa”, “REC_POLICY_MODE_SAVE=”) in new stack
4525 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/incoming-00000caa”, “0?Set(REC_STATUS=NO)”) in new stack
4526 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/incoming-00000caa”, “2?checkaction”) in new stack
4527 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (sub-record-check,s,17)
4528 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/incoming-00000caa”, “1?sub-record-check,in,1”) in new stack
4529 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (sub-record-check,in,1)
4530 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [in@sub-record-check:1] NoOp(“SIP/incoming-00000caa”, “Inbound Recording Check to +3185XXXXXXX”) in new stack
4531 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [in@sub-record-check:2] Set(“SIP/incoming-00000caa”, “FROMEXTEN=unknown”) in new stack
4532 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [in@sub-record-check:3] ExecIf(“SIP/incoming-00000caa”, “12?Set(FROMEXTEN=+316XXXXXXXX)”) in new stack
4533 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [in@sub-record-check:4] Gosub(“SIP/incoming-00000caa”, “recordcheck,1(dontcare,in,+3185XXXXXXX)”) in new stack
4534 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/incoming-00000caa”, “Starting recording check against dontcare”) in new stack
4535 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/incoming-00000caa”, “dontcare”) in new stack
4536 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
4537 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/incoming-00000caa”, “”) in new stack
4538 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [in@sub-record-check:5] Return(“SIP/incoming-00000caa”, “”) in new stack
4539 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:3] Set(“SIP/incoming-00000caa”, “CHANNEL(tonezone)=us”) in new stack
4540 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:4] Set(“SIP/incoming-00000caa”, “__FROM_DID=+3185XXXXXXX”) in new stack
4541 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:5] Set(“SIP/incoming-00000caa”, “returnhere=1”) in new stack
4542 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:6] Gosub(“SIP/incoming-00000caa”, “app-blacklist-check,s,1()”) in new stack
4543 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@app-blacklist-check:1] GotoIf(“SIP/incoming-00000caa”, “0?blacklisted”) in new stack
4544 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@app-blacklist-check:2] Set(“SIP/incoming-00000caa”, “CALLED_BLACKLIST=1”) in new stack
4545 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@app-blacklist-check:3] Return(“SIP/incoming-00000caa”, “”) in new stack
4546 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:7] Set(“SIP/incoming-00000caa”, “CDR(did)=+3185XXXXXXX”) in new stack
4547 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:8] GotoIf(“SIP/incoming-00000caa”, “0?”) in new stack
4548 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:9] ExecIf(“SIP/incoming-00000caa”, “1 ?Set(CALLERID(name)=+316XXXXXXXX)”) in new stack
4549 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:10] Set(“SIP/incoming-00000caa”, “__MOHCLASS=”) in new stack
4550 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:11] Set(“SIP/incoming-00000caa”, “__REVERSAL_REJECT=FALSE”) in new stack
4551 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:12] GotoIf(“SIP/incoming-00000caa”, “1?post-reverse-charge”) in new stack
4552 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (from-trunk,+3185XXXXXXX,14)
4553 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:14] NoOp(“SIP/incoming-00000caa”, “”) in new stack
4554 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:15] Set(“SIP/incoming-00000caa”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
4555 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:16] Set(“SIP/incoming-00000caa”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
4556 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:17] Set(“SIP/incoming-00000caa”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
4557 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:18] Set(“SIP/incoming-00000caa”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
4558 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:19] NoOp(“SIP/incoming-00000caa”, “CallerID Entry Point”) in new stack
4559 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:20] Set(“SIP/incoming-00000caa”, “__CRM_DIRECTION=INBOUND”) in new stack
4560 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:21] Set(“SIP/incoming-00000caa”, “__CRM_SOURCE=+316XXXXXXXX”) in new stack
4561 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:22] Set(“SIP/incoming-00000caa”, “__CRM_LINKEDID=1613117476.5078”) in new stack
4562 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:23] AGI(“SIP/incoming-00000caa”, “agi://127.0.0.1/sangomacrm.agi,true”) in new stack
4563 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] res_agi.c: <SIP/incoming-00000caa>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
4564 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:24] ExecIf(“SIP/incoming-00000caa”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
4565 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [+3185XXXXXXX@from-trunk:25] Goto(“SIP/incoming-00000caa”, “from-did-direct,4000,1”) in new stack
4566 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (from-did-direct,4000,1)
4567 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [4000@from-did-direct:1] GotoIf(“SIP/incoming-00000caa”, “1?ext-local,4000,1:followme-check,4000,1”) in new stack
4568 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (ext-local,4000,1)
4569 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [4000@ext-local:1] Set(“SIP/incoming-00000caa”, “__RINGTIMER=15”) in new stack
4570 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [4000@ext-local:2] ExecIf(“SIP/incoming-00000caa”, “0?Set(__CWIGNORE=)”) in new stack
4571 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [4000@ext-local:3] Macro(“SIP/incoming-00000caa”, “exten-vm,novm,4000,0,0,0”) in new stack
4572 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:1] Macro(“SIP/incoming-00000caa”, “user-callerid,”) in new stack
4573 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/incoming-00000caa”, “TOUCH_MONITOR=1613117476.5078”) in new stack
4574 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/incoming-00000caa”, “AMPUSER=+316XXXXXXXX”) in new stack
4575 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:3] Set(“SIP/incoming-00000caa”, “HOTDESCKCHAN=incoming-00000caa”) in new stack
4576 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:4] Set(“SIP/incoming-00000caa”, “HOTDESKEXTEN=incoming”) in new stack
4577 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/incoming-00000caa”, “HOTDESKCALL=0”) in new stack
4578 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:6] ExecIf(“SIP/incoming-00000caa”, “0?Set(HOTDESKCALL=1)”) in new stack
4579 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:7] ExecIf(“SIP/incoming-00000caa”, “0?Set(CALLERID(name)=)”) in new stack
4580 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/incoming-00000caa”, “0?report”) in new stack
4581 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:9] ExecIf(“SIP/incoming-00000caa”, “1?Set(REALCALLERIDNUM=+316XXXXXXXX)”) in new stack
4582 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/incoming-00000caa”, “AMPUSER=”) in new stack
4583 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:11] GotoIf(“SIP/incoming-00000caa”, “0?limit”) in new stack
4584 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:12] Set(“SIP/incoming-00000caa”, “AMPUSERCIDNAME=”) in new stack
4585 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/incoming-00000caa”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
4586 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“SIP/incoming-00000caa”, “1?report”) in new stack
4587 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (macro-user-callerid,s,23)
4588 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:23] NoOp(“SIP/incoming-00000caa”, “Macro Depth is 2”) in new stack
4589 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:24] GotoIf(“SIP/incoming-00000caa”, “1?report2:macroerror”) in new stack
4590 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (macro-user-callerid,s,25)
4591 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:25] GotoIf(“SIP/incoming-00000caa”, “0?continue”) in new stack
4592 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:26] ExecIf(“SIP/incoming-00000caa”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
4593 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:27] Set(“SIP/incoming-00000caa”, “__TTL=64”) in new stack
4594 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:28] GotoIf(“SIP/incoming-00000caa”, “1?continue”) in new stack
4595 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (macro-user-callerid,s,44)
4596 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:44] Set(“SIP/incoming-00000caa”, “CALLERID(number)=+316XXXXXXXX”) in new stack
4597 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:45] Set(“SIP/incoming-00000caa”, “CALLERID(name)=+316XXXXXXXX”) in new stack
4598 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:46] GotoIf(“SIP/incoming-00000caa”, “0?cnum”) in new stack
4599 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:47] Set(“SIP/incoming-00000caa”, “CDR(cnam)=+316XXXXXXXX”) in new stack
4600 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:48] Set(“SIP/incoming-00000caa”, “CDR(cnum)=+316XXXXXXXX”) in new stack
4601 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-user-callerid:49] Set(“SIP/incoming-00000caa”, “CHANNEL(language)=nl”) in new stack
4602 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:2] Set(“SIP/incoming-00000caa”, “RingGroupMethod=none”) in new stack
4603 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:3] Set(“SIP/incoming-00000caa”, “__EXTTOCALL=4000”) in new stack
4604 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:4] Set(“SIP/incoming-00000caa”, “__PICKUPMARK=4000”) in new stack
4605 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:5] Set(“SIP/incoming-00000caa”, “RT=”) in new stack
4606 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:6] ExecIf(“SIP/incoming-00000caa”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
4607 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:7] ExecIf(“SIP/incoming-00000caa”, “0?MacroExit()”) in new stack
4608 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:8] ExecIf(“SIP/incoming-00000caa”, “0?Gosub(ext-intercom,*804000,1())”) in new stack
4609 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:9] ExecIf(“SIP/incoming-00000caa”, “0?MacroExit()”) in new stack
4610 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:10] ExecIf(“SIP/incoming-00000caa”, “0?ChanSpy(SIP/4000,q)”) in new stack
4611 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:11] ExecIf(“SIP/incoming-00000caa”, “0?MacroExit()”) in new stack
4612 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4613 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:12] ExecIf(“SIP/incoming-00000caa”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
4614 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4615 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4616 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:13] ExecIf(“SIP/incoming-00000caa”, “0?MacroExit()”) in new stack
4617 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4618 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4619 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:14] ExecIf(“SIP/incoming-00000caa”, “0?Gosub(ext-intercom,*804000,1())”) in new stack
4620 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4621 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4622 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:15] ExecIf(“SIP/incoming-00000caa”, “0?MacroExit()”) in new stack
4623 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4624 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4625 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:16] ExecIf(“SIP/incoming-00000caa”, “0?ChanSpy(SIP/4000,q)”) in new stack
4626 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4627 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4628 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:17] ExecIf(“SIP/incoming-00000caa”, “0?MacroExit()”) in new stack
4629 [2021-02-12 09:11:16] ERROR[16564][C-000031c1] pbx_functions.c: Function PJSIP_HEADER not registered
4630 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:18] Gosub(“SIP/incoming-00000caa”, “sub-record-check,s,1(exten,4000,dontcare)”) in new stack
4631 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/incoming-00000caa”, “12?initialized”) in new stack
4632 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (sub-record-check,s,10)
4633 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/incoming-00000caa”, “Recordings initialized”) in new stack
4634 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/incoming-00000caa”, “0?Set(ARG3=dontcare)”) in new stack
4635 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/incoming-00000caa”, “REC_POLICY_MODE_SAVE=”) in new stack
4636 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/incoming-00000caa”, “0?Set(REC_STATUS=NO)”) in new stack
4637 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/incoming-00000caa”, “5?checkaction”) in new stack
4638 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (sub-record-check,s,17)
4639 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/incoming-00000caa”, “1?sub-record-check,exten,1”) in new stack
4640 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (sub-record-check,exten,1)
4641 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [exten@sub-record-check:1] NoOp(“SIP/incoming-00000caa”, “Exten Recording Check between +316XXXXXXXX and 4000”) in new stack
4642 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [exten@sub-record-check:2] Set(“SIP/incoming-00000caa”, “CALLTYPE=external”) in new stack
4643 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“SIP/incoming-00000caa”, “0?Set(CALLTYPE=)”) in new stack
4644 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [exten@sub-record-check:4] Set(“SIP/incoming-00000caa”, “CALLEE=dontcare”) in new stack
4645 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“SIP/incoming-00000caa”, “0?Set(CALLEE=dontcare)”) in new stack
4646 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“SIP/incoming-00000caa”, “1?callee”) in new stack
4647 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (sub-record-check,exten,11)
4648 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [exten@sub-record-check:11] Gosub(“SIP/incoming-00000caa”, “recordcheck,1(dontcare,external,4000)”) in new stack
4649 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/incoming-00000caa”, “Starting recording check against dontcare”) in new stack
4650 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/incoming-00000caa”, “dontcare”) in new stack
4651 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
4652 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/incoming-00000caa”, “”) in new stack
4653 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [exten@sub-record-check:12] Return(“SIP/incoming-00000caa”, “”) in new stack
4654 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:19] GotoIf(“SIP/incoming-00000caa”, “1?macrodial”) in new stack
4655 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (macro-exten-vm,s,25)
4656 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:25] GosubIf(“SIP/incoming-00000caa”, “0?clrheader,1()”) in new stack
4657 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-exten-vm:26] Macro(“SIP/incoming-00000caa”, “dial-one,HhTtr,4000”) in new stack
4658 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:1] Set(“SIP/incoming-00000caa”, “DEXTEN=4000”) in new stack
4659 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:2] Set(“SIP/incoming-00000caa”, “__CRM_SOURCE=+316XXXXXXXX”) in new stack
4660 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:3] ExecIf(“SIP/incoming-00000caa”, “0?Set(__EXTTOCALL=4000)”) in new stack
4661 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:4] Set(“SIP/incoming-00000caa”, “DIALSTATUS_CW=”) in new stack
4662 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:5] GosubIf(“SIP/incoming-00000caa”, “0?screen,1()”) in new stack
4663 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:6] GosubIf(“SIP/incoming-00000caa”, “0?cf,1()”) in new stack
4664 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:7] GotoIf(“SIP/incoming-00000caa”, “1?skip1”) in new stack
4665 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (macro-dial-one,s,10)
4666 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:10] GotoIf(“SIP/incoming-00000caa”, “0?nodial”) in new stack
4667 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:11] GotoIf(“SIP/incoming-00000caa”, “0?continue”) in new stack
4668 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:12] Set(“SIP/incoming-00000caa”, “EXTHASCW=ENABLED”) in new stack
4669 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:13] GotoIf(“SIP/incoming-00000caa”, “0?next1:cwinusebusy”) in new stack
4670 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (macro-dial-one,s,25)
4671 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:25] GotoIf(“SIP/incoming-00000caa”, “0?next3:continue”) in new stack
4672 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (macro-dial-one,s,27)
4673 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:27] GotoIf(“SIP/incoming-00000caa”, “0?nodial”) in new stack
4674 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:28] GosubIf(“SIP/incoming-00000caa”, “1?dstring,1():dlocal,1()”) in new stack
4675 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:1] Set(“SIP/incoming-00000caa”, “DSTRING=”) in new stack
4676 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:2] Set(“SIP/incoming-00000caa”, “DEVICES=4000”) in new stack
4677 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“SIP/incoming-00000caa”, “0?Return()”) in new stack
4678 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“SIP/incoming-00000caa”, “0?Set(DEVICES=000)”) in new stack
4679 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:5] Set(“SIP/incoming-00000caa”, “LOOPCNT=1”) in new stack
4680 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:6] Set(“SIP/incoming-00000caa”, “ITER=1”) in new stack
4681 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:7] Set(“SIP/incoming-00000caa”, “THISDIAL=SIP/4000”) in new stack
4682 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf(“SIP/incoming-00000caa”, “1?docheck”) in new stack
4683 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx_builtins.c: Goto (macro-dial-one,dstring,14)
4684 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf(“SIP/incoming-00000caa”, “0?skipset”) in new stack
4685 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:15] Set(“SIP/incoming-00000caa”, “DSTRING=SIP/4000&”) in new stack
4686 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:16] Set(“SIP/incoming-00000caa”, “ITER=2”) in new stack
4687 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf(“SIP/incoming-00000caa”, “0?begin”) in new stack
4688 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf(“SIP/incoming-00000caa”, “0?Return()”) in new stack
4689 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:19] Set(“SIP/incoming-00000caa”, “DSTRING=SIP/4000”) in new stack
4690 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [dstring@macro-dial-one:20] Return(“SIP/incoming-00000caa”, “”) in new stack
4691 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:29] GotoIf(“SIP/incoming-00000caa”, “0?nodial”) in new stack
4692 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:30] GotoIf(“SIP/incoming-00000caa”, “0?skiptrace”) in new stack
4693 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:31] GosubIf(“SIP/incoming-00000caa”, “1?ctset,1():ctclear,1()”) in new stack
4694 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [ctset@macro-dial-one:1] Set(“SIP/incoming-00000caa”, “DB(CALLTRACE/4000)=+316XXXXXXXX”) in new stack
4695 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [ctset@macro-dial-one:2] Return(“SIP/incoming-00000caa”, “”) in new stack
4696 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:32] Set(“SIP/incoming-00000caa”, “D_OPTIONS=HhTtr”) in new stack
4697 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:33] GosubIf(“SIP/incoming-00000caa”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
4698 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:34] NoOp(“SIP/incoming-00000caa”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
4699 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:35] ExecIf(“SIP/incoming-00000caa”, “0?Set(ALERT_INFO=)”) in new stack
4700 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:36] ExecIf(“SIP/incoming-00000caa”, “0?Set(ALERT_INFO=)”) in new stack
4701 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:37] ExecIf(“SIP/incoming-00000caa”, “0?Set(ALERT_INFO=)”) in new stack
4702 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:38] ExecIf(“SIP/incoming-00000caa”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
4703 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:39] ExecIf(“SIP/incoming-00000caa”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
4704 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-one:40] GosubIf(“SIP/incoming-00000caa”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
4705 [2021-02-12 09:11:16] VERBOSE[16564][C-000031c1] pbx.c: Executing [s@macro-dial-