full_2 the normal log
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-dial-one:52] Macro("Local/6031@from-queue-0000031c;2", "dialout-one-predial-hook,") in new stack
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("Local/6031@from-queue-0000031c;2", "") in new stack
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-dial-one:53] ExecIf("Local/6031@from-queue-0000031c;2", "1?Set(D_OPTIONS=HhtrgM(auto-blkvm)I)") in new stack
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-dial-one:54] NoOp("Local/6031@from-queue-0000031c;2", "") in new stack
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-dial-one:55] Dial("Local/6031@from-queue-0000031c;2", "SIP/6031,,HhtrgM(auto-blkvm)Ib(func-apply-sipheaders^s^1)") in new stack
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] netsock2.c: Using SIP RTP TOS bits 184
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] netsock2.c: Using SIP RTP CoS mark 5
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] app_stack.c: SIP/6031-000007cb Internal Gosub(func-apply-sipheaders,s,1) start
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/6031-000007cb", "Applying SIP Headers to channel") in new stack
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/6031-000007cb", "SIPHEADERKEYS=") in new stack
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/6031-000007cb", "0") in new stack
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] app_while.c: Jumping to priority 7
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/6031-000007cb", "") in new stack
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] app_stack.c: Spawn extension (from-internal, 6031, 1) exited non-zero on 'SIP/6031-000007cb'
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] app_stack.c: SIP/6031-000007cb Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] app_dial.c: Called SIP/6031
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] app_dial.c: Connected line update to Local/6031@from-queue-0000031c;2 prevented.
[2019-07-15 15:05:36] VERBOSE[17243][C-00000407] app_queue.c: Local/6031@from-queue-0000031c;1 is ringing
[2019-07-15 15:05:36] VERBOSE[17975][C-00000407] app_dial.c: SIP/6031-000007cb is ringing
[2019-07-15 15:05:36] VERBOSE[17243][C-00000407] app_queue.c: Local/6031@from-queue-0000031c;1 is ringing
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] app_dial.c: Connected line update to Local/6031@from-queue-0000031c;2 prevented.
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] app_dial.c: SIP/6031-000007cb answered Local/6031@from-queue-0000031c;2
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:1] ExecIf("SIP/6031-000007cb", "1?Set(CDR(recordingfile)=in-6031-18984525659-20190715-150536-1563174336.5427.wav)") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:2] ExecIf("SIP/6031-000007cb", "1?Set(CDR(calltype)=in)") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:3] ExecIf("SIP/6031-000007cb", "0?Set(CDR(calltype)=in)") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:4] Set("SIP/6031-000007cb", "__MACRO_RESULT=") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:5] Set("SIP/6031-000007cb", "CFIGNORE=") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:6] Set("SIP/6031-000007cb", "MASTER_CHANNEL(CFIGNORE)=") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:7] Set("SIP/6031-000007cb", "FORWARD_CONTEXT=from-internal") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:8] Set("SIP/6031-000007cb", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:9] Macro("SIP/6031-000007cb", "blkvm-clr,") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-blkvm-clr:1] Set("SIP/6031-000007cb", "SHARED(BLKVM,SIP/trucksz2E1-000007c7)=") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-blkvm-clr:2] Set("SIP/6031-000007cb", "GOSUB_RETVAL=") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("SIP/6031-000007cb", "") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:10] ExecIf("SIP/6031-000007cb", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=6031)") in new stack
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-auto-blkvm:11] ExecIf("SIP/6031-000007cb", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=6031)") in new stack
[2019-07-15 15:05:37] VERBOSE[17243][C-00000407] app_queue.c: Local/6031@from-queue-0000031c;1 answered SIP/trucksz2E1-000007c7
[2019-07-15 15:05:37] VERBOSE[17243][C-00000407] file.c: <Local/6031@from-queue-0000031c;1> Playing 'custom/beep.slin' (language 'cn')
[2019-07-15 15:05:37] VERBOSE[17978][C-00000407] bridge_channel.c: Channel SIP/6031-000007cb joined 'simple_bridge' basic-bridge <9a7c812d-8f7f-4a71-80ba-c2d2e5eeb42c>
[2019-07-15 15:05:37] VERBOSE[17975][C-00000407] bridge_channel.c: Channel Local/6031@from-queue-0000031c;2 joined 'simple_bridge' basic-bridge <9a7c812d-8f7f-4a71-80ba-c2d2e5eeb42c>
[2019-07-15 15:05:37] VERBOSE[17243][C-00000407] res_musiconhold.c: Stopped music on hold on SIP/trucksz2E1-000007c7
[2019-07-15 15:05:37] VERBOSE[17979][C-00000407] bridge_channel.c: Channel Local/6031@from-queue-0000031c;1 joined 'simple_bridge' basic-bridge <31bfef64-df04-4dd6-a6ac-13c0ab0952fc>
[2019-07-15 15:05:37] VERBOSE[17243][C-00000407] bridge_channel.c: Channel SIP/trucksz2E1-000007c7 joined 'simple_bridge' basic-bridge <31bfef64-df04-4dd6-a6ac-13c0ab0952fc>
[2019-07-15 15:08:48] VERBOSE[17978][C-00000407] bridge_channel.c: Channel SIP/6031-000007cb left 'simple_bridge' basic-bridge <9a7c812d-8f7f-4a71-80ba-c2d2e5eeb42c>
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] bridge_channel.c: Channel Local/6031@from-queue-0000031c;2 left 'simple_bridge' basic-bridge <9a7c812d-8f7f-4a71-80ba-c2d2e5eeb42c>
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] pbx.c: Executing [s@macro-dial-one:56] ExecIf("Local/6031@from-queue-0000031c;2", "1?agi(base.agi,comment,18984525659,6031,in)") in new stack
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/base.agi
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_request: base.agi
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_channel: Local/6031@from-queue-0000031c;2
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_language: cn
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_type: Local
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_uniqueid: 1563174336.5427
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_version: 15.2.0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_callerid: 18984525659
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_calleridname: 18984525659
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_callingpres: 0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_callingani2: 0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_callington: 0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_callingtns: 0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_dnid: unknown
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_rdnis: unknown
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_context: macro-dial-one
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_extension: s
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_priority: 56
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_enhanced: 0.0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_accountcode:
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_threadid: 140099193153280
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_arg_1: comment
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_arg_2: 18984525659
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_arg_3: 6031
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> agi_arg_4: in
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >>
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << EXEC noop action:-----------------base_comment
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: AGI Script Executing Application: (noop) Options: (action:-----------------base_comment)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << EXEC nocdr
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: AGI Script Executing Application: (nocdr) Options: ()
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << GET VARIABLE UNIQUEID
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=1 (1563174336.5427)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << GET VARIABLE CALLFILENAME
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=1 (in-6031-18984525659-20190715-150536-1563174336.5427)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << GET VARIABLE MON_FMT
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=1 (wav)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << GET VARIABLE transfertag
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=1 (from-queue)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << EXEC noop ====transfertag===from-queue============
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: AGI Script Executing Application: (noop) Options: (====transfertag===from-queue============)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << GET VARIABLE CHANNEL
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=1 (Local/6031@from-queue-0000031c;2)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << EXEC noop ===CHANNEL=Local/6031@from-queue-0000031c;2============
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: AGI Script Executing Application: (noop) Options: (===CHANNEL=Local/6031@from-queue-0000031c;2============)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << EXEC noop ==connect_id==81397916============
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: AGI Script Executing Application: (noop) Options: (==connect_id==81397916============)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << GET VARIABLE MYCALLTYPE
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=1 (in)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << EXEC noop ==calltype==in============
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: AGI Script Executing Application: (noop) Options: (==calltype==in============)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << GET VARIABLE QAGENT
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=1 (6031)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << GET VARIABLE BLKVM_CHANNEL
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=1 (SIP/trucksz2E1-000007c7)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << EXEC noop ==new_connect_id==6031============
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: AGI Script Executing Application: (noop) Options: (==new_connect_id==6031============)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << EXEC UserEvent "MixComment,CallerID: 18984525659,CalleeID: 6031,DateTime: 2019-07-15 15:08:48,RecordingFile: in-6031-18984525659-20190715-150536-1563174336.5427.wav,Dialinout: in,UniqueID: 1563174336.5427"
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: AGI Script Executing Application: (UserEvent) Options: (MixComment,CallerID: 18984525659,CalleeID: 6031,DateTime: 2019-07-15 15:08:48,RecordingFile: in-6031-18984525659-20190715-150536-1563174336.5427.wav,Dialinout: in,UniqueID: 1563174336.5427)
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=0
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << GET DATA custom/mycomment 5000 1
[2019-07-15 15:08:48] VERBOSE[17975][C-00000407] file.c: <Local/6031@from-queue-0000031c;2> Playing 'custom/mycomment.slin' (language 'cn')
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=1
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << EXEC UserEvent "MixComment,CallerID: 18984525659,CalleeID: 6031,DateTime: 2019-07-15 15:08:55,RecordingFile: in-6031-18984525659-20190715-150536-1563174336.5427.wav,Dialinout: in,UniqueID: 1563174336.5427,Key: 1"
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] res_agi.c: AGI Script Executing Application: (UserEvent) Options: (MixComment,CallerID: 18984525659,CalleeID: 6031,DateTime: 2019-07-15 15:08:55,RecordingFile: in-6031-18984525659-20190715-150536-1563174336.5427.wav,Dialinout: in,UniqueID: 1563174336.5427,Key: 1)
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=0
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Rx << EXEC Goto app-announcement-7,s,1
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] res_agi.c: AGI Script Executing Application: (Goto) Options: (app-announcement-7,s,1)
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] pbx_builtins.c: Goto (app-announcement-7,s,1)
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Tx >> 200 result=0
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] res_agi.c: <Local/6031@from-queue-0000031c;2>AGI Script base.agi completed, returning 0
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] app_macro.c: Channel 'Local/6031@from-queue-0000031c;2' jumping out of macro 'dial-one'
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] app_macro.c: Channel 'Local/6031@from-queue-0000031c;2' jumping out of macro 'exten-vm'
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] pbx.c: Executing [s@app-announcement-7:1] GotoIf("Local/6031@from-queue-0000031c;2", "1?begin") in new stack
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] pbx_builtins.c: Goto (app-announcement-7,s,4)
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] pbx.c: Executing [s@app-announcement-7:4] NoOp("Local/6031@from-queue-0000031c;2", "Playing announcement thankyou") in new stack
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] pbx.c: Executing [s@app-announcement-7:5] Playback("Local/6031@from-queue-0000031c;2", "custom/thankyou,noanswer") in new stack
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] file.c: <Local/6031@from-queue-0000031c;2> Playing 'custom/thankyou.slin' (language 'cn')
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] pbx.c: Executing [s@app-announcement-7:6] Goto("Local/6031@from-queue-0000031c;2", "app-blackhole,hangup,1") in new stack
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] pbx_builtins.c: Goto (app-blackhole,hangup,1)
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] pbx.c: Executing [hangup@app-blackhole:1] NoOp("Local/6031@from-queue-0000031c;2", "Blackhole Dest: Hangup") in new stack
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] pbx.c: Executing [hangup@app-blackhole:2] Hangup("Local/6031@from-queue-0000031c;2", "") in new stack
[2019-07-15 15:08:55] VERBOSE[17975][C-00000407] pbx.c: Spawn extension (app-blackhole, hangup, 2) exited non-zero on 'Local/6031@from-queue-0000031c;2'
[2019-07-15 15:08:55] VERBOSE[17976][C-00000407] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2019-07-15 15:08:55] VERBOSE[17976][C-00000407] app_mixmonitor.c: Executing [/opt/doscs/mixmonitor in-6031-18984525659-20190715-150536-1563174336.5427.wav]
[2019-07-15 15:08:55] VERBOSE[17979][C-00000407] bridge_channel.c: Channel Local/6031@from-queue-0000031c;1 left 'simple_bridge' basic-bridge <31bfef64-df04-4dd6-a6ac-13c0ab0952fc>
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] bridge_channel.c: Channel SIP/trucksz2E1-000007c7 left 'simple_bridge' basic-bridge <31bfef64-df04-4dd6-a6ac-13c0ab0952fc>
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Spawn extension (ext-queues, 208, 49) exited non-zero on 'SIP/trucksz2E1-000007c7'
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Executing [h@ext-queues:1] Macro("SIP/trucksz2E1-000007c7", "hangupcall,") in new stack
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/trucksz2E1-000007c7", "1?theend") in new stack
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/trucksz2E1-000007c7", "0?Set(CDR(recordingfile)=)") in new stack
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/trucksz2E1-000007c7", "Local/6031@from-queue-0000031c;1 monior file= ") in new stack
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Executing [s@macro-hangupcall:5] Set("SIP/trucksz2E1-000007c7", "MYEXTENSION=") in new stack
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Executing [s@macro-hangupcall:6] Set("SIP/trucksz2E1-000007c7", "CDR(callroute)=ivr_12-queue_208-hangupcall_-") in new stack
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Executing [s@macro-hangupcall:7] Set("SIP/trucksz2E1-000007c7", "CDR(uid)=uid1563174299.5416") in new stack
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Executing [s@macro-hangupcall:8] Set("SIP/trucksz2E1-000007c7", "CDR(calltype)=in") in new stack
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Executing [s@macro-hangupcall:9] AGI("SIP/trucksz2E1-000007c7", "attendedtransfer-rec-restart.php,Local/6031@from-queue-0000031c;1,") in new stack
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_request: attendedtransfer-rec-restart.php
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_channel: SIP/trucksz2E1-000007c7
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_language: cn
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_type: SIP
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_uniqueid: 1563174299.5416
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_version: 15.2.0
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_callerid: 18984525659
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_calleridname: 18984525659
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_callingpres: 0
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_callingani2: 0
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_callington: 0
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_callingtns: 0
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_dnid: 81397916
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_rdnis: unknown
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_context: macro-hangupcall
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_extension: s
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_priority: 9
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_enhanced: 0.0
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_accountcode:
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_threadid: 140101837960960
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_arg_1: Local/6031@from-queue-0000031c;1
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >> agi_arg_2:
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Tx >>
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] res_agi.c: <SIP/trucksz2E1-000007c7>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Executing [s@macro-hangupcall:10] Hangup("SIP/trucksz2E1-000007c7", "") in new stack
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] app_macro.c: Spawn extension (macro-hangupcall, s, 10) exited non-zero on 'SIP/trucksz2E1-000007c7' in macro 'hangupcall'
[2019-07-15 15:08:55] VERBOSE[17243][C-00000407] pbx.c: Spawn extension (ext-queues, h, 1) exited non-zero on 'SIP/trucksz2E1-000007c7'
[2019-07-15 15:08:56] VERBOSE[17976][C-00000407] app_mixmonitor.c: End MixMonitor Recording Local/6031@from-queue-0000031c;