Pjsip stuck 'in use' after paging

Deployment w/ latest ISO & all patches…Setup Paging Pro
I have 6 endpoints that the paging group will ‘ring’ for the bell schedule but the endpoints are stuck ‘In Use’ until I force hangup. Anyone else have issues like this?
If I just call an endpoint it doesn’t hang when I just intercom to it.

I’d suggest posting a PJSIP DEBUG and perhaps a /var/log/asterisk/full extract showing what happens when you call. That might give us some clues.

Also showing how you set it up would be helpful. I’ve randomly had a channel hang to an extensions. paging adapter and or phone in the past. but rare.

There are a few recent changes to paging, I would recommend updating to edge:

fwconsole ma upgrade paging pagingpro --edge

Thanks for the suggestion on edge. Might try that tonight. The setup is an HP micropc running freepbx and 6 UAP-AC-EDU registered with extensions 101-106. Paging groups setup with Pro and scheduled on 4 different schedules ( different school needs )…
Bell rings and when done the endpoints all stuck ‘in use’ …
Network is flat … One vlan across these devices
Hope this helps paint a better picture

I’ll do some debug tonight and post back. Mobile right now

Try manually buzzing one to make sure that the general concept works?

asterisk -x "channel originate local/101@from-internal application playback en/custom/yoursoundfilename"

If that works do the same but use the page group extension.

Do these work well for this? I’ve been thinking about some for a client.

1 Like

Not sure yet…hope to find out when I’m done troubleshooting this issue :slight_smile:

Testing tonite…

Status of extensions…
Endpoint: 101/101 Not in use 0 of inf
InAuth: 101-auth/101
Aor: 101 1
Contact: 101/sip:[email protected]:5060 3d2e2250ce Avail 2.232

Endpoint: 102/102 Not in use 0 of inf
InAuth: 102-auth/102
Aor: 102 1
Contact: 102/sip:[email protected]:5060 975279df2b Avail 2.066

Endpoint: 103/103 Not in use 0 of inf
InAuth: 103-auth/103
Aor: 103 1
Contact: 103/sip:[email protected]:5060 4148470de2 Avail 2.091

Endpoint: 104/104 Not in use 0 of inf
InAuth: 104-auth/104
Aor: 104 1
Contact: 104/sip:[email protected]:5060 9bae8cf55a Avail 2.032

Endpoint: 105/105 Not in use 0 of inf
InAuth: 105-auth/105
Aor: 105 1
Contact: 105/sip:[email protected]:5060 fde3481249 Avail 2.182

Endpoint: 106/106 Not in use 0 of inf
InAuth: 106-auth/106
Aor: 106 1
Contact: 106/sip:[email protected]:5060 e8995566f2 Avail 2.142

…ringing 8:20 test bell…

now all extensions show ‘in use’

Here’s paste from /var/log/asterisk/full

[2019-02-20 20:18:24] VERBOSE[2225] asterisk.c: Remote UNIX connection
[2019-02-20 20:20:01] VERBOSE[16931] pbx_spool.c: Attempting call on Local/1000@from-internal for application Playback(silence/1) (Retry 1)
[2019-02-20 20:20:01] VERBOSE[16931] dial.c: Called 1000@from-internal
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@from-internal:1] Goto(“Local/1000@from-internal-00000063;2”, “app-pagegroups,1000,1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx_builtins.c: Goto (app-pagegroups,1000,1)
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:1] Macro(“Local/1000@from-internal-00000063;2”, “user-callerid,”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:1] Set(“Local/1000@from-internal-00000063;2”, “TOUCH_MONITOR=1550715601.255”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:2] Set(“Local/1000@from-internal-00000063;2”, “AMPUSER=1000”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“Local/1000@from-internal-00000063;2”, “0?report”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“Local/1000@from-internal-00000063;2”, “1?Set(REALCALLERIDNUM=1000)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:5] Set(“Local/1000@from-internal-00000063;2”, “AMPUSER=”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“Local/1000@from-internal-00000063;2”, “0?limit”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:7] Set(“Local/1000@from-internal-00000063;2”, “AMPUSERCIDNAME=”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“Local/1000@from-internal-00000063;2”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“Local/1000@from-internal-00000063;2”, “1?report”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“Local/1000@from-internal-00000063;2”, “Macro Depth is 1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“Local/1000@from-internal-00000063;2”, “1?report2:macroerror”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“Local/1000@from-internal-00000063;2”, “0?continue”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(“Local/1000@from-internal-00000063;2”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:20] Set(“Local/1000@from-internal-00000063;2”, “__TTL=64”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:21] GotoIf(“Local/1000@from-internal-00000063;2”, “1?continue”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:37] Set(“Local/1000@from-internal-00000063;2”, “CALLERID(number)=1000”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:38] Set(“Local/1000@from-internal-00000063;2”, “CALLERID(name)=test”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“Local/1000@from-internal-00000063;2”, “0?cnum”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:40] Set(“Local/1000@from-internal-00000063;2”, “CDR(cnam)=test”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:41] Set(“Local/1000@from-internal-00000063;2”, “CDR(cnum)=1000”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [s@macro-user-callerid:42] Set(“Local/1000@from-internal-00000063;2”, “CHANNEL(language)=en”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:2] Set(“Local/1000@from-internal-00000063;2”, “_PAGEGROUP=1000”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:3] Set(“Local/1000@from-internal-00000063;2”, “ANNOUNCE_COUNT=1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:4] GotoIf(“Local/1000@from-internal-00000063;2”, “1?:busy”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:5] Set(“Local/1000@from-internal-00000063;2”, “DEVICE_STATE(Custom:PAGE1000)=INUSE”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:6] Gosub(“Local/1000@from-internal-00000063;2”, “app-paging,ssetup,1()”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [ssetup@app-paging:1] Set(“Local/1000@from-internal-00000063;2”, “_SIPURI=”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [ssetup@app-paging:2] Set(“Local/1000@from-internal-00000063;2”, “_ALERTINFO=Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [ssetup@app-paging:3] Set(“Local/1000@from-internal-00000063;2”, “_CALLINFO=;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [ssetup@app-paging:4] Set(“Local/1000@from-internal-00000063;2”, “_SIPURI=intercom=true”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [ssetup@app-paging:5] Set(“Local/1000@from-internal-00000063;2”, “_DTIME=5”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [ssetup@app-paging:6] Set(“Local/1000@from-internal-00000063;2”, “_ANSWERMACRO=”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [ssetup@app-paging:7] Set(“Local/1000@from-internal-00000063;2”, “PAGE_CONF=1550715601282”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [ssetup@app-paging:8] Return(“Local/1000@from-internal-00000063;2”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:7] Set(“Local/1000@from-internal-00000063;2”, “PAGEMODE=FPAGE”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:8] Set(“Local/1000@from-internal-00000063;2”, “PAGE_MEMBERS=101-102-103-104-105-106”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:9] Set(“Local/1000@from-internal-00000063;2”, “PAGE_CONF_OPTS=”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:10] Set(“Local/1000@from-internal-00000063;2”, “ANNOUNCEMENT=custom/school-bell-fade”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:11] Set(“Local/1000@from-internal-00000063;2”, “STREAM=NONE”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:12] AGI(“Local/1000@from-internal-00000063;2”, “page.agi”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/page.agi
[2019-02-20 20:20:01] VERBOSE[16969] dial.c: Called s@app-page-stream
[2019-02-20 20:20:01] VERBOSE[16976] dial.c: Called PAGE102@app-paging/n
[2019-02-20 20:20:01] VERBOSE[16974] dial.c: Called PAGE103@app-paging/n
[2019-02-20 20:20:01] VERBOSE[16983] dial.c: Called PAGE104@app-paging/n
[2019-02-20 20:20:01] VERBOSE[16975][C-00000050] pbx.c: Executing [s@app-page-stream:1] Wait(“Local/s@app-page-stream-00000064;2”, “1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] res_agi.c: <Local/1000@from-internal-00000063;2>AGI Script page.agi completed, returning 0
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [PAGE102@app-paging:1] Macro(“Local/PAGE102@app-paging-00000066;2”, “autoanswer,102”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:1] GotoIf(“Local/PAGE102@app-paging-00000066;2”, “1?knowndial”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:19] Set(“Local/PAGE102@app-paging-00000066;2”, “DIAL=PJSIP/102/sip:[email protected]:5060”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:20] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “0?Set(DIAL=DAHDIIP/102/sip:[email protected]:5060)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:21] GotoIf(“Local/PAGE102@app-paging-00000066;2”, “0?macro”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:22] GotoIf(“Local/PAGE102@app-paging-00000066;2”, “1?pjsipua”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx_builtins.c: Goto (macro-autoanswer,s,25)
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:25] Set(“Local/PAGE102@app-paging-00000066;2”, “AOR=102”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:26] Set(“Local/PAGE102@app-paging-00000066;2”, “CONTACT=102;@975279df2b8e212f8087d7f157937e9e”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:27] Set(“Local/PAGE102@app-paging-00000066;2”, “USERAGENT=baresip v0.4.19 (mips/linux)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:28] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “1?Set(USERAGENT=baresip v0.4.19 (mips/linux))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:29] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “0?Set(PAGE_VOL=;volume=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:30] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “0?Set(ALERTINFO=ring-answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:31] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “0?Set(CALLINFO=sip:broadworks.net;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:32] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “0?Set(ALERTINFO=http://example.com;info=alert-autoanswer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:33] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “0?Set(ALERTINFO=Intercom)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:34] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “0?Set(ALERTINFO=info=Auto Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:35] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “0?Set(ALERTINFO=http://www.sangoma.com;info=external)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@macro-autoanswer:36] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “1?Set(__SIP_URI_OPTIONS=intercom=true)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [PAGE102@app-paging:2] NoOp(“Local/PAGE102@app-paging-00000066;2”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [PAGE102@app-paging:3] GotoIf(“Local/PAGE102@app-paging-00000066;2”, “1?doptions”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx_builtins.c: Goto (app-paging,PAGE102,6)
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [PAGE102@app-paging:6] ExecIf(“Local/PAGE102@app-paging-00000066;2”, “1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,;answer-after=0)))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [PAGE102@app-paging:7] Dial(“Local/PAGE102@app-paging-00000066;2”, “PJSIP/102/sip:[email protected]:5060,5,A(custom/school-bell-fade)b(autoanswer^s^1(Ring Answer,;answer-after=0))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [PAGE103@app-paging:1] Macro(“Local/PAGE103@app-paging-00000065;2”, “autoanswer,103”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:1] GotoIf(“Local/PAGE103@app-paging-00000065;2”, “1?knowndial”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:19] Set(“Local/PAGE103@app-paging-00000065;2”, “DIAL=PJSIP/103/sip:[email protected]:5060”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:20] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “0?Set(DIAL=DAHDIIP/103/sip:[email protected]:5060)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:21] GotoIf(“Local/PAGE103@app-paging-00000065;2”, “0?macro”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:22] GotoIf(“Local/PAGE103@app-paging-00000065;2”, “1?pjsipua”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx_builtins.c: Goto (macro-autoanswer,s,25)
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:25] Set(“Local/PAGE103@app-paging-00000065;2”, “AOR=103”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:26] Set(“Local/PAGE103@app-paging-00000065;2”, “CONTACT=103;@4148470de26fa86a0aceb27a3856745b”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:27] Set(“Local/PAGE103@app-paging-00000065;2”, “USERAGENT=baresip v0.4.19 (mips/linux)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:28] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “1?Set(USERAGENT=baresip v0.4.19 (mips/linux))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:29] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “0?Set(PAGE_VOL=;volume=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:30] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “0?Set(ALERTINFO=ring-answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:31] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “0?Set(CALLINFO=sip:broadworks.net;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:32] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “0?Set(ALERTINFO=http://example.com;info=alert-autoanswer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:33] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “0?Set(ALERTINFO=Intercom)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:34] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “0?Set(ALERTINFO=info=Auto Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:35] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “0?Set(ALERTINFO=http://www.sangoma.com;info=external)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@macro-autoanswer:36] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “1?Set(__SIP_URI_OPTIONS=intercom=true)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [PAGE103@app-paging:2] NoOp(“Local/PAGE103@app-paging-00000065;2”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [PAGE103@app-paging:3] GotoIf(“Local/PAGE103@app-paging-00000065;2”, “1?doptions”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx_builtins.c: Goto (app-paging,PAGE103,6)
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [PAGE103@app-paging:6] ExecIf(“Local/PAGE103@app-paging-00000065;2”, “1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,;answer-after=0)))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [PAGE103@app-paging:7] Dial(“Local/PAGE103@app-paging-00000065;2”, “PJSIP/103/sip:[email protected]:5060,5,A(custom/school-bell-fade)b(autoanswer^s^1(Ring Answer,;answer-after=0))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16985] dial.c: Called PAGE101@app-paging/n
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:13] Set(“Local/1000@from-internal-00000063;2”, “CONFBRIDGE(user,template)=page_user_duplex”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:14] Set(“Local/1000@from-internal-00000063;2”, “CONFBRIDGE(user,admin)=yes”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:15] Set(“Local/1000@from-internal-00000063;2”, “CONFBRIDGE(user,marked)=yes”) in new stack
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:16] Answer(“Local/1000@from-internal-00000063;2”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16931] dial.c: Local/1000@from-internal-00000063;1 answered
[2019-02-20 20:20:01] VERBOSE[16931] file.c: <Local/1000@from-internal-00000063;1> Playing ‘silence/1.slin16’ (language ‘en’)
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] pbx.c: Executing [1000@app-pagegroups:17] ConfBridge(“Local/1000@from-internal-00000063;2”, “1550715601282,admin_menu”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] app_stack.c: PJSIP/102-00000038 Internal Gosub(autoanswer,s,1(Ring Answer,;answer-after=0)) start
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@autoanswer:1] GosubIf(“PJSIP/102-00000038”, “1?func-set-sipheader,s,1(Alert-Info,Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/102-00000038”, “Sip Add Header function called. Adding Alert-Info = Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/102-00000038”, “HASH(__SIPHEADERS,Alert-Info)=Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/102-00000038”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@autoanswer:2] GosubIf(“PJSIP/102-00000038”, “1?func-set-sipheader,s,1(Call-Info,;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/102-00000038”, “Sip Add Header function called. Adding Call-Info = ;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/102-00000038”, “HASH(__SIPHEADERS,Call-Info)=;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/102-00000038”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@autoanswer:3] Gosub(“PJSIP/102-00000038”, “func-apply-sipheaders,s,1()”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/102-00000038”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/102-00000038”, “Applying SIP Headers to channel PJSIP/102-00000038”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/102-00000038”, “TECH=PJSIP”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/102-00000038”, “SIPHEADERKEYS=Call-Info,Alert-Info”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/102-00000038”, “1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/102-00000038”, “sipheader=;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/102-00000038”, “0?SIPRemoveHeader(Call-Info:)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“PJSIP/102-00000038”, “0?Set(PJSIP_HEADER(remove,Call-Info)=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/102-00000038”, “0?Set(sipheader=http://127.0.0.1;info=;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/102-00000038”, “0?SIPAddHeader(Call-Info:;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/102-00000038”, “1?Set(PJSIP_HEADER(add,Call-Info)=;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] app_stack.c: PJSIP/103-00000039 Internal Gosub(autoanswer,s,1(Ring Answer,;answer-after=0)) start
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@autoanswer:1] GosubIf(“PJSIP/103-00000039”, “1?func-set-sipheader,s,1(Alert-Info,Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/103-00000039”, “Sip Add Header function called. Adding Alert-Info = Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/103-00000039”, “HASH(__SIPHEADERS,Alert-Info)=Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/103-00000039”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@autoanswer:2] GosubIf(“PJSIP/103-00000039”, “1?func-set-sipheader,s,1(Call-Info,;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/103-00000039”, “Sip Add Header function called. Adding Call-Info = ;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/103-00000039”, “HASH(__SIPHEADERS,Call-Info)=;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/103-00000039”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@autoanswer:3] Gosub(“PJSIP/103-00000039”, “func-apply-sipheaders,s,1()”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/103-00000039”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/103-00000039”, “Applying SIP Headers to channel PJSIP/103-00000039”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/103-00000039”, “TECH=PJSIP”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/103-00000039”, “SIPHEADERKEYS=Call-Info,Alert-Info”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/103-00000039”, “1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16987] dial.c: Called PAGE106@app-paging/n

[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/103-00000039”, “sipheader=;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/103-00000039”, “0?SIPRemoveHeader(Call-Info:)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“PJSIP/103-00000039”, “0?Set(PJSIP_HEADER(remove,Call-Info)=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/103-00000039”, “0?Set(sipheader=http://127.0.0.1;info=;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/103-00000039”, “0?SIPAddHeader(Call-Info:;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/103-00000039”, “1?Set(PJSIP_HEADER(add,Call-Info)=;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16993][C-0000004f] bridge_channel.c: Channel CBAnn/1550715601282-0000006b;2 joined ‘softmix’ base-bridge <5f22fbd7-c8f2-4d3f-83e3-366690a05307>
[2019-02-20 20:20:01] VERBOSE[16934][C-0000004f] bridge_channel.c: Channel Local/1000@from-internal-00000063;2 joined ‘softmix’ base-bridge <5f22fbd7-c8f2-4d3f-83e3-366690a05307>
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [PAGE106@app-paging:1] Macro(“Local/PAGE106@app-paging-00000069;2”, “autoanswer,106”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:1] GotoIf(“Local/PAGE106@app-paging-00000069;2”, “1?knowndial”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:19] Set(“Local/PAGE106@app-paging-00000069;2”, “DIAL=PJSIP/106/sip:[email protected]:5060”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:20] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “0?Set(DIAL=DAHDIIP/106/sip:[email protected]:5060)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:21] GotoIf(“Local/PAGE106@app-paging-00000069;2”, “0?macro”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:22] GotoIf(“Local/PAGE106@app-paging-00000069;2”, “1?pjsipua”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx_builtins.c: Goto (macro-autoanswer,s,25)
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:25] Set(“Local/PAGE106@app-paging-00000069;2”, “AOR=106”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:26] Set(“Local/PAGE106@app-paging-00000069;2”, “CONTACT=106;@e8995566f214895adc3898487a56cc51”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:27] Set(“Local/PAGE106@app-paging-00000069;2”, “USERAGENT=baresip v0.4.19 (mips/linux)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:28] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “1?Set(USERAGENT=baresip v0.4.19 (mips/linux))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:29] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “0?Set(PAGE_VOL=;volume=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:30] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “0?Set(ALERTINFO=ring-answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:31] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “0?Set(CALLINFO=sip:broadworks.net;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:32] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “0?Set(ALERTINFO=http://example.com;info=alert-autoanswer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:33] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “0?Set(ALERTINFO=Intercom)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:34] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “0?Set(ALERTINFO=info=Auto Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:35] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “0?Set(ALERTINFO=http://www.sangoma.com;info=external)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [s@macro-autoanswer:36] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “1?Set(__SIP_URI_OPTIONS=intercom=true)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [PAGE106@app-paging:2] NoOp(“Local/PAGE106@app-paging-00000069;2”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [PAGE106@app-paging:3] GotoIf(“Local/PAGE106@app-paging-00000069;2”, “1?doptions”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx_builtins.c: Goto (app-paging,PAGE106,6)
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [PAGE106@app-paging:6] ExecIf(“Local/PAGE106@app-paging-00000069;2”, “1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,;answer-after=0)))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16996][C-00000053] pbx.c: Executing [PAGE106@app-paging:7] Dial(“Local/PAGE106@app-paging-00000069;2”, “PJSIP/106/sip:[email protected]:5060,5,A(custom/school-bell-fade)b(autoanswer^s^1(Ring Answer,;answer-after=0))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [PAGE104@app-paging:1] Macro(“Local/PAGE104@app-paging-00000067;2”, “autoanswer,104”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:1] GotoIf(“Local/PAGE104@app-paging-00000067;2”, “1?knowndial”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:19] Set(“Local/PAGE104@app-paging-00000067;2”, “DIAL=PJSIP/104/sip:[email protected]:5060”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:20] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “0?Set(DIAL=DAHDIIP/104/sip:[email protected]:5060)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:21] GotoIf(“Local/PAGE104@app-paging-00000067;2”, “0?macro”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:22] GotoIf(“Local/PAGE104@app-paging-00000067;2”, “1?pjsipua”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx_builtins.c: Goto (macro-autoanswer,s,25)
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:25] Set(“Local/PAGE104@app-paging-00000067;2”, “AOR=104”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:26] Set(“Local/PAGE104@app-paging-00000067;2”, “CONTACT=104;@9bae8cf55a8e69846e34ed25570b3bbf”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:27] Set(“Local/PAGE104@app-paging-00000067;2”, “USERAGENT=baresip v0.4.19 (mips/linux)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:28] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “1?Set(USERAGENT=baresip v0.4.19 (mips/linux))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:29] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “0?Set(PAGE_VOL=;volume=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:30] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “0?Set(ALERTINFO=ring-answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:31] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “0?Set(CALLINFO=sip:broadworks.net;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:32] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “0?Set(ALERTINFO=http://example.com;info=alert-autoanswer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:33] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “0?Set(ALERTINFO=Intercom)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:34] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “0?Set(ALERTINFO=info=Auto Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:35] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “0?Set(ALERTINFO=http://www.sangoma.com;info=external)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@macro-autoanswer:36] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “1?Set(__SIP_URI_OPTIONS=intercom=true)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [PAGE104@app-paging:2] NoOp(“Local/PAGE104@app-paging-00000067;2”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [PAGE104@app-paging:3] GotoIf(“Local/PAGE104@app-paging-00000067;2”, “1?doptions”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx_builtins.c: Goto (app-paging,PAGE104,6)
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [PAGE104@app-paging:6] ExecIf(“Local/PAGE104@app-paging-00000067;2”, “1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,;answer-after=0)))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [PAGE104@app-paging:7] Dial(“Local/PAGE104@app-paging-00000067;2”, “PJSIP/104/sip:[email protected]:5060,5,A(custom/school-bell-fade)b(autoanswer^s^1(Ring Answer,;answer-after=0))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] app_stack.c: PJSIP/104-0000003a Internal Gosub(autoanswer,s,1(Ring Answer,;answer-after=0)) start
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@autoanswer:1] GosubIf(“PJSIP/104-0000003a”, “1?func-set-sipheader,s,1(Alert-Info,Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/104-0000003a”, “Sip Add Header function called. Adding Alert-Info = Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/104-0000003a”, “HASH(__SIPHEADERS,Alert-Info)=Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/104-0000003a”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@autoanswer:2] GosubIf(“PJSIP/104-0000003a”, “1?func-set-sipheader,s,1(Call-Info,;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/104-0000003a”, “Sip Add Header function called. Adding Call-Info = ;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/104-0000003a”, “HASH(__SIPHEADERS,Call-Info)=;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/104-0000003a”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@autoanswer:3] Gosub(“PJSIP/104-0000003a”, “func-apply-sipheaders,s,1()”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/104-0000003a”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/104-0000003a”, “Applying SIP Headers to channel PJSIP/104-0000003a”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/104-0000003a”, “TECH=PJSIP”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/104-0000003a”, “SIPHEADERKEYS=Call-Info,Alert-Info”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/104-0000003a”, “1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/104-0000003a”, “sipheader=;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/104-0000003a”, “0?SIPRemoveHeader(Call-Info:)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“PJSIP/104-0000003a”, “0?Set(PJSIP_HEADER(remove,Call-Info)=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/104-0000003a”, “0?Set(sipheader=http://127.0.0.1;info=;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/104-0000003a”, “0?SIPAddHeader(Call-Info:;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/104-0000003a”, “1?Set(PJSIP_HEADER(add,Call-Info)=;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:12] EndWhile(“PJSIP/103-00000039”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/103-00000039”, “1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/103-00000039”, “sipheader=Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/103-00000039”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“PJSIP/103-00000039”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/103-00000039”, “1?Set(sipheader=http://127.0.0.1;info=Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/103-00000039”, “0?SIPAddHeader(Alert-Info:http://127.0.0.1;info=Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/103-00000039”, “1?Set(PJSIP_HEADER(add,Alert-Info)=http://127.0.0.1;info=Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:12] EndWhile(“PJSIP/102-00000038”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/102-00000038”, “1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/102-00000038”, “sipheader=Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/102-00000038”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“PJSIP/102-00000038”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/102-00000038”, “1?Set(sipheader=http://127.0.0.1;info=Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/102-00000038”, “0?SIPAddHeader(Alert-Info:http://127.0.0.1;info=Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/102-00000038”, “1?Set(PJSIP_HEADER(add,Alert-Info)=http://127.0.0.1;info=Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:12] EndWhile(“PJSIP/104-0000003a”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:12] EndWhile(“PJSIP/103-00000039”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/104-0000003a”, “1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/103-00000039”, “0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/104-0000003a”, “sipheader=Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@func-apply-sipheaders:13] Return(“PJSIP/103-00000039”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/104-0000003a”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“PJSIP/104-0000003a”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] pbx.c: Executing [s@autoanswer:4] Return(“PJSIP/103-00000039”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/104-0000003a”, “1?Set(sipheader=http://127.0.0.1;info=Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] app_stack.c: Spawn extension (from-internal, PAGE103, 1) exited non-zero on ‘PJSIP/103-00000039’
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] app_stack.c: PJSIP/103-00000039 Internal Gosub(autoanswer,s,1(Ring Answer,;answer-after=0)) complete GOSUB_RETVAL=
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/104-0000003a”, “0?SIPAddHeader(Alert-Info:http://127.0.0.1;info=Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/104-0000003a”, “1?Set(PJSIP_HEADER(add,Alert-Info)=http://127.0.0.1;info=Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:12] EndWhile(“PJSIP/102-00000038”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/102-00000038”, “0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@func-apply-sipheaders:13] Return(“PJSIP/102-00000038”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] pbx.c: Executing [s@autoanswer:4] Return(“PJSIP/102-00000038”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] app_stack.c: Spawn extension (from-internal, PAGE102, 1) exited non-zero on ‘PJSIP/102-00000038’
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] app_stack.c: PJSIP/102-00000038 Internal Gosub(autoanswer,s,1(Ring Answer,;answer-after=0)) complete GOSUB_RETVAL=
[2019-02-20 20:20:01] VERBOSE[16997] dial.c: Called PAGE105@app-paging/n
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] app_dial.c: Called PJSIP/103/sip:[email protected]:5060
[2019-02-20 20:20:01] VERBOSE[16981][C-00000051] app_dial.c: Called PJSIP/102/sip:[email protected]:5060
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [PAGE101@app-paging:1] Macro(“Local/PAGE101@app-paging-00000068;2”, “autoanswer,101”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:1] GotoIf(“Local/PAGE101@app-paging-00000068;2”, “1?knowndial”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:19] Set(“Local/PAGE101@app-paging-00000068;2”, “DIAL=PJSIP/101/sip:[email protected]:5060”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:20] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “0?Set(DIAL=DAHDIIP/101/sip:[email protected]:5060)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:21] GotoIf(“Local/PAGE101@app-paging-00000068;2”, “0?macro”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:22] GotoIf(“Local/PAGE101@app-paging-00000068;2”, “1?pjsipua”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx_builtins.c: Goto (macro-autoanswer,s,25)
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:25] Set(“Local/PAGE101@app-paging-00000068;2”, “AOR=101”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:26] Set(“Local/PAGE101@app-paging-00000068;2”, “CONTACT=101;@3d2e2250ce307cb46abbc126d79af7ff”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:27] Set(“Local/PAGE101@app-paging-00000068;2”, “USERAGENT=baresip v0.4.19 (mips/linux)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:28] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “1?Set(USERAGENT=baresip v0.4.19 (mips/linux))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:29] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “0?Set(PAGE_VOL=;volume=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:30] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “0?Set(ALERTINFO=ring-answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:31] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “0?Set(CALLINFO=sip:broadworks.net;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:32] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “0?Set(ALERTINFO=http://example.com;info=alert-autoanswer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:33] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “0?Set(ALERTINFO=Intercom)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:34] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “0?Set(ALERTINFO=info=Auto Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:35] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “0?Set(ALERTINFO=http://www.sangoma.com;info=external)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@macro-autoanswer:36] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “1?Set(__SIP_URI_OPTIONS=intercom=true)”) in new stack
[2019-02-20 20:20:01] VERBOSE[17104] netsock2.c: Using SIP RTP Audio TOS bits 184
[2019-02-20 20:20:01] VERBOSE[17104] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2019-02-20 20:20:01] VERBOSE[17104] netsock2.c: Using SIP RTP Audio CoS mark 5
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [PAGE101@app-paging:2] NoOp(“Local/PAGE101@app-paging-00000068;2”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [PAGE101@app-paging:3] GotoIf(“Local/PAGE101@app-paging-00000068;2”, “1?doptions”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx_builtins.c: Goto (app-paging,PAGE101,6)
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [PAGE101@app-paging:6] ExecIf(“Local/PAGE101@app-paging-00000068;2”, “1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,;answer-after=0)))”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [PAGE101@app-paging:7] Dial(“Local/PAGE101@app-paging-00000068;2”, “PJSIP/101/sip:[email protected]:5060,5,A(custom/school-bell-fade)b(autoanswer^s^1(Ring Answer,;answer-after=0))”) in new stack
[2019-02-20 20:20:01] VERBOSE[17104] netsock2.c: Using SIP RTP Audio TOS bits 184
[2019-02-20 20:20:01] VERBOSE[17104] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2019-02-20 20:20:01] VERBOSE[17104] netsock2.c: Using SIP RTP Audio CoS mark 5
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:12] EndWhile(“PJSIP/104-0000003a”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/104-0000003a”, “0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@func-apply-sipheaders:13] Return(“PJSIP/104-0000003a”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] pbx.c: Executing [s@autoanswer:4] Return(“PJSIP/104-0000003a”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] app_stack.c: Spawn extension (from-internal, PAGE104, 1) exited non-zero on ‘PJSIP/104-0000003a’
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] app_stack.c: PJSIP/104-0000003a Internal Gosub(autoanswer,s,1(Ring Answer,;answer-after=0)) complete GOSUB_RETVAL=
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] app_stack.c: PJSIP/101-0000003b Internal Gosub(autoanswer,s,1(Ring Answer,;answer-after=0)) start
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@autoanswer:1] GosubIf(“PJSIP/101-0000003b”, “1?func-set-sipheader,s,1(Alert-Info,Ring Answer)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/101-0000003b”, “Sip Add Header function called. Adding Alert-Info = Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/101-0000003b”, “HASH(__SIPHEADERS,Alert-Info)=Ring Answer”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/101-0000003b”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@autoanswer:2] GosubIf(“PJSIP/101-0000003b”, “1?func-set-sipheader,s,1(Call-Info,;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-set-sipheader:1] NoOp(“PJSIP/101-0000003b”, “Sip Add Header function called. Adding Call-Info = ;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-set-sipheader:2] Set(“PJSIP/101-0000003b”, “HASH(__SIPHEADERS,Call-Info)=;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-set-sipheader:3] Return(“PJSIP/101-0000003b”, “”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@autoanswer:3] Gosub(“PJSIP/101-0000003b”, “func-apply-sipheaders,s,1()”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/101-0000003b”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/101-0000003b”, “Applying SIP Headers to channel PJSIP/101-0000003b”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/101-0000003b”, “TECH=PJSIP”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/101-0000003b”, “SIPHEADERKEYS=Call-Info,Alert-Info”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/101-0000003b”, “1”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/101-0000003b”, “sipheader=;answer-after=0”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“PJSIP/101-0000003b”, “0?SIPRemoveHeader(Call-Info:)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“PJSIP/101-0000003b”, “0?Set(PJSIP_HEADER(remove,Call-Info)=)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“PJSIP/101-0000003b”, “0?Set(sipheader=http://127.0.0.1;info=;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/101-0000003b”, “0?SIPAddHeader(Call-Info:;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16989][C-00000055] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/101-0000003b”, “1?Set(PJSIP_HEADER(add,Call-Info)=;answer-after=0)”) in new stack
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] app_dial.c: PJSIP/103-00000039 is ringing
[2019-02-20 20:20:01] VERBOSE[16982][C-00000052] app_dial.c: PJSIP/103-00000039 is ringing
[2019-02-20 20:20:01] VERBOSE[16986][C-00000054] app_dial.c: Called PJSIP/104/sip:[email protected]:5060
[2019-02-20 20:20:01] VERBOSE[17003][C-00000056] pbx.c: Executing [PAGE105@app-paging:1] Macro(“Local/PAGE105@app-paging-0000006a;2”, “autoanswer,105”) in new stack
[2019-02-20 20:20:01] VERBOSE[17003][C-00000056] pbx.c: Executing [s@macro-autoanswer:1] GotoIf(“Local/PAGE105@app-paging-0000006a;2”, “1?knowndial”) in new stack
[2019-02-20 20:20:01] VERBOSE[17003][C-00000056] pbx_builtins.c: Goto (macro-autoanswer,s,19)
[2019-02-20 20:20:01] VERBOSE[17003][C-00000056] pbx.c: Executing [s@macro-autoanswer:19] Set(“Local/PAGE105@app-paging-0000006a;2”, “DIAL=PJSIP/105/sip:[email protected]:5060”) in new stack
[2019-02-20 20:20:01] VERBOSE[14463] netsock2.c: Using SIP RTP Audio TOS bits 184
[2019-02-20 20:20:01] VERBOSE[14463] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2019-02-20 20:20:01] VERBOSE[14463] netsock2.c: Using SIP RTP Audio CoS mark 5
[2019-02-20 20:20:01] VERBOSE[17003][C-00000056] pbx.c: Executing [s@macro-autoanswer:20] ExecIf(“Local/PAGE105@app-paging-0000006a;2”, “0?Set(DIAL=DAHDIIP/105/sip:[email protected]:5060)”) in new stack
[2019-02-20 20:20:01] VERBOSE[17003][C-00000056] pbx.c: Executing [s@macro-autoanswer:21] GotoIf(“Local/PAGE105@app-paging-0000006a;2”, “0?macro”) in new stack
[2019-02-20 20:20:01] VERBOSE[17003][C-00000056] pbx.c: Executing [s@macro-autoanswer:22] GotoIf(“Local/PAGE105@app-paging-0000006a;2”, “1?pjsipua”) in new stack
[2019-02-20 20:20:01] VERBOSE[17003][C-00000056] pbx_builtins.c: Goto (macro-autoanswer,s,25)
[2019-02-20 20:20:01] VERBOSE[17003][C-00000056] pbx.c: Executing [s@macro-autoanswer:25] Set(“Local/PAGE105@app-paging-0000006a;2”, “AOR=105”) in new stack
[2019-02-20 20:20:01] VERBOSE[17003][C-00000056] pbx.c: Executing [s@macro-autoanswer:26] Set(“Local/PAGE105@app-paging-0000006a;2”, “CONTACT=105;@fde3481249a53cac680da75bb916dbbc”) in new stack

You need to use something like pastebin for this so the formatting isn’t screwed up by being parsed at HTML tags. Plus this is no where near the full call for this. So almost everything that we would need to see is still missing.

Possibly this?

https://issues.freepbx.org/browse/FREEPBX-15580

Well it is almost a year old and shows to be have fixed in 14.0.2. It was suggested you run the edge update on the module since there where changes. Did you do that? What version of the module are you running?

Apologies for the admittedly poor log dumps…so here’s my latest. I updated to edge - still had the issue, then I trimmed my wav file from 9s down to 4s and it started to work. I haven’t seen the ‘in use’ hang since I cut the audio file down (9s file was 146k) …(4s file is 72k) - not sure if/how that has anything to do w/ it. I wrote a script to ‘hunt and kill’ orphaned channels…I’ll check my logfile in a few.

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