Unconditional Call Forward (failed) LOGS =================================== [root@22-v3-02-act x86_64]# asterisk -rvvvvvvv Asterisk 13.21.0, Copyright (C) 1999 - 2014, Digium, Inc. and others. Created by Mark Spencer Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details. This is free software, with components licensed under the GNU General Public License version 2 and other licenses; you are welcome to redistribute it under certain conditions. Type 'core show license' for details. ========================================================================= Connected to Asterisk 13.21.0 currently running on 22-v3-02-act (pid = 28588) == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/DefaultGW-0000000d -- Executing [0363868357@default:1] ExecIf("SIP/DefaultGW-0000000d", "0?Goto(3)") -- Executing [0363868357@default:2] Macro("SIP/DefaultGW-0000000d", "incominglimit") -- Executing [s@macro-incominglimit:1] Set("SIP/DefaultGW-0000000d", "CHANNEL=ja") in new stack -- Executing [s@macro-incominglimit:2] Set("SIP/DefaultGW-0000000d", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-incominglimit:3] Set("SIP/DefaultGW-0000000d", "__INBOUND_PHONE_NUM=0363868357") in new stack -- Executing [s@macro-incominglimit:4] Set("SIP/DefaultGW-0000000d", "CDR(inboundphonenumber)=0363868357") in new stack -- Executing [s@macro-incominglimit:5] Set("SIP/DefaultGW-0000000d", "_INCOMING=yes") in new stack -- Executing [s@macro-incominglimit:6] AGI("SIP/DefaultGW-0000000d", "incominglimit.agi,0363868357") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/incominglimit.agi -- AGI Script Executing Application: (Set) Options: (INCOMING_MAX=0) -- AGI Script Executing Application: (Set) Options: (INOUT_MAX=0) -- AGI Script Executing Application: (Set) Options: (TENANT_NAME=a) -- AGI Script Executing Application: (Set) Options: (LINE_MAX=30) -- AGI Script incominglimit.agi completed, returning 0 -- Executing [s@macro-incominglimit:7] Set("SIP/DefaultGW-0000000d", "GROUP(INOUT_a)=0363868357") in new stack -- Executing [s@macro-incominglimit:8] GotoIf("SIP/DefaultGW-0000000d", "1?set_incoming") in new stack -- Goto (macro-incominglimit,s,10) -- Executing [s@macro-incominglimit:10] Set("SIP/DefaultGW-0000000d", "GROUP(INCOMING_a)=0363868357") in new stack -- Executing [s@macro-incominglimit:11] GotoIf("SIP/DefaultGW-0000000d", "1?set_line") in new stack -- Goto (macro-incominglimit,s,13) -- Executing [s@macro-incominglimit:13] Set("SIP/DefaultGW-0000000d", "GROUP(LINE_a)=a") in new stack -- Executing [s@macro-incominglimit:14] GotoIf("SIP/DefaultGW-0000000d", "0?line_limit") in new stack -- Executing [s@macro-incominglimit:15] GotoIf("SIP/DefaultGW-0000000d", "0?line_limit") in new stack -- Executing [s@macro-incominglimit:16] MacroExit("SIP/DefaultGW-0000000d", "") in new stack -- Executing [0363868357@default:3] Macro("SIP/DefaultGW-0000000d", "p-called-party-id") -- Executing [s@macro-p-called-party-id:1] GotoIf("SIP/DefaultGW-0000000d", "0?7") in new stack -- Executing [s@macro-p-called-party-id:2] Set("SIP/DefaultGW-0000000d", "__INBOUND_EXTEN=0363868357") in new stack -- Executing [s@macro-p-called-party-id:3] GotoIf("SIP/DefaultGW-0000000d", "1?4:7") in new stack -- Goto (macro-p-called-party-id,s,4) -- Executing [s@macro-p-called-party-id:4] Set("SIP/DefaultGW-0000000d", "__PCPID=sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-p-called-party-id:5] NoOp("SIP/DefaultGW-0000000d", "PCPID: sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-p-called-party-id:6] MacroExit("SIP/DefaultGW-0000000d", "") in new stack -- Executing [0363868357@default:4] Macro("SIP/DefaultGW-0000000d", "privacy") -- Executing [s@macro-privacy:1] GotoIf("SIP/DefaultGW-0000000d", "0?end,1") in new stack -- Executing [s@macro-privacy:2] GotoIf("SIP/DefaultGW-0000000d", "1?9") in new stack -- Goto (macro-privacy,s,9) -- Executing [s@macro-privacy:9] Set("SIP/DefaultGW-0000000d", "RS=100001") in new stack -- Executing [s@macro-privacy:10] GosubIf("SIP/DefaultGW-0000000d", "1?map-1,1") in new stack -- Executing [map-1@macro-privacy:1] GotoIf("SIP/DefaultGW-0000000d", "0?7") in new stack -- Executing [map-1@macro-privacy:2] GotoIf("SIP/DefaultGW-0000000d", "0?set,1") in new stack -- Executing [map-1@macro-privacy:3] GotoIf("SIP/DefaultGW-0000000d", "0?set,1") in new stack -- Executing [map-1@macro-privacy:4] GotoIf("SIP/DefaultGW-0000000d", "0?set,1") in new stack -- Executing [map-1@macro-privacy:5] GotoIf("SIP/DefaultGW-0000000d", "0?set,1") in new stack -- Executing [map-1@macro-privacy:6] Return("SIP/DefaultGW-0000000d", "") in new stack -- Executing [s@macro-privacy:11] GotoIf("SIP/DefaultGW-0000000d", "1?15") in new stack -- Goto (macro-privacy,s,15) -- Executing [s@macro-privacy:15] GotoIf("SIP/DefaultGW-0000000d", "1?18") in new stack -- Goto (macro-privacy,s,18) -- Executing [s@macro-privacy:18] Goto("SIP/DefaultGW-0000000d", "ct-,1") in new stack -- Goto (macro-privacy,ct-,1) -- Executing [0363868357@default:5] Goto("SIP/DefaultGW-0000000d", "a_common,*00001,1") -- Goto (a_common,*00001,1) -- Executing [*00001@a_common:1] Set("SIP/DefaultGW-0000000d", "__INBOUND_TIME=1529465450") -- Executing [*00001@a_common:2] ExecIf("SIP/DefaultGW-0000000d", "0?Set(CDR(userfield)=incoming,)") -- Executing [*00001@a_common:3] Macro("SIP/DefaultGW-0000000d", "inbound-exectime,0,*,*,*,*,*,1529465450") -- Executing [s@macro-inbound-exectime:1] Set("SIP/DefaultGW-0000000d", "EXEC_INBOUND_APP=0") in new stack -- Executing [s@macro-inbound-exectime:2] NoOp("SIP/DefaultGW-0000000d", "Set(CDR(userfield)=incoming)") in new stack -- Executing [s@macro-inbound-exectime:3] GotoIf("SIP/DefaultGW-0000000d", "0?4:9") in new stack -- Goto (macro-inbound-exectime,s,9) -- Executing [s@macro-inbound-exectime:9] AGI("SIP/DefaultGW-0000000d", "inbound-exectime.agi,*,1529465450") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/inbound-exectime.agi -- AGI Script Executing Application: (Set) Options: (YEAR_RESULT=1) -- AGI Script inbound-exectime.agi completed, returning 0 -- Executing [s@macro-inbound-exectime:10] GotoIf("SIP/DefaultGW-0000000d", "0?13:11") in new stack -- Goto (macro-inbound-exectime,s,11) -- Executing [s@macro-inbound-exectime:11] ExecIfTime("SIP/DefaultGW-0000000d", "*,*,*,*?Set(EXEC_INBOUND_APP=1)") in new stack -- Executing [s@macro-inbound-exectime:12] NoOp("SIP/DefaultGW-0000000d", "current time matched: 1") in new stack -- Executing [s@macro-inbound-exectime:13] MacroExit("SIP/DefaultGW-0000000d", "") in new stack -- Executing [*00001@a_common:4] ExecIf("SIP/DefaultGW-0000000d", "1?Macro(queue,a_acd_01,ring,,,,)") -- Executing [s@macro-queue:1] Set("SIP/DefaultGW-0000000d", "__QUEUE_NOLOG=queue") in new stack -- Executing [s@macro-queue:2] NoOp("SIP/DefaultGW-0000000d", "Macro(setcallerid)") in new stack -- Executing [s@macro-queue:3] Macro("SIP/DefaultGW-0000000d", "queue-count,") in new stack -- Executing [s@macro-queue-count:1] Set("SIP/DefaultGW-0000000d", "ACD=1") in new stack -- Executing [s@macro-queue-count:2] ExecIf("SIP/DefaultGW-0000000d", "1?Set(ACD=100)") in new stack -- Executing [s@macro-queue-count:3] Set("SIP/DefaultGW-0000000d", "E=") in new stack -- Executing [s@macro-queue-count:4] GotoIf("SIP/DefaultGW-0000000d", "1?6") in new stack -- Goto (macro-queue-count,s,6) -- Executing [s@macro-queue-count:6] GotoIf("SIP/DefaultGW-0000000d", "1?8") in new stack -- Goto (macro-queue-count,s,8) -- Executing [s@macro-queue-count:8] Set("SIP/DefaultGW-0000000d", "GROUP()=queue") in new stack -- Executing [s@macro-queue-count:9] MacroExit("SIP/DefaultGW-0000000d", "") in new stack -- Executing [s@macro-queue:4] Set("SIP/DefaultGW-0000000d", "OPT=t") in new stack -- Executing [s@macro-queue:5] GotoIf("SIP/DefaultGW-0000000d", "1?6:8") in new stack -- Goto (macro-queue,s,6) -- Executing [s@macro-queue:6] Set("SIP/DefaultGW-0000000d", "OPT=tr") in new stack -- Executing [s@macro-queue:7] Goto("SIP/DefaultGW-0000000d", "11") in new stack -- Goto (macro-queue,s,11) -- Executing [s@macro-queue:11] Set("SIP/DefaultGW-0000000d", "__TRAIL=a_acd_01") in new stack -- Executing [s@macro-queue:12] Queue("SIP/DefaultGW-0000000d", "a_acd_01,tr,,,,,trailclear") in new stack -- Called Local/202@a_context_01/n -- Executing [202@a_context_01:1] Set("Local/202@a_context_01-00000004;2", "CHANNEL(parkinglot)=parkinglot_a_01") == Begin MixMonitor Recording Local/202@a_context_01-00000004;1 == Begin MixMonitor Recording Local/202@a_context_01-00000004;2 -- Executing [202@a_context_01:2] Goto("Local/202@a_context_01-00000004;2", "_a_context_01,202,1") -- Goto (_a_context_01,202,1) -- Executing [202@_a_context_01:1] Macro("Local/202@a_context_01-00000004;2", "transfer,100007") -- Executing [s@macro-transfer:1] Macro("Local/202@a_context_01-00000004;2", "loopdetect,100007@_a_context_01") in new stack -- Executing [s@macro-loopdetect:1] Set("Local/202@a_context_01-00000004;2", "TMP=a1e384a002f3b1f4f01dd3b516e8803d") in new stack -- Executing [s@macro-loopdetect:2] GotoIf("Local/202@a_context_01-00000004;2", "0?5") in new stack -- Executing [s@macro-loopdetect:3] Set("Local/202@a_context_01-00000004;2", "__C=a1e384a002f3b1f4f01dd3b516e8803d-") in new stack -- Executing [s@macro-loopdetect:4] MacroExit("Local/202@a_context_01-00000004;2", "") in new stack -- Executing [s@macro-transfer:2] Macro("Local/202@a_context_01-00000004;2", "expire") in new stack -- Executing [s@macro-expire:1] GotoIf("Local/202@a_context_01-00000004;2", "1?4") in new stack -- Goto (macro-expire,s,4) -- Executing [s@macro-expire:4] Set("Local/202@a_context_01-00000004;2", "E=") in new stack -- Executing [s@macro-expire:5] GotoIf("Local/202@a_context_01-00000004;2", "1?7") in new stack -- Goto (macro-expire,s,7) -- Executing [s@macro-expire:7] MacroExit("Local/202@a_context_01-00000004;2", "") in new stack -- Executing [s@macro-transfer:3] Set("Local/202@a_context_01-00000004;2", "CDR(userfield)=transfer") in new stack -- Executing [s@macro-transfer:4] Set("Local/202@a_context_01-00000004;2", "_TRANS=transfer") in new stack -- Executing [s@macro-transfer:5] Dial("Local/202@a_context_01-00000004;2", "Local/100007@_a_context_01,,") in new stack -- Called Local/100007@_a_context_01 -- Executing [100007@_a_context_01:1] Macro("Local/100007@_a_context_01-00000005;2", "online,SIP/100003,BUSY") -- Executing [s@macro-online:1] Set("Local/100007@_a_context_01-00000005;2", "CHANNEL(language)=ja") in new stack -- Executing [s@macro-online:2] Macro("Local/100007@_a_context_01-00000005;2", "expire") in new stack -- Executing [s@macro-expire:1] GotoIf("Local/100007@_a_context_01-00000005;2", "1?4") in new stack -- Goto (macro-expire,s,4) -- Executing [s@macro-expire:4] Set("Local/100007@_a_context_01-00000005;2", "E=") in new stack -- Executing [s@macro-expire:5] GotoIf("Local/100007@_a_context_01-00000005;2", "1?7") in new stack -- Goto (macro-expire,s,7) -- Executing [s@macro-expire:7] MacroExit("Local/100007@_a_context_01-00000005;2", "") in new stack -- Executing [s@macro-online:3] Set("Local/100007@_a_context_01-00000005;2", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-online:4] ExecIf("Local/100007@_a_context_01-00000005;2", "1?Set(CDR(inboundphonenumber)=0363868357)") in new stack -- Executing [s@macro-online:5] GotoIf("Local/100007@_a_context_01-00000005;2", "1?7:6]") in new stack -- Goto (macro-online,s,7) -- Executing [s@macro-online:7] GotoIf("Local/100007@_a_context_01-00000005;2", "0?11:8") in new stack -- Goto (macro-online,s,8) -- Executing [s@macro-online:8] SIPRemoveHeader("Local/100007@_a_context_01-00000005;2", "P-Called-Party-ID") in new stack -- Executing [s@macro-online:9] SIPAddHeader("Local/100007@_a_context_01-00000005;2", "P-Called-Party-ID: sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-online:10] Set("Local/100007@_a_context_01-00000005;2", "__PCPID=") in new stack -- Executing [s@macro-online:11] Set("Local/100007@_a_context_01-00000005;2", "__CALLID=SIP/100003") in new stack -- Executing [s@macro-online:12] ExecIf("Local/100007@_a_context_01-00000005;2", "0?Set(CALLERID(num)=)") in new stack -- Executing [s@macro-online:13] AGI("Local/100007@_a_context_01-00000005;2", "setexten.agi,,0363161312,_a_context_01,SIP/100003") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/setexten.agi == Begin MixMonitor Recording Local/100007@_a_context_01-00000005;1 == Begin MixMonitor Recording Local/100007@_a_context_01-00000005;2 -- AGI Script Executing Application: (Set) Options: (RESULT=0) -- AGI Script Executing Application: (Set) Options: (EXTENSION=0) -- AGI Script Executing Application: (Set) Options: (CURRENT_CONTEXT=a_context_01) -- AGI Script Executing Application: (Set) Options: (DEBUG_MESSAGE=SIP_NOT_FOUND) -- AGI Script Executing Application: (Set) Options: (RESULT=NOT_MATCHED) -- AGI Script setexten.agi completed, returning 0 -- Executing [s@macro-online:14] ExecIf("Local/100007@_a_context_01-00000005;2", "0?Stasis(crm-popup") in new stack -- Executing [s@macro-online:15] Dial("Local/100007@_a_context_01-00000005;2", "SIP/100003,,tT") in new stack == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/100003-0000000e -- Called SIP/100003 -- Local/202@a_context_01-00000004;1 connected line has changed. Saving it until answer for SIP/DefaultGW-0000000d -- SIP/100003-0000000e is ringing -- Local/100007@_a_context_01-00000005;1 is ringing -- Local/202@a_context_01-00000004;1 is ringing -- SIP/100003-0000000e answered Local/100007@_a_context_01-00000005;2 -- Local/100007@_a_context_01-00000005;1 answered Local/202@a_context_01-00000004;2 -- Channel SIP/100003-0000000e joined 'simple_bridge' basic-bridge -- Local/202@a_context_01-00000004;1 answered SIP/DefaultGW-0000000d [Jun 20 12:30:54] NOTICE[1039][C-00000009]: app_queue.c:6720 try_calling: Delaying member connect for 1 seconds -- Channel Local/100007@_a_context_01-00000005;1 joined 'simple_bridge' basic-bridge -- Channel Local/100007@_a_context_01-00000005;2 joined 'simple_bridge' basic-bridge -- Channel Local/202@a_context_01-00000004;2 joined 'simple_bridge' basic-bridge -- Channel SIP/100003-0000000e left 'simple_bridge' basic-bridge -- Channel Local/100007@_a_context_01-00000005;2 left 'simple_bridge' basic-bridge == Spawn extension (macro-online, s, 15) exited non-zero on 'Local/100007@_a_context_01-00000005;2' in macro 'online' == Spawn extension (_a_context_01, 100007, 1) exited non-zero on 'Local/100007@_a_context_01-00000005;2' == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465450.47.wav] -- Executing [h@_a_context_01:1] ExecIf("Local/100007@_a_context_01-00000005;2", "0?Set(CDR(userfield)=incoming,)") -- Executing [h@_a_context_01:2] Macro("Local/100007@_a_context_01-00000005;2", "conclusion") -- Executing [s@macro-conclusion:1] Set("Local/100007@_a_context_01-00000005;2", "__BILLSEC=0") in new stack -- Executing [s@macro-conclusion:2] Set("Local/100007@_a_context_01-00000005;2", "__DISPOSITION=ANSWERED") in new stack -- Executing [s@macro-conclusion:3] Set("Local/100007@_a_context_01-00000005;2", "__LINKEDID=1529465450.42") in new stack -- Executing [s@macro-conclusion:4] Set("Local/100007@_a_context_01-00000005;2", "__USERFIELD=incoming") in new stack -- Executing [s@macro-conclusion:5] MacroExit("Local/100007@_a_context_01-00000005;2", "") in new stack == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465450.46.wav] -- Channel Local/100007@_a_context_01-00000005;1 left 'simple_bridge' basic-bridge -- Channel Local/202@a_context_01-00000004;2 left 'simple_bridge' basic-bridge == Spawn extension (macro-transfer, s, 5) exited non-zero on 'Local/202@a_context_01-00000004;2' in macro 'transfer' == Spawn extension (_a_context_01, 202, 1) exited non-zero on 'Local/202@a_context_01-00000004;2' == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465450.45.wav] -- Executing [h@_a_context_01:1] ExecIf("Local/202@a_context_01-00000004;2", "0?Set(CDR(userfield)=transfer,)") -- Executing [h@_a_context_01:2] Macro("Local/202@a_context_01-00000004;2", "conclusion") -- Executing [s@macro-conclusion:1] Set("Local/202@a_context_01-00000004;2", "__BILLSEC=0") in new stack -- Executing [s@macro-conclusion:2] Set("Local/202@a_context_01-00000004;2", "__DISPOSITION=ANSWERED") in new stack -- Executing [s@macro-conclusion:3] Set("Local/202@a_context_01-00000004;2", "__LINKEDID=1529465450.42") in new stack -- Executing [s@macro-conclusion:4] Set("Local/202@a_context_01-00000004;2", "__USERFIELD=transfer") in new stack -- Executing [s@macro-conclusion:5] MacroExit("Local/202@a_context_01-00000004;2", "") in new stack == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465450.44.wav] [Jun 20 12:30:55] WARNING[1039][C-00000009]: app_queue.c:6753 try_calling: Agent on Local/202@a_context_01-00000004;1 hungup on the customer. == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465450.43.wav] == End MixMonitor Recording Local/100007@_a_context_01-00000005;1 == End MixMonitor Recording Local/100007@_a_context_01-00000005;2 == End MixMonitor Recording SIP/100003-0000000e == End MixMonitor Recording Local/202@a_context_01-00000004;2 == End MixMonitor Recording Local/202@a_context_01-00000004;1 == Spawn extension (macro-queue, s, 12) exited non-zero on 'SIP/DefaultGW-0000000d' in macro 'queue' == Spawn extension (a_common, *00001, 4) exited non-zero on 'SIP/DefaultGW-0000000d' -- Executing [h@a_common:1] ExecIf("SIP/DefaultGW-0000000d", "1?Goto(3)") -- Goto (a_common,h,3) -- Executing [h@a_common:3] Macro("SIP/DefaultGW-0000000d", "conclusion") -- Executing [s@macro-conclusion:1] Set("SIP/DefaultGW-0000000d", "__BILLSEC=0") in new stack -- Executing [s@macro-conclusion:2] Set("SIP/DefaultGW-0000000d", "__DISPOSITION=ANSWERED") in new stack -- Executing [s@macro-conclusion:3] Set("SIP/DefaultGW-0000000d", "__LINKEDID=1529465450.42") in new stack -- Executing [s@macro-conclusion:4] Set("SIP/DefaultGW-0000000d", "__USERFIELD=incoming") in new stack -- Executing [s@macro-conclusion:5] MacroExit("SIP/DefaultGW-0000000d", "") in new stack == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465450.42.wav] == End MixMonitor Recording SIP/DefaultGW-0000000d 22-v3-02-act*CLI> 22-v3-02-act*CLI> 22-v3-02-act*CLI> 22-v3-02-act*CLI> =============================================== Make a call again after hanging up. ================================== 22-v3-02-act*CLI> 22-v3-02-act*CLI> 22-v3-02-act*CLI> 22-v3-02-act*CLI> == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/DefaultGW-0000000f -- Executing [0363868357@default:1] ExecIf("SIP/DefaultGW-0000000f", "0?Goto(3)") -- Executing [0363868357@default:2] Macro("SIP/DefaultGW-0000000f", "incominglimit") -- Executing [s@macro-incominglimit:1] Set("SIP/DefaultGW-0000000f", "CHANNEL=ja") in new stack -- Executing [s@macro-incominglimit:2] Set("SIP/DefaultGW-0000000f", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-incominglimit:3] Set("SIP/DefaultGW-0000000f", "__INBOUND_PHONE_NUM=0363868357") in new stack -- Executing [s@macro-incominglimit:4] Set("SIP/DefaultGW-0000000f", "CDR(inboundphonenumber)=0363868357") in new stack -- Executing [s@macro-incominglimit:5] Set("SIP/DefaultGW-0000000f", "_INCOMING=yes") in new stack -- Executing [s@macro-incominglimit:6] AGI("SIP/DefaultGW-0000000f", "incominglimit.agi,0363868357") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/incominglimit.agi -- AGI Script Executing Application: (Set) Options: (INCOMING_MAX=0) -- AGI Script Executing Application: (Set) Options: (INOUT_MAX=0) -- AGI Script Executing Application: (Set) Options: (TENANT_NAME=a) -- AGI Script Executing Application: (Set) Options: (LINE_MAX=30) -- AGI Script incominglimit.agi completed, returning 0 -- Executing [s@macro-incominglimit:7] Set("SIP/DefaultGW-0000000f", "GROUP(INOUT_a)=0363868357") in new stack -- Executing [s@macro-incominglimit:8] GotoIf("SIP/DefaultGW-0000000f", "1?set_incoming") in new stack -- Goto (macro-incominglimit,s,10) -- Executing [s@macro-incominglimit:10] Set("SIP/DefaultGW-0000000f", "GROUP(INCOMING_a)=0363868357") in new stack -- Executing [s@macro-incominglimit:11] GotoIf("SIP/DefaultGW-0000000f", "1?set_line") in new stack -- Goto (macro-incominglimit,s,13) -- Executing [s@macro-incominglimit:13] Set("SIP/DefaultGW-0000000f", "GROUP(LINE_a)=a") in new stack -- Executing [s@macro-incominglimit:14] GotoIf("SIP/DefaultGW-0000000f", "0?line_limit") in new stack -- Executing [s@macro-incominglimit:15] GotoIf("SIP/DefaultGW-0000000f", "0?line_limit") in new stack -- Executing [s@macro-incominglimit:16] MacroExit("SIP/DefaultGW-0000000f", "") in new stack -- Executing [0363868357@default:3] Macro("SIP/DefaultGW-0000000f", "p-called-party-id") -- Executing [s@macro-p-called-party-id:1] GotoIf("SIP/DefaultGW-0000000f", "0?7") in new stack -- Executing [s@macro-p-called-party-id:2] Set("SIP/DefaultGW-0000000f", "__INBOUND_EXTEN=0363868357") in new stack -- Executing [s@macro-p-called-party-id:3] GotoIf("SIP/DefaultGW-0000000f", "1?4:7") in new stack -- Goto (macro-p-called-party-id,s,4) -- Executing [s@macro-p-called-party-id:4] Set("SIP/DefaultGW-0000000f", "__PCPID=sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-p-called-party-id:5] NoOp("SIP/DefaultGW-0000000f", "PCPID: sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-p-called-party-id:6] MacroExit("SIP/DefaultGW-0000000f", "") in new stack -- Executing [0363868357@default:4] Macro("SIP/DefaultGW-0000000f", "privacy") -- Executing [s@macro-privacy:1] GotoIf("SIP/DefaultGW-0000000f", "0?end,1") in new stack -- Executing [s@macro-privacy:2] GotoIf("SIP/DefaultGW-0000000f", "1?9") in new stack -- Goto (macro-privacy,s,9) -- Executing [s@macro-privacy:9] Set("SIP/DefaultGW-0000000f", "RS=100001") in new stack -- Executing [s@macro-privacy:10] GosubIf("SIP/DefaultGW-0000000f", "1?map-1,1") in new stack -- Executing [map-1@macro-privacy:1] GotoIf("SIP/DefaultGW-0000000f", "0?7") in new stack -- Executing [map-1@macro-privacy:2] GotoIf("SIP/DefaultGW-0000000f", "0?set,1") in new stack -- Executing [map-1@macro-privacy:3] GotoIf("SIP/DefaultGW-0000000f", "0?set,1") in new stack -- Executing [map-1@macro-privacy:4] GotoIf("SIP/DefaultGW-0000000f", "0?set,1") in new stack -- Executing [map-1@macro-privacy:5] GotoIf("SIP/DefaultGW-0000000f", "0?set,1") in new stack -- Executing [map-1@macro-privacy:6] Return("SIP/DefaultGW-0000000f", "") in new stack -- Executing [s@macro-privacy:11] GotoIf("SIP/DefaultGW-0000000f", "1?15") in new stack -- Goto (macro-privacy,s,15) -- Executing [s@macro-privacy:15] GotoIf("SIP/DefaultGW-0000000f", "1?18") in new stack -- Goto (macro-privacy,s,18) -- Executing [s@macro-privacy:18] Goto("SIP/DefaultGW-0000000f", "ct-,1") in new stack -- Goto (macro-privacy,ct-,1) -- Executing [0363868357@default:5] Goto("SIP/DefaultGW-0000000f", "a_common,*00001,1") -- Goto (a_common,*00001,1) -- Executing [*00001@a_common:1] Set("SIP/DefaultGW-0000000f", "__INBOUND_TIME=1529465471") -- Executing [*00001@a_common:2] ExecIf("SIP/DefaultGW-0000000f", "0?Set(CDR(userfield)=incoming,)") -- Executing [*00001@a_common:3] Macro("SIP/DefaultGW-0000000f", "inbound-exectime,0,*,*,*,*,*,1529465471") -- Executing [s@macro-inbound-exectime:1] Set("SIP/DefaultGW-0000000f", "EXEC_INBOUND_APP=0") in new stack -- Executing [s@macro-inbound-exectime:2] NoOp("SIP/DefaultGW-0000000f", "Set(CDR(userfield)=incoming)") in new stack -- Executing [s@macro-inbound-exectime:3] GotoIf("SIP/DefaultGW-0000000f", "0?4:9") in new stack -- Goto (macro-inbound-exectime,s,9) -- Executing [s@macro-inbound-exectime:9] AGI("SIP/DefaultGW-0000000f", "inbound-exectime.agi,*,1529465471") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/inbound-exectime.agi -- AGI Script Executing Application: (Set) Options: (YEAR_RESULT=1) -- AGI Script inbound-exectime.agi completed, returning 0 -- Executing [s@macro-inbound-exectime:10] GotoIf("SIP/DefaultGW-0000000f", "0?13:11") in new stack -- Goto (macro-inbound-exectime,s,11) -- Executing [s@macro-inbound-exectime:11] ExecIfTime("SIP/DefaultGW-0000000f", "*,*,*,*?Set(EXEC_INBOUND_APP=1)") in new stack -- Executing [s@macro-inbound-exectime:12] NoOp("SIP/DefaultGW-0000000f", "current time matched: 1") in new stack -- Executing [s@macro-inbound-exectime:13] MacroExit("SIP/DefaultGW-0000000f", "") in new stack -- Executing [*00001@a_common:4] ExecIf("SIP/DefaultGW-0000000f", "1?Macro(queue,a_acd_01,ring,,,,)") -- Executing [s@macro-queue:1] Set("SIP/DefaultGW-0000000f", "__QUEUE_NOLOG=queue") in new stack -- Executing [s@macro-queue:2] NoOp("SIP/DefaultGW-0000000f", "Macro(setcallerid)") in new stack -- Executing [s@macro-queue:3] Macro("SIP/DefaultGW-0000000f", "queue-count,") in new stack -- Executing [s@macro-queue-count:1] Set("SIP/DefaultGW-0000000f", "ACD=1") in new stack -- Executing [s@macro-queue-count:2] ExecIf("SIP/DefaultGW-0000000f", "1?Set(ACD=100)") in new stack -- Executing [s@macro-queue-count:3] Set("SIP/DefaultGW-0000000f", "E=") in new stack -- Executing [s@macro-queue-count:4] GotoIf("SIP/DefaultGW-0000000f", "1?6") in new stack -- Goto (macro-queue-count,s,6) -- Executing [s@macro-queue-count:6] GotoIf("SIP/DefaultGW-0000000f", "1?8") in new stack -- Goto (macro-queue-count,s,8) -- Executing [s@macro-queue-count:8] Set("SIP/DefaultGW-0000000f", "GROUP()=queue") in new stack -- Executing [s@macro-queue-count:9] MacroExit("SIP/DefaultGW-0000000f", "") in new stack -- Executing [s@macro-queue:4] Set("SIP/DefaultGW-0000000f", "OPT=t") in new stack -- Executing [s@macro-queue:5] GotoIf("SIP/DefaultGW-0000000f", "1?6:8") in new stack -- Goto (macro-queue,s,6) -- Executing [s@macro-queue:6] Set("SIP/DefaultGW-0000000f", "OPT=tr") in new stack -- Executing [s@macro-queue:7] Goto("SIP/DefaultGW-0000000f", "11") in new stack -- Goto (macro-queue,s,11) -- Executing [s@macro-queue:11] Set("SIP/DefaultGW-0000000f", "__TRAIL=a_acd_01") in new stack -- Executing [s@macro-queue:12] Queue("SIP/DefaultGW-0000000f", "a_acd_01,tr,,,,,trailclear") in new stack == Spawn extension (macro-queue, s, 12) exited non-zero on 'SIP/DefaultGW-0000000f' in macro 'queue' == Spawn extension (a_common, *00001, 4) exited non-zero on 'SIP/DefaultGW-0000000f' -- Executing [h@a_common:1] ExecIf("SIP/DefaultGW-0000000f", "1?Goto(3)") -- Goto (a_common,h,3) -- Executing [h@a_common:3] Macro("SIP/DefaultGW-0000000f", "conclusion") -- Executing [s@macro-conclusion:1] Set("SIP/DefaultGW-0000000f", "__BILLSEC=0") in new stack -- Executing [s@macro-conclusion:2] Set("SIP/DefaultGW-0000000f", "__DISPOSITION=FAILED") in new stack -- Executing [s@macro-conclusion:3] Set("SIP/DefaultGW-0000000f", "__LINKEDID=1529465471.55") in new stack -- Executing [s@macro-conclusion:4] Set("SIP/DefaultGW-0000000f", "__USERFIELD=incoming") in new stack -- Executing [s@macro-conclusion:5] MacroExit("SIP/DefaultGW-0000000f", "") in new stack == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465471.55.wav] == End MixMonitor Recording SIP/DefaultGW-0000000f 22-v3-02-act*CLI> ========================================================================================== Unconditional Call Forward (worked well) LOGS ==================== [root@22-v3-02-act x86_64]# asterisk -rvvvvvvv Asterisk 13.21.0, Copyright (C) 1999 - 2014, Digium, Inc. and others. Created by Mark Spencer Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details. This is free software, with components licensed under the GNU General Public License version 2 and other licenses; you are welcome to redistribute it under certain conditions. Type 'core show license' for details. ========================================================================= Connected to Asterisk 13.21.0 currently running on 22-v3-02-act (pid = 4209) == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/DefaultGW-00000007 -- Executing [0363868357@default:1] ExecIf("SIP/DefaultGW-00000007", "0?Goto(3)") -- Executing [0363868357@default:2] Macro("SIP/DefaultGW-00000007", "incominglimit") -- Executing [s@macro-incominglimit:1] Set("SIP/DefaultGW-00000007", "CHANNEL=ja") in new stack -- Executing [s@macro-incominglimit:2] Set("SIP/DefaultGW-00000007", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-incominglimit:3] Set("SIP/DefaultGW-00000007", "__INBOUND_PHONE_NUM=0363868357") in new stack -- Executing [s@macro-incominglimit:4] Set("SIP/DefaultGW-00000007", "CDR(inboundphonenumber)=0363868357") in new stack -- Executing [s@macro-incominglimit:5] Set("SIP/DefaultGW-00000007", "_INCOMING=yes") in new stack -- Executing [s@macro-incominglimit:6] AGI("SIP/DefaultGW-00000007", "incominglimit.agi,0363868357") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/incominglimit.agi -- AGI Script Executing Application: (Set) Options: (INCOMING_MAX=0) -- AGI Script Executing Application: (Set) Options: (INOUT_MAX=0) -- AGI Script Executing Application: (Set) Options: (TENANT_NAME=a) -- AGI Script Executing Application: (Set) Options: (LINE_MAX=30) -- AGI Script incominglimit.agi completed, returning 0 -- Executing [s@macro-incominglimit:7] Set("SIP/DefaultGW-00000007", "GROUP(INOUT_a)=0363868357") in new stack -- Executing [s@macro-incominglimit:8] GotoIf("SIP/DefaultGW-00000007", "1?set_incoming") in new stack -- Goto (macro-incominglimit,s,10) -- Executing [s@macro-incominglimit:10] Set("SIP/DefaultGW-00000007", "GROUP(INCOMING_a)=0363868357") in new stack -- Executing [s@macro-incominglimit:11] GotoIf("SIP/DefaultGW-00000007", "1?set_line") in new stack -- Goto (macro-incominglimit,s,13) -- Executing [s@macro-incominglimit:13] Set("SIP/DefaultGW-00000007", "GROUP(LINE_a)=a") in new stack -- Executing [s@macro-incominglimit:14] GotoIf("SIP/DefaultGW-00000007", "0?line_limit") in new stack -- Executing [s@macro-incominglimit:15] GotoIf("SIP/DefaultGW-00000007", "0?line_limit") in new stack -- Executing [s@macro-incominglimit:16] MacroExit("SIP/DefaultGW-00000007", "") in new stack -- Executing [0363868357@default:3] Macro("SIP/DefaultGW-00000007", "p-called-party-id") -- Executing [s@macro-p-called-party-id:1] GotoIf("SIP/DefaultGW-00000007", "0?7") in new stack -- Executing [s@macro-p-called-party-id:2] Set("SIP/DefaultGW-00000007", "__INBOUND_EXTEN=0363868357") in new stack -- Executing [s@macro-p-called-party-id:3] GotoIf("SIP/DefaultGW-00000007", "1?4:7") in new stack -- Goto (macro-p-called-party-id,s,4) -- Executing [s@macro-p-called-party-id:4] Set("SIP/DefaultGW-00000007", "__PCPID=sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-p-called-party-id:5] NoOp("SIP/DefaultGW-00000007", "PCPID: sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-p-called-party-id:6] MacroExit("SIP/DefaultGW-00000007", "") in new stack -- Executing [0363868357@default:4] Macro("SIP/DefaultGW-00000007", "privacy") -- Executing [s@macro-privacy:1] GotoIf("SIP/DefaultGW-00000007", "0?end,1") in new stack -- Executing [s@macro-privacy:2] GotoIf("SIP/DefaultGW-00000007", "1?9") in new stack -- Goto (macro-privacy,s,9) -- Executing [s@macro-privacy:9] Set("SIP/DefaultGW-00000007", "RS=100001") in new stack -- Executing [s@macro-privacy:10] GosubIf("SIP/DefaultGW-00000007", "1?map-1,1") in new stack -- Executing [map-1@macro-privacy:1] GotoIf("SIP/DefaultGW-00000007", "0?7") in new stack -- Executing [map-1@macro-privacy:2] GotoIf("SIP/DefaultGW-00000007", "0?set,1") in new stack -- Executing [map-1@macro-privacy:3] GotoIf("SIP/DefaultGW-00000007", "0?set,1") in new stack -- Executing [map-1@macro-privacy:4] GotoIf("SIP/DefaultGW-00000007", "0?set,1") in new stack -- Executing [map-1@macro-privacy:5] GotoIf("SIP/DefaultGW-00000007", "0?set,1") in new stack -- Executing [map-1@macro-privacy:6] Return("SIP/DefaultGW-00000007", "") in new stack -- Executing [s@macro-privacy:11] GotoIf("SIP/DefaultGW-00000007", "1?15") in new stack -- Goto (macro-privacy,s,15) -- Executing [s@macro-privacy:15] GotoIf("SIP/DefaultGW-00000007", "1?18") in new stack -- Goto (macro-privacy,s,18) -- Executing [s@macro-privacy:18] Goto("SIP/DefaultGW-00000007", "ct-,1") in new stack -- Goto (macro-privacy,ct-,1) -- Executing [0363868357@default:5] Goto("SIP/DefaultGW-00000007", "a_common,*00001,1") -- Goto (a_common,*00001,1) -- Executing [*00001@a_common:1] Set("SIP/DefaultGW-00000007", "__INBOUND_TIME=1529465696") -- Executing [*00001@a_common:2] ExecIf("SIP/DefaultGW-00000007", "0?Set(CDR(userfield)=incoming,)") -- Executing [*00001@a_common:3] Macro("SIP/DefaultGW-00000007", "inbound-exectime,0,*,*,*,*,*,1529465696") -- Executing [s@macro-inbound-exectime:1] Set("SIP/DefaultGW-00000007", "EXEC_INBOUND_APP=0") in new stack -- Executing [s@macro-inbound-exectime:2] NoOp("SIP/DefaultGW-00000007", "Set(CDR(userfield)=incoming)") in new stack -- Executing [s@macro-inbound-exectime:3] GotoIf("SIP/DefaultGW-00000007", "0?4:9") in new stack -- Goto (macro-inbound-exectime,s,9) -- Executing [s@macro-inbound-exectime:9] AGI("SIP/DefaultGW-00000007", "inbound-exectime.agi,*,1529465696") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/inbound-exectime.agi -- AGI Script Executing Application: (Set) Options: (YEAR_RESULT=1) -- AGI Script inbound-exectime.agi completed, returning 0 -- Executing [s@macro-inbound-exectime:10] GotoIf("SIP/DefaultGW-00000007", "0?13:11") in new stack -- Goto (macro-inbound-exectime,s,11) -- Executing [s@macro-inbound-exectime:11] ExecIfTime("SIP/DefaultGW-00000007", "*,*,*,*?Set(EXEC_INBOUND_APP=1)") in new stack -- Executing [s@macro-inbound-exectime:12] NoOp("SIP/DefaultGW-00000007", "current time matched: 1") in new stack -- Executing [s@macro-inbound-exectime:13] MacroExit("SIP/DefaultGW-00000007", "") in new stack -- Executing [*00001@a_common:4] ExecIf("SIP/DefaultGW-00000007", "1?Macro(queue,a_acd_01,ring,,,,)") -- Executing [s@macro-queue:1] Set("SIP/DefaultGW-00000007", "__QUEUE_NOLOG=queue") in new stack -- Executing [s@macro-queue:2] NoOp("SIP/DefaultGW-00000007", "Macro(setcallerid)") in new stack -- Executing [s@macro-queue:3] Macro("SIP/DefaultGW-00000007", "queue-count,") in new stack -- Executing [s@macro-queue-count:1] Set("SIP/DefaultGW-00000007", "ACD=1") in new stack -- Executing [s@macro-queue-count:2] ExecIf("SIP/DefaultGW-00000007", "1?Set(ACD=100)") in new stack -- Executing [s@macro-queue-count:3] Set("SIP/DefaultGW-00000007", "E=") in new stack -- Executing [s@macro-queue-count:4] GotoIf("SIP/DefaultGW-00000007", "1?6") in new stack -- Goto (macro-queue-count,s,6) -- Executing [s@macro-queue-count:6] GotoIf("SIP/DefaultGW-00000007", "1?8") in new stack -- Goto (macro-queue-count,s,8) -- Executing [s@macro-queue-count:8] Set("SIP/DefaultGW-00000007", "GROUP()=queue") in new stack -- Executing [s@macro-queue-count:9] MacroExit("SIP/DefaultGW-00000007", "") in new stack -- Executing [s@macro-queue:4] Set("SIP/DefaultGW-00000007", "OPT=t") in new stack -- Executing [s@macro-queue:5] GotoIf("SIP/DefaultGW-00000007", "1?6:8") in new stack -- Goto (macro-queue,s,6) -- Executing [s@macro-queue:6] Set("SIP/DefaultGW-00000007", "OPT=tr") in new stack -- Executing [s@macro-queue:7] Goto("SIP/DefaultGW-00000007", "11") in new stack -- Goto (macro-queue,s,11) -- Executing [s@macro-queue:11] Set("SIP/DefaultGW-00000007", "__TRAIL=a_acd_01") in new stack -- Executing [s@macro-queue:12] Queue("SIP/DefaultGW-00000007", "a_acd_01,tr,,,,,trailclear") in new stack -- Called Local/202@a_context_01/n -- Executing [202@a_context_01:1] Set("Local/202@a_context_01-00000001;2", "CHANNEL(parkinglot)=parkinglot_a_01") == Begin MixMonitor Recording Local/202@a_context_01-00000001;1 -- Executing [202@a_context_01:2] Goto("Local/202@a_context_01-00000001;2", "_a_context_01,202,1") -- Goto (_a_context_01,202,1) == Begin MixMonitor Recording Local/202@a_context_01-00000001;2 -- Executing [202@_a_context_01:1] Macro("Local/202@a_context_01-00000001;2", "transfer,100007") -- Executing [s@macro-transfer:1] Macro("Local/202@a_context_01-00000001;2", "loopdetect,100007@_a_context_01") in new stack -- Executing [s@macro-loopdetect:1] Set("Local/202@a_context_01-00000001;2", "TMP=a1e384a002f3b1f4f01dd3b516e8803d") in new stack -- Executing [s@macro-loopdetect:2] GotoIf("Local/202@a_context_01-00000001;2", "0?5") in new stack -- Executing [s@macro-loopdetect:3] Set("Local/202@a_context_01-00000001;2", "__C=a1e384a002f3b1f4f01dd3b516e8803d-") in new stack -- Executing [s@macro-loopdetect:4] MacroExit("Local/202@a_context_01-00000001;2", "") in new stack -- Executing [s@macro-transfer:2] Macro("Local/202@a_context_01-00000001;2", "expire") in new stack -- Executing [s@macro-expire:1] GotoIf("Local/202@a_context_01-00000001;2", "1?4") in new stack -- Goto (macro-expire,s,4) -- Executing [s@macro-expire:4] Set("Local/202@a_context_01-00000001;2", "E=") in new stack -- Executing [s@macro-expire:5] GotoIf("Local/202@a_context_01-00000001;2", "1?7") in new stack -- Goto (macro-expire,s,7) -- Executing [s@macro-expire:7] MacroExit("Local/202@a_context_01-00000001;2", "") in new stack -- Executing [s@macro-transfer:3] Set("Local/202@a_context_01-00000001;2", "CDR(userfield)=transfer") in new stack -- Executing [s@macro-transfer:4] Set("Local/202@a_context_01-00000001;2", "_TRANS=transfer") in new stack -- Executing [s@macro-transfer:5] Dial("Local/202@a_context_01-00000001;2", "Local/100007@_a_context_01,,") in new stack -- Called Local/100007@_a_context_01 == Begin MixMonitor Recording Local/100007@_a_context_01-00000002;1 -- Executing [100007@_a_context_01:1] Macro("Local/100007@_a_context_01-00000002;2", "online,SIP/100003,BUSY") -- Executing [s@macro-online:1] Set("Local/100007@_a_context_01-00000002;2", "CHANNEL(language)=ja") in new stack -- Executing [s@macro-online:2] Macro("Local/100007@_a_context_01-00000002;2", "expire") in new stack -- Executing [s@macro-expire:1] GotoIf("Local/100007@_a_context_01-00000002;2", "1?4") in new stack -- Goto (macro-expire,s,4) -- Executing [s@macro-expire:4] Set("Local/100007@_a_context_01-00000002;2", "E=") in new stack -- Executing [s@macro-expire:5] GotoIf("Local/100007@_a_context_01-00000002;2", "1?7") in new stack -- Goto (macro-expire,s,7) -- Executing [s@macro-expire:7] MacroExit("Local/100007@_a_context_01-00000002;2", "") in new stack -- Executing [s@macro-online:3] Set("Local/100007@_a_context_01-00000002;2", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-online:4] ExecIf("Local/100007@_a_context_01-00000002;2", "1?Set(CDR(inboundphonenumber)=0363868357)") in new stack -- Executing [s@macro-online:5] GotoIf("Local/100007@_a_context_01-00000002;2", "1?7:6]") in new stack -- Goto (macro-online,s,7) -- Executing [s@macro-online:7] GotoIf("Local/100007@_a_context_01-00000002;2", "0?11:8") in new stack -- Goto (macro-online,s,8) -- Executing [s@macro-online:8] SIPRemoveHeader("Local/100007@_a_context_01-00000002;2", "P-Called-Party-ID") in new stack -- Executing [s@macro-online:9] SIPAddHeader("Local/100007@_a_context_01-00000002;2", "P-Called-Party-ID: sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-online:10] Set("Local/100007@_a_context_01-00000002;2", "__PCPID=") in new stack -- Executing [s@macro-online:11] Set("Local/100007@_a_context_01-00000002;2", "__CALLID=SIP/100003") in new stack -- Executing [s@macro-online:12] ExecIf("Local/100007@_a_context_01-00000002;2", "0?Set(CALLERID(num)=)") in new stack -- Executing [s@macro-online:13] AGI("Local/100007@_a_context_01-00000002;2", "setexten.agi,,0363161312,_a_context_01,SIP/100003") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/setexten.agi == Begin MixMonitor Recording Local/100007@_a_context_01-00000002;2 -- AGI Script Executing Application: (Set) Options: (RESULT=0) -- AGI Script Executing Application: (Set) Options: (EXTENSION=0) -- AGI Script Executing Application: (Set) Options: (CURRENT_CONTEXT=a_context_01) -- AGI Script Executing Application: (Set) Options: (DEBUG_MESSAGE=SIP_NOT_FOUND) -- AGI Script Executing Application: (Set) Options: (RESULT=NOT_MATCHED) -- AGI Script setexten.agi completed, returning 0 -- Executing [s@macro-online:14] ExecIf("Local/100007@_a_context_01-00000002;2", "0?Stasis(crm-popup") in new stack -- Executing [s@macro-online:15] Dial("Local/100007@_a_context_01-00000002;2", "SIP/100003,,tT") in new stack == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/100003-00000008 -- Called SIP/100003 -- Local/202@a_context_01-00000001;1 connected line has changed. Saving it until answer for SIP/DefaultGW-00000007 -- SIP/100003-00000008 is ringing -- Local/100007@_a_context_01-00000002;1 is ringing -- Local/202@a_context_01-00000001;1 is ringing -- SIP/100003-00000008 answered Local/100007@_a_context_01-00000002;2 -- Local/100007@_a_context_01-00000002;1 answered Local/202@a_context_01-00000001;2 -- Channel SIP/100003-00000008 joined 'simple_bridge' basic-bridge -- Local/202@a_context_01-00000001;1 answered SIP/DefaultGW-00000007 [Jun 20 12:35:00] NOTICE[6634][C-00000006]: app_queue.c:6720 try_calling: Delaying member connect for 1 seconds -- Channel Local/100007@_a_context_01-00000002;1 joined 'simple_bridge' basic-bridge <5ba62381-1003-4fb4-83bb-78d30cad93a3> -- Channel Local/100007@_a_context_01-00000002;2 joined 'simple_bridge' basic-bridge -- Channel Local/202@a_context_01-00000001;2 joined 'simple_bridge' basic-bridge <5ba62381-1003-4fb4-83bb-78d30cad93a3> -- Executing [s@macro-trailclear:1] Set("Local/202@a_context_01-00000001;1", "__TRAIL=''") in new stack -- Channel Local/202@a_context_01-00000001;1 joined 'simple_bridge' basic-bridge -- Channel SIP/DefaultGW-00000007 joined 'simple_bridge' basic-bridge -- Channel SIP/100003-00000008 left 'simple_bridge' basic-bridge -- Channel Local/100007@_a_context_01-00000002;2 left 'simple_bridge' basic-bridge == Spawn extension (macro-online, s, 15) exited non-zero on 'Local/100007@_a_context_01-00000002;2' in macro 'online' == Spawn extension (_a_context_01, 100007, 1) exited non-zero on 'Local/100007@_a_context_01-00000002;2' == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465696.23.wav] -- Executing [h@_a_context_01:1] ExecIf("Local/100007@_a_context_01-00000002;2", "0?Set(CDR(userfield)=incoming,)") -- Executing [h@_a_context_01:2] Macro("Local/100007@_a_context_01-00000002;2", "conclusion") -- Executing [s@macro-conclusion:1] Set("Local/100007@_a_context_01-00000002;2", "__BILLSEC=9") in new stack -- Executing [s@macro-conclusion:2] Set("Local/100007@_a_context_01-00000002;2", "__DISPOSITION=ANSWERED") in new stack -- Executing [s@macro-conclusion:3] Set("Local/100007@_a_context_01-00000002;2", "__LINKEDID=1529465696.18") in new stack -- Executing [s@macro-conclusion:4] Set("Local/100007@_a_context_01-00000002;2", "__USERFIELD=incoming") in new stack -- Executing [s@macro-conclusion:5] MacroExit("Local/100007@_a_context_01-00000002;2", "") in new stack == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465696.22.wav] -- Channel Local/100007@_a_context_01-00000002;1 left 'simple_bridge' basic-bridge <5ba62381-1003-4fb4-83bb-78d30cad93a3> -- Channel Local/202@a_context_01-00000001;2 left 'simple_bridge' basic-bridge <5ba62381-1003-4fb4-83bb-78d30cad93a3> == Spawn extension (macro-transfer, s, 5) exited non-zero on 'Local/202@a_context_01-00000001;2' in macro 'transfer' == Spawn extension (_a_context_01, 202, 1) exited non-zero on 'Local/202@a_context_01-00000001;2' == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465696.21.wav] -- Executing [h@_a_context_01:1] ExecIf("Local/202@a_context_01-00000001;2", "0?Set(CDR(userfield)=transfer,)") -- Executing [h@_a_context_01:2] Macro("Local/202@a_context_01-00000001;2", "conclusion") -- Executing [s@macro-conclusion:1] Set("Local/202@a_context_01-00000001;2", "__BILLSEC=9") in new stack -- Executing [s@macro-conclusion:2] Set("Local/202@a_context_01-00000001;2", "__DISPOSITION=ANSWERED") in new stack -- Executing [s@macro-conclusion:3] Set("Local/202@a_context_01-00000001;2", "__LINKEDID=1529465696.18") in new stack -- Executing [s@macro-conclusion:4] Set("Local/202@a_context_01-00000001;2", "__USERFIELD=transfer") in new stack -- Executing [s@macro-conclusion:5] MacroExit("Local/202@a_context_01-00000001;2", "") in new stack == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465696.20.wav] -- Channel Local/202@a_context_01-00000001;1 left 'simple_bridge' basic-bridge -- Channel SIP/DefaultGW-00000007 left 'simple_bridge' basic-bridge == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465696.19.wav] == Spawn extension (macro-queue, s, 12) exited non-zero on 'SIP/DefaultGW-00000007' in macro 'queue' == Spawn extension (a_common, *00001, 4) exited non-zero on 'SIP/DefaultGW-00000007' -- Executing [h@a_common:1] ExecIf("SIP/DefaultGW-00000007", "1?Goto(3)") -- Goto (a_common,h,3) -- Executing [h@a_common:3] Macro("SIP/DefaultGW-00000007", "conclusion") -- Executing [s@macro-conclusion:1] Set("SIP/DefaultGW-00000007", "__BILLSEC=8") in new stack -- Executing [s@macro-conclusion:2] Set("SIP/DefaultGW-00000007", "__DISPOSITION=ANSWERED") in new stack -- Executing [s@macro-conclusion:3] Set("SIP/DefaultGW-00000007", "__LINKEDID=1529465696.18") in new stack -- Executing [s@macro-conclusion:4] Set("SIP/DefaultGW-00000007", "__USERFIELD=incoming") in new stack -- Executing [s@macro-conclusion:5] MacroExit("SIP/DefaultGW-00000007", "") in new stack == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201806/1529465696.18.wav] == End MixMonitor Recording Local/100007@_a_context_01-00000002;1 == End MixMonitor Recording SIP/100003-00000008 == End MixMonitor Recording Local/202@a_context_01-00000001;2 == End MixMonitor Recording Local/100007@_a_context_01-00000002;2 == End MixMonitor Recording Local/202@a_context_01-00000001;1 == End MixMonitor Recording SIP/DefaultGW-00000007 22-v3-02-act*CLI>