[Bug] Paging Pro (Valet Paging): Unnecessary second call triggered with no audio (file already deleted)

kwun · April 29, 2026, 9:19am

System Environment:

Current PBX Version: 17.0.28
Current System Version: 12.7.8-2408-1.sng12
Paging and Intercom Module: 17.0.3 Stable (Enabled and up to date)
Paging Pro Module: 17.0.1.9 Stable (Commercial, Enabled and up to date)

Issue Description:
Hello everyone,

I’ve encountered a logic bug in the Paging Pro module when using the “Force Valet” (Valet Paging) feature. The system is making unnecessary duplicate calls to extensions, and the second call has no audio.

Here is exactly what happens:
When a user pages a group with “Force Valet” enabled, the system records the announcement and successfully calls the target extensions. The extensions ring, answer, and the recorded audio is played perfectly.

However, shortly after the first successful playback finishes and the call hangs up, the PBX automatically calls the extension again. When the user answers this second phantom call, there is dead air (no audio).

Based on my observation and logs, there are two issues happening here:

The Phantom Retry: The system is triggering a retry/second call even though the first call was successfully answered and played.
The Premature File Deletion: During the second call, no audio is played because the temporary recording file was already deleted by the system immediately after the first call finished.

Steps to Reproduce:

Create a Page Group and set Force Valet to Yes.
Dial the page group and record a message.
The target extension (e.g., SIP/36) rings, answers, and plays the recording completely.
Wait a few moments after the first call ends. The extension will ring again.
Answer the second call → No audio is played (Asterisk logs will show a “file not found” error because the spooler/system already deleted it).

Expected Behavior:

The system should NOT call the extension again if the Valet Page has already been successfully delivered.
If retries are strictly necessary for unanswered/busy extensions, the temporary audio file must not be deleted until all retries are exhausted.

Logs:

I have just registered as a new account and am unable to upload attachments to my journal. Pasting the entire text is also overly burdensome. What steps can be taken to address this issue?

kwun · April 29, 2026, 9:26am

6769	[2026-04-29 11:16:14] VERBOSE[9221] loader.c: Reloading module 'res_prometheus.so' (Asterisk Prometheus Module)	
6770	[2026-04-29 11:16:14] VERBOSE[9221] loader.c: Reloading module 'app_queue.so' (True Call Queueing)	
6771	[2026-04-29 11:16:14] VERBOSE[9221] asterisk.c: Remote UNIX connection disconnected	
6772	[2026-04-29 11:16:23] VERBOSE[2485] netsock2.c: Using SIP RTP Audio TOS bits 184	
6773	[2026-04-29 11:16:23] VERBOSE[2485] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.	
6774	[2026-04-29 11:16:23] VERBOSE[2485] netsock2.c: Using SIP RTP Audio CoS mark 5	
6775	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [8001@from-internal:1] NoOp("PJSIP/37-0000000f", "Checking Permission for Paging Group A") in new stack	
6776	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [8001@from-internal:2] GotoIf("PJSIP/37-0000000f", "1?allow:deny") in new stack	
6777	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx_builtins.c: Goto (from-internal,8001,3)	
6778	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [8001@from-internal:3] Goto("PJSIP/37-0000000f", "ext-paging,956324785421,1") in new stack	
6779	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx_builtins.c: Goto (ext-paging,956324785421,1)	
6780	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@ext-paging:1] Goto("PJSIP/37-0000000f", "app-pagegroups,956324785421,1") in new stack	
6781	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx_builtins.c: Goto (app-pagegroups,956324785421,1)	
6782	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:1] Gosub("PJSIP/37-0000000f", "macro-user-callerid,s,1()") in new stack	
6783	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/37-0000000f", "TOUCH_MONITOR=1777432583.180") in new stack	
6784	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/37-0000000f", "CHANCONTEXT=") in new stack	
6785	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/37-0000000f", "CHANCONTEXT=") in new stack	
6786	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/37-0000000f", "CHANEXTENCONTEXT=37-0000000f") in new stack	
6787	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/37-0000000f", "CHANEXTEN=37-0000000f") in new stack	
6788	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/37-0000000f", "CALLERID(number)=37") in new stack	
6789	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/37-0000000f", "AMPUSER=37") in new stack	
6790	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/37-0000000f", "HOTDESCKCHAN=37-0000000f") in new stack	
6791	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/37-0000000f", "HOTDESKEXTEN=37") in new stack	
6792	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/37-0000000f", "HOTDESKCALL=0") in new stack	
6793	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/37-0000000f", "0?Set(HOTDESKCALL=1)") in new stack	
6794	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/37-0000000f", "0?Set(CALLERID(name)=)") in new stack	
6795	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/37-0000000f", "0?report") in new stack	
6796	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/37-0000000f", "1?Set(REALCALLERIDNUM=37)") in new stack	
6797	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/37-0000000f", "AMPUSER=37") in new stack	
6798	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/37-0000000f", "0?limit") in new stack	
6799	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/37-0000000f", "AMPUSERCIDNAME=37") in new stack	
6800	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/37-0000000f", "0?Set(__CIDMASQUERADING=TRUE)") in new stack	
6801	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/37-0000000f", "0?report") in new stack	
6802	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/37-0000000f", "AMPUSERCID=37") in new stack	
6803	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/37-0000000f", "__DIAL_OPTIONS=HhTtr") in new stack	
6804	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/37-0000000f", "CALLERID(all)="37" <37>") in new stack	
6805	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/37-0000000f", "0?Set(CUSDIAL=)") in new stack	
6806	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/37-0000000f", "0?Set(CALLERID(all)="37" <37>)") in new stack	
6807	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/37-0000000f", "0?limit") in new stack	
6808	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/37-0000000f", "0?Set(GROUP(concurrency_limit)=37)") in new stack	
6809	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("PJSIP/37-0000000f", "0?Set(CHANNEL(language)=)") in new stack	
6810	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/37-0000000f", "Macro depricated!! To keep the same line numbers") in new stack	
6811	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:29] NoOp("PJSIP/37-0000000f", "Macro depricated !! To keep the same line numbers") in new stack	
6812	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/37-0000000f", "0?continue") in new stack	
6813	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("PJSIP/37-0000000f", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack	
6814	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/37-0000000f", "__TTL=64") in new stack	
6815	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("PJSIP/37-0000000f", "1?continue") in new stack	
6816	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx_builtins.c: Goto (macro-user-callerid,s,49)	
6817	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/37-0000000f", "CALLERID(number)=37") in new stack	
6818	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/37-0000000f", "CALLERID(name)=37") in new stack	
6819	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("PJSIP/37-0000000f", "0?cnum") in new stack	
6820	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/37-0000000f", "__MCNUM=37") in new stack	
6821	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/37-0000000f", "__MCNAME=37") in new stack	
6822	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/37-0000000f", "__MCEXTEN=37") in new stack	
6823	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/37-0000000f", "__MCORGCHAN=PJSIP/37-0000000f") in new stack	
6824	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:56] Set("PJSIP/37-0000000f", "CDR(cnam)=37") in new stack	
6825	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:57] Set("PJSIP/37-0000000f", "CDR(cnum)=37") in new stack	
6826	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [s@macro-user-callerid:58] Return("PJSIP/37-0000000f", "") in new stack	
6827	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:2] Set("PJSIP/37-0000000f", "_PAGEGROUP=956324785421") in new stack	
6828	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:3] Set("PJSIP/37-0000000f", "_PVOL=2") in new stack	
6829	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:4] Set("PJSIP/37-0000000f", "ANNOUNCE_COUNT=1") in new stack	
6830	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:5] ExecIf("PJSIP/37-0000000f", "1?Goto(busy)") in new stack	
6831	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx_builtins.c: Goto (app-pagegroups,956324785421,21)	
6832	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:21] Set("PJSIP/37-0000000f", "PAGE956324785421BUSY=TRUE") in new stack	
6833	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:22] ExecIf("PJSIP/37-0000000f", "0?Busy()") in new stack	
6834	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:23] GotoIf("PJSIP/37-0000000f", "0?busy-hang") in new stack	
6835	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:24] Set("PJSIP/37-0000000f", "PAGE_RECORDING=/var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573") in new stack	
6836	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:25] Record("PJSIP/37-0000000f", "/var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573.sln,3,,k") in new stack	
6837	[2026-04-29 11:16:23] VERBOSE[9629][C-00000033] file.c: <PJSIP/37-0000000f> Playing 'beep.ulaw' (language 'en')	
6838	[2026-04-29 11:16:26] DTMF[9629][C-00000033] channel.c: DTMF begin '#' received on PJSIP/37-0000000f	
6839	[2026-04-29 11:16:26] DTMF[9629][C-00000033] channel.c: DTMF begin passthrough '#' on PJSIP/37-0000000f	
6840	[2026-04-29 11:16:26] DTMF[9629][C-00000033] channel.c: DTMF end '#' received on PJSIP/37-0000000f, duration 100 ms	
6841	[2026-04-29 11:16:26] DTMF[9629][C-00000033] channel.c: DTMF end accepted with begin '#' on PJSIP/37-0000000f	
6842	[2026-04-29 11:16:26] DTMF[9629][C-00000033] channel.c: DTMF end '#' detected to have actual duration 78 on the wire, emulation will be triggered on PJSIP/37-0000000f	
6843	[2026-04-29 11:16:26] DTMF[9629][C-00000033] channel.c: DTMF end '#' has duration 78 but want minimum 80, emulating on PJSIP/37-0000000f	
6844	[2026-04-29 11:16:26] DTMF[9629][C-00000033] channel.c: DTMF end emulation of '#' queued on PJSIP/37-0000000f	
6845	[2026-04-29 11:16:26] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:26] NoOp("PJSIP/37-0000000f", "DTMF") in new stack	
6846	[2026-04-29 11:16:26] VERBOSE[9629][C-00000033] pbx.c: Executing [956324785421@app-pagegroups:27] Goto("PJSIP/37-0000000f", "app-pagegroups,h,1") in new stack	
6847	[2026-04-29 11:16:26] VERBOSE[9629][C-00000033] pbx_builtins.c: Goto (app-pagegroups,h,1)	
6848	[2026-04-29 11:16:26] VERBOSE[9629][C-00000033] pbx.c: Executing [h@app-pagegroups:1] ExecIf("PJSIP/37-0000000f", "0?Set(DEVICE_STATE(Custom:PAGE956324785421)=NOT_INUSE)") in new stack	
6849	[2026-04-29 11:16:26] VERBOSE[9629][C-00000033] pbx.c: Executing [h@app-pagegroups:2] GosubIf("PJSIP/37-0000000f", "1?record-page,1()") in new stack	
6850	[2026-04-29 11:16:26] VERBOSE[9629][C-00000033] pbx.c: Executing [record-page@app-pagegroups:1] AGI("PJSIP/37-0000000f", "agi://127.0.0.1/pagingpro_call_spooler.agi,calltype=valet_page") in new stack	
6851	[2026-04-29 11:16:27] VERBOSE[9637] pbx_spool.c: Attempting call on Local/956324785421@ext-paging for s@pagingpro-play-message:1 (Retry 1)	
6852	[2026-04-29 11:16:27] VERBOSE[9637] dial.c: Called 956324785421@ext-paging	
6853	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@ext-paging:1] Goto("Local/956324785421@ext-paging-0000002d;2", "app-pagegroups,956324785421,1") in new stack	
6854	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx_builtins.c: Goto (app-pagegroups,956324785421,1)	
6855	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:1] Gosub("Local/956324785421@ext-paging-0000002d;2", "macro-user-callerid,s,1()") in new stack	
6856	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/956324785421@ext-paging-0000002d;2", "TOUCH_MONITOR=1777432587.182") in new stack	
6857	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/956324785421@ext-paging-0000002d;2", "CHANCONTEXT=ext-paging-0000002d;2") in new stack	
6858	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:3] Set("Local/956324785421@ext-paging-0000002d;2", "CHANCONTEXT=ext") in new stack	
6859	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:4] Set("Local/956324785421@ext-paging-0000002d;2", "CHANEXTENCONTEXT=956324785421@ext-paging-0000002d;2") in new stack	
6860	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/956324785421@ext-paging-0000002d;2", "CHANEXTEN=956324785421") in new stack	
6861	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:6] Set("Local/956324785421@ext-paging-0000002d;2", "CALLERID(number)=37") in new stack	
6862	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/956324785421@ext-paging-0000002d;2", "AMPUSER=37") in new stack	
6863	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:8] Set("Local/956324785421@ext-paging-0000002d;2", "HOTDESCKCHAN=956324785421@ext-paging-0000002d;2") in new stack	
6864	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:9] Set("Local/956324785421@ext-paging-0000002d;2", "HOTDESKEXTEN=956324785421@ext") in new stack	
6865	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:10] Set("Local/956324785421@ext-paging-0000002d;2", "HOTDESKCALL=0") in new stack	
6866	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("Local/956324785421@ext-paging-0000002d;2", "0?Set(HOTDESKCALL=1)") in new stack	
6867	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("Local/956324785421@ext-paging-0000002d;2", "0?Set(CALLERID(name)=)") in new stack	
6868	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("Local/956324785421@ext-paging-0000002d;2", "0?report") in new stack	
6869	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("Local/956324785421@ext-paging-0000002d;2", "1?Set(REALCALLERIDNUM=37)") in new stack	
6870	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:15] Set("Local/956324785421@ext-paging-0000002d;2", "AMPUSER=37") in new stack	
6871	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("Local/956324785421@ext-paging-0000002d;2", "0?limit") in new stack	
6872	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:17] Set("Local/956324785421@ext-paging-0000002d;2", "AMPUSERCIDNAME=37") in new stack	
6873	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("Local/956324785421@ext-paging-0000002d;2", "0?Set(__CIDMASQUERADING=TRUE)") in new stack	
6874	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("Local/956324785421@ext-paging-0000002d;2", "0?report") in new stack	
6875	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:20] Set("Local/956324785421@ext-paging-0000002d;2", "AMPUSERCID=37") in new stack	
6876	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:21] Set("Local/956324785421@ext-paging-0000002d;2", "__DIAL_OPTIONS=HhTtr") in new stack	
6877	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:22] Set("Local/956324785421@ext-paging-0000002d;2", "CALLERID(all)="37" <37>") in new stack	
6878	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("Local/956324785421@ext-paging-0000002d;2", "0?Set(CUSDIAL=)") in new stack	
6879	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("Local/956324785421@ext-paging-0000002d;2", "0?Set(CALLERID(all)="37" <37>)") in new stack	
6880	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("Local/956324785421@ext-paging-0000002d;2", "0?limit") in new stack	
6881	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("Local/956324785421@ext-paging-0000002d;2", "0?Set(GROUP(concurrency_limit)=37)") in new stack	
6882	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("Local/956324785421@ext-paging-0000002d;2", "0?Set(CHANNEL(language)=)") in new stack	
6883	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:28] NoOp("Local/956324785421@ext-paging-0000002d;2", "Macro depricated!! To keep the same line numbers") in new stack	
6884	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:29] NoOp("Local/956324785421@ext-paging-0000002d;2", "Macro depricated !! To keep the same line numbers") in new stack	
6885	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("Local/956324785421@ext-paging-0000002d;2", "0?continue") in new stack	
6886	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("Local/956324785421@ext-paging-0000002d;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack	
6887	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:32] Set("Local/956324785421@ext-paging-0000002d;2", "__TTL=64") in new stack	
6888	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("Local/956324785421@ext-paging-0000002d;2", "1?continue") in new stack	
6889	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx_builtins.c: Goto (macro-user-callerid,s,49)	
6890	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:49] Set("Local/956324785421@ext-paging-0000002d;2", "CALLERID(number)=37") in new stack	
6891	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:50] Set("Local/956324785421@ext-paging-0000002d;2", "CALLERID(name)=37") in new stack	
6892	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("Local/956324785421@ext-paging-0000002d;2", "0?cnum") in new stack	
6893	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:52] Set("Local/956324785421@ext-paging-0000002d;2", "__MCNUM=37") in new stack	
6894	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:53] Set("Local/956324785421@ext-paging-0000002d;2", "__MCNAME=37") in new stack	
6895	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:54] Set("Local/956324785421@ext-paging-0000002d;2", "__MCEXTEN=37") in new stack	
6896	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:55] Set("Local/956324785421@ext-paging-0000002d;2", "__MCORGCHAN=Local/956324785421@ext-paging-0000002d;2") in new stack	
6897	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:56] Set("Local/956324785421@ext-paging-0000002d;2", "CDR(cnam)=37") in new stack	
6898	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:57] Set("Local/956324785421@ext-paging-0000002d;2", "CDR(cnum)=37") in new stack	
6899	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [s@macro-user-callerid:58] Return("Local/956324785421@ext-paging-0000002d;2", "") in new stack	
6900	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:2] Set("Local/956324785421@ext-paging-0000002d;2", "_PAGEGROUP=956324785421") in new stack	
6901	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:3] Set("Local/956324785421@ext-paging-0000002d;2", "_PVOL=2") in new stack	
6902	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:4] Set("Local/956324785421@ext-paging-0000002d;2", "ANNOUNCE_COUNT=1") in new stack	
6903	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:5] ExecIf("Local/956324785421@ext-paging-0000002d;2", "0?Goto(busy)") in new stack	
6904	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:6] GotoIf("Local/956324785421@ext-paging-0000002d;2", "1?:busy") in new stack	
6905	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:7] Set("Local/956324785421@ext-paging-0000002d;2", "DEVICE_STATE(Custom:PAGE956324785421)=INUSE") in new stack	
6906	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:8] Gosub("Local/956324785421@ext-paging-0000002d;2", "app-paging,ssetup,1()") in new stack	
6907	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [ssetup@app-paging:1] Set("Local/956324785421@ext-paging-0000002d;2", "_SIPURI=") in new stack	
6908	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [ssetup@app-paging:2] Set("Local/956324785421@ext-paging-0000002d;2", "_ALERTINFO=Ring Answer") in new stack	
6909	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [ssetup@app-paging:3] Set("Local/956324785421@ext-paging-0000002d;2", "_CALLINFO=<uri>;answer-after=0") in new stack	
6910	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [ssetup@app-paging:4] Set("Local/956324785421@ext-paging-0000002d;2", "_SIPURI=intercom=true") in new stack	
6911	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [ssetup@app-paging:5] Set("Local/956324785421@ext-paging-0000002d;2", "_DTIME=5") in new stack	
6912	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [ssetup@app-paging:6] Set("Local/956324785421@ext-paging-0000002d;2", "_ANSWERMACRO=") in new stack	
6913	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [ssetup@app-paging:7] Set("Local/956324785421@ext-paging-0000002d;2", "PAGE_CONF=1777432587963") in new stack	
6914	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [ssetup@app-paging:8] Return("Local/956324785421@ext-paging-0000002d;2", "") in new stack	
6915	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] res_agi.c: <PJSIP/37-0000000f>AGI Script agi://127.0.0.1/pagingpro_call_spooler.agi completed, returning 0	
6916	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] pbx.c: Executing [record-page@app-pagegroups:2] Return("PJSIP/37-0000000f", "") in new stack	
6917	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] pbx.c: Executing [h@app-pagegroups:3] ExecIf("PJSIP/37-0000000f", "0?System(rm /var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573.sln)") in new stack	
6918	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:9] Set("Local/956324785421@ext-paging-0000002d;2", "PAGEMODE=PAGE") in new stack

kwun · April 29, 2026, 9:27am

6919	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] pbx.c: Executing [h@app-pagegroups:4] ExecIf("PJSIP/37-0000000f", "0?System(rm -f /var/spool/asterisk/outgoing/)") in new stack	
6920	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:10] Set("Local/956324785421@ext-paging-0000002d;2", "PAGE_MEMBERS=36") in new stack	
6921	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] pbx.c: Auto fallthrough, channel 'PJSIP/37-0000000f' status is 'UNKNOWN'	
6922	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:11] Set("Local/956324785421@ext-paging-0000002d;2", "PAGE_CONF_OPTS=") in new stack	
6923	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:12] Set("Local/956324785421@ext-paging-0000002d;2", "ANNOUNCEMENT=beep") in new stack	
6924	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] pbx.c: Executing [h@app-pagegroups:1] ExecIf("PJSIP/37-0000000f", "0?Set(DEVICE_STATE(Custom:PAGE956324785421)=NOT_INUSE)") in new stack	
6925	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:13] Set("Local/956324785421@ext-paging-0000002d;2", "STREAM=NONE") in new stack	
6926	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:14] AGI("Local/956324785421@ext-paging-0000002d;2", "agi://127.0.0.1/page.agi") in new stack	
6927	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] pbx.c: Executing [h@app-pagegroups:2] GosubIf("PJSIP/37-0000000f", "1?record-page,1()") in new stack	
6928	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] pbx.c: Executing [record-page@app-pagegroups:1] AGI("PJSIP/37-0000000f", "agi://127.0.0.1/pagingpro_call_spooler.agi,calltype=valet_page") in new stack	
6929	[2026-04-29 11:16:27] VERBOSE[9647] pbx_spool.c: Attempting call on Local/956324785421@ext-paging for s@pagingpro-play-message:1 (Retry 1)	
6930	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [956324785421@ext-paging:1] Goto("Local/956324785421@ext-paging-0000002e;2", "app-pagegroups,956324785421,1") in new stack	
6931	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx_builtins.c: Goto (app-pagegroups,956324785421,1)	
6932	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [956324785421@app-pagegroups:1] Gosub("Local/956324785421@ext-paging-0000002e;2", "macro-user-callerid,s,1()") in new stack	
6933	[2026-04-29 11:16:27] VERBOSE[9647] dial.c: Called 956324785421@ext-paging	
6934	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/956324785421@ext-paging-0000002e;2", "TOUCH_MONITOR=1777432587.184") in new stack	
6935	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/956324785421@ext-paging-0000002e;2", "CHANCONTEXT=ext-paging-0000002e;2") in new stack	
6936	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:3] Set("Local/956324785421@ext-paging-0000002e;2", "CHANCONTEXT=ext") in new stack	
6937	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:4] Set("Local/956324785421@ext-paging-0000002e;2", "CHANEXTENCONTEXT=956324785421@ext-paging-0000002e;2") in new stack	
6938	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/956324785421@ext-paging-0000002e;2", "CHANEXTEN=956324785421") in new stack	
6939	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:6] Set("Local/956324785421@ext-paging-0000002e;2", "CALLERID(number)=37") in new stack	
6940	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] res_agi.c: <PJSIP/37-0000000f>AGI Script agi://127.0.0.1/pagingpro_call_spooler.agi completed, returning 0	
6941	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/956324785421@ext-paging-0000002e;2", "AMPUSER=37") in new stack	
6942	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] pbx.c: Executing [record-page@app-pagegroups:2] Return("PJSIP/37-0000000f", "") in new stack	
6943	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:8] Set("Local/956324785421@ext-paging-0000002e;2", "HOTDESCKCHAN=956324785421@ext-paging-0000002e;2") in new stack	
6944	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] pbx.c: Executing [h@app-pagegroups:3] ExecIf("PJSIP/37-0000000f", "0?System(rm /var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573.sln)") in new stack	
6945	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:9] Set("Local/956324785421@ext-paging-0000002e;2", "HOTDESKEXTEN=956324785421@ext") in new stack	
6946	[2026-04-29 11:16:27] VERBOSE[9629][C-00000033] pbx.c: Executing [h@app-pagegroups:4] ExecIf("PJSIP/37-0000000f", "0?System(rm -f /var/spool/asterisk/outgoing/)") in new stack	
6947	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:10] Set("Local/956324785421@ext-paging-0000002e;2", "HOTDESKCALL=0") in new stack	
6948	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?Set(HOTDESKCALL=1)") in new stack	
6949	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?Set(CALLERID(name)=)") in new stack	
6950	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("Local/956324785421@ext-paging-0000002e;2", "0?report") in new stack	
6951	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("Local/956324785421@ext-paging-0000002e;2", "1?Set(REALCALLERIDNUM=37)") in new stack	
6952	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:15] Set("Local/956324785421@ext-paging-0000002e;2", "AMPUSER=37") in new stack	
6953	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("Local/956324785421@ext-paging-0000002e;2", "0?limit") in new stack	
6954	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:17] Set("Local/956324785421@ext-paging-0000002e;2", "AMPUSERCIDNAME=37") in new stack	
6955	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?Set(__CIDMASQUERADING=TRUE)") in new stack	
6956	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("Local/956324785421@ext-paging-0000002e;2", "0?report") in new stack	
6957	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:20] Set("Local/956324785421@ext-paging-0000002e;2", "AMPUSERCID=37") in new stack	
6958	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:21] Set("Local/956324785421@ext-paging-0000002e;2", "__DIAL_OPTIONS=HhTtr") in new stack	
6959	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:22] Set("Local/956324785421@ext-paging-0000002e;2", "CALLERID(all)="37" <37>") in new stack	
6960	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?Set(CUSDIAL=)") in new stack	
6961	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?Set(CALLERID(all)="37" <37>)") in new stack	
6962	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("Local/956324785421@ext-paging-0000002e;2", "0?limit") in new stack	
6963	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?Set(GROUP(concurrency_limit)=37)") in new stack	
6964	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?Set(CHANNEL(language)=)") in new stack	
6965	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:28] NoOp("Local/956324785421@ext-paging-0000002e;2", "Macro depricated!! To keep the same line numbers") in new stack	
6966	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:29] NoOp("Local/956324785421@ext-paging-0000002e;2", "Macro depricated !! To keep the same line numbers") in new stack	
6967	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("Local/956324785421@ext-paging-0000002e;2", "0?continue") in new stack	
6968	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("Local/956324785421@ext-paging-0000002e;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack	
6969	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:32] Set("Local/956324785421@ext-paging-0000002e;2", "__TTL=64") in new stack	
6970	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("Local/956324785421@ext-paging-0000002e;2", "1?continue") in new stack	
6971	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx_builtins.c: Goto (macro-user-callerid,s,49)	
6972	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:49] Set("Local/956324785421@ext-paging-0000002e;2", "CALLERID(number)=37") in new stack	
6973	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:50] Set("Local/956324785421@ext-paging-0000002e;2", "CALLERID(name)=37") in new stack	
6974	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("Local/956324785421@ext-paging-0000002e;2", "0?cnum") in new stack	
6975	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:52] Set("Local/956324785421@ext-paging-0000002e;2", "__MCNUM=37") in new stack	
6976	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:53] Set("Local/956324785421@ext-paging-0000002e;2", "__MCNAME=37") in new stack	
6977	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:54] Set("Local/956324785421@ext-paging-0000002e;2", "__MCEXTEN=37") in new stack	
6978	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:55] Set("Local/956324785421@ext-paging-0000002e;2", "__MCORGCHAN=Local/956324785421@ext-paging-0000002e;2") in new stack	
6979	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:56] Set("Local/956324785421@ext-paging-0000002e;2", "CDR(cnam)=37") in new stack	
6980	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:57] Set("Local/956324785421@ext-paging-0000002e;2", "CDR(cnum)=37") in new stack	
6981	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [s@macro-user-callerid:58] Return("Local/956324785421@ext-paging-0000002e;2", "") in new stack	
6982	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [956324785421@app-pagegroups:2] Set("Local/956324785421@ext-paging-0000002e;2", "_PAGEGROUP=956324785421") in new stack	
6983	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [956324785421@app-pagegroups:3] Set("Local/956324785421@ext-paging-0000002e;2", "_PVOL=2") in new stack	
6984	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [956324785421@app-pagegroups:4] Set("Local/956324785421@ext-paging-0000002e;2", "ANNOUNCE_COUNT=1") in new stack	
6985	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [956324785421@app-pagegroups:5] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?Goto(busy)") in new stack	
6986	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [956324785421@app-pagegroups:6] GotoIf("Local/956324785421@ext-paging-0000002e;2", "0?:busy") in new stack	
6987	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx_builtins.c: Goto (app-pagegroups,956324785421,21)	
6988	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [956324785421@app-pagegroups:21] Set("Local/956324785421@ext-paging-0000002e;2", "PAGE956324785421BUSY=TRUE") in new stack	
6989	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [956324785421@app-pagegroups:22] ExecIf("Local/956324785421@ext-paging-0000002e;2", "1?Busy()") in new stack	
6990	[2026-04-29 11:16:27] VERBOSE[9647] dial.c: Local/956324785421@ext-paging-0000002e;1 is busy	
6991	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Spawn extension (app-pagegroups, 956324785421, 22) exited non-zero on 'Local/956324785421@ext-paging-0000002e;2'	
6992	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [h@app-pagegroups:1] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?Set(DEVICE_STATE(Custom:PAGE956324785421)=NOT_INUSE)") in new stack	
6993	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [h@app-pagegroups:2] GosubIf("Local/956324785421@ext-paging-0000002e;2", "0?record-page,1()") in new stack	
6994	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [h@app-pagegroups:3] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?System(rm /var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573.sln)") in new stack	
6995	[2026-04-29 11:16:27] VERBOSE[9648][C-00000035] pbx.c: Executing [h@app-pagegroups:4] ExecIf("Local/956324785421@ext-paging-0000002e;2", "0?System(rm -f /var/spool/asterisk/outgoing/pagingpro_call_1777432587.142202038)") in new stack	
6996	[2026-04-29 11:16:27] VERBOSE[9650] dial.c: Called s@app-page-stream	
6997	[2026-04-29 11:16:27] VERBOSE[9651][C-00000036] pbx.c: Executing [s@app-page-stream:1] Wait("Local/s@app-page-stream-0000002f;2", "1") in new stack	
6998	[2026-04-29 11:16:27] VERBOSE[9653] dial.c: Called PAGE36@app-paging/n	
6999	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [PAGE36@app-paging:1] Gosub("Local/PAGE36@app-paging-00000030;2", "macro-autoanswer,s,1(36)") in new stack	
7000	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:1] GotoIf("Local/PAGE36@app-paging-00000030;2", "1?knowndial") in new stack	
7001	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx_builtins.c: Goto (macro-autoanswer,s,19)	
7002	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:19] Set("Local/PAGE36@app-paging-00000030;2", "DIAL=PJSIP/36/sip:[email protected]:58919;transport=TCP") in new stack	
7003	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:20] GotoIf("Local/PAGE36@app-paging-00000030;2", "0?macro") in new stack	
7004	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:21] GotoIf("Local/PAGE36@app-paging-00000030;2", "1?pjsipua") in new stack	
7005	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx_builtins.c: Goto (macro-autoanswer,s,24)	
7006	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:24] Set("Local/PAGE36@app-paging-00000030;2", "AOR=36") in new stack	
7007	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:25] Set("Local/PAGE36@app-paging-00000030;2", "CONTACT=36;@77255a45c912190a9fc11ba950a8f34a") in new stack	
7008	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:26] Set("Local/PAGE36@app-paging-00000030;2", "USERAGENT=ATCOM A48-2.8.9.b2105 808287103C38") in new stack	
7009	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:27] ExecIf("Local/PAGE36@app-paging-00000030;2", "1?Set(USERAGENT=ATCOM A48-2.8.9.b2105 808287103C38)") in new stack	
7010	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:28] ExecIf("Local/PAGE36@app-paging-00000030;2", "1?Set(PAGE_VOL=;volume=2)") in new stack	
7011	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:29] ExecIf("Local/PAGE36@app-paging-00000030;2", "0?Set(ALERTINFO=ring-answer)") in new stack	
7012	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:30] ExecIf("Local/PAGE36@app-paging-00000030;2", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack	
7013	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:31] ExecIf("Local/PAGE36@app-paging-00000030;2", "0?Set(ALERTINFO=<http://example.com>;info=alert-autoanswer)") in new stack	
7014	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:32] ExecIf("Local/PAGE36@app-paging-00000030;2", "0?Set(ALERTINFO=Intercom)") in new stack	
7015	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:33] ExecIf("Local/PAGE36@app-paging-00000030;2", "0?Set(ALERTINFO=Alert-Info: Auto Answer)") in new stack	
7016	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:34] ExecIf("Local/PAGE36@app-paging-00000030;2", "0?Set(ALERTINFO=Direct-Intercom)") in new stack	
7017	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:35] ExecIf("Local/PAGE36@app-paging-00000030;2", "0?Set(ALERTINFO=ring-answer)") in new stack	
7018	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:36] ExecIf("Local/PAGE36@app-paging-00000030;2", "0?Set(ALERTINFO=<http://www.sangoma.com>;info=external;volume=2)") in new stack	
7019	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:37] ExecIf("Local/PAGE36@app-paging-00000030;2", "0?Set(ALERTINFO=Direct-Intercom)") in new stack	
7020	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:38] ExecIf("Local/PAGE36@app-paging-00000030;2", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack	
7021	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@macro-autoanswer:39] Return("Local/PAGE36@app-paging-00000030;2", "") in new stack	
7022	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [PAGE36@app-paging:2] NoOp("Local/PAGE36@app-paging-00000030;2", "") in new stack	
7023	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [PAGE36@app-paging:3] GotoIf("Local/PAGE36@app-paging-00000030;2", "1?doptions") in new stack	
7024	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx_builtins.c: Goto (app-paging,PAGE36,6)	
7025	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [PAGE36@app-paging:6] ExecIf("Local/PAGE36@app-paging-00000030;2", "1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0)))") in new stack	
7026	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [PAGE36@app-paging:7] Dial("Local/PAGE36@app-paging-00000030;2", "PJSIP/36/sip:[email protected]:58919;transport=TCP,5,A(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack	
7027	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] res_agi.c: <Local/956324785421@ext-paging-0000002d;2>AGI Script agi://127.0.0.1/page.agi completed, returning 0	
7028	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:15] Set("Local/956324785421@ext-paging-0000002d;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack	
7029	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:16] Set("Local/956324785421@ext-paging-0000002d;2", "CONFBRIDGE(user,admin)=yes") in new stack	
7030	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:17] Set("Local/956324785421@ext-paging-0000002d;2", "CONFBRIDGE(user,marked)=yes") in new stack	
7031	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:18] Answer("Local/956324785421@ext-paging-0000002d;2", "") in new stack	
7032	[2026-04-29 11:16:27] VERBOSE[9637] dial.c: Local/956324785421@ext-paging-0000002d;1 answered	
7033	[2026-04-29 11:16:27] VERBOSE[9637][C-00000038] pbx.c: Executing [s@pagingpro-play-message:1] Set("Local/956324785421@ext-paging-0000002d;1", "i=1") in new stack	
7034	[2026-04-29 11:16:27] VERBOSE[9637][C-00000038] pbx.c: Executing [s@pagingpro-play-message:2] While("Local/956324785421@ext-paging-0000002d;1", "1") in new stack	
7035	[2026-04-29 11:16:27] VERBOSE[9637][C-00000038] pbx.c: Executing [s@pagingpro-play-message:3] WaitForSilence("Local/956324785421@ext-paging-0000002d;1", "2000") in new stack	
7036	[2026-04-29 11:16:27] VERBOSE[9637][C-00000038] app_waitforsilence.c: Waiting 1 time(s) for 2000ms of silence with 0s timeout	
7037	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] app_stack.c: PJSIP/36-00000010 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start	
7038	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@autoanswer:1] GosubIf("PJSIP/36-00000010", "1?func-set-sipheader,s,1(Alert-Info,Ring Answer)") in new stack	
7039	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-set-sipheader:1] NoOp("PJSIP/36-00000010", "Sip Add Header function called. Adding Alert-Info = Ring Answer") in new stack	
7040	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-set-sipheader:2] Set("PJSIP/36-00000010", "HASH(__SIPHEADERS,Alert-Info)=Ring Answer") in new stack	
7041	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-set-sipheader:3] Return("PJSIP/36-00000010", "") in new stack	
7042	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@autoanswer:2] GosubIf("PJSIP/36-00000010", "1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)") in new stack	
7043	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-set-sipheader:1] NoOp("PJSIP/36-00000010", "Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0") in new stack	
7044	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-set-sipheader:2] Set("PJSIP/36-00000010", "HASH(__SIPHEADERS,Call-Info)=<uri>;answer-after=0") in new stack	
7045	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-set-sipheader:3] Return("PJSIP/36-00000010", "") in new stack	
7046	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@autoanswer:3] Gosub("PJSIP/36-00000010", "func-apply-sipheaders,s,1()") in new stack	
7047	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/36-00000010", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack	
7048	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/36-00000010", "Applying SIP Headers to channel PJSIP/36-00000010") in new stack	
7049	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/36-00000010", "localchan=36-00000010") in new stack	
7050	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/36-00000010", "DialMCEXT=36") in new stack	
7051	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:5] Set("PJSIP/36-00000010", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,36,1") in new stack	
7052	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/36-00000010", "Dchan=36-00000010") in new stack	
7053	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:7] Set("PJSIP/36-00000010", "TECH=PJSIP") in new stack	
7054	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:8] Set("PJSIP/36-00000010", "SIPHEADERKEYS=Call-Info,Alert-Info") in new stack	
7055	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:9] While("PJSIP/36-00000010", "1") in new stack	
7056	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:10] Set("PJSIP/36-00000010", "sipheader=<uri>;answer-after=0") in new stack	
7057	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/36-00000010", "0?Set(PJSIP_HEADER(remove,Call-Info)=)") in new stack	
7058	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/36-00000010", "0?Set(sipheader=<http://127.0.0.1>;info=<uri>;answer-after=0)") in new stack	
7059	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf("PJSIP/36-00000010", "0?Set(sipheader=<http://127.0.0.1><uri>;answer-after=0)") in new stack	
7060	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:14] ExecIf("PJSIP/36-00000010", "1?Set(PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0)") in new stack	
7061	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:15] EndWhile("PJSIP/36-00000010", "") in new stack	
7062	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:9] While("PJSIP/36-00000010", "1") in new stack	
7063	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:10] Set("PJSIP/36-00000010", "sipheader=Ring Answer") in new stack	
7064	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/36-00000010", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack	
7065	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/36-00000010", "1?Set(sipheader=<http://127.0.0.1>;info=Ring Answer)") in new stack	
7066	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf("PJSIP/36-00000010", "0?Set(sipheader=<http://127.0.0.1><http://127.0.0.1>;info=Ring Answer)") in new stack	
7067	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:14] ExecIf("PJSIP/36-00000010", "1?Set(PJSIP_HEADER(add,Alert-Info)=<http://127.0.0.1>;info=Ring Answer)") in new stack	
7068	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:15] EndWhile("PJSIP/36-00000010", "") in new stack

kwun · April 29, 2026, 9:28am

7069	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:9] While("PJSIP/36-00000010", "0") in new stack	
7070	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@func-apply-sipheaders:16] Return("PJSIP/36-00000010", "") in new stack	
7071	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] pbx.c: Executing [s@autoanswer:4] Return("PJSIP/36-00000010", "") in new stack	
7072	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] app_stack.c: Spawn extension (from-internal, PAGE36, 1) exited non-zero on 'PJSIP/36-00000010'	
7073	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] app_stack.c: PJSIP/36-00000010 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=	
7074	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] app_dial.c: Called PJSIP/36/sip:[email protected]:58919;transport=TCP	
7075	[2026-04-29 11:16:27] VERBOSE[2485] netsock2.c: Using SIP RTP Audio TOS bits 184	
7076	[2026-04-29 11:16:27] VERBOSE[2485] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.	
7077	[2026-04-29 11:16:27] VERBOSE[2485] netsock2.c: Using SIP RTP Audio CoS mark 5	
7078	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] app_dial.c: PJSIP/36-00000010 is ringing	
7079	[2026-04-29 11:16:27] VERBOSE[9653] dial.c: Local/PAGE36@app-paging-00000030;1 is ringing	
7080	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] app_dial.c: PJSIP/36-00000010 answered Local/PAGE36@app-paging-00000030;2	
7081	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] file.c: <PJSIP/36-00000010> Playing 'beep.ulaw' (language 'cth')	
7082	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] pbx.c: Executing [956324785421@app-pagegroups:19] ConfBridge("Local/956324785421@ext-paging-0000002d;2", "1777432587963,,,admin_menu") in new stack	
7083	[2026-04-29 11:16:27] VERBOSE[9660][C-00000034] bridge_channel.c: Channel CBAnn/1777432587963-00000031;2 joined 'softmix' base-bridge <4a521b00-2435-4a2d-bdaf-4917680e0b89>	
7084	[2026-04-29 11:16:27] VERBOSE[9638][C-00000034] bridge_channel.c: Channel Local/956324785421@ext-paging-0000002d;2 joined 'softmix' base-bridge <4a521b00-2435-4a2d-bdaf-4917680e0b89>	
7085	[2026-04-29 11:16:27] VERBOSE[9653] dial.c: Local/PAGE36@app-paging-00000030;1 answered	
7086	[2026-04-29 11:16:27] VERBOSE[9653] bridge_channel.c: Channel Local/PAGE36@app-paging-00000030;1 joined 'softmix' base-bridge <4a521b00-2435-4a2d-bdaf-4917680e0b89>	
7087	[2026-04-29 11:16:27] VERBOSE[9661][C-00000037] bridge_channel.c: Channel PJSIP/36-00000010 joined 'simple_bridge' basic-bridge <850109bd-a947-4439-8aed-bba3476c4c77>	
7088	[2026-04-29 11:16:27] VERBOSE[9654][C-00000037] bridge_channel.c: Channel Local/PAGE36@app-paging-00000030;2 joined 'simple_bridge' basic-bridge <850109bd-a947-4439-8aed-bba3476c4c77>	
7089	[2026-04-29 11:16:28] VERBOSE[9651][C-00000036] pbx.c: Executing [s@app-page-stream:2] Answer("Local/s@app-page-stream-0000002f;2", "") in new stack	
7090	[2026-04-29 11:16:28] VERBOSE[9650] dial.c: Local/s@app-page-stream-0000002f;1 answered	
7091	[2026-04-29 11:16:28] VERBOSE[9651][C-00000036] pbx.c: Executing [s@app-page-stream:3] Set("Local/s@app-page-stream-0000002f;2", "CHANNEL(language)=en") in new stack	
7092	[2026-04-29 11:16:28] VERBOSE[9651][C-00000036] pbx.c: Executing [s@app-page-stream:4] Set("Local/s@app-page-stream-0000002f;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack	
7093	[2026-04-29 11:16:28] VERBOSE[9651][C-00000036] pbx.c: Executing [s@app-page-stream:5] Set("Local/s@app-page-stream-0000002f;2", "CONFBRIDGE(user,marked)=yes") in new stack	
7094	[2026-04-29 11:16:28] VERBOSE[9651][C-00000036] pbx.c: Executing [s@app-page-stream:6] ExecIf("Local/s@app-page-stream-0000002f;2", "0?Set(CONFBRIDGE(user,startmuted)=yes)") in new stack	
7095	[2026-04-29 11:16:28] VERBOSE[9651][C-00000036] pbx.c: Executing [s@app-page-stream:7] ExecIf("Local/s@app-page-stream-0000002f;2", "0?Set(CONFBRIDGE(user,marked)=yes)") in new stack	
7096	[2026-04-29 11:16:28] VERBOSE[9651][C-00000036] pbx.c: Executing [s@app-page-stream:8] ConfBridge("Local/s@app-page-stream-0000002f;2", "1777432587963,,,") in new stack	
7097	[2026-04-29 11:16:28] VERBOSE[9651][C-00000036] bridge_channel.c: Channel Local/s@app-page-stream-0000002f;2 joined 'softmix' base-bridge <4a521b00-2435-4a2d-bdaf-4917680e0b89>	
7098	[2026-04-29 11:16:29] VERBOSE[9637][C-00000038] app_waitforsilence.c: Exiting with 2000ms of silence >= 2000ms required	
7099	[2026-04-29 11:16:29] VERBOSE[9637][C-00000038] pbx.c: Executing [s@pagingpro-play-message:4] Playback("Local/956324785421@ext-paging-0000002d;1", "/var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573") in new stack	
7100	[2026-04-29 11:16:29] VERBOSE[9637][C-00000038] file.c: <Local/956324785421@ext-paging-0000002d;1> Playing '/var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573.slin' (language 'en')	
7101	[2026-04-29 11:16:32] VERBOSE[9637][C-00000038] pbx.c: Executing [s@pagingpro-play-message:5] Set("Local/956324785421@ext-paging-0000002d;1", "i=2") in new stack	
7102	[2026-04-29 11:16:32] VERBOSE[9637][C-00000038] pbx.c: Executing [s@pagingpro-play-message:6] EndWhile("Local/956324785421@ext-paging-0000002d;1", "") in new stack	
7103	[2026-04-29 11:16:32] VERBOSE[9637][C-00000038] pbx.c: Executing [s@pagingpro-play-message:2] While("Local/956324785421@ext-paging-0000002d;1", "0") in new stack	
7104	[2026-04-29 11:16:32] VERBOSE[9637][C-00000038] pbx.c: Executing [s@pagingpro-play-message:7] Hangup("Local/956324785421@ext-paging-0000002d;1", "") in new stack	
7105	[2026-04-29 11:16:32] VERBOSE[9637][C-00000038] pbx.c: Spawn extension (pagingpro-play-message, s, 7) exited non-zero on 'Local/956324785421@ext-paging-0000002d;1'	
7106	[2026-04-29 11:16:32] VERBOSE[9638][C-00000034] bridge_channel.c: Channel Local/956324785421@ext-paging-0000002d;2 left 'softmix' base-bridge <4a521b00-2435-4a2d-bdaf-4917680e0b89>	
7107	[2026-04-29 11:16:32] VERBOSE[9638][C-00000034] pbx.c: Executing [h@app-pagegroups:1] ExecIf("Local/956324785421@ext-paging-0000002d;2", "1?Set(DEVICE_STATE(Custom:PAGE956324785421)=NOT_INUSE)") in new stack	
7108	[2026-04-29 11:16:32] VERBOSE[9638][C-00000034] pbx.c: Executing [h@app-pagegroups:2] GosubIf("Local/956324785421@ext-paging-0000002d;2", "0?record-page,1()") in new stack	
7109	[2026-04-29 11:16:32] VERBOSE[9638][C-00000034] pbx.c: Executing [h@app-pagegroups:3] ExecIf("Local/956324785421@ext-paging-0000002d;2", "1?System(rm /var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573.sln)") in new stack	
7110	[2026-04-29 11:16:32] VERBOSE[9638][C-00000034] pbx.c: Executing [h@app-pagegroups:4] ExecIf("Local/956324785421@ext-paging-0000002d;2", "1?System(rm -f /var/spool/asterisk/outgoing/pagingpro_call_1777432587.0147329236)") in new stack	
7111	[2026-04-29 11:16:33] VERBOSE[9651][C-00000036] bridge_channel.c: Channel Local/s@app-page-stream-0000002f;2 left 'softmix' base-bridge <4a521b00-2435-4a2d-bdaf-4917680e0b89>	
7112	[2026-04-29 11:16:33] VERBOSE[9653] bridge_channel.c: Channel Local/PAGE36@app-paging-00000030;1 left 'softmix' base-bridge <4a521b00-2435-4a2d-bdaf-4917680e0b89>	
7113	[2026-04-29 11:16:33] VERBOSE[9660][C-00000034] bridge_channel.c: Channel CBAnn/1777432587963-00000031;2 left 'softmix' base-bridge <4a521b00-2435-4a2d-bdaf-4917680e0b89>	
7114	[2026-04-29 11:16:33] VERBOSE[9654][C-00000037] bridge_channel.c: Channel Local/PAGE36@app-paging-00000030;2 left 'simple_bridge' basic-bridge <850109bd-a947-4439-8aed-bba3476c4c77>	
7115	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] bridge_channel.c: Channel PJSIP/36-00000010 left 'simple_bridge' basic-bridge <850109bd-a947-4439-8aed-bba3476c4c77>	
7116	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] app_stack.c: PJSIP/36-00000010 Internal Gosub(app-missedcall-hangup,36,1) start	
7117	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] pbx.c: Executing [36@app-missedcall-hangup:1] NoOp("PJSIP/36-00000010", "Dialed: 36") in new stack	
7118	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] pbx.c: Executing [36@app-missedcall-hangup:2] NoOp("PJSIP/36-00000010", "Caller: ") in new stack	
7119	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] pbx.c: Executing [36@app-missedcall-hangup:3] GotoIf("PJSIP/36-00000010", "0?exit") in new stack	
7120	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] pbx.c: Executing [36@app-missedcall-hangup:4] Set("PJSIP/36-00000010", "EXTENNUM=36") in new stack	
7121	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] pbx.c: Executing [36@app-missedcall-hangup:5] Set("PJSIP/36-00000010", "FEXTENNUM=36") in new stack	
7122	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] pbx.c: Executing [36@app-missedcall-hangup:6] GotoIf("PJSIP/36-00000010", "1?exit") in new stack	
7123	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] pbx_builtins.c: Goto (app-missedcall-hangup,36,8)	
7124	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] pbx.c: Executing [36@app-missedcall-hangup:8] Return("PJSIP/36-00000010", "") in new stack	
7125	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] app_stack.c: Spawn extension (from-internal, , 1) exited non-zero on 'PJSIP/36-00000010'	
7126	[2026-04-29 11:16:33] VERBOSE[9661][C-00000037] app_stack.c: PJSIP/36-00000010 Internal Gosub(app-missedcall-hangup,36,1) complete GOSUB_RETVAL=	
7127	[2026-04-29 11:16:33] VERBOSE[9654][C-00000037] pbx.c: Spawn extension (app-paging, PAGE36, 7) exited non-zero on 'Local/PAGE36@app-paging-00000030;2'	
7128	[2026-04-29 11:16:57] VERBOSE[9723] pbx_spool.c: Attempting call on Local/956324785421@ext-paging for s@pagingpro-play-message:1 (Retry 2)	
7129	[2026-04-29 11:16:57] VERBOSE[9723] dial.c: Called 956324785421@ext-paging	
7130	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@ext-paging:1] Goto("Local/956324785421@ext-paging-00000032;2", "app-pagegroups,956324785421,1") in new stack	
7131	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx_builtins.c: Goto (app-pagegroups,956324785421,1)	
7132	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:1] Gosub("Local/956324785421@ext-paging-00000032;2", "macro-user-callerid,s,1()") in new stack	
7133	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/956324785421@ext-paging-00000032;2", "TOUCH_MONITOR=1777432617.201") in new stack	
7134	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/956324785421@ext-paging-00000032;2", "CHANCONTEXT=ext-paging-00000032;2") in new stack	
7135	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:3] Set("Local/956324785421@ext-paging-00000032;2", "CHANCONTEXT=ext") in new stack	
7136	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:4] Set("Local/956324785421@ext-paging-00000032;2", "CHANEXTENCONTEXT=956324785421@ext-paging-00000032;2") in new stack	
7137	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/956324785421@ext-paging-00000032;2", "CHANEXTEN=956324785421") in new stack	
7138	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:6] Set("Local/956324785421@ext-paging-00000032;2", "CALLERID(number)=37") in new stack	
7139	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/956324785421@ext-paging-00000032;2", "AMPUSER=37") in new stack	
7140	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:8] Set("Local/956324785421@ext-paging-00000032;2", "HOTDESCKCHAN=956324785421@ext-paging-00000032;2") in new stack	
7141	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:9] Set("Local/956324785421@ext-paging-00000032;2", "HOTDESKEXTEN=956324785421@ext") in new stack	
7142	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:10] Set("Local/956324785421@ext-paging-00000032;2", "HOTDESKCALL=0") in new stack	
7143	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("Local/956324785421@ext-paging-00000032;2", "0?Set(HOTDESKCALL=1)") in new stack	
7144	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("Local/956324785421@ext-paging-00000032;2", "0?Set(CALLERID(name)=)") in new stack	
7145	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("Local/956324785421@ext-paging-00000032;2", "0?report") in new stack	
7146	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("Local/956324785421@ext-paging-00000032;2", "1?Set(REALCALLERIDNUM=37)") in new stack	
7147	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:15] Set("Local/956324785421@ext-paging-00000032;2", "AMPUSER=37") in new stack	
7148	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("Local/956324785421@ext-paging-00000032;2", "0?limit") in new stack	
7149	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:17] Set("Local/956324785421@ext-paging-00000032;2", "AMPUSERCIDNAME=37") in new stack	
7150	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("Local/956324785421@ext-paging-00000032;2", "0?Set(__CIDMASQUERADING=TRUE)") in new stack	
7151	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("Local/956324785421@ext-paging-00000032;2", "0?report") in new stack	
7152	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:20] Set("Local/956324785421@ext-paging-00000032;2", "AMPUSERCID=37") in new stack	
7153	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:21] Set("Local/956324785421@ext-paging-00000032;2", "__DIAL_OPTIONS=HhTtr") in new stack	
7154	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:22] Set("Local/956324785421@ext-paging-00000032;2", "CALLERID(all)="37" <37>") in new stack	
7155	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("Local/956324785421@ext-paging-00000032;2", "0?Set(CUSDIAL=)") in new stack	
7156	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("Local/956324785421@ext-paging-00000032;2", "0?Set(CALLERID(all)="37" <37>)") in new stack	
7157	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("Local/956324785421@ext-paging-00000032;2", "0?limit") in new stack	
7158	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("Local/956324785421@ext-paging-00000032;2", "0?Set(GROUP(concurrency_limit)=37)") in new stack	
7159	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("Local/956324785421@ext-paging-00000032;2", "0?Set(CHANNEL(language)=)") in new stack	
7160	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:28] NoOp("Local/956324785421@ext-paging-00000032;2", "Macro depricated!! To keep the same line numbers") in new stack	
7161	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:29] NoOp("Local/956324785421@ext-paging-00000032;2", "Macro depricated !! To keep the same line numbers") in new stack	
7162	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("Local/956324785421@ext-paging-00000032;2", "0?continue") in new stack	
7163	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("Local/956324785421@ext-paging-00000032;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack	
7164	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:32] Set("Local/956324785421@ext-paging-00000032;2", "__TTL=64") in new stack	
7165	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("Local/956324785421@ext-paging-00000032;2", "1?continue") in new stack	
7166	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx_builtins.c: Goto (macro-user-callerid,s,49)	
7167	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:49] Set("Local/956324785421@ext-paging-00000032;2", "CALLERID(number)=37") in new stack	
7168	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:50] Set("Local/956324785421@ext-paging-00000032;2", "CALLERID(name)=37") in new stack	
7169	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("Local/956324785421@ext-paging-00000032;2", "0?cnum") in new stack	
7170	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:52] Set("Local/956324785421@ext-paging-00000032;2", "__MCNUM=37") in new stack	
7171	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:53] Set("Local/956324785421@ext-paging-00000032;2", "__MCNAME=37") in new stack	
7172	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:54] Set("Local/956324785421@ext-paging-00000032;2", "__MCEXTEN=37") in new stack	
7173	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:55] Set("Local/956324785421@ext-paging-00000032;2", "__MCORGCHAN=Local/956324785421@ext-paging-00000032;2") in new stack	
7174	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:56] Set("Local/956324785421@ext-paging-00000032;2", "CDR(cnam)=37") in new stack	
7175	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:57] Set("Local/956324785421@ext-paging-00000032;2", "CDR(cnum)=37") in new stack	
7176	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [s@macro-user-callerid:58] Return("Local/956324785421@ext-paging-00000032;2", "") in new stack	
7177	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:2] Set("Local/956324785421@ext-paging-00000032;2", "_PAGEGROUP=956324785421") in new stack	
7178	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:3] Set("Local/956324785421@ext-paging-00000032;2", "_PVOL=2") in new stack	
7179	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:4] Set("Local/956324785421@ext-paging-00000032;2", "ANNOUNCE_COUNT=1") in new stack	
7180	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:5] ExecIf("Local/956324785421@ext-paging-00000032;2", "0?Goto(busy)") in new stack	
7181	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:6] GotoIf("Local/956324785421@ext-paging-00000032;2", "1?:busy") in new stack	
7182	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:7] Set("Local/956324785421@ext-paging-00000032;2", "DEVICE_STATE(Custom:PAGE956324785421)=INUSE") in new stack	
7183	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:8] Gosub("Local/956324785421@ext-paging-00000032;2", "app-paging,ssetup,1()") in new stack	
7184	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [ssetup@app-paging:1] Set("Local/956324785421@ext-paging-00000032;2", "_SIPURI=") in new stack	
7185	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [ssetup@app-paging:2] Set("Local/956324785421@ext-paging-00000032;2", "_ALERTINFO=Ring Answer") in new stack	
7186	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [ssetup@app-paging:3] Set("Local/956324785421@ext-paging-00000032;2", "_CALLINFO=<uri>;answer-after=0") in new stack	
7187	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [ssetup@app-paging:4] Set("Local/956324785421@ext-paging-00000032;2", "_SIPURI=intercom=true") in new stack	
7188	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [ssetup@app-paging:5] Set("Local/956324785421@ext-paging-00000032;2", "_DTIME=5") in new stack	
7189	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [ssetup@app-paging:6] Set("Local/956324785421@ext-paging-00000032;2", "_ANSWERMACRO=") in new stack	
7190	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [ssetup@app-paging:7] Set("Local/956324785421@ext-paging-00000032;2", "PAGE_CONF=1777432617552") in new stack	
7191	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [ssetup@app-paging:8] Return("Local/956324785421@ext-paging-00000032;2", "") in new stack	
7192	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:9] Set("Local/956324785421@ext-paging-00000032;2", "PAGEMODE=PAGE") in new stack	
7193	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:10] Set("Local/956324785421@ext-paging-00000032;2", "PAGE_MEMBERS=36") in new stack	
7194	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:11] Set("Local/956324785421@ext-paging-00000032;2", "PAGE_CONF_OPTS=") in new stack	
7195	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:12] Set("Local/956324785421@ext-paging-00000032;2", "ANNOUNCEMENT=beep") in new stack	
7196	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:13] Set("Local/956324785421@ext-paging-00000032;2", "STREAM=NONE") in new stack	
7197	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:14] AGI("Local/956324785421@ext-paging-00000032;2", "agi://127.0.0.1/page.agi") in new stack	
7198	[2026-04-29 11:16:57] VERBOSE[9744] dial.c: Called s@app-page-stream	
7199	[2026-04-29 11:16:57] VERBOSE[9745][C-0000003a] pbx.c: Executing [s@app-page-stream:1] Wait("Local/s@app-page-stream-00000033;2", "1") in new stack	
7200	[2026-04-29 11:16:57] VERBOSE[9747] dial.c: Called PAGE36@app-paging/n	
7201	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [PAGE36@app-paging:1] Gosub("Local/PAGE36@app-paging-00000034;2", "macro-autoanswer,s,1(36)") in new stack	
7202	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:1] GotoIf("Local/PAGE36@app-paging-00000034;2", "1?knowndial") in new stack	
7203	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx_builtins.c: Goto (macro-autoanswer,s,19)	
7204	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:19] Set("Local/PAGE36@app-paging-00000034;2", "DIAL=PJSIP/36/sip:[email protected]:58919;transport=TCP") in new stack	
7205	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:20] GotoIf("Local/PAGE36@app-paging-00000034;2", "0?macro") in new stack	
7206	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:21] GotoIf("Local/PAGE36@app-paging-00000034;2", "1?pjsipua") in new stack	
7207	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx_builtins.c: Goto (macro-autoanswer,s,24)	
7208	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:24] Set("Local/PAGE36@app-paging-00000034;2", "AOR=36") in new stack	
7209	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:25] Set("Local/PAGE36@app-paging-00000034;2", "CONTACT=36;@77255a45c912190a9fc11ba950a8f34a") in new stack	
7210	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:26] Set("Local/PAGE36@app-paging-00000034;2", "USERAGENT=ATCOM A48-2.8.9.b2105 808287103C38") in new stack	
7211	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:27] ExecIf("Local/PAGE36@app-paging-00000034;2", "1?Set(USERAGENT=ATCOM A48-2.8.9.b2105 808287103C38)") in new stack	
7212	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:28] ExecIf("Local/PAGE36@app-paging-00000034;2", "1?Set(PAGE_VOL=;volume=2)") in new stack	
7213	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:29] ExecIf("Local/PAGE36@app-paging-00000034;2", "0?Set(ALERTINFO=ring-answer)") in new stack	
7214	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:30] ExecIf("Local/PAGE36@app-paging-00000034;2", "0?Set(CALLINFO=<sip:broadworks.net>;answer-after=0)") in new stack	
7215	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:31] ExecIf("Local/PAGE36@app-paging-00000034;2", "0?Set(ALERTINFO=<http://example.com>;info=alert-autoanswer)") in new stack	
7216	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:32] ExecIf("Local/PAGE36@app-paging-00000034;2", "0?Set(ALERTINFO=Intercom)") in new stack	
7217	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:33] ExecIf("Local/PAGE36@app-paging-00000034;2", "0?Set(ALERTINFO=Alert-Info: Auto Answer)") in new stack	
7218	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:34] ExecIf("Local/PAGE36@app-paging-00000034;2", "0?Set(ALERTINFO=Direct-Intercom)") in new stack

kwun · April 29, 2026, 9:28am

7219	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:35] ExecIf("Local/PAGE36@app-paging-00000034;2", "0?Set(ALERTINFO=ring-answer)") in new stack	
7220	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:36] ExecIf("Local/PAGE36@app-paging-00000034;2", "0?Set(ALERTINFO=<http://www.sangoma.com>;info=external;volume=2)") in new stack	
7221	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:37] ExecIf("Local/PAGE36@app-paging-00000034;2", "0?Set(ALERTINFO=Direct-Intercom)") in new stack	
7222	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:38] ExecIf("Local/PAGE36@app-paging-00000034;2", "1?Set(__SIP_URI_OPTIONS=intercom=true)") in new stack	
7223	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@macro-autoanswer:39] Return("Local/PAGE36@app-paging-00000034;2", "") in new stack	
7224	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [PAGE36@app-paging:2] NoOp("Local/PAGE36@app-paging-00000034;2", "") in new stack	
7225	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [PAGE36@app-paging:3] GotoIf("Local/PAGE36@app-paging-00000034;2", "1?doptions") in new stack	
7226	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx_builtins.c: Goto (app-paging,PAGE36,6)	
7227	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [PAGE36@app-paging:6] ExecIf("Local/PAGE36@app-paging-00000034;2", "1?Set(_DOPTIONS=b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0)))") in new stack	
7228	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [PAGE36@app-paging:7] Dial("Local/PAGE36@app-paging-00000034;2", "PJSIP/36/sip:[email protected]:58919;transport=TCP,5,A(beep)b(autoanswer^s^1(Ring Answer,<uri>;answer-after=0))") in new stack	
7229	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] app_stack.c: PJSIP/36-00000011 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) start	
7230	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@autoanswer:1] GosubIf("PJSIP/36-00000011", "1?func-set-sipheader,s,1(Alert-Info,Ring Answer)") in new stack	
7231	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-set-sipheader:1] NoOp("PJSIP/36-00000011", "Sip Add Header function called. Adding Alert-Info = Ring Answer") in new stack	
7232	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-set-sipheader:2] Set("PJSIP/36-00000011", "HASH(__SIPHEADERS,Alert-Info)=Ring Answer") in new stack	
7233	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-set-sipheader:3] Return("PJSIP/36-00000011", "") in new stack	
7234	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@autoanswer:2] GosubIf("PJSIP/36-00000011", "1?func-set-sipheader,s,1(Call-Info,<uri>;answer-after=0)") in new stack	
7235	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-set-sipheader:1] NoOp("PJSIP/36-00000011", "Sip Add Header function called. Adding Call-Info = <uri>;answer-after=0") in new stack	
7236	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-set-sipheader:2] Set("PJSIP/36-00000011", "HASH(__SIPHEADERS,Call-Info)=<uri>;answer-after=0") in new stack	
7237	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-set-sipheader:3] Return("PJSIP/36-00000011", "") in new stack	
7238	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@autoanswer:3] Gosub("PJSIP/36-00000011", "func-apply-sipheaders,s,1()") in new stack	
7239	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/36-00000011", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack	
7240	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/36-00000011", "Applying SIP Headers to channel PJSIP/36-00000011") in new stack	
7241	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/36-00000011", "localchan=36-00000011") in new stack	
7242	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/36-00000011", "DialMCEXT=36") in new stack	
7243	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:5] Set("PJSIP/36-00000011", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,36,1") in new stack	
7244	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/36-00000011", "Dchan=36-00000011") in new stack	
7245	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:7] Set("PJSIP/36-00000011", "TECH=PJSIP") in new stack	
7246	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:8] Set("PJSIP/36-00000011", "SIPHEADERKEYS=Call-Info,Alert-Info") in new stack	
7247	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:9] While("PJSIP/36-00000011", "1") in new stack	
7248	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:10] Set("PJSIP/36-00000011", "sipheader=<uri>;answer-after=0") in new stack	
7249	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/36-00000011", "0?Set(PJSIP_HEADER(remove,Call-Info)=)") in new stack	
7250	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] res_agi.c: <Local/956324785421@ext-paging-00000032;2>AGI Script agi://127.0.0.1/page.agi completed, returning 0	
7251	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:15] Set("Local/956324785421@ext-paging-00000032;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack	
7252	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:16] Set("Local/956324785421@ext-paging-00000032;2", "CONFBRIDGE(user,admin)=yes") in new stack	
7253	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:17] Set("Local/956324785421@ext-paging-00000032;2", "CONFBRIDGE(user,marked)=yes") in new stack	
7254	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:18] Answer("Local/956324785421@ext-paging-00000032;2", "") in new stack	
7255	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/36-00000011", "0?Set(sipheader=<http://127.0.0.1>;info=<uri>;answer-after=0)") in new stack	
7256	[2026-04-29 11:16:57] VERBOSE[9723] dial.c: Local/956324785421@ext-paging-00000032;1 answered	
7257	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf("PJSIP/36-00000011", "0?Set(sipheader=<http://127.0.0.1><uri>;answer-after=0)") in new stack	
7258	[2026-04-29 11:16:57] VERBOSE[9723][C-0000003c] pbx.c: Executing [s@pagingpro-play-message:1] Set("Local/956324785421@ext-paging-00000032;1", "i=1") in new stack	
7259	[2026-04-29 11:16:57] VERBOSE[9723][C-0000003c] pbx.c: Executing [s@pagingpro-play-message:2] While("Local/956324785421@ext-paging-00000032;1", "1") in new stack	
7260	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:14] ExecIf("PJSIP/36-00000011", "1?Set(PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0)") in new stack	
7261	[2026-04-29 11:16:57] VERBOSE[9723][C-0000003c] pbx.c: Executing [s@pagingpro-play-message:3] WaitForSilence("Local/956324785421@ext-paging-00000032;1", "2000") in new stack	
7262	[2026-04-29 11:16:57] VERBOSE[9723][C-0000003c] app_waitforsilence.c: Waiting 1 time(s) for 2000ms of silence with 0s timeout	
7263	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:15] EndWhile("PJSIP/36-00000011", "") in new stack	
7264	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:9] While("PJSIP/36-00000011", "1") in new stack	
7265	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:10] Set("PJSIP/36-00000011", "sipheader=Ring Answer") in new stack	
7266	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/36-00000011", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack	
7267	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/36-00000011", "1?Set(sipheader=<http://127.0.0.1>;info=Ring Answer)") in new stack	
7268	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf("PJSIP/36-00000011", "0?Set(sipheader=<http://127.0.0.1><http://127.0.0.1>;info=Ring Answer)") in new stack	
7269	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:14] ExecIf("PJSIP/36-00000011", "1?Set(PJSIP_HEADER(add,Alert-Info)=<http://127.0.0.1>;info=Ring Answer)") in new stack	
7270	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:15] EndWhile("PJSIP/36-00000011", "") in new stack	
7271	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:9] While("PJSIP/36-00000011", "0") in new stack	
7272	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@func-apply-sipheaders:16] Return("PJSIP/36-00000011", "") in new stack	
7273	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] pbx.c: Executing [s@autoanswer:4] Return("PJSIP/36-00000011", "") in new stack	
7274	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] app_stack.c: Spawn extension (from-internal, PAGE36, 1) exited non-zero on 'PJSIP/36-00000011'	
7275	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] app_stack.c: PJSIP/36-00000011 Internal Gosub(autoanswer,s,1(Ring Answer,<uri>;answer-after=0)) complete GOSUB_RETVAL=	
7276	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] app_dial.c: Called PJSIP/36/sip:[email protected]:58919;transport=TCP	
7277	[2026-04-29 11:16:57] VERBOSE[2485] netsock2.c: Using SIP RTP Audio TOS bits 184	
7278	[2026-04-29 11:16:57] VERBOSE[2485] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.	
7279	[2026-04-29 11:16:57] VERBOSE[2485] netsock2.c: Using SIP RTP Audio CoS mark 5	
7280	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] app_dial.c: PJSIP/36-00000011 is ringing	
7281	[2026-04-29 11:16:57] VERBOSE[9747] dial.c: Local/PAGE36@app-paging-00000034;1 is ringing	
7282	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] app_dial.c: PJSIP/36-00000011 answered Local/PAGE36@app-paging-00000034;2	
7283	[2026-04-29 11:16:57] VERBOSE[9748][C-0000003b] file.c: <PJSIP/36-00000011> Playing 'beep.ulaw' (language 'cth')	
7284	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] pbx.c: Executing [956324785421@app-pagegroups:19] ConfBridge("Local/956324785421@ext-paging-00000032;2", "1777432617552,,,admin_menu") in new stack	
7285	[2026-04-29 11:16:57] VERBOSE[9771][C-00000039] bridge_channel.c: Channel CBAnn/1777432617552-00000035;2 joined 'softmix' base-bridge <14665301-3c2b-483e-ba09-a09aacb4b1c0>	
7286	[2026-04-29 11:16:57] VERBOSE[9733][C-00000039] bridge_channel.c: Channel Local/956324785421@ext-paging-00000032;2 joined 'softmix' base-bridge <14665301-3c2b-483e-ba09-a09aacb4b1c0>	
7287	[2026-04-29 11:16:58] VERBOSE[9747] dial.c: Local/PAGE36@app-paging-00000034;1 answered	
7288	[2026-04-29 11:16:58] VERBOSE[9747] bridge_channel.c: Channel Local/PAGE36@app-paging-00000034;1 joined 'softmix' base-bridge <14665301-3c2b-483e-ba09-a09aacb4b1c0>	
7289	[2026-04-29 11:16:58] VERBOSE[9772][C-0000003b] bridge_channel.c: Channel PJSIP/36-00000011 joined 'simple_bridge' basic-bridge <232ccc05-049b-4a67-8420-0a2d8fc44956>	
7290	[2026-04-29 11:16:58] VERBOSE[9748][C-0000003b] bridge_channel.c: Channel Local/PAGE36@app-paging-00000034;2 joined 'simple_bridge' basic-bridge <232ccc05-049b-4a67-8420-0a2d8fc44956>	
7291	[2026-04-29 11:16:58] VERBOSE[9745][C-0000003a] pbx.c: Executing [s@app-page-stream:2] Answer("Local/s@app-page-stream-00000033;2", "") in new stack	
7292	[2026-04-29 11:16:58] VERBOSE[9744] dial.c: Local/s@app-page-stream-00000033;1 answered	
7293	[2026-04-29 11:16:58] VERBOSE[9745][C-0000003a] pbx.c: Executing [s@app-page-stream:3] Set("Local/s@app-page-stream-00000033;2", "CHANNEL(language)=en") in new stack	
7294	[2026-04-29 11:16:58] VERBOSE[9745][C-0000003a] pbx.c: Executing [s@app-page-stream:4] Set("Local/s@app-page-stream-00000033;2", "CONFBRIDGE(user,template)=page_user_duplex") in new stack	
7295	[2026-04-29 11:16:58] VERBOSE[9745][C-0000003a] pbx.c: Executing [s@app-page-stream:5] Set("Local/s@app-page-stream-00000033;2", "CONFBRIDGE(user,marked)=yes") in new stack	
7296	[2026-04-29 11:16:58] VERBOSE[9745][C-0000003a] pbx.c: Executing [s@app-page-stream:6] ExecIf("Local/s@app-page-stream-00000033;2", "0?Set(CONFBRIDGE(user,startmuted)=yes)") in new stack	
7297	[2026-04-29 11:16:58] VERBOSE[9745][C-0000003a] pbx.c: Executing [s@app-page-stream:7] ExecIf("Local/s@app-page-stream-00000033;2", "0?Set(CONFBRIDGE(user,marked)=yes)") in new stack	
7298	[2026-04-29 11:16:58] VERBOSE[9745][C-0000003a] pbx.c: Executing [s@app-page-stream:8] ConfBridge("Local/s@app-page-stream-00000033;2", "1777432617552,,,") in new stack	
7299	[2026-04-29 11:16:58] VERBOSE[9745][C-0000003a] bridge_channel.c: Channel Local/s@app-page-stream-00000033;2 joined 'softmix' base-bridge <14665301-3c2b-483e-ba09-a09aacb4b1c0>	
7300	[2026-04-29 11:16:59] VERBOSE[9723][C-0000003c] app_waitforsilence.c: Exiting with 2000ms of silence >= 2000ms required	
7301	[2026-04-29 11:16:59] VERBOSE[9723][C-0000003c] pbx.c: Executing [s@pagingpro-play-message:4] Playback("Local/956324785421@ext-paging-00000032;1", "/var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573") in new stack	
7302	[2026-04-29 11:16:59] WARNING[9723][C-0000003c] file.c: File /var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573 does not exist in any format	
7303	[2026-04-29 11:16:59] WARNING[9723][C-0000003c] file.c: Unable to open /var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573 (format (slin)): No such file or directory	
7304	[2026-04-29 11:16:59] WARNING[9723][C-0000003c] app_playback.c: Playback failed on Local/956324785421@ext-paging-00000032;1 for /var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573	
7305	[2026-04-29 11:16:59] VERBOSE[9723][C-0000003c] pbx.c: Executing [s@pagingpro-play-message:5] Set("Local/956324785421@ext-paging-00000032;1", "i=2") in new stack	
7306	[2026-04-29 11:16:59] VERBOSE[9723][C-0000003c] pbx.c: Executing [s@pagingpro-play-message:6] EndWhile("Local/956324785421@ext-paging-00000032;1", "") in new stack	
7307	[2026-04-29 11:16:59] VERBOSE[9723][C-0000003c] pbx.c: Executing [s@pagingpro-play-message:2] While("Local/956324785421@ext-paging-00000032;1", "0") in new stack	
7308	[2026-04-29 11:16:59] VERBOSE[9723][C-0000003c] pbx.c: Executing [s@pagingpro-play-message:7] Hangup("Local/956324785421@ext-paging-00000032;1", "") in new stack	
7309	[2026-04-29 11:16:59] VERBOSE[9723][C-0000003c] pbx.c: Spawn extension (pagingpro-play-message, s, 7) exited non-zero on 'Local/956324785421@ext-paging-00000032;1'	
7310	[2026-04-29 11:16:59] VERBOSE[9733][C-00000039] bridge_channel.c: Channel Local/956324785421@ext-paging-00000032;2 left 'softmix' base-bridge <14665301-3c2b-483e-ba09-a09aacb4b1c0>	
7311	[2026-04-29 11:16:59] VERBOSE[9733][C-00000039] pbx.c: Executing [h@app-pagegroups:1] ExecIf("Local/956324785421@ext-paging-00000032;2", "1?Set(DEVICE_STATE(Custom:PAGE956324785421)=NOT_INUSE)") in new stack	
7312	[2026-04-29 11:16:59] VERBOSE[9733][C-00000039] pbx.c: Executing [h@app-pagegroups:2] GosubIf("Local/956324785421@ext-paging-00000032;2", "0?record-page,1()") in new stack	
7313	[2026-04-29 11:16:59] VERBOSE[9733][C-00000039] pbx.c: Executing [h@app-pagegroups:3] ExecIf("Local/956324785421@ext-paging-00000032;2", "1?System(rm /var/spool/asterisk/tmp/PAGE_RECORDING-956324785421-1777432583573.sln)") in new stack	
7314	[2026-04-29 11:16:59] VERBOSE[9733][C-00000039] pbx.c: Executing [h@app-pagegroups:4] ExecIf("Local/956324785421@ext-paging-00000032;2", "1?System(rm -f /var/spool/asterisk/outgoing/pagingpro_call_1777432587.142202038)") in new stack	
7315	[2026-04-29 11:17:03] VERBOSE[9745][C-0000003a] bridge_channel.c: Channel Local/s@app-page-stream-00000033;2 left 'softmix' base-bridge <14665301-3c2b-483e-ba09-a09aacb4b1c0>	
7316	[2026-04-29 11:17:03] VERBOSE[9747] bridge_channel.c: Channel Local/PAGE36@app-paging-00000034;1 left 'softmix' base-bridge <14665301-3c2b-483e-ba09-a09aacb4b1c0>	
7317	[2026-04-29 11:17:03] VERBOSE[9771][C-00000039] bridge_channel.c: Channel CBAnn/1777432617552-00000035;2 left 'softmix' base-bridge <14665301-3c2b-483e-ba09-a09aacb4b1c0>	
7318	[2026-04-29 11:17:03] VERBOSE[9748][C-0000003b] bridge_channel.c: Channel Local/PAGE36@app-paging-00000034;2 left 'simple_bridge' basic-bridge <232ccc05-049b-4a67-8420-0a2d8fc44956>	
7319	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] bridge_channel.c: Channel PJSIP/36-00000011 left 'simple_bridge' basic-bridge <232ccc05-049b-4a67-8420-0a2d8fc44956>	
7320	[2026-04-29 11:17:03] VERBOSE[9748][C-0000003b] pbx.c: Spawn extension (app-paging, PAGE36, 7) exited non-zero on 'Local/PAGE36@app-paging-00000034;2'	
7321	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] app_stack.c: PJSIP/36-00000011 Internal Gosub(app-missedcall-hangup,36,1) start	
7322	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] pbx.c: Executing [36@app-missedcall-hangup:1] NoOp("PJSIP/36-00000011", "Dialed: 36") in new stack	
7323	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] pbx.c: Executing [36@app-missedcall-hangup:2] NoOp("PJSIP/36-00000011", "Caller: ") in new stack	
7324	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] pbx.c: Executing [36@app-missedcall-hangup:3] GotoIf("PJSIP/36-00000011", "0?exit") in new stack	
7325	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] pbx.c: Executing [36@app-missedcall-hangup:4] Set("PJSIP/36-00000011", "EXTENNUM=36") in new stack	
7326	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] pbx.c: Executing [36@app-missedcall-hangup:5] Set("PJSIP/36-00000011", "FEXTENNUM=36") in new stack	
7327	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] pbx.c: Executing [36@app-missedcall-hangup:6] GotoIf("PJSIP/36-00000011", "1?exit") in new stack	
7328	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] pbx_builtins.c: Goto (app-missedcall-hangup,36,8)	
7329	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] pbx.c: Executing [36@app-missedcall-hangup:8] Return("PJSIP/36-00000011", "") in new stack	
7330	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] app_stack.c: Spawn extension (from-internal, , 1) exited non-zero on 'PJSIP/36-00000011'	
7331	[2026-04-29 11:17:03] VERBOSE[9772][C-0000003b] app_stack.c: PJSIP/36-00000011 Internal Gosub(app-missedcall-hangup,36,1) complete GOSUB_RETVAL=	
7332	[2026-04-29 11:19:04] VERBOSE[2485] res_pjsip/pjsip_configuration.c: Endpoint 10 is now Unreachable	
7333	[2026-04-29 11:19:04] VERBOSE[2485] res_pjsip/pjsip_options.c: Contact 10/sip:[email protected]:51922 is now Unreachable. RTT: 0.000 msec	
7334	[2026-04-29 11:20:03] VERBOSE[10169] res_pjsip/pjsip_configuration.c: Endpoint 10 is now Reachable	
7335	[2026-04-29 11:20:03] VERBOSE[10169] res_pjsip/pjsip_options.c: Contact 10/sip:[email protected]:51922 is now Reachable. RTT: 1942.751 msec