FreePBX | Register | Issues | Wiki | Portal | Support

ERROR: WARNING:Autodestruct on dialog

freepbx
Tags: #<Tag:0x00007fafc4aeb688>

(Zyyyyyyy) #1

I’m running FreePBX 11.3 on centos 7.4(Asterisk Version 15.2.0)
I have trouble this month , like that
[2019-07-21 09:01:09] WARNING[10909] chan_sip.c: Autodestruct on dialog ‘3d2973f0c08b213ef434f2da051bff2b@14.XX.XX.40’ with owner SIP/trucksz2E1-00003c61 in place (Method: BYE). Rescheduling destruction for 10000 ms
[2019-07-21 09:01:10] WARNING[10909] chan_sip.c: Autodestruct on dialog ‘96685a19d2af7349c4b61953c24a0151@192.168.1.10’ with owner SIP/fs4001002632-00003c59 in place (Method: BYE). Rescheduling destruction for 10000 ms
[2019-07-21 09:01:15] WARNING[10909] chan_sip.c: Autodestruct on dialog ‘3d2973f0c08b213ef434f2da051bff2b@14.XX.XX.40’ with owner SIP/trucksz2E1-00003c61 in place (Method: BYE). Rescheduling destruction for 10000 ms
[2019-07-21 09:01:17] WARNING[10909] chan_sip.c: Autodestruct on dialog ‘96685a19d2af7349c4b61953c24a0151@192.168.1.10’ with owner SIP/fs4001002632-00003c59 in

This problem is a small probability problem. We have 5,000 calls per day, and there will be several random occurrences in January. Every time this problem occurs, I can only restore it by fwconsole restart.
You can’t hang up the channel with channel request hangup, and the entire queue module is in a stagnant state. Any queue related cli cannot be executed, for example: queue show

Then I tried to find the problem in the asterisk_full log,There are some differences. I can’t understand why it’s different. I hope you can provide some suggestions and help. Thank you very much.


(Zyyyyyyy) #2
full_1(Exception log)

	Line 1078526: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-dial-one:54] NoOp("Local/6031@from-queue-000003c8;2", "") in new stack
	Line 1078527: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-dial-one:55] Dial("Local/6031@from-queue-000003c8;2", "SIP/6031,,HhtrgM(auto-blkvm)Ib(func-apply-sipheaders^s^1)") in new stack
	Line 1078528: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] netsock2.c: Using SIP RTP TOS bits 184
	Line 1078529: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] netsock2.c: Using SIP RTP CoS mark 5
	Line 1078530: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] app_stack.c: SIP/6031-000009e9 Internal Gosub(func-apply-sipheaders,s,1) start
	Line 1078531: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/6031-000009e9", "Applying SIP Headers to channel") in new stack
	Line 1078532: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/6031-000009e9", "SIPHEADERKEYS=") in new stack
	Line 1078533: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/6031-000009e9", "0") in new stack
	Line 1078534: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] app_while.c: Jumping to priority 7
	Line 1078535: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] pbx.c: Executing [s@func-apply-sipheaders:8] Return("SIP/6031-000009e9", "") in new stack
	Line 1078536: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] app_stack.c: Spawn extension (from-internal, 6031, 1) exited non-zero on 'SIP/6031-000009e9'
	Line 1078537: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] app_stack.c: SIP/6031-000009e9 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
	Line 1078538: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] app_dial.c: Called SIP/6031
	Line 1078539: [2019-07-15 15:44:56] VERBOSE[30268][C-00000518] app_dial.c: Connected line update to Local/6031@from-queue-000003c8;2 prevented.
	Line 1078540: [2019-07-15 15:44:56] VERBOSE[29632][C-00000518] app_queue.c: Local/6031@from-queue-000003c8;1 is ringing
	Line 1078541: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] app_dial.c: SIP/6031-000009e9 is ringing
	Line 1078542: [2019-07-15 15:44:57] VERBOSE[29632][C-00000518] app_queue.c: Local/6031@from-queue-000003c8;1 is ringing
	Line 1078718: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] app_dial.c: Connected line update to Local/6031@from-queue-000003c8;2 prevented.
	Line 1078719: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] app_dial.c: SIP/6031-000009e9 answered Local/6031@from-queue-000003c8;2
	Line 1078720: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:1] ExecIf("SIP/6031-000009e9", "1?Set(CDR(recordingfile)=in-6031-17301596191-20190715-154456-1563176696.6779.wav)") in new stack
	Line 1078721: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:2] ExecIf("SIP/6031-000009e9", "1?Set(CDR(calltype)=in)") in new stack
	Line 1078722: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:3] ExecIf("SIP/6031-000009e9", "0?Set(CDR(calltype)=in)") in new stack
	Line 1078723: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:4] Set("SIP/6031-000009e9", "__MACRO_RESULT=") in new stack
	Line 1078724: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:5] Set("SIP/6031-000009e9", "CFIGNORE=") in new stack
	Line 1078725: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:6] Set("SIP/6031-000009e9", "MASTER_CHANNEL(CFIGNORE)=") in new stack
	Line 1078726: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:7] Set("SIP/6031-000009e9", "FORWARD_CONTEXT=from-internal") in new stack
	Line 1078727: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:8] Set("SIP/6031-000009e9", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
	Line 1078728: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:9] Macro("SIP/6031-000009e9", "blkvm-clr,") in new stack
	Line 1078729: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-blkvm-clr:1] Set("SIP/6031-000009e9", "SHARED(BLKVM,SIP/fs4001002632-000009e1)=") in new stack
	Line 1078730: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-blkvm-clr:2] Set("SIP/6031-000009e9", "GOSUB_RETVAL=") in new stack
	Line 1078731: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("SIP/6031-000009e9", "") in new stack
	Line 1078732: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:10] ExecIf("SIP/6031-000009e9", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=6031)") in new stack
	Line 1078733: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-auto-blkvm:11] ExecIf("SIP/6031-000009e9", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=6031)") in new stack
	Line 1078734: [2019-07-15 15:44:57] VERBOSE[29632][C-00000518] app_queue.c: Local/6031@from-queue-000003c8;1 answered SIP/fs4001002632-000009e1
	Line 1078735: [2019-07-15 15:44:57] VERBOSE[30273][C-00000518] bridge_channel.c: Channel SIP/6031-000009e9 joined 'simple_bridge' basic-bridge <561155bf-fb82-4975-9e7d-652a95e3f318>
	Line 1078736: [2019-07-15 15:44:57] VERBOSE[30268][C-00000518] bridge_channel.c: Channel Local/6031@from-queue-000003c8;2 joined 'simple_bridge' basic-bridge <561155bf-fb82-4975-9e7d-652a95e3f318>
	Line 1078737: [2019-07-15 15:44:57] VERBOSE[29632][C-00000518] file.c: <Local/6031@from-queue-000003c8;1> Playing 'custom/beep.slin' (language 'cn')
	Line 1078738: [2019-07-15 15:44:57] VERBOSE[29632][C-00000518] res_musiconhold.c: Stopped music on hold on SIP/fs4001002632-000009e1
	Line 1078739: [2019-07-15 15:44:57] VERBOSE[30274][C-00000518] bridge_channel.c: Channel Local/6031@from-queue-000003c8;1 joined 'simple_bridge' basic-bridge <1e7aeef6-53c7-4613-8ad1-55f711760bf6>
	Line 1078740: [2019-07-15 15:44:57] VERBOSE[29632][C-00000518] bridge_channel.c: Channel SIP/fs4001002632-000009e1 joined 'simple_bridge' basic-bridge <1e7aeef6-53c7-4613-8ad1-55f711760bf6>
	Line 1083962: [2019-07-15 15:46:41] VERBOSE[30273][C-00000518] bridge_channel.c: Channel SIP/6031-000009e9 left 'simple_bridge' basic-bridge <561155bf-fb82-4975-9e7d-652a95e3f318>
	Line 1083963: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] bridge_channel.c: Channel Local/6031@from-queue-000003c8;2 left 'simple_bridge' basic-bridge <561155bf-fb82-4975-9e7d-652a95e3f318>
	Line 1083964: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] pbx.c: Executing [s@macro-dial-one:56] ExecIf("Local/6031@from-queue-000003c8;2", "1?agi(base.agi,comment,17301596191,6031,in)") in new stack
	Line 1083965: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/base.agi
	Line 1083966: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_request: base.agi
	Line 1083967: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_channel: Local/6031@from-queue-000003c8;2
	Line 1083968: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_language: cn
	Line 1083969: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_type: Local
	Line 1083970: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_uniqueid: 1563176696.6779
	Line 1083971: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_version: 15.2.0
	Line 1083972: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_callerid: 17301596191
	Line 1083973: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_calleridname: 17301596191
	Line 1083974: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_callingpres: 0
	Line 1083975: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_callingani2: 0
	Line 1083976: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_callington: 0
	Line 1083977: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_callingtns: 0
	Line 1083978: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_dnid: unknown
	Line 1083979: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_rdnis: unknown
	Line 1083980: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_context: macro-dial-one
	Line 1083981: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_extension: s
	Line 1083982: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_priority: 56
	Line 1083983: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_enhanced: 0.0
	Line 1083984: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_accountcode: 
	Line 1083985: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_threadid: 140099165218560
	Line 1083986: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_arg_1: comment
	Line 1083987: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_arg_2: 17301596191
	Line 1083988: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_arg_3: 6031
	Line 1083989: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> agi_arg_4: in
	Line 1083990: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 
	Line 1083991: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << EXEC noop action:-----------------base_comment
	Line 1083992: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: AGI Script Executing Application: (noop) Options: (action:-----------------base_comment)
	Line 1083993: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=0
	Line 1083994: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << EXEC nocdr
	Line 1083995: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: AGI Script Executing Application: (nocdr) Options: ()
	Line 1083996: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=0
	Line 1083997: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << GET VARIABLE UNIQUEID
	Line 1083998: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=1 (1563176696.6779)
	Line 1083999: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << GET VARIABLE CALLFILENAME
	Line 1084000: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=1 (in-6031-17301596191-20190715-154456-1563176696.6779)
	Line 1084001: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << GET VARIABLE MON_FMT
	Line 1084002: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=1 (wav)
	Line 1084003: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << GET VARIABLE transfertag
	Line 1084004: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=1 (from-queue)
	Line 1084005: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << EXEC noop ====transfertag===from-queue============
	Line 1084006: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: AGI Script Executing Application: (noop) Options: (====transfertag===from-queue============)
	Line 1084007: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=0
	Line 1084008: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << GET VARIABLE CHANNEL
	Line 1084009: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=1 (Local/6031@from-queue-000003c8;2)
	Line 1084010: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << EXEC noop ===CHANNEL=Local/6031@from-queue-000003c8;2============
	Line 1084011: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: AGI Script Executing Application: (noop) Options: (===CHANNEL=Local/6031@from-queue-000003c8;2============)
	Line 1084012: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=0
	Line 1084013: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << EXEC noop ==connect_id==27887570============
	Line 1084014: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: AGI Script Executing Application: (noop) Options: (==connect_id==27887570============)
	Line 1084015: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=0
	Line 1084016: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << GET VARIABLE MYCALLTYPE
	Line 1084017: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=1 (in)
	Line 1084018: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << EXEC noop ==calltype==in============
	Line 1084019: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: AGI Script Executing Application: (noop) Options: (==calltype==in============)
	Line 1084020: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=0
	Line 1084021: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << GET VARIABLE QAGENT
	Line 1084022: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=1 (6031)
	Line 1084023: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << GET VARIABLE BLKVM_CHANNEL
	Line 1084024: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=1 (SIP/fs4001002632-000009e1)
	Line 1084025: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << EXEC noop ==new_connect_id==6031============
	Line 1084026: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: AGI Script Executing Application: (noop) Options: (==new_connect_id==6031============)
	Line 1084027: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=0
	Line 1084028: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << EXEC UserEvent "MixComment,CallerID: 17301596191,CalleeID: 6031,DateTime: 2019-07-15 15:46:41,RecordingFile: in-6031-17301596191-20190715-154456-1563176696.6779.wav,Dialinout: in,UniqueID: 1563176696.6779"
	Line 1084029: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: AGI Script Executing Application: (UserEvent) Options: (MixComment,CallerID: 17301596191,CalleeID: 6031,DateTime: 2019-07-15 15:46:41,RecordingFile: in-6031-17301596191-20190715-154456-1563176696.6779.wav,Dialinout: in,UniqueID: 1563176696.6779)
	Line 1084030: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=0
	Line 1084031: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << GET DATA custom/mycomment 5000 1
	Line 1084032: [2019-07-15 15:46:41] VERBOSE[30268][C-00000518] file.c: <Local/6031@from-queue-000003c8;2> Playing 'custom/mycomment.slin' (language 'cn')
	Line 1084304: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=1
	Line 1084305: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << EXEC UserEvent "MixComment,CallerID: 17301596191,CalleeID: 6031,DateTime: 2019-07-15 15:46:47,RecordingFile: in-6031-17301596191-20190715-154456-1563176696.6779.wav,Dialinout: in,UniqueID: 1563176696.6779,Key: 1"
	Line 1084306: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] res_agi.c: AGI Script Executing Application: (UserEvent) Options: (MixComment,CallerID: 17301596191,CalleeID: 6031,DateTime: 2019-07-15 15:46:47,RecordingFile: in-6031-17301596191-20190715-154456-1563176696.6779.wav,Dialinout: in,UniqueID: 1563176696.6779,Key: 1)
	Line 1084307: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=0
	Line 1084308: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Rx << EXEC Goto app-announcement-7,s,1
	Line 1084309: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] res_agi.c: AGI Script Executing Application: (Goto) Options: (app-announcement-7,s,1)
	Line 1084310: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] pbx_builtins.c: Goto (app-announcement-7,s,1)
	Line 1084311: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Tx >> 200 result=0
	Line 1084312: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] res_agi.c: <Local/6031@from-queue-000003c8;2>AGI Script base.agi completed, returning 0
	Line 1084313: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] app_macro.c: Channel 'Local/6031@from-queue-000003c8;2' jumping out of macro 'dial-one'
	Line 1084314: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] app_macro.c: Channel 'Local/6031@from-queue-000003c8;2' jumping out of macro 'exten-vm'
	Line 1084315: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] pbx.c: Executing [s@app-announcement-7:1] GotoIf("Local/6031@from-queue-000003c8;2", "1?begin") in new stack
	Line 1084316: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] pbx_builtins.c: Goto (app-announcement-7,s,4)
	Line 1084317: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] pbx.c: Executing [s@app-announcement-7:4] NoOp("Local/6031@from-queue-000003c8;2", "Playing announcement thankyou") in new stack
	Line 1084318: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] pbx.c: Executing [s@app-announcement-7:5] Playback("Local/6031@from-queue-000003c8;2", "custom/thankyou,noanswer") in new stack
	Line 1084319: [2019-07-15 15:46:47] VERBOSE[30268][C-00000518] file.c: <Local/6031@from-queue-000003c8;2> Playing 'custom/thankyou.slin' (language 'cn')
	Line 1084320: [2019-07-15 15:46:48] VERBOSE[30268][C-00000518] pbx.c: Executing [s@app-announcement-7:6] Goto("Local/6031@from-queue-000003c8;2", "app-blackhole,hangup,1") in new stack
	Line 1084321: [2019-07-15 15:46:48] VERBOSE[30268][C-00000518] pbx_builtins.c: Goto (app-blackhole,hangup,1)
	Line 1084322: [2019-07-15 15:46:48] VERBOSE[30268][C-00000518] pbx.c: Executing [hangup@app-blackhole:1] NoOp("Local/6031@from-queue-000003c8;2", "Blackhole Dest: Hangup") in new stack
	Line 1084323: [2019-07-15 15:46:48] VERBOSE[30268][C-00000518] pbx.c: Executing [hangup@app-blackhole:2] Hangup("Local/6031@from-queue-000003c8;2", "") in new stack
	Line 1084324: [2019-07-15 15:46:48] VERBOSE[30268][C-00000518] pbx.c: Spawn extension (app-blackhole, hangup, 2) exited non-zero on 'Local/6031@from-queue-000003c8;2'
	Line 1084325: [2019-07-15 15:46:48] VERBOSE[30274][C-00000518] bridge_channel.c: Channel Local/6031@from-queue-000003c8;1 left 'simple_bridge' basic-bridge <1e7aeef6-53c7-4613-8ad1-55f711760bf6>
	Line 1084326: [2019-07-15 15:46:48] VERBOSE[30269][C-00000518] app_mixmonitor.c: MixMonitor close filestream (mixed)
	Line 1084327: [2019-07-15 15:46:48] VERBOSE[30269][C-00000518] app_mixmonitor.c: Executing [/opt/doscs/mixmonitor in-6031-17301596191-20190715-154456-1563176696.6779.wav]
	Line 1084328: [2019-07-15 15:46:48] VERBOSE[29632][C-00000518] bridge_channel.c: Channel SIP/fs4001002632-000009e1 left 'simple_bridge' basic-bridge <1e7aeef6-53c7-4613-8ad1-55f711760bf6>
	Line 1084505: [2019-07-15 15:46:48] VERBOSE[30269][C-00000518] app_mixmonitor.c: End MixMonitor Recording Local/6031@from-queue-000003c8;2

(Zyyyyyyy) #3
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;

(Zyyyyyyy) #4


(system) closed #5

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.