1. Make a call to extention 202(phone A) from an outside phone. ======================= 22-v3-02-act*CLI> == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/DefaultGW-00000000 -- Executing [0363868357@default:1] Macro("SIP/DefaultGW-00000000", "incominglimit") -- Executing [s@macro-incominglimit:1] Set("SIP/DefaultGW-00000000", "CHANNEL=ja") in new stack -- Executing [s@macro-incominglimit:2] Set("SIP/DefaultGW-00000000", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-incominglimit:3] Set("SIP/DefaultGW-00000000", "__INBOUND_PHONE_NUM=0363868357") in new stack -- Executing [s@macro-incominglimit:4] Set("SIP/DefaultGW-00000000", "CDR(inboundphonenumber)=0363868357") in new stack -- Executing [s@macro-incominglimit:5] Set("SIP/DefaultGW-00000000", "_INCOMING=yes") in new stack -- Executing [s@macro-incominglimit:6] AGI("SIP/DefaultGW-00000000", "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-00000000", "GROUP(INOUT_a)=0363868357") in new stack -- Executing [s@macro-incominglimit:8] GotoIf("SIP/DefaultGW-00000000", "1?set_incoming") in new stack -- Goto (macro-incominglimit,s,10) -- Executing [s@macro-incominglimit:10] Set("SIP/DefaultGW-00000000", "GROUP(INCOMING_a)=0363868357") in new stack -- Executing [s@macro-incominglimit:11] GotoIf("SIP/DefaultGW-00000000", "1?set_line") in new stack -- Goto (macro-incominglimit,s,13) -- Executing [s@macro-incominglimit:13] Set("SIP/DefaultGW-00000000", "GROUP(LINE_a)=a") in new stack -- Executing [s@macro-incominglimit:14] GotoIf("SIP/DefaultGW-00000000", "0?line_limit") in new stack -- Executing [s@macro-incominglimit:15] GotoIf("SIP/DefaultGW-00000000", "0?line_limit") in new stack -- Executing [s@macro-incominglimit:16] MacroExit("SIP/DefaultGW-00000000", "") in new stack -- Executing [0363868357@default:2] Macro("SIP/DefaultGW-00000000", "p-called-party-id") -- Executing [s@macro-p-called-party-id:1] GotoIf("SIP/DefaultGW-00000000", "0?7") in new stack -- Executing [s@macro-p-called-party-id:2] Set("SIP/DefaultGW-00000000", "__INBOUND_EXTEN=0363868357") in new stack -- Executing [s@macro-p-called-party-id:3] GotoIf("SIP/DefaultGW-00000000", "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-00000000", "__PCPID=sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-p-called-party-id:5] NoOp("SIP/DefaultGW-00000000", "PCPID: sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-p-called-party-id:6] MacroExit("SIP/DefaultGW-00000000", "") in new stack -- Executing [0363868357@default:3] Macro("SIP/DefaultGW-00000000", "privacy") -- Executing [s@macro-privacy:1] GotoIf("SIP/DefaultGW-00000000", "0?end,1") in new stack -- Executing [s@macro-privacy:2] GotoIf("SIP/DefaultGW-00000000", "1?9") in new stack -- Goto (macro-privacy,s,9) -- Executing [s@macro-privacy:9] Set("SIP/DefaultGW-00000000", "RS=205") in new stack -- Executing [s@macro-privacy:10] GosubIf("SIP/DefaultGW-00000000", "1?map-1,1") in new stack -- Executing [map-1@macro-privacy:1] GotoIf("SIP/DefaultGW-00000000", "0?7") in new stack -- Executing [map-1@macro-privacy:2] GotoIf("SIP/DefaultGW-00000000", "0?set,1") in new stack -- Executing [map-1@macro-privacy:3] GotoIf("SIP/DefaultGW-00000000", "0?set,1") in new stack -- Executing [map-1@macro-privacy:4] GotoIf("SIP/DefaultGW-00000000", "0?set,1") in new stack -- Executing [map-1@macro-privacy:5] GotoIf("SIP/DefaultGW-00000000", "0?set,1") in new stack -- Executing [map-1@macro-privacy:6] Return("SIP/DefaultGW-00000000", "") in new stack -- Executing [s@macro-privacy:11] GotoIf("SIP/DefaultGW-00000000", "1?15") in new stack -- Goto (macro-privacy,s,15) -- Executing [s@macro-privacy:15] GotoIf("SIP/DefaultGW-00000000", "1?18") in new stack -- Goto (macro-privacy,s,18) -- Executing [s@macro-privacy:18] Goto("SIP/DefaultGW-00000000", "ct-,1") in new stack -- Goto (macro-privacy,ct-,1) -- Executing [0363868357@default:4] Goto("SIP/DefaultGW-00000000", "a_common,*00001,1") -- Goto (a_common,*00001,1) -- Executing [*00001@a_common:1] Set("SIP/DefaultGW-00000000", "__INBOUND_TIME=1526450664") -- Executing [*00001@a_common:2] ExecIf("SIP/DefaultGW-00000000", "0?Set(CDR(userfield)=incoming,)") -- Executing [*00001@a_common:3] Macro("SIP/DefaultGW-00000000", "inbound-exectime,0,*,*,*,*,*,1526450664") -- Executing [s@macro-inbound-exectime:1] Set("SIP/DefaultGW-00000000", "EXEC_INBOUND_APP=0") in new stack -- Executing [s@macro-inbound-exectime:2] NoOp("SIP/DefaultGW-00000000", "Set(CDR(userfield)=incoming)") in new stack -- Executing [s@macro-inbound-exectime:3] GotoIf("SIP/DefaultGW-00000000", "0?4:9") in new stack -- Goto (macro-inbound-exectime,s,9) -- Executing [s@macro-inbound-exectime:9] AGI("SIP/DefaultGW-00000000", "inbound-exectime.agi,*,1526450664") 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-00000000", "0?13:11") in new stack -- Goto (macro-inbound-exectime,s,11) -- Executing [s@macro-inbound-exectime:11] ExecIfTime("SIP/DefaultGW-00000000", "*,*,*,*?Set(EXEC_INBOUND_APP=1)") in new stack -- Executing [s@macro-inbound-exectime:12] NoOp("SIP/DefaultGW-00000000", "current time matched: 1") in new stack -- Executing [s@macro-inbound-exectime:13] MacroExit("SIP/DefaultGW-00000000", "") in new stack -- Executing [*00001@a_common:4] ExecIf("SIP/DefaultGW-00000000", "1?Macro(queue,a_acd_01,ring,,,,)") -- Executing [s@macro-queue:1] Set("SIP/DefaultGW-00000000", "__QUEUE_NOLOG=queue") in new stack -- Executing [s@macro-queue:2] NoOp("SIP/DefaultGW-00000000", "Macro(setcallerid)") in new stack -- Executing [s@macro-queue:3] Macro("SIP/DefaultGW-00000000", "queue-count,") in new stack -- Executing [s@macro-queue-count:1] Set("SIP/DefaultGW-00000000", "ACD=1") in new stack -- Executing [s@macro-queue-count:2] ExecIf("SIP/DefaultGW-00000000", "1?Set(ACD=100)") in new stack -- Executing [s@macro-queue-count:3] Set("SIP/DefaultGW-00000000", "E=") in new stack -- Executing [s@macro-queue-count:4] GotoIf("SIP/DefaultGW-00000000", "1?6") in new stack -- Goto (macro-queue-count,s,6) -- Executing [s@macro-queue-count:6] GotoIf("SIP/DefaultGW-00000000", "1?8") in new stack -- Goto (macro-queue-count,s,8) -- Executing [s@macro-queue-count:8] Set("SIP/DefaultGW-00000000", "GROUP()=queue") in new stack -- Executing [s@macro-queue-count:9] MacroExit("SIP/DefaultGW-00000000", "") in new stack -- Executing [s@macro-queue:4] Set("SIP/DefaultGW-00000000", "OPT=t") in new stack -- Executing [s@macro-queue:5] GotoIf("SIP/DefaultGW-00000000", "1?6:8") in new stack -- Goto (macro-queue,s,6) -- Executing [s@macro-queue:6] Set("SIP/DefaultGW-00000000", "OPT=tr") in new stack -- Executing [s@macro-queue:7] Goto("SIP/DefaultGW-00000000", "11") in new stack -- Goto (macro-queue,s,11) -- Executing [s@macro-queue:11] Set("SIP/DefaultGW-00000000", "__TRAIL=a_acd_01") in new stack -- Executing [s@macro-queue:12] Queue("SIP/DefaultGW-00000000", "a_acd_01,tr,,,,,trailclear") in new stack -- Called Local/201@a_context_01/n -- Executing [201@a_context_01:1] Set("Local/201@a_context_01-00000000;2", "CHANNEL(parkinglot)=parkinglot_a_01") -- Called Local/202@a_context_01/n == Begin MixMonitor Recording Local/201@a_context_01-00000000;1 == Begin MixMonitor Recording Local/201@a_context_01-00000000;2 -- Executing [201@a_context_01:2] Goto("Local/201@a_context_01-00000000;2", "_a_context_01,201,1") -- Goto (_a_context_01,201,1) == Begin MixMonitor Recording Local/202@a_context_01-00000001;1 == Begin MixMonitor Recording Local/202@a_context_01-00000001;2 -- Executing [202@a_context_01:1] Set("Local/202@a_context_01-00000001;2", "CHANNEL(parkinglot)=parkinglot_a_01") -- Executing [201@_a_context_01:1] Macro("Local/201@a_context_01-00000000;2", "online,SIP/100003,BUSY") -- Executing [s@macro-online:1] Set("Local/201@a_context_01-00000000;2", "CHANNEL(language)=ja") in new stack -- Executing [s@macro-online:2] Macro("Local/201@a_context_01-00000000;2", "expire") in new stack -- Executing [s@macro-expire:1] GotoIf("Local/201@a_context_01-00000000;2", "1?4") in new stack -- Goto (macro-expire,s,4) -- Executing [s@macro-expire:4] Set("Local/201@a_context_01-00000000;2", "E=") in new stack -- Executing [s@macro-expire:5] GotoIf("Local/201@a_context_01-00000000;2", "1?7") in new stack -- Goto (macro-expire,s,7) -- Executing [s@macro-expire:7] MacroExit("Local/201@a_context_01-00000000;2", "") in new stack -- Executing [s@macro-online:3] Set("Local/201@a_context_01-00000000;2", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-online:4] ExecIf("Local/201@a_context_01-00000000;2", "1?Set(CDR(inboundphonenumber)=0363868357)") in new stack -- Executing [s@macro-online:5] GotoIf("Local/201@a_context_01-00000000;2", "1?7:6]") in new stack -- Goto (macro-online,s,7) -- Executing [s@macro-online:7] GotoIf("Local/201@a_context_01-00000000;2", "0?11:8") in new stack -- Goto (macro-online,s,8) -- Executing [s@macro-online:8] SIPRemoveHeader("Local/201@a_context_01-00000000;2", "P-Called-Party-ID") in new stack -- Executing [s@macro-online:9] SIPAddHeader("Local/201@a_context_01-00000000;2", "P-Called-Party-ID: sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-online:10] Set("Local/201@a_context_01-00000000;2", "__PCPID=") in new stack -- Executing [s@macro-online:11] Set("Local/201@a_context_01-00000000;2", "__CALLID=SIP/100003") in new stack -- Executing [s@macro-online:12] ExecIf("Local/201@a_context_01-00000000;2", "0?Set(CALLERID(num)=)") in new stack -- Executing [s@macro-online:13] AGI("Local/201@a_context_01-00000000;2", "setexten.agi,,0363161312,_a_context_01,SIP/100003") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/setexten.agi -- 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) -- Executing [202@_a_context_01:1] Macro("Local/202@a_context_01-00000001;2", "online,SIP/100002,BUSY") -- Executing [s@macro-online:1] Set("Local/202@a_context_01-00000001;2", "CHANNEL(language)=ja") in new stack -- Executing [s@macro-online: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-online:3] Set("Local/202@a_context_01-00000001;2", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-online:4] ExecIf("Local/202@a_context_01-00000001;2", "1?Set(CDR(inboundphonenumber)=0363868357)") in new stack -- Executing [s@macro-online:5] GotoIf("Local/202@a_context_01-00000001;2", "1?7:6]") in new stack -- Goto (macro-online,s,7) -- Executing [s@macro-online:7] GotoIf("Local/202@a_context_01-00000001;2", "0?11:8") in new stack -- Goto (macro-online,s,8) -- Executing [s@macro-online:8] SIPRemoveHeader("Local/202@a_context_01-00000001;2", "P-Called-Party-ID") in new stack -- Executing [s@macro-online:9] SIPAddHeader("Local/202@a_context_01-00000001;2", "P-Called-Party-ID: sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-online:10] Set("Local/202@a_context_01-00000001;2", "__PCPID=") in new stack -- Executing [s@macro-online:11] Set("Local/202@a_context_01-00000001;2", "__CALLID=SIP/100002") in new stack -- Executing [s@macro-online:12] ExecIf("Local/202@a_context_01-00000001;2", "0?Set(CALLERID(num)=)") in new stack -- Executing [s@macro-online:13] AGI("Local/202@a_context_01-00000001;2", "setexten.agi,,0363161312,_a_context_01,SIP/100002") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/setexten.agi -- AGI Script Executing Application: (Set) Options: (RESULT=0) -- AGI Script Executing Application: (Set) Options: (RESULT=0) -- AGI Script Executing Application: (Set) Options: (EXTENSION=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: (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 -- 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/202@a_context_01-00000001;2", "0?Stasis(crm-popup") in new stack -- Executing [s@macro-online:15] Dial("Local/202@a_context_01-00000001;2", "SIP/100002,,tT") in new stack -- Executing [s@macro-online:14] ExecIf("Local/201@a_context_01-00000000;2", "0?Stasis(crm-popup") in new stack -- Executing [s@macro-online:15] Dial("Local/201@a_context_01-00000000;2", "SIP/100003,,tT") in new stack == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/100002-00000001 == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/100003-00000002 -- Called SIP/100002 -- Local/202@a_context_01-00000001;1 connected line has changed. Saving it until answer for SIP/DefaultGW-00000000 -- Called SIP/100003 -- Local/201@a_context_01-00000000;1 connected line has changed. Saving it until answer for SIP/DefaultGW-00000000 -- SIP/100002-00000001 is ringing -- Local/202@a_context_01-00000001;1 is ringing -- SIP/100003-00000002 is ringing -- Local/201@a_context_01-00000000;1 is ringing 22-v3-02-act*CLI> 22-v3-02-act*CLI> =========================================== 202 PHONE A was picked up and answered. ================== -- SIP/100002-00000001 answered Local/202@a_context_01-00000001;2 -- Local/202@a_context_01-00000001;1 answered SIP/DefaultGW-00000000 [May 16 15:04:31] NOTICE[533][C-00000000]: app_queue.c:6720 try_calling: Delaying member connect for 1 seconds -- Channel SIP/100002-00000001 joined 'simple_bridge' basic-bridge <8297b92b-4688-43b1-afa1-0ea2a046f195> == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201805/1526450664.1.wav] == Spawn extension (macro-online, s, 15) exited non-zero on 'Local/201@a_context_01-00000000;2' in macro 'online' == Spawn extension (_a_context_01, 201, 1) exited non-zero on 'Local/201@a_context_01-00000000;2' -- Channel Local/202@a_context_01-00000001;2 joined 'simple_bridge' basic-bridge <8297b92b-4688-43b1-afa1-0ea2a046f195> == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201805/1526450664.6.wav] -- Executing [h@_a_context_01:1] ExecIf("Local/201@a_context_01-00000000;2", "0?Set(CDR(userfield)=incoming,)") -- Executing [h@_a_context_01:2] Macro("Local/201@a_context_01-00000000;2", "conclusion") -- Executing [s@macro-conclusion:1] Set("Local/201@a_context_01-00000000;2", "__BILLSEC=0") in new stack -- Executing [s@macro-conclusion:2] Set("Local/201@a_context_01-00000000;2", "__DISPOSITION=NO ANSWER") in new stack -- Executing [s@macro-conclusion:3] Set("Local/201@a_context_01-00000000;2", "__LINKEDID=1526450664.0") in new stack -- Executing [s@macro-conclusion:4] Set("Local/201@a_context_01-00000000;2", "__USERFIELD=incoming") in new stack -- Executing [s@macro-conclusion:5] MacroExit("Local/201@a_context_01-00000000;2", "") in new stack == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201805/1526450664.2.wav] == End MixMonitor Recording Local/201@a_context_01-00000000;1 == End MixMonitor Recording SIP/100003-00000002 == End MixMonitor Recording Local/201@a_context_01-00000000;2 -- 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 <5637ef9d-6915-4590-80df-b2360182a2eb> -- Channel SIP/DefaultGW-00000000 joined 'simple_bridge' basic-bridge <5637ef9d-6915-4590-80df-b2360182a2eb> == Manager 'agentstatus' logged off from 127.0.0.1 == Manager 'agentstatus' logged on from 127.0.0.1 [May 16 15:05:20] WARNING[2662][C-00000000]: channel.c:1143 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/202@a_context_01-00000001;2 [May 16 15:05:20] WARNING[549][C-00000000]: channel.c:1143 __ast_queue_frame: Exceptionally long voice queue length queuing to Local/202@a_context_01-00000001;1 == Client from 192.168.128.139, failed to authenticate in 30 seconds == Connect attempt from '192.168.128.139' unable to authenticate == Manager 'agentstatus' logged off from 127.0.0.1 == Manager 'agentstatus' logged on from 127.0.0.1 == Manager 'infinitalk' logged on from 192.168.128.139 == Manager 'agentstatus' logged off from 127.0.0.1 == Manager 'agentstatus' logged on from 127.0.0.1 Now, Phone A is answering the outside call. ============================================================== ON Tera Term AMI Interface: Action: Atxfer ActionID: 1 Channel: SIP/100002-00000001 Exten: 201 Response: Success ActionID: 1 Message: Atxfer successfully queued Event: Hold Privilege: call,all Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Event: BridgeLeave Privilege: call,all BridgeUniqueid: 8297b92b-4688-43b1-afa1-0ea2a046f195 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 1 BridgeVideoSourceMode: none Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Event: BridgeEnter Privilege: call,all BridgeUniqueid: 011f9671-09c7-4371-a869-d1f33d0eb769 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 2 BridgeVideoSourceMode: none Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Event: NewConnectedLine Privilege: call,all Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: ConnectedLineName: Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Event: BridgeLeave Privilege: call,all BridgeUniqueid: 011f9671-09c7-4371-a869-d1f33d0eb769 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 0 BridgeVideoSourceMode: none Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: ConnectedLineName: Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Event: BridgeEnter Privilege: call,all BridgeUniqueid: 8297b92b-4688-43b1-afa1-0ea2a046f195 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 2 BridgeVideoSourceMode: none Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: ConnectedLineName: Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Event: Unhold Privilege: call,all Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: ConnectedLineName: Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Event: Cdr Privilege: cdr,all AccountCode: 0000000004 Source: 202 Destination: 20 DestinationContext: a_context_01 CallerID: "" <202> Channel: Local/20@a_context_01-00000002;2 DestinationChannel: LastApplication: Goto LastData: _a_context_01,20,1 StartTime: 2018-05-16 15:07:08 AnswerTime: EndTime: 2018-05-16 15:07:08 Duration: 0 BillableSeconds: 0 Disposition: FAILED AMAFlags: DOCUMENTATION UniqueID: 1526450828.13 UserField: Event: NewConnectedLine Privilege: call,all Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Event: HangupRequest Privilege: call,all Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Event: BridgeLeave Privilege: call,all BridgeUniqueid: 8297b92b-4688-43b1-afa1-0ea2a046f195 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 1 BridgeVideoSourceMode: none Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Event: Hangup Privilege: call,all Channel: SIP/100002-00000001 ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526450664.5 Linkedid: 1526450664.0 Cause: 16 Cause-txt: Normal Clearing Event: Cdr Privilege: cdr,all AccountCode: 0000000004 Source: 202 Destination: DestinationContext: a_context_01 CallerID: "" <202> Channel: SIP/100002-00000001 DestinationChannel: Local/20@a_context_01-00000002;1 LastApplication: AppDial LastData: (Outgoing Line) StartTime: 2018-05-16 15:07:08 AnswerTime: 2018-05-16 15:07:08 EndTime: 2018-05-16 15:07:08 Duration: 0 BillableSeconds: 0 Disposition: ANSWERED AMAFlags: DOCUMENTATION UniqueID: 1526450664.5 UserField: Event: Cdr Privilege: cdr,all AccountCode: 0000000004 Source: 202 Destination: DestinationContext: a_context_01 CallerID: "" <202> Channel: SIP/100002-00000001 DestinationChannel: LastApplication: AppDial LastData: (Outgoing Line) StartTime: 2018-05-16 15:07:08 AnswerTime: 2018-05-16 15:07:08 EndTime: 2018-05-16 15:09:13 Duration: 124 BillableSeconds: 124 Disposition: ANSWERED AMAFlags: DOCUMENTATION UniqueID: 1526450664.5 UserField: Event: Cdr Privilege: cdr,all AccountCode: 0000000004 Source: 0363161312 Destination: 202 DestinationContext: _a_context_01 CallerID: "205" <0363161312> Channel: Local/202@a_context_01-00000001;2 DestinationChannel: SIP/100002-00000001 LastApplication: Dial LastData: SIP/100002,,tT StartTime: 2018-05-16 15:04:24 AnswerTime: 2018-05-16 15:04:31 EndTime: 2018-05-16 15:09:13 Duration: 288 BillableSeconds: 281 Disposition: ANSWERED AMAFlags: DOCUMENTATION UniqueID: 1526450664.4 UserField: incoming Event: Cdr Privilege: cdr,all AccountCode: Source: 0363161312 Destination: *00001 DestinationContext: a_common CallerID: "205" <0363161312> Channel: SIP/DefaultGW-00000000 DestinationChannel: Local/201@a_context_01-00000000;1 LastApplication: Queue LastData: a_acd_01,tr,,,,,trailclear StartTime: 2018-05-16 15:04:24 AnswerTime: EndTime: 2018-05-16 15:04:31 Duration: 7 BillableSeconds: 0 Disposition: NO ANSWER AMAFlags: DOCUMENTATION UniqueID: 1526450664.0 UserField: incoming Event: Cdr Privilege: cdr,all AccountCode: Source: 0363161312 Destination: *00001 DestinationContext: a_common CallerID: "205" <0363161312> Channel: SIP/DefaultGW-00000000 DestinationChannel: Local/202@a_context_01-00000001;1 LastApplication: Queue LastData: a_acd_01,tr,,,,,trailclear StartTime: 2018-05-16 15:04:24 AnswerTime: 2018-05-16 15:04:32 EndTime: 2018-05-16 15:09:13 Duration: 288 BillableSeconds: 280 Disposition: ANSWERED AMAFlags: DOCUMENTATION UniqueID: 1526450664.0 UserField: incoming ============================================================== Here is the outputlogs of Attended Transfer on AMI interface: [May 16 15:07:04] DTMF[2365][C-00000000]: channel.c:4038 __ast_read: DTMF end '*' received on SIP/100002-00000001, duration 0 ms [May 16 15:07:04] DTMF[2365][C-00000000]: channel.c:4065 __ast_read: DTMF begin emulation of '*' with duration 100 queued on SIP/100002-00000001 [May 16 15:07:04] DTMF[2365][C-00000000]: channel.c:4158 __ast_read: DTMF end emulation of '*' queued on SIP/100002-00000001 [May 16 15:07:04] DTMF[2365][C-00000000]: channel.c:4038 __ast_read: DTMF end '2' received on SIP/100002-00000001, duration 0 ms [May 16 15:07:04] DTMF[2365][C-00000000]: channel.c:4065 __ast_read: DTMF begin emulation of '2' with duration 100 queued on SIP/100002-00000001 [May 16 15:07:05] DTMF[2365][C-00000000]: channel.c:4158 __ast_read: DTMF end emulation of '2' queued on SIP/100002-00000001 -- Channel SIP/100002-00000001: Started DTMF attended transfer. -- Playing 'pbx-transfer.gsm' (language 'ja') [May 16 15:07:05] DTMF[2365][C-00000000]: channel.c:4038 __ast_read: DTMF end '2' received on SIP/100002-00000001, duration 0 ms [May 16 15:07:05] DTMF[2365][C-00000000]: channel.c:4097 __ast_read: DTMF end accepted without begin '2' on SIP/100002-00000001 [May 16 15:07:05] DTMF[2365][C-00000000]: channel.c:4108 __ast_read: DTMF end passthrough '2' on SIP/100002-00000001 [May 16 15:07:05] DTMF[2365][C-00000000]: channel.c:4038 __ast_read: DTMF end '0' received on SIP/100002-00000001, duration 0 ms [May 16 15:07:05] DTMF[2365][C-00000000]: channel.c:4097 __ast_read: DTMF end accepted without begin '0' on SIP/100002-00000001 [May 16 15:07:05] DTMF[2365][C-00000000]: channel.c:4108 __ast_read: DTMF end passthrough '0' on SIP/100002-00000001 [May 16 15:07:05] DTMF[534][C-00000000]: channel.c:4038 __ast_read: DTMF end '1' received on SIP/100002-00000001, duration 0 ms [May 16 15:07:05] DTMF[534][C-00000000]: channel.c:4097 __ast_read: DTMF end accepted without begin '1' on SIP/100002-00000001 [May 16 15:07:05] DTMF[534][C-00000000]: channel.c:4108 __ast_read: DTMF end passthrough '1' on SIP/100002-00000001 -- Started music on hold, class 'default', on channel 'Local/202@a_context_01-00000001;2' == Begin MixMonitor Recording Local/20@a_context_01-00000002;1 == Begin MixMonitor Recording Local/20@a_context_01-00000002;2 -- Channel Local/20@a_context_01-00000002;1 joined 'simple_bridge' basic-bridge <011f9671-09c7-4371-a869-d1f33d0eb769> -- Channel SIP/100002-00000001 left 'simple_bridge' basic-bridge <8297b92b-4688-43b1-afa1-0ea2a046f195> -- Channel SIP/100002-00000001 joined 'simple_bridge' basic-bridge <011f9671-09c7-4371-a869-d1f33d0eb769> -- Executing [20@a_context_01:1] Set("Local/20@a_context_01-00000002;2", "CHANNEL(parkinglot)=parkinglot_a_01") -- Executing [20@a_context_01:2] Goto("Local/20@a_context_01-00000002;2", "_a_context_01,20,1") -- Goto (_a_context_01,20,1) [May 16 15:07:08] WARNING[8211][C-00000000]: pbx.c:4418 __ast_pbx_run: Channel 'Local/20@a_context_01-00000002;2' sent to invalid extension but no invalid handler: context,exten,priority=_a_context_01,20,1 -- Executing [h@_a_context_01:1] ExecIf("Local/20@a_context_01-00000002;2", "0?Set(CDR(userfield)=,)") -- Executing [h@_a_context_01:2] Macro("Local/20@a_context_01-00000002;2", "conclusion") -- Executing [s@macro-conclusion:1] Set("Local/20@a_context_01-00000002;2", "__BILLSEC=0") in new stack -- Executing [s@macro-conclusion:2] Set("Local/20@a_context_01-00000002;2", "__DISPOSITION=FAILED") in new stack -- Executing [s@macro-conclusion:3] Set("Local/20@a_context_01-00000002;2", "__LINKEDID=1526450664.0") in new stack -- Executing [s@macro-conclusion:4] Set("Local/20@a_context_01-00000002;2", "__USERFIELD=") in new stack -- Executing [s@macro-conclusion:5] MacroExit("Local/20@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/201805/1526450828.13.wav] -- Channel Local/20@a_context_01-00000002;1 left 'simple_bridge' basic-bridge <011f9671-09c7-4371-a869-d1f33d0eb769> -- Channel SIP/100002-00000001 left 'simple_bridge' basic-bridge <011f9671-09c7-4371-a869-d1f33d0eb769> -- Channel SIP/100002-00000001 joined 'simple_bridge' basic-bridge <8297b92b-4688-43b1-afa1-0ea2a046f195> == MixMonitor close filestream (mixed) -- Playing 'beeperr.gsm' (language 'ja') -- Stopped music on hold on Local/202@a_context_01-00000001;2 == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201805/1526450828.12.wav] == End MixMonitor Recording Local/20@a_context_01-00000002;2 == End MixMonitor Recording Local/20@a_context_01-00000002;1 == Manager 'agentstatus' logged off from 127.0.0.1 == Manager 'agentstatus' logged on from 127.0.0.1 == Manager 'agentstatus' logged off from 127.0.0.1 == Manager 'agentstatus' logged on from 127.0.0.1 -- Channel SIP/100002-00000001 left 'simple_bridge' basic-bridge <8297b92b-4688-43b1-afa1-0ea2a046f195> -- Channel Local/202@a_context_01-00000001;2 left 'simple_bridge' basic-bridge <8297b92b-4688-43b1-afa1-0ea2a046f195> == Spawn extension (macro-online, s, 15) exited non-zero on 'Local/202@a_context_01-00000001;2' in macro 'online' == 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/201805/1526450664.5.wav] -- Executing [h@_a_context_01:1] ExecIf("Local/202@a_context_01-00000001;2", "0?Set(CDR(userfield)=incoming,)") -- 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=281") 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=1526450664.0") in new stack -- Executing [s@macro-conclusion:4] Set("Local/202@a_context_01-00000001;2", "__USERFIELD=incoming") 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/201805/1526450664.4.wav] -- Channel Local/202@a_context_01-00000001;1 left 'simple_bridge' basic-bridge <5637ef9d-6915-4590-80df-b2360182a2eb> -- Channel SIP/DefaultGW-00000000 left 'simple_bridge' basic-bridge <5637ef9d-6915-4590-80df-b2360182a2eb> == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201805/1526450664.3.wav] == Spawn extension (macro-queue, s, 12) exited non-zero on 'SIP/DefaultGW-00000000' in macro 'queue' == Spawn extension (a_common, *00001, 4) exited non-zero on 'SIP/DefaultGW-00000000' -- Exec END.