Не могу понять причину/инициатора Хэнгапа
Добавлено: 25 сен 2023, 10:49
Здравствуйте, коллеги. Нужно узнать пичину хэнгапа. 2 входящих звонка на группу.
2 входящий
PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:1] Set("SIP/SIPNAME-00011f8d", "__FROM_DID=349") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:2] Gosub("SIP/SIPNAME-00011f8d", "app-blacklist-check,s,1") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/SIPNAME-00011f8d", "0?blacklisted") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/SIPNAME-00011f8d", "CALLED_BLACKLIST=1") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/SIPNAME-00011f8d", "") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:3] ExecIf("SIP/SIPNAME-00011f8d", "0 ?Set(CALLERID(name)=SIPNAME)") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:4] Set("SIP/SIPNAME-00011f8d", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:5] Set("SIP/SIPNAME-00011f8d", "CALLERPRES()=allowed_not_screened") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:6] Goto("SIP/SIPNAME-00011f8d", "ext-group,349,1") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (ext-group,349,1)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:1] Macro("SIP/SIPNAME-00011f8d", "user-callerid,") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/SIPNAME-00011f8d", "AMPUSER=SIPNAME") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/SIPNAME-00011f8d", "0?report") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/SIPNAME-00011f8d", "1?Set(REALCALLERIDNUM=SIPNAME)") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/SIPNAME-00011f8d", "AMPUSER=") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/SIPNAME-00011f8d", "AMPUSERCIDNAME=") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/SIPNAME-00011f8d", "1?report") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-user-callerid,s,10)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/SIPNAME-00011f8d", "0?continue") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/SIPNAME-00011f8d", "__TTL=64") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/SIPNAME-00011f8d", "1?continue") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-user-callerid,s,19)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:19] Set("SIP/SIPNAME-00011f8d", "CALLERID(number)=SIPNAME") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:20] Set("SIP/SIPNAME-00011f8d", "CALLERID(name)=000phonenumber0000:1693931055.18685") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:21] NoOp("SIP/SIPNAME-00011f8d", "Using CallerID "000phonenumber0000:1693931055.18685" <SIPNAME>") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:2] GotoIf("SIP/SIPNAME-00011f8d", "1?skipdb") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (ext-group,349,4)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:4] Set("SIP/SIPNAME-00011f8d", "__NODEST=") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:5] Set("SIP/SIPNAME-00011f8d", "__BLKVM_OVERRIDE=BLKVM/349/SIP/SIPNAME-00011f8d") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:6] Set("SIP/SIPNAME-00011f8d", "__BLKVM_BASE=349") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:7] Set("SIP/SIPNAME-00011f8d", "DB(BLKVM/349/SIP/SIPNAME-00011f8d)=TRUE") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:8] Set("SIP/SIPNAME-00011f8d", "RRNODEST=") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:9] Set("SIP/SIPNAME-00011f8d", "__NODEST=349") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:10] GosubIf("SIP/SIPNAME-00011f8d", "0?sub-rgsetcid,s,1") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:11] Set("SIP/SIPNAME-00011f8d", "__CWIGNORE=TRUE") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:12] Set("SIP/SIPNAME-00011f8d", "RecordMethod=Group") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:13] Macro("SIP/SIPNAME-00011f8d", "record-enable,237-306-313-314-331-332-356,Group") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/SIPNAME-00011f8d", "1?check") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-record-enable,s,4)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:4] ExecIf("SIP/SIPNAME-00011f8d", "0?MacroExit()") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:5] GotoIf("SIP/SIPNAME-00011f8d", "1?Group:OUT") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-record-enable,s,6)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:6] Set("SIP/SIPNAME-00011f8d", "LOOPCNT=7") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:7] Set("SIP/SIPNAME-00011f8d", "ITER=1") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:8] GotoIf("SIP/SIPNAME-00011f8d", "0?continue") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:9] Set("SIP/SIPNAME-00011f8d", "TEXTEN=237") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:10] NoOp("SIP/SIPNAME-00011f8d", "Recording enable for 237") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:11] Set("SIP/SIPNAME-00011f8d", "CALLFILENAME=g237-20230905-192416-1693931056.79233") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:12] Goto("SIP/SIPNAME-00011f8d", "record") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-record-enable,s,23)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:23] MixMonitor("SIP/SIPNAME-00011f8d", "g237-20230905-192416-1693931056.79233.wav,,") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:24] Set("SIP/SIPNAME-00011f8d", "CDR(userfield)=audio:g237-20230905-192416-1693931056.79233.wav") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:25] MacroExit("SIP/SIPNAME-00011f8d", "") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:14] Set("SIP/SIPNAME-00011f8d", "RingGroupMethod=ringall") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:15] Macro("SIP/SIPNAME-00011f8d", "dial,25,tr,237-306-313-314-331-332-356") in new stack
[Sep 5 19:24:16] VERBOSE[5783] app_mixmonitor.c: == Begin MixMonitor Recording SIP/SIPNAME-00011f8d
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/SIPNAME-00011f8d", "1?dial") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-dial,s,3)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/SIPNAME-00011f8d", "dialparties.agi") in new stack
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Caller ID name is '000phonenumber0000:1693931055.18685' number is 'SIPNAME'
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 237 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 306 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 313 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 314 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 331 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 332 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 356 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 237 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 306 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 313 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 314 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 331 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 332 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 356 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 237 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 306 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 313 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 314 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 331 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 332 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 356 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 237 has ExtensionState: 0
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 237
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/237 - Caller ID is not defined
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 306 has ExtensionState: 0
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 306
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/306 - Caller ID is not defined
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 313 has ExtensionState: 0
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 313
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/313 - Caller ID is not defined
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 16 (ONHOLD)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 314 has ExtensionState: 16
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 314
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 314 is not available to be called
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 314 has call waiting disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 1 (INUSE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 331 has ExtensionState: 1
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 331
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 331 is not available to be called
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 331 has call waiting disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 4 (UNAVAILABLE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 332 has ExtensionState: 4
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 332
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/332 - Caller ID is not defined
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 356 has ExtensionState: 0
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 356
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/356 - Caller ID is not defined
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Filtered ARG3: 237-306-313-332-356
[Sep 5 19:24:17] VERBOSE[5782] res_agi.c: -- <SIP/SIPNAME-00011f8d>AGI Script dialparties.agi completed, returning 0
[Sep 5 19:24:17] VERBOSE[5782] pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/SIPNAME-00011f8d", "SIP/237&SIP/306&SIP/313&SIP/332&SIP/356,25,trM(auto-blkvm)") in new stack
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- Called SIP/237
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- Called SIP/306
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- Called SIP/313
[Sep 5 19:24:17] WARNING[5782] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- Called SIP/356
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/237-00011f8e connected line has changed. Saving it until answer for SIP/SIPNAME-00011f8d
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/306-00011f8f connected line has changed. Saving it until answer for SIP/SIPNAME-00011f8d
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/313-00011f90 connected line has changed. Saving it until answer for SIP/SIPNAME-00011f8d
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/356-00011f91 connected line has changed. Saving it until answer for SIP/SIPNAME-00011f8d
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/237-00011f8e is ringing
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/313-00011f90 is ringing
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/356-00011f91 is ringing
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/306-00011f8f is ringing
[Sep 5 19:24:26] VERBOSE[5782] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/SIPNAME-00011f8d' in macro 'dial'
[Sep 5 19:24:26] VERBOSE[5782] pbx.c: == Spawn extension (ext-group, 349, 15) exited non-zero on 'SIP/SIPNAME-00011f8d'
[Sep 5 19:24:26] VERBOSE[5782] pbx.c: -- Executing [h@ext-group:1] Macro("SIP/SIPNAME-00011f8d", "hangupcall,") in new stack
[Sep 5 19:24:26] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/SIPNAME-00011f8d", "0?endmixmoncheck") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:2] Set("SIP/SIPNAME-00011f8d", "MIXMON_CALLFILENAME=/var/spool/asterisk/monitor/g237-20230905-192416-1693931056.79233.wav") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/SIPNAME-00011f8d", "1?defaultmixmondir") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,5)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:5] System("SIP/SIPNAME-00011f8d", "test -e /var/spool/asterisk/monitor/g237-20230905-192416-1693931056.79233.wav") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:6] NoOp("SIP/SIPNAME-00011f8d", "SYSTEMSTATUS = APPERROR") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/SIPNAME-00011f8d", "0?endmixmoncheck") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:8] Set("SIP/SIPNAME-00011f8d", "CDR(userfield)=") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:9] NoOp("SIP/SIPNAME-00011f8d", "End of MIXMON check") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:10] GotoIf("SIP/SIPNAME-00011f8d", "1?nomeetmemon") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,28)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:28] NoOp("SIP/SIPNAME-00011f8d", "End of MEETME check") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:29] GotoIf("SIP/SIPNAME-00011f8d", "1?noautomon") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,34)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:34] NoOp("SIP/SIPNAME-00011f8d", "TOUCH_MONITOR_OUTPUT=") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:35] GotoIf("SIP/SIPNAME-00011f8d", "1?noautomon2") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,41)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:41] NoOp("SIP/SIPNAME-00011f8d", "MONITOR_FILENAME=") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:42] GotoIf("SIP/SIPNAME-00011f8d", "1?skiprg") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,45)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:45] GotoIf("SIP/SIPNAME-00011f8d", "0?skipblkvm") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:46] NoOp("SIP/SIPNAME-00011f8d", "Cleaning Up Block VM Flag: BLKVM/349/SIP/SIPNAME-00011f8d") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:47] NoOp("SIP/SIPNAME-00011f8d", "Deleting: BLKVM/349/SIP/SIPNAME-00011f8d TRUE") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:48] GotoIf("SIP/SIPNAME-00011f8d", "1?theend") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,50)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:50] AGI("SIP/SIPNAME-00011f8d", "hangup.agi") in new stack
[Sep 5 19:24:27] VERBOSE[5782] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
[Sep 5 19:24:27] VERBOSE[5782] res_agi.c: -- <SIP/SIPNAME-00011f8d>AGI Script hangup.agi completed, returning 0
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:51] Hangup("SIP/SIPNAME-00011f8d", "") in new stack
[Sep 5 19:24:27] VERBOSE[5782] app_macro.c: == Spawn extension (macro-hangupcall, s, 51) exited non-zero on 'SIP/SIPNAME-00011f8d' in macro 'hangupcall'
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: == Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/SIPNAME-00011f8d'
[Sep 5 19:24:27] VERBOSE[5783] app_mixmonitor.c: == End MixMonitor Recording SIP/SIPNAME-00011f8d
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:2] Gosub("SIP/SIPNAME-00011f8d", "app-blacklist-check,s,1") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/SIPNAME-00011f8d", "0?blacklisted") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/SIPNAME-00011f8d", "CALLED_BLACKLIST=1") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/SIPNAME-00011f8d", "") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:3] ExecIf("SIP/SIPNAME-00011f8d", "0 ?Set(CALLERID(name)=SIPNAME)") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:4] Set("SIP/SIPNAME-00011f8d", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:5] Set("SIP/SIPNAME-00011f8d", "CALLERPRES()=allowed_not_screened") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@from-truSIPNAME:6] Goto("SIP/SIPNAME-00011f8d", "ext-group,349,1") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (ext-group,349,1)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:1] Macro("SIP/SIPNAME-00011f8d", "user-callerid,") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/SIPNAME-00011f8d", "AMPUSER=SIPNAME") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/SIPNAME-00011f8d", "0?report") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/SIPNAME-00011f8d", "1?Set(REALCALLERIDNUM=SIPNAME)") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/SIPNAME-00011f8d", "AMPUSER=") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/SIPNAME-00011f8d", "AMPUSERCIDNAME=") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/SIPNAME-00011f8d", "1?report") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-user-callerid,s,10)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/SIPNAME-00011f8d", "0?continue") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/SIPNAME-00011f8d", "__TTL=64") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/SIPNAME-00011f8d", "1?continue") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-user-callerid,s,19)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:19] Set("SIP/SIPNAME-00011f8d", "CALLERID(number)=SIPNAME") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:20] Set("SIP/SIPNAME-00011f8d", "CALLERID(name)=000phonenumber0000:1693931055.18685") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-user-callerid:21] NoOp("SIP/SIPNAME-00011f8d", "Using CallerID "000phonenumber0000:1693931055.18685" <SIPNAME>") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:2] GotoIf("SIP/SIPNAME-00011f8d", "1?skipdb") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (ext-group,349,4)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:4] Set("SIP/SIPNAME-00011f8d", "__NODEST=") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:5] Set("SIP/SIPNAME-00011f8d", "__BLKVM_OVERRIDE=BLKVM/349/SIP/SIPNAME-00011f8d") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:6] Set("SIP/SIPNAME-00011f8d", "__BLKVM_BASE=349") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:7] Set("SIP/SIPNAME-00011f8d", "DB(BLKVM/349/SIP/SIPNAME-00011f8d)=TRUE") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:8] Set("SIP/SIPNAME-00011f8d", "RRNODEST=") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:9] Set("SIP/SIPNAME-00011f8d", "__NODEST=349") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:10] GosubIf("SIP/SIPNAME-00011f8d", "0?sub-rgsetcid,s,1") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:11] Set("SIP/SIPNAME-00011f8d", "__CWIGNORE=TRUE") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:12] Set("SIP/SIPNAME-00011f8d", "RecordMethod=Group") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:13] Macro("SIP/SIPNAME-00011f8d", "record-enable,237-306-313-314-331-332-356,Group") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/SIPNAME-00011f8d", "1?check") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-record-enable,s,4)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:4] ExecIf("SIP/SIPNAME-00011f8d", "0?MacroExit()") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:5] GotoIf("SIP/SIPNAME-00011f8d", "1?Group:OUT") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-record-enable,s,6)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:6] Set("SIP/SIPNAME-00011f8d", "LOOPCNT=7") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:7] Set("SIP/SIPNAME-00011f8d", "ITER=1") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:8] GotoIf("SIP/SIPNAME-00011f8d", "0?continue") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:9] Set("SIP/SIPNAME-00011f8d", "TEXTEN=237") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:10] NoOp("SIP/SIPNAME-00011f8d", "Recording enable for 237") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:11] Set("SIP/SIPNAME-00011f8d", "CALLFILENAME=g237-20230905-192416-1693931056.79233") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:12] Goto("SIP/SIPNAME-00011f8d", "record") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-record-enable,s,23)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:23] MixMonitor("SIP/SIPNAME-00011f8d", "g237-20230905-192416-1693931056.79233.wav,,") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:24] Set("SIP/SIPNAME-00011f8d", "CDR(userfield)=audio:g237-20230905-192416-1693931056.79233.wav") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-record-enable:25] MacroExit("SIP/SIPNAME-00011f8d", "") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:14] Set("SIP/SIPNAME-00011f8d", "RingGroupMethod=ringall") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [349@ext-group:15] Macro("SIP/SIPNAME-00011f8d", "dial,25,tr,237-306-313-314-331-332-356") in new stack
[Sep 5 19:24:16] VERBOSE[5783] app_mixmonitor.c: == Begin MixMonitor Recording SIP/SIPNAME-00011f8d
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/SIPNAME-00011f8d", "1?dial") in new stack
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Goto (macro-dial,s,3)
[Sep 5 19:24:16] VERBOSE[5782] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/SIPNAME-00011f8d", "dialparties.agi") in new stack
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Caller ID name is '000phonenumber0000:1693931055.18685' number is 'SIPNAME'
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 237 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 306 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 313 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 314 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 331 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 332 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Added extension 356 to extension map
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 237 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 306 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 313 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 314 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 331 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 332 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 356 cf is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 237 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 306 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 313 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 314 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 331 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 332 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Extension 356 do not disturb is disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 237 has ExtensionState: 0
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 237
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/237 - Caller ID is not defined
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 306 has ExtensionState: 0
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 306
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/306 - Caller ID is not defined
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 313 has ExtensionState: 0
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 313
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/313 - Caller ID is not defined
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 16 (ONHOLD)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 314 has ExtensionState: 16
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 314
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 314 is not available to be called
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 314 has call waiting disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 1 (INUSE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 331 has ExtensionState: 1
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 331
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 331 is not available to be called
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 331 has call waiting disabled
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 4 (UNAVAILABLE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 332 has ExtensionState: 4
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 332
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/332 - Caller ID is not defined
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: dialparties.agi: Extension 356 has ExtensionState: 0
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 356
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/356 - Caller ID is not defined
[Sep 5 19:24:16] VERBOSE[5782] res_agi.c: -- dialparties.agi: Filtered ARG3: 237-306-313-332-356
[Sep 5 19:24:17] VERBOSE[5782] res_agi.c: -- <SIP/SIPNAME-00011f8d>AGI Script dialparties.agi completed, returning 0
[Sep 5 19:24:17] VERBOSE[5782] pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/SIPNAME-00011f8d", "SIP/237&SIP/306&SIP/313&SIP/332&SIP/356,25,trM(auto-blkvm)") in new stack
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- Called SIP/237
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- Called SIP/306
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- Called SIP/313
[Sep 5 19:24:17] WARNING[5782] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 5 19:24:17] VERBOSE[5782] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- Called SIP/356
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/237-00011f8e connected line has changed. Saving it until answer for SIP/SIPNAME-00011f8d
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/306-00011f8f connected line has changed. Saving it until answer for SIP/SIPNAME-00011f8d
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/313-00011f90 connected line has changed. Saving it until answer for SIP/SIPNAME-00011f8d
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/356-00011f91 connected line has changed. Saving it until answer for SIP/SIPNAME-00011f8d
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/237-00011f8e is ringing
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/313-00011f90 is ringing
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/356-00011f91 is ringing
[Sep 5 19:24:17] VERBOSE[5782] app_dial.c: -- SIP/306-00011f8f is ringing
[Sep 5 19:24:26] VERBOSE[5782] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/SIPNAME-00011f8d' in macro 'dial'
[Sep 5 19:24:26] VERBOSE[5782] pbx.c: == Spawn extension (ext-group, 349, 15) exited non-zero on 'SIP/SIPNAME-00011f8d'
[Sep 5 19:24:26] VERBOSE[5782] pbx.c: -- Executing [h@ext-group:1] Macro("SIP/SIPNAME-00011f8d", "hangupcall,") in new stack
[Sep 5 19:24:26] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/SIPNAME-00011f8d", "0?endmixmoncheck") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:2] Set("SIP/SIPNAME-00011f8d", "MIXMON_CALLFILENAME=/var/spool/asterisk/monitor/g237-20230905-192416-1693931056.79233.wav") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/SIPNAME-00011f8d", "1?defaultmixmondir") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,5)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:5] System("SIP/SIPNAME-00011f8d", "test -e /var/spool/asterisk/monitor/g237-20230905-192416-1693931056.79233.wav") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:6] NoOp("SIP/SIPNAME-00011f8d", "SYSTEMSTATUS = APPERROR") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/SIPNAME-00011f8d", "0?endmixmoncheck") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:8] Set("SIP/SIPNAME-00011f8d", "CDR(userfield)=") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:9] NoOp("SIP/SIPNAME-00011f8d", "End of MIXMON check") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:10] GotoIf("SIP/SIPNAME-00011f8d", "1?nomeetmemon") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,28)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:28] NoOp("SIP/SIPNAME-00011f8d", "End of MEETME check") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:29] GotoIf("SIP/SIPNAME-00011f8d", "1?noautomon") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,34)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:34] NoOp("SIP/SIPNAME-00011f8d", "TOUCH_MONITOR_OUTPUT=") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:35] GotoIf("SIP/SIPNAME-00011f8d", "1?noautomon2") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,41)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:41] NoOp("SIP/SIPNAME-00011f8d", "MONITOR_FILENAME=") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:42] GotoIf("SIP/SIPNAME-00011f8d", "1?skiprg") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,45)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:45] GotoIf("SIP/SIPNAME-00011f8d", "0?skipblkvm") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:46] NoOp("SIP/SIPNAME-00011f8d", "Cleaning Up Block VM Flag: BLKVM/349/SIP/SIPNAME-00011f8d") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:47] NoOp("SIP/SIPNAME-00011f8d", "Deleting: BLKVM/349/SIP/SIPNAME-00011f8d TRUE") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:48] GotoIf("SIP/SIPNAME-00011f8d", "1?theend") in new stack
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Goto (macro-hangupcall,s,50)
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:50] AGI("SIP/SIPNAME-00011f8d", "hangup.agi") in new stack
[Sep 5 19:24:27] VERBOSE[5782] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
[Sep 5 19:24:27] VERBOSE[5782] res_agi.c: -- <SIP/SIPNAME-00011f8d>AGI Script hangup.agi completed, returning 0
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: -- Executing [s@macro-hangupcall:51] Hangup("SIP/SIPNAME-00011f8d", "") in new stack
[Sep 5 19:24:27] VERBOSE[5782] app_macro.c: == Spawn extension (macro-hangupcall, s, 51) exited non-zero on 'SIP/SIPNAME-00011f8d' in macro 'hangupcall'
[Sep 5 19:24:27] VERBOSE[5782] pbx.c: == Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/SIPNAME-00011f8d'
[Sep 5 19:24:27] VERBOSE[5783] app_mixmonitor.c: == End MixMonitor Recording SIP/SIPNAME-00011f8d
2 входящий
PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:1] Set("SIP/SOMESIP-00013e18", "__FROM_DID=349") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:2] Gosub("SIP/SOMESIP-00013e18", "app-blacklist-check,s,1") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/SOMESIP-00013e18", "0?blacklisted") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/SOMESIP-00013e18", "CALLED_BLACKLIST=1") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/SOMESIP-00013e18", "") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:3] ExecIf("SIP/SOMESIP-00013e18", "0 ?Set(CALLERID(name)=SOMESIP)") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:4] Set("SIP/SOMESIP-00013e18", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:5] Set("SIP/SOMESIP-00013e18", "CALLERPRES()=allowed_not_screened") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:6] Goto("SIP/SOMESIP-00013e18", "ext-group,349,1") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Goto (ext-group,349,1)
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:1] Macro("SIP/SOMESIP-00013e18", "user-callerid,") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/SOMESIP-00013e18", "AMPUSER=SOMESIP") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/SOMESIP-00013e18", "0?report") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/SOMESIP-00013e18", "1?Set(REALCALLERIDNUM=SOMESIP)") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/SOMESIP-00013e18", "AMPUSER=") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/SOMESIP-00013e18", "AMPUSERCIDNAME=") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/SOMESIP-00013e18", "1?report") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Goto (macro-user-callerid,s,10)
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/SOMESIP-00013e18", "0?continue") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/SOMESIP-00013e18", "__TTL=64") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/SOMESIP-00013e18", "1?continue") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Goto (macro-user-callerid,s,19)
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:19] Set("SIP/SOMESIP-00013e18", "CALLERID(number)=SOMESIP") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:20] Set("SIP/SOMESIP-00013e18", "CALLERID(name)=+000phonenumberr000:1694429426.39606") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:21] NoOp("SIP/SOMESIP-00013e18", "Using CallerID "+000phonenumberr000:1694429426.39606" <SOMESIP>") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:2] GotoIf("SIP/SOMESIP-00013e18", "1?skipdb") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Goto (ext-group,349,4)
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:4] Set("SIP/SOMESIP-00013e18", "__NODEST=") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:5] Set("SIP/SOMESIP-00013e18", "__BLKVM_OVERRIDE=BLKVM/349/SIP/SOMESIP-00013e18") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:6] Set("SIP/SOMESIP-00013e18", "__BLKVM_BASE=349") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:7] Set("SIP/SOMESIP-00013e18", "DB(BLKVM/349/SIP/SOMESIP-00013e18)=TRUE") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:8] Set("SIP/SOMESIP-00013e18", "RRNODEST=") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:9] Set("SIP/SOMESIP-00013e18", "__NODEST=349") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:10] GosubIf("SIP/SOMESIP-00013e18", "0?sub-rgsetcid,s,1") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:11] Set("SIP/SOMESIP-00013e18", "__CWIGNORE=TRUE") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:12] Set("SIP/SOMESIP-00013e18", "RecordMethod=Group") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:13] Macro("SIP/SOMESIP-00013e18", "record-enable,306-313-314-331-332-356,Group") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/SOMESIP-00013e18", "1?check") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Goto (macro-record-enable,s,4)
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:4] ExecIf("SIP/SOMESIP-00013e18", "0?MacroExit()") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:5] GotoIf("SIP/SOMESIP-00013e18", "1?Group:OUT") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Goto (macro-record-enable,s,6)
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:6] Set("SIP/SOMESIP-00013e18", "LOOPCNT=6") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:7] Set("SIP/SOMESIP-00013e18", "ITER=1") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:8] GotoIf("SIP/SOMESIP-00013e18", "0?continue") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:9] Set("SIP/SOMESIP-00013e18", "TEXTEN=306") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:10] NoOp("SIP/SOMESIP-00013e18", "Recording enable for 306") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:11] Set("SIP/SOMESIP-00013e18", "CALLFILENAME=g306-20230911-135032-1694429427.87788") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:12] Goto("SIP/SOMESIP-00013e18", "record") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Goto (macro-record-enable,s,23)
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:23] MixMonitor("SIP/SOMESIP-00013e18", "g306-20230911-135032-1694429427.87788.wav,,") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:24] Set("SIP/SOMESIP-00013e18", "CDR(userfield)=audio:g306-20230911-135032-1694429427.87788.wav") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:25] MacroExit("SIP/SOMESIP-00013e18", "") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:14] Set("SIP/SOMESIP-00013e18", "RingGroupMethod=ringall") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:15] Macro("SIP/SOMESIP-00013e18", "dial,25,tr,306-313-314-331-332-356") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/SOMESIP-00013e18", "1?dial") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Goto (macro-dial,s,3)
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/SOMESIP-00013e18", "dialparties.agi") in new stack
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Caller ID name is '+000phonenumberr000:1694429426.39606' number is 'SOMESIP'
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 306 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 313 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 314 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 331 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 332 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 356 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 306 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 313 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 314 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 331 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 332 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 356 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 306 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 313 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 314 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 331 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 332 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 356 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 306 has ExtensionState: 0
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 306
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/306 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 313 has ExtensionState: 0
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 313
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/313 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 314 has ExtensionState: 0
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 314
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/314 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 331 has ExtensionState: 0
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 331
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/331 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 4 (UNAVAILABLE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 332 has ExtensionState: 4
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 332
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/332 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 356 has ExtensionState: 0
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 356
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/356 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Filtered ARG3: 306-313-314-331-332-356
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- <SIP/SOMESIP-00013e18>AGI Script dialparties.agi completed, returning 0
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/SOMESIP-00013e18", "SIP/306&SIP/313&SIP/314&SIP/331&SIP/332&SIP/356,25,trM(auto-blkvm)") in new stack
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- Called SIP/306
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- Called SIP/313
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- Called SIP/314
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- Called SIP/331
[Sep 11 13:50:32] WARNING[12842] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- Called SIP/356
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/306-00013e19 connected line has changed. Saving it until answer for SIP/SOMESIP-00013e18
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/313-00013e1a connected line has changed. Saving it until answer for SIP/SOMESIP-00013e18
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/314-00013e1b connected line has changed. Saving it until answer for SIP/SOMESIP-00013e18
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/331-00013e1c connected line has changed. Saving it until answer for SIP/SOMESIP-00013e18
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/356-00013e1d connected line has changed. Saving it until answer for SIP/SOMESIP-00013e18
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/313-00013e1a is ringing
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/331-00013e1c is ringing
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/314-00013e1b is ringing
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/356-00013e1d is ringing
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/306-00013e19 is ringing
[Sep 11 13:50:45] VERBOSE[12842] app_dial.c: -- SIP/331-00013e1c is busy
[Sep 11 13:50:57] VERBOSE[12842] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/SOMESIP-00013e18' in macro 'dial'
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: == Spawn extension (ext-group, 349, 15) exited non-zero on 'SIP/SOMESIP-00013e18'
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [h@ext-group:1] Macro("SIP/SOMESIP-00013e18", "hangupcall,") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/SOMESIP-00013e18", "0?endmixmoncheck") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:2] Set("SIP/SOMESIP-00013e18", "MIXMON_CALLFILENAME=/var/spool/asterisk/monitor/g306-20230911-135032-1694429427.87788.wav") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/SOMESIP-00013e18", "1?defaultmixmondir") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,5)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:5] System("SIP/SOMESIP-00013e18", "test -e /var/spool/asterisk/monitor/g306-20230911-135032-1694429427.87788.wav") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:6] NoOp("SIP/SOMESIP-00013e18", "SYSTEMSTATUS = APPERROR") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/SOMESIP-00013e18", "0?endmixmoncheck") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:8] Set("SIP/SOMESIP-00013e18", "CDR(userfield)=") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:9] NoOp("SIP/SOMESIP-00013e18", "End of MIXMON check") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:10] GotoIf("SIP/SOMESIP-00013e18", "1?nomeetmemon") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,28)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:28] NoOp("SIP/SOMESIP-00013e18", "End of MEETME check") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:29] GotoIf("SIP/SOMESIP-00013e18", "1?noautomon") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,34)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:34] NoOp("SIP/SOMESIP-00013e18", "TOUCH_MONITOR_OUTPUT=") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:35] GotoIf("SIP/SOMESIP-00013e18", "1?noautomon2") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,41)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:41] NoOp("SIP/SOMESIP-00013e18", "MONITOR_FILENAME=") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:42] GotoIf("SIP/SOMESIP-00013e18", "1?skiprg") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,45)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:45] GotoIf("SIP/SOMESIP-00013e18", "0?skipblkvm") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:46] NoOp("SIP/SOMESIP-00013e18", "Cleaning Up Block VM Flag: BLKVM/349/SIP/SOMESIP-00013e18") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:47] NoOp("SIP/SOMESIP-00013e18", "Deleting: BLKVM/349/SIP/SOMESIP-00013e18 TRUE") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:48] GotoIf("SIP/SOMESIP-00013e18", "1?theend") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,50)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:50] AGI("SIP/SOMESIP-00013e18", "hangup.agi") in new stack
[Sep 11 13:50:57] VERBOSE[12842] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
[Sep 11 13:50:57] VERBOSE[12842] res_agi.c: -- <SIP/SOMESIP-00013e18>AGI Script hangup.agi completed, returning 0
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:51] Hangup("SIP/SOMESIP-00013e18", "") in new stack
[Sep 11 13:50:57] VERBOSE[12842] app_macro.c: == Spawn extension (macro-hangupcall, s, 51) exited non-zero on 'SIP/SOMESIP-00013e18' in macro 'hangupcall'
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: == Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/SOMESIP-00013e18'
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:2] Gosub("SIP/SOMESIP-00013e18", "app-blacklist-check,s,1") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/SOMESIP-00013e18", "0?blacklisted") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/SOMESIP-00013e18", "CALLED_BLACKLIST=1") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/SOMESIP-00013e18", "") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:3] ExecIf("SIP/SOMESIP-00013e18", "0 ?Set(CALLERID(name)=SOMESIP)") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:4] Set("SIP/SOMESIP-00013e18", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:5] Set("SIP/SOMESIP-00013e18", "CALLERPRES()=allowed_not_screened") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@from-truSOMESIP:6] Goto("SIP/SOMESIP-00013e18", "ext-group,349,1") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Goto (ext-group,349,1)
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:1] Macro("SIP/SOMESIP-00013e18", "user-callerid,") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/SOMESIP-00013e18", "AMPUSER=SOMESIP") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/SOMESIP-00013e18", "0?report") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/SOMESIP-00013e18", "1?Set(REALCALLERIDNUM=SOMESIP)") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/SOMESIP-00013e18", "AMPUSER=") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/SOMESIP-00013e18", "AMPUSERCIDNAME=") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/SOMESIP-00013e18", "1?report") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Goto (macro-user-callerid,s,10)
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/SOMESIP-00013e18", "0?continue") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/SOMESIP-00013e18", "__TTL=64") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/SOMESIP-00013e18", "1?continue") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Goto (macro-user-callerid,s,19)
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:19] Set("SIP/SOMESIP-00013e18", "CALLERID(number)=SOMESIP") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:20] Set("SIP/SOMESIP-00013e18", "CALLERID(name)=+000phonenumberr000:1694429426.39606") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [s@macro-user-callerid:21] NoOp("SIP/SOMESIP-00013e18", "Using CallerID "+000phonenumberr000:1694429426.39606" <SOMESIP>") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:2] GotoIf("SIP/SOMESIP-00013e18", "1?skipdb") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Goto (ext-group,349,4)
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:4] Set("SIP/SOMESIP-00013e18", "__NODEST=") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:5] Set("SIP/SOMESIP-00013e18", "__BLKVM_OVERRIDE=BLKVM/349/SIP/SOMESIP-00013e18") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:6] Set("SIP/SOMESIP-00013e18", "__BLKVM_BASE=349") in new stack
[Sep 11 13:50:27] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:7] Set("SIP/SOMESIP-00013e18", "DB(BLKVM/349/SIP/SOMESIP-00013e18)=TRUE") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:8] Set("SIP/SOMESIP-00013e18", "RRNODEST=") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:9] Set("SIP/SOMESIP-00013e18", "__NODEST=349") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:10] GosubIf("SIP/SOMESIP-00013e18", "0?sub-rgsetcid,s,1") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:11] Set("SIP/SOMESIP-00013e18", "__CWIGNORE=TRUE") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:12] Set("SIP/SOMESIP-00013e18", "RecordMethod=Group") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:13] Macro("SIP/SOMESIP-00013e18", "record-enable,306-313-314-331-332-356,Group") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/SOMESIP-00013e18", "1?check") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Goto (macro-record-enable,s,4)
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:4] ExecIf("SIP/SOMESIP-00013e18", "0?MacroExit()") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:5] GotoIf("SIP/SOMESIP-00013e18", "1?Group:OUT") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Goto (macro-record-enable,s,6)
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:6] Set("SIP/SOMESIP-00013e18", "LOOPCNT=6") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:7] Set("SIP/SOMESIP-00013e18", "ITER=1") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:8] GotoIf("SIP/SOMESIP-00013e18", "0?continue") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:9] Set("SIP/SOMESIP-00013e18", "TEXTEN=306") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:10] NoOp("SIP/SOMESIP-00013e18", "Recording enable for 306") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:11] Set("SIP/SOMESIP-00013e18", "CALLFILENAME=g306-20230911-135032-1694429427.87788") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:12] Goto("SIP/SOMESIP-00013e18", "record") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Goto (macro-record-enable,s,23)
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:23] MixMonitor("SIP/SOMESIP-00013e18", "g306-20230911-135032-1694429427.87788.wav,,") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:24] Set("SIP/SOMESIP-00013e18", "CDR(userfield)=audio:g306-20230911-135032-1694429427.87788.wav") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-record-enable:25] MacroExit("SIP/SOMESIP-00013e18", "") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:14] Set("SIP/SOMESIP-00013e18", "RingGroupMethod=ringall") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [349@ext-group:15] Macro("SIP/SOMESIP-00013e18", "dial,25,tr,306-313-314-331-332-356") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/SOMESIP-00013e18", "1?dial") in new stack
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Goto (macro-dial,s,3)
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/SOMESIP-00013e18", "dialparties.agi") in new stack
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Caller ID name is '+000phonenumberr000:1694429426.39606' number is 'SOMESIP'
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 306 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 313 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 314 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 331 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 332 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Added extension 356 to extension map
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 306 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 313 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 314 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 331 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 332 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 356 cf is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 306 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 313 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 314 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 331 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 332 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Extension 356 do not disturb is disabled
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 306 has ExtensionState: 0
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 306
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/306 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 313 has ExtensionState: 0
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 313
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/313 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 314 has ExtensionState: 0
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 314
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/314 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 331 has ExtensionState: 0
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 331
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/331 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 4 (UNAVAILABLE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 332 has ExtensionState: 4
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 332
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/332 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: dialparties.agi: Extension 356 has ExtensionState: 0
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Checking CW and CFB status for extension 356
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: DbDel CALLTRACE/356 - Caller ID is not defined
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- dialparties.agi: Filtered ARG3: 306-313-314-331-332-356
[Sep 11 13:50:32] VERBOSE[12842] res_agi.c: -- <SIP/SOMESIP-00013e18>AGI Script dialparties.agi completed, returning 0
[Sep 11 13:50:32] VERBOSE[12842] pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/SOMESIP-00013e18", "SIP/306&SIP/313&SIP/314&SIP/331&SIP/332&SIP/356,25,trM(auto-blkvm)") in new stack
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- Called SIP/306
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- Called SIP/313
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- Called SIP/314
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- Called SIP/331
[Sep 11 13:50:32] WARNING[12842] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP TOS bits 184
[Sep 11 13:50:32] VERBOSE[12842] netsock2.c: == Using SIP RTP CoS mark 5
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- Called SIP/356
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/306-00013e19 connected line has changed. Saving it until answer for SIP/SOMESIP-00013e18
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/313-00013e1a connected line has changed. Saving it until answer for SIP/SOMESIP-00013e18
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/314-00013e1b connected line has changed. Saving it until answer for SIP/SOMESIP-00013e18
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/331-00013e1c connected line has changed. Saving it until answer for SIP/SOMESIP-00013e18
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/356-00013e1d connected line has changed. Saving it until answer for SIP/SOMESIP-00013e18
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/313-00013e1a is ringing
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/331-00013e1c is ringing
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/314-00013e1b is ringing
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/356-00013e1d is ringing
[Sep 11 13:50:32] VERBOSE[12842] app_dial.c: -- SIP/306-00013e19 is ringing
[Sep 11 13:50:45] VERBOSE[12842] app_dial.c: -- SIP/331-00013e1c is busy
[Sep 11 13:50:57] VERBOSE[12842] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/SOMESIP-00013e18' in macro 'dial'
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: == Spawn extension (ext-group, 349, 15) exited non-zero on 'SIP/SOMESIP-00013e18'
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [h@ext-group:1] Macro("SIP/SOMESIP-00013e18", "hangupcall,") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/SOMESIP-00013e18", "0?endmixmoncheck") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:2] Set("SIP/SOMESIP-00013e18", "MIXMON_CALLFILENAME=/var/spool/asterisk/monitor/g306-20230911-135032-1694429427.87788.wav") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/SOMESIP-00013e18", "1?defaultmixmondir") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,5)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:5] System("SIP/SOMESIP-00013e18", "test -e /var/spool/asterisk/monitor/g306-20230911-135032-1694429427.87788.wav") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:6] NoOp("SIP/SOMESIP-00013e18", "SYSTEMSTATUS = APPERROR") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/SOMESIP-00013e18", "0?endmixmoncheck") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:8] Set("SIP/SOMESIP-00013e18", "CDR(userfield)=") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:9] NoOp("SIP/SOMESIP-00013e18", "End of MIXMON check") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:10] GotoIf("SIP/SOMESIP-00013e18", "1?nomeetmemon") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,28)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:28] NoOp("SIP/SOMESIP-00013e18", "End of MEETME check") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:29] GotoIf("SIP/SOMESIP-00013e18", "1?noautomon") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,34)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:34] NoOp("SIP/SOMESIP-00013e18", "TOUCH_MONITOR_OUTPUT=") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:35] GotoIf("SIP/SOMESIP-00013e18", "1?noautomon2") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,41)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:41] NoOp("SIP/SOMESIP-00013e18", "MONITOR_FILENAME=") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:42] GotoIf("SIP/SOMESIP-00013e18", "1?skiprg") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,45)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:45] GotoIf("SIP/SOMESIP-00013e18", "0?skipblkvm") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:46] NoOp("SIP/SOMESIP-00013e18", "Cleaning Up Block VM Flag: BLKVM/349/SIP/SOMESIP-00013e18") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:47] NoOp("SIP/SOMESIP-00013e18", "Deleting: BLKVM/349/SIP/SOMESIP-00013e18 TRUE") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:48] GotoIf("SIP/SOMESIP-00013e18", "1?theend") in new stack
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Goto (macro-hangupcall,s,50)
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:50] AGI("SIP/SOMESIP-00013e18", "hangup.agi") in new stack
[Sep 11 13:50:57] VERBOSE[12842] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
[Sep 11 13:50:57] VERBOSE[12842] res_agi.c: -- <SIP/SOMESIP-00013e18>AGI Script hangup.agi completed, returning 0
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: -- Executing [s@macro-hangupcall:51] Hangup("SIP/SOMESIP-00013e18", "") in new stack
[Sep 11 13:50:57] VERBOSE[12842] app_macro.c: == Spawn extension (macro-hangupcall, s, 51) exited non-zero on 'SIP/SOMESIP-00013e18' in macro 'hangupcall'
[Sep 11 13:50:57] VERBOSE[12842] pbx.c: == Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/SOMESIP-00013e18'