1. Make a call to extention 202(phone A) from an outside phone. ======================= 22-v3-02-act*CLI> == Manager 'agentstatus' logged off from 127.0.0.1 == Manager 'agentstatus' logged on from 127.0.0.1 == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/DefaultGW-00000089 -- Executing [0363868357@default:1] Macro("SIP/DefaultGW-00000089", "incominglimit") -- Executing [s@macro-incominglimit:1] Set("SIP/DefaultGW-00000089", "CHANNEL=ja") in new stack -- Executing [s@macro-incominglimit:2] Set("SIP/DefaultGW-00000089", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-incominglimit:3] Set("SIP/DefaultGW-00000089", "__INBOUND_PHONE_NUM=0363868357") in new stack -- Executing [s@macro-incominglimit:4] Set("SIP/DefaultGW-00000089", "CDR(inboundphonenumber)=0363868357") in new stack -- Executing [s@macro-incominglimit:5] Set("SIP/DefaultGW-00000089", "_INCOMING=yes") in new stack -- Executing [s@macro-incominglimit:6] AGI("SIP/DefaultGW-00000089", "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-00000089", "GROUP(INOUT_a)=0363868357") in new stack -- Executing [s@macro-incominglimit:8] GotoIf("SIP/DefaultGW-00000089", "1?set_incoming") in new stack -- Goto (macro-incominglimit,s,10) -- Executing [s@macro-incominglimit:10] Set("SIP/DefaultGW-00000089", "GROUP(INCOMING_a)=0363868357") in new stack -- Executing [s@macro-incominglimit:11] GotoIf("SIP/DefaultGW-00000089", "1?set_line") in new stack -- Goto (macro-incominglimit,s,13) -- Executing [s@macro-incominglimit:13] Set("SIP/DefaultGW-00000089", "GROUP(LINE_a)=a") in new stack -- Executing [s@macro-incominglimit:14] GotoIf("SIP/DefaultGW-00000089", "0?line_limit") in new stack -- Executing [s@macro-incominglimit:15] GotoIf("SIP/DefaultGW-00000089", "0?line_limit") in new stack -- Executing [s@macro-incominglimit:16] MacroExit("SIP/DefaultGW-00000089", "") in new stack -- Executing [0363868357@default:2] Macro("SIP/DefaultGW-00000089", "p-called-party-id") -- Executing [s@macro-p-called-party-id:1] GotoIf("SIP/DefaultGW-00000089", "0?7") in new stack -- Executing [s@macro-p-called-party-id:2] Set("SIP/DefaultGW-00000089", "__INBOUND_EXTEN=0363868357") in new stack -- Executing [s@macro-p-called-party-id:3] GotoIf("SIP/DefaultGW-00000089", "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-00000089", "__PCPID=sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-p-called-party-id:5] NoOp("SIP/DefaultGW-00000089", "PCPID: sip:0363868357@192.168.22.22") in new stack -- Executing [s@macro-p-called-party-id:6] MacroExit("SIP/DefaultGW-00000089", "") in new stack -- Executing [0363868357@default:3] Macro("SIP/DefaultGW-00000089", "privacy") -- Executing [s@macro-privacy:1] GotoIf("SIP/DefaultGW-00000089", "0?end,1") in new stack -- Executing [s@macro-privacy:2] GotoIf("SIP/DefaultGW-00000089", "1?9") in new stack -- Goto (macro-privacy,s,9) -- Executing [s@macro-privacy:9] Set("SIP/DefaultGW-00000089", "RS=205") in new stack -- Executing [s@macro-privacy:10] GosubIf("SIP/DefaultGW-00000089", "1?map-1,1") in new stack -- Executing [map-1@macro-privacy:1] GotoIf("SIP/DefaultGW-00000089", "0?7") in new stack -- Executing [map-1@macro-privacy:2] GotoIf("SIP/DefaultGW-00000089", "0?set,1") in new stack -- Executing [map-1@macro-privacy:3] GotoIf("SIP/DefaultGW-00000089", "0?set,1") in new stack -- Executing [map-1@macro-privacy:4] GotoIf("SIP/DefaultGW-00000089", "0?set,1") in new stack -- Executing [map-1@macro-privacy:5] GotoIf("SIP/DefaultGW-00000089", "0?set,1") in new stack -- Executing [map-1@macro-privacy:6] Return("SIP/DefaultGW-00000089", "") in new stack -- Executing [s@macro-privacy:11] GotoIf("SIP/DefaultGW-00000089", "1?15") in new stack -- Goto (macro-privacy,s,15) -- Executing [s@macro-privacy:15] GotoIf("SIP/DefaultGW-00000089", "1?18") in new stack -- Goto (macro-privacy,s,18) -- Executing [s@macro-privacy:18] Goto("SIP/DefaultGW-00000089", "ct-,1") in new stack -- Goto (macro-privacy,ct-,1) -- Executing [0363868357@default:4] Goto("SIP/DefaultGW-00000089", "a_common,*00001,1") -- Goto (a_common,*00001,1) -- Executing [*00001@a_common:1] Set("SIP/DefaultGW-00000089", "__INBOUND_TIME=1526439378") -- Executing [*00001@a_common:2] ExecIf("SIP/DefaultGW-00000089", "0?Set(CDR(userfield)=incoming,)") -- Executing [*00001@a_common:3] Macro("SIP/DefaultGW-00000089", "inbound-exectime,0,*,*,*,*,*,1526439378") -- Executing [s@macro-inbound-exectime:1] Set("SIP/DefaultGW-00000089", "EXEC_INBOUND_APP=0") in new stack -- Executing [s@macro-inbound-exectime:2] NoOp("SIP/DefaultGW-00000089", "Set(CDR(userfield)=incoming)") in new stack -- Executing [s@macro-inbound-exectime:3] GotoIf("SIP/DefaultGW-00000089", "0?4:9") in new stack -- Goto (macro-inbound-exectime,s,9) -- Executing [s@macro-inbound-exectime:9] AGI("SIP/DefaultGW-00000089", "inbound-exectime.agi,*,1526439378") 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-00000089", "0?13:11") in new stack -- Goto (macro-inbound-exectime,s,11) -- Executing [s@macro-inbound-exectime:11] ExecIfTime("SIP/DefaultGW-00000089", "*,*,*,*?Set(EXEC_INBOUND_APP=1)") in new stack -- Executing [s@macro-inbound-exectime:12] NoOp("SIP/DefaultGW-00000089", "current time matched: 1") in new stack -- Executing [s@macro-inbound-exectime:13] MacroExit("SIP/DefaultGW-00000089", "") in new stack -- Executing [*00001@a_common:4] ExecIf("SIP/DefaultGW-00000089", "1?Macro(queue,a_acd_01,ring,,,,)") -- Executing [s@macro-queue:1] Set("SIP/DefaultGW-00000089", "__QUEUE_NOLOG=queue") in new stack -- Executing [s@macro-queue:2] NoOp("SIP/DefaultGW-00000089", "Macro(setcallerid)") in new stack -- Executing [s@macro-queue:3] Macro("SIP/DefaultGW-00000089", "queue-count,") in new stack -- Executing [s@macro-queue-count:1] Set("SIP/DefaultGW-00000089", "ACD=1") in new stack -- Executing [s@macro-queue-count:2] ExecIf("SIP/DefaultGW-00000089", "1?Set(ACD=100)") in new stack -- Executing [s@macro-queue-count:3] Set("SIP/DefaultGW-00000089", "E=") in new stack -- Executing [s@macro-queue-count:4] GotoIf("SIP/DefaultGW-00000089", "1?6") in new stack -- Goto (macro-queue-count,s,6) -- Executing [s@macro-queue-count:6] GotoIf("SIP/DefaultGW-00000089", "1?8") in new stack -- Goto (macro-queue-count,s,8) -- Executing [s@macro-queue-count:8] Set("SIP/DefaultGW-00000089", "GROUP()=queue") in new stack -- Executing [s@macro-queue-count:9] MacroExit("SIP/DefaultGW-00000089", "") in new stack -- Executing [s@macro-queue:4] Set("SIP/DefaultGW-00000089", "OPT=t") in new stack -- Executing [s@macro-queue:5] GotoIf("SIP/DefaultGW-00000089", "1?6:8") in new stack -- Goto (macro-queue,s,6) -- Executing [s@macro-queue:6] Set("SIP/DefaultGW-00000089", "OPT=tr") in new stack -- Executing [s@macro-queue:7] Goto("SIP/DefaultGW-00000089", "11") in new stack -- Goto (macro-queue,s,11) -- Executing [s@macro-queue:11] Set("SIP/DefaultGW-00000089", "__TRAIL=a_acd_01") in new stack -- Executing [s@macro-queue:12] Queue("SIP/DefaultGW-00000089", "a_acd_01,tr,,,,,trailclear") in new stack -- Called Local/201@a_context_01/n -- Called Local/202@a_context_01/n == Begin MixMonitor Recording Local/201@a_context_01-0000006c;1 -- Executing [201@a_context_01:1] Set("Local/201@a_context_01-0000006c;2", "CHANNEL(parkinglot)=parkinglot_a_01") == Begin MixMonitor Recording Local/201@a_context_01-0000006c;2 -- Executing [202@a_context_01:1] Set("Local/202@a_context_01-0000006d;2", "CHANNEL(parkinglot)=parkinglot_a_01") -- Executing [201@a_context_01:2] Goto("Local/201@a_context_01-0000006c;2", "_a_context_01,201,1") -- Goto (_a_context_01,201,1) == Begin MixMonitor Recording Local/202@a_context_01-0000006d;1 -- Executing [202@a_context_01:2] Goto("Local/202@a_context_01-0000006d;2", "_a_context_01,202,1") -- Goto (_a_context_01,202,1) -- Executing [201@_a_context_01:1] Macro("Local/201@a_context_01-0000006c;2", "online,SIP/100003,BUSY") == Begin MixMonitor Recording Local/202@a_context_01-0000006d;2 -- Executing [s@macro-online:1] Set("Local/201@a_context_01-0000006c;2", "CHANNEL(language)=ja") in new stack -- Executing [s@macro-online:2] Macro("Local/201@a_context_01-0000006c;2", "expire") in new stack -- Executing [s@macro-expire:1] GotoIf("Local/201@a_context_01-0000006c;2", "1?4") in new stack -- Goto (macro-expire,s,4) -- Executing [s@macro-expire:4] Set("Local/201@a_context_01-0000006c;2", "E=") in new stack -- Executing [s@macro-expire:5] GotoIf("Local/201@a_context_01-0000006c;2", "1?7") in new stack -- Goto (macro-expire,s,7) -- Executing [s@macro-expire:7] MacroExit("Local/201@a_context_01-0000006c;2", "") in new stack -- Executing [s@macro-online:3] Set("Local/201@a_context_01-0000006c;2", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-online:4] ExecIf("Local/201@a_context_01-0000006c;2", "1?Set(CDR(inboundphonenumber)=0363868357)") in new stack -- Executing [s@macro-online:5] GotoIf("Local/201@a_context_01-0000006c;2", "1?7:6]") in new stack -- Goto (macro-online,s,7) -- Executing [s@macro-online:7] GotoIf("Local/201@a_context_01-0000006c;2", "0?11:8") in new stack -- Goto (macro-online,s,8) -- Executing [s@macro-online:8] SIPRemoveHeader("Local/201@a_context_01-0000006c;2", "P-Called-Party-ID") in new stack -- Executing [s@macro-online:9] SIPAddHeader("Local/201@a_context_01-0000006c;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-0000006c;2", "__PCPID=") in new stack -- Executing [s@macro-online:11] Set("Local/201@a_context_01-0000006c;2", "__CALLID=SIP/100003") in new stack -- Executing [s@macro-online:12] ExecIf("Local/201@a_context_01-0000006c;2", "0?Set(CALLERID(num)=)") in new stack -- Executing [s@macro-online:13] AGI("Local/201@a_context_01-0000006c;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:1] Macro("Local/202@a_context_01-0000006d;2", "online,SIP/100002,BUSY") -- Executing [s@macro-online:1] Set("Local/202@a_context_01-0000006d;2", "CHANNEL(language)=ja") in new stack -- Executing [s@macro-online:2] Macro("Local/202@a_context_01-0000006d;2", "expire") in new stack -- Executing [s@macro-expire:1] GotoIf("Local/202@a_context_01-0000006d;2", "1?4") in new stack -- Goto (macro-expire,s,4) -- Executing [s@macro-expire:4] Set("Local/202@a_context_01-0000006d;2", "E=") in new stack -- Executing [s@macro-expire:5] GotoIf("Local/202@a_context_01-0000006d;2", "1?7") in new stack -- Goto (macro-expire,s,7) -- Executing [s@macro-expire:7] MacroExit("Local/202@a_context_01-0000006d;2", "") in new stack -- Executing [s@macro-online:3] Set("Local/202@a_context_01-0000006d;2", "CDR(userfield)=incoming") in new stack -- Executing [s@macro-online:4] ExecIf("Local/202@a_context_01-0000006d;2", "1?Set(CDR(inboundphonenumber)=0363868357)") in new stack -- Executing [s@macro-online:5] GotoIf("Local/202@a_context_01-0000006d;2", "1?7:6]") in new stack -- Goto (macro-online,s,7) -- Executing [s@macro-online:7] GotoIf("Local/202@a_context_01-0000006d;2", "0?11:8") in new stack -- Goto (macro-online,s,8) -- Executing [s@macro-online:8] SIPRemoveHeader("Local/202@a_context_01-0000006d;2", "P-Called-Party-ID") in new stack -- Executing [s@macro-online:9] SIPAddHeader("Local/202@a_context_01-0000006d;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-0000006d;2", "__PCPID=") in new stack -- Executing [s@macro-online:11] Set("Local/202@a_context_01-0000006d;2", "__CALLID=SIP/100002") in new stack -- Executing [s@macro-online:12] ExecIf("Local/202@a_context_01-0000006d;2", "0?Set(CALLERID(num)=)") in new stack -- Executing [s@macro-online:13] AGI("Local/202@a_context_01-0000006d;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 -- Executing [s@macro-online:14] ExecIf("Local/201@a_context_01-0000006c;2", "0?Stasis(crm-popup") in new stack -- Executing [s@macro-online:15] Dial("Local/201@a_context_01-0000006c;2", "SIP/100003,,tT") in new stack -- 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-0000006d;2", "0?Stasis(crm-popup") in new stack -- Executing [s@macro-online:15] Dial("Local/202@a_context_01-0000006d;2", "SIP/100002,,tT") in new stack == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/100003-0000008a == Using SIP RTP TOS bits 128 == Using SIP RTP CoS mark 5 == Begin MixMonitor Recording SIP/100002-0000008b -- Called SIP/100003 -- Local/201@a_context_01-0000006c;1 connected line has changed. Saving it until answer for SIP/DefaultGW-00000089 -- Called SIP/100002 -- Local/202@a_context_01-0000006d;1 connected line has changed. Saving it until answer for SIP/DefaultGW-00000089 -- SIP/100002-0000008b is ringing -- Local/202@a_context_01-0000006d;1 is ringing -- SIP/100003-0000008a is ringing -- Local/201@a_context_01-0000006c;1 is ringing 22-v3-02-act*CLI> 22-v3-02-act*CLI> 22-v3-02-act*CLI> 22-v3-02-act*CLI> 22-v3-02-act*CLI> -- SIP/100002-0000008b answered Local/202@a_context_01-0000006d;2 -- Local/202@a_context_01-0000006d;1 answered SIP/DefaultGW-00000089 [May 16 11:56:37] NOTICE[25413][C-0000002d]: app_queue.c:7141 try_calling: Delaying member connect for 1 seconds -- Channel SIP/100002-0000008b joined 'simple_bridge' basic-bridge <873ce359-e9f8-47d9-a098-8a198cabad8b> == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201805/1526439378.949.wav] == Spawn extension (macro-online, s, 15) exited non-zero on 'Local/201@a_context_01-0000006c;2' in macro 'online' == Spawn extension (_a_context_01, 201, 1) exited non-zero on 'Local/201@a_context_01-0000006c;2' -- Channel Local/202@a_context_01-0000006d;2 joined 'simple_bridge' basic-bridge <873ce359-e9f8-47d9-a098-8a198cabad8b> == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201805/1526439378.953.wav] -- Executing [h@_a_context_01:1] ExecIf("Local/201@a_context_01-0000006c;2", "0?Set(CDR(userfield)=incoming,)") -- Executing [h@_a_context_01:2] Macro("Local/201@a_context_01-0000006c;2", "conclusion") -- Executing [s@macro-conclusion:1] Set("Local/201@a_context_01-0000006c;2", "__BILLSEC=0") in new stack -- Executing [s@macro-conclusion:2] Set("Local/201@a_context_01-0000006c;2", "__DISPOSITION=NO ANSWER") in new stack -- Executing [s@macro-conclusion:3] Set("Local/201@a_context_01-0000006c;2", "__LINKEDID=1526439378.948") in new stack -- Executing [s@macro-conclusion:4] Set("Local/201@a_context_01-0000006c;2", "__USERFIELD=incoming") in new stack -- Executing [s@macro-conclusion:5] MacroExit("Local/201@a_context_01-0000006c;2", "") in new stack == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201805/1526439378.950.wav] == End MixMonitor Recording SIP/100003-0000008a == End MixMonitor Recording Local/201@a_context_01-0000006c;1 == End MixMonitor Recording Local/201@a_context_01-0000006c;2 -- Executing [s@macro-trailclear:1] Set("Local/202@a_context_01-0000006d;1", "__TRAIL=''") in new stack -- Channel Local/202@a_context_01-0000006d;1 joined 'simple_bridge' basic-bridge -- Channel SIP/DefaultGW-00000089 joined 'simple_bridge' basic-bridge == Manager 'agentstatus' logged off from 127.0.0.1 == Manager 'agentstatus' logged on from 127.0.0.1 22-v3-02-act*CLI> 22-v3-02-act*CLI> 22-v3-02-act*CLI> 22-v3-02-act*CLI> Now, Phone A is answering the outside call. ============================================================== ON Tera Term AMI Interface: Action: Atxfer ActionID: 1 Channel: SIP/100002-0000008b Exten: 201 Response: Success ActionID: 1 Message: Atxfer successfully queued Event: Hold Privilege: call,all SequenceNumber: 37410 File: manager_channels.c Line: 1176 Func: channel_hold_cb Channel: SIP/100002-0000008b ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526439378.954 Linkedid: 1526439378.948 Event: BridgeLeave Privilege: call,all SequenceNumber: 37450 File: manager_bridges.c Line: 402 Func: channel_leave_cb BridgeUniqueid: 873ce359-e9f8-47d9-a098-8a198cabad8b BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 1 Channel: SIP/100002-0000008b ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526439378.954 Linkedid: 1526439378.948 Event: BridgeEnter Privilege: call,all SequenceNumber: 37451 File: manager_bridges.c Line: 382 Func: channel_enter_cb BridgeUniqueid: 75e8598b-2f87-4ea2-b871-192a86167c62 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 2 Channel: SIP/100002-0000008b ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526439378.954 Linkedid: 1526439378.948 Event: NewConnectedLine Privilege: call,all SequenceNumber: 37455 File: manager_channels.c Line: 723 Func: channel_snapshot_update Channel: SIP/100002-0000008b ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: ConnectedLineName: Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526439378.954 Linkedid: 1526439378.948 Event: BridgeLeave Privilege: call,all SequenceNumber: 37489 File: manager_bridges.c Line: 402 Func: channel_leave_cb BridgeUniqueid: 75e8598b-2f87-4ea2-b871-192a86167c62 BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 0 Channel: SIP/100002-0000008b ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: ConnectedLineName: Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526439378.954 Linkedid: 1526439378.948 Event: BridgeEnter Privilege: call,all SequenceNumber: 37491 File: manager_bridges.c Line: 382 Func: channel_enter_cb BridgeUniqueid: 873ce359-e9f8-47d9-a098-8a198cabad8b BridgeType: basic BridgeTechnology: simple_bridge BridgeCreator: BridgeName: BridgeNumChannels: 2 Channel: SIP/100002-0000008b ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: ConnectedLineName: Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526439378.954 Linkedid: 1526439378.948 Event: Unhold Privilege: call,all SequenceNumber: 37495 File: manager_channels.c Line: 1195 Func: channel_unhold_cb Channel: SIP/100002-0000008b ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: ConnectedLineName: Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526439378.954 Linkedid: 1526439378.948 Event: Cdr Privilege: cdr,all SequenceNumber: 37500 File: cdr_manager.c Line: 334 Func: manager_log AccountCode: 0000000004 Source: 202 Destination: 20 DestinationContext: a_context_01 CallerID: "" <202> Channel: Local/20@a_context_01-0000006e;2 DestinationChannel: LastApplication: Goto LastData: _a_context_01,20,1 StartTime: 2018-05-16 11:57:40 AnswerTime: EndTime: 2018-05-16 11:57:40 Duration: 0 BillableSeconds: 0 Disposition: FAILED AMAFlags: DOCUMENTATION UniqueID: 1526439460.961 UserField: Event: NewConnectedLine Privilege: call,all SequenceNumber: 37502 File: manager_channels.c Line: 723 Func: channel_snapshot_update Channel: SIP/100002-0000008b ChannelState: 6 ChannelStateDesc: Up CallerIDNum: 202 CallerIDName: ConnectedLineNum: 0363161312 ConnectedLineName: 205 Language: ja AccountCode: 0000000004 Context: a_context_01 Exten: Priority: 1 Uniqueid: 1526439378.954 Linkedid: 1526439378.948 ================================================================== Here is the outputlogs of Attended Transfer on AMI interface: [May 16 11:57:36] DTMF[30450][C-0000002d]: channel.c:3972 __ast_read: DTMF end '*' received on SIP/100002-0000008b, duration 0 ms [May 16 11:57:36] DTMF[30450][C-0000002d]: channel.c:3999 __ast_read: DTMF begin emulation of '*' with duration 100 queued on SIP/100002-0000008b [May 16 11:57:36] DTMF[30450][C-0000002d]: channel.c:4092 __ast_read: DTMF end emulation of '*' queued on SIP/100002-0000008b [May 16 11:57:36] DTMF[30450][C-0000002d]: channel.c:3972 __ast_read: DTMF end '2' received on SIP/100002-0000008b, duration 0 ms [May 16 11:57:36] DTMF[30450][C-0000002d]: channel.c:3999 __ast_read: DTMF begin emulation of '2' with duration 100 queued on SIP/100002-0000008b [May 16 11:57:37] DTMF[30450][C-0000002d]: channel.c:4092 __ast_read: DTMF end emulation of '2' queued on SIP/100002-0000008b -- Playing 'pbx-transfer.gsm' (language 'ja') [May 16 11:57:37] DTMF[30450][C-0000002d]: channel.c:3972 __ast_read: DTMF end '2' received on SIP/100002-0000008b, duration 0 ms [May 16 11:57:37] DTMF[30450][C-0000002d]: channel.c:4031 __ast_read: DTMF end accepted without begin '2' on SIP/100002-0000008b [May 16 11:57:37] DTMF[30450][C-0000002d]: channel.c:4042 __ast_read: DTMF end passthrough '2' on SIP/100002-0000008b [May 16 11:57:37] DTMF[30450][C-0000002d]: channel.c:3972 __ast_read: DTMF end '0' received on SIP/100002-0000008b, duration 0 ms [May 16 11:57:37] DTMF[30450][C-0000002d]: channel.c:4031 __ast_read: DTMF end accepted without begin '0' on SIP/100002-0000008b [May 16 11:57:37] DTMF[30450][C-0000002d]: channel.c:4042 __ast_read: DTMF end passthrough '0' on SIP/100002-0000008b [May 16 11:57:37] DTMF[3584][C-0000002d]: channel.c:3972 __ast_read: DTMF end '1' received on SIP/100002-0000008b, duration 0 ms [May 16 11:57:37] DTMF[3584][C-0000002d]: channel.c:4031 __ast_read: DTMF end accepted without begin '1' on SIP/100002-0000008b [May 16 11:57:37] DTMF[3584][C-0000002d]: channel.c:4042 __ast_read: DTMF end passthrough '1' on SIP/100002-0000008b -- Started music on hold, class 'default', on channel 'Local/202@a_context_01-0000006d;2' == Begin MixMonitor Recording Local/20@a_context_01-0000006e;1 == Begin MixMonitor Recording Local/20@a_context_01-0000006e;2 -- Channel Local/20@a_context_01-0000006e;1 joined 'simple_bridge' basic-bridge <75e8598b-2f87-4ea2-b871-192a86167c62> -- Channel SIP/100002-0000008b left 'simple_bridge' basic-bridge <873ce359-e9f8-47d9-a098-8a198cabad8b> -- Channel SIP/100002-0000008b joined 'simple_bridge' basic-bridge <75e8598b-2f87-4ea2-b871-192a86167c62> -- Executing [20@a_context_01:1] Set("Local/20@a_context_01-0000006e;2", "CHANNEL(parkinglot)=parkinglot_a_01") -- Executing [20@a_context_01:2] Goto("Local/20@a_context_01-0000006e;2", "_a_context_01,20,1") -- Goto (_a_context_01,20,1) [May 16 11:57:40] WARNING[14731][C-0000002d]: pbx.c:4415 __ast_pbx_run: Channel 'Local/20@a_context_01-0000006e;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-0000006e;2", "0?Set(CDR(userfield)=,)") -- Executing [h@_a_context_01:2] Macro("Local/20@a_context_01-0000006e;2", "conclusion") -- Executing [s@macro-conclusion:1] Set("Local/20@a_context_01-0000006e;2", "__BILLSEC=0") in new stack -- Executing [s@macro-conclusion:2] Set("Local/20@a_context_01-0000006e;2", "__DISPOSITION=FAILED") in new stack -- Executing [s@macro-conclusion:3] Set("Local/20@a_context_01-0000006e;2", "__LINKEDID=1526439378.948") in new stack -- Executing [s@macro-conclusion:4] Set("Local/20@a_context_01-0000006e;2", "__USERFIELD=") in new stack -- Executing [s@macro-conclusion:5] MacroExit("Local/20@a_context_01-0000006e;2", "") in new stack == MixMonitor close filestream (mixed) == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201805/1526439460.961.wav] -- Channel Local/20@a_context_01-0000006e;1 left 'simple_bridge' basic-bridge <75e8598b-2f87-4ea2-b871-192a86167c62> -- Channel SIP/100002-0000008b left 'simple_bridge' basic-bridge <75e8598b-2f87-4ea2-b871-192a86167c62> -- Channel SIP/100002-0000008b joined 'simple_bridge' basic-bridge <873ce359-e9f8-47d9-a098-8a198cabad8b> == MixMonitor close filestream (mixed) -- Playing 'beeperr.gsm' (language 'ja') -- Stopped music on hold on Local/202@a_context_01-0000006d;2 == Executing [/var/lib/asterisk/bin/delete_or_change_format.sh /var/spool/asterisk/monitor/201805/1526439460.960.wav] == End MixMonitor Recording Local/20@a_context_01-0000006e;2 == End MixMonitor Recording Local/20@a_context_01-0000006e;1 == Manager 'agentstatus' logged off from 127.0.0.1 == Manager 'agentstatus' logged on from 127.0.0.1 22-v3-02-act*CLI>