Queue (randomly) does not transfer call to extensions

jeet · October 2, 2014, 8:18pm

Hi Everyone…

I have run into some serious problems. I have noted that my asterisk box is behaving very strange randomly.
The scenario is as following…
Caller calls in, gets greeted by IVR, selects 1 for support and then enter’s queue 100 (Support). After this the call never gets transfered to any of the extentions. This happens randomnly and then sorts out itself without me doing anything. The CLI is showing following when the calls dont get transferred. Somebody pleaseeeeeee help me. Please…

Instance 1 where the caller was not transfered to any extensions in queue.

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [441234567890@from-trunk:1] NoOp("SIP/vonage-0000073c", "Catch-All DID Match - Found 441234567890 - You probably want a DID for this.") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [441234567890@from-trunk:2] Goto("SIP/vonage-0000073c", "ext-did,s,1") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Goto (ext-did,s,1)

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ext-did:1] Set("SIP/vonage-0000073c", "__FROM_DID=s") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ext-did:2] Gosub("SIP/vonage-0000073c", "app-blacklist-check,s,1") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@app-blacklist-check:1] GotoIf("SIP/vonage-0000073c", "0?blacklisted") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@app-blacklist-check:2] Set("SIP/vonage-0000073c", "CALLED_BLACKLIST=1") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@app-blacklist-check:3] Return("SIP/vonage-0000073c", "") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ext-did:3] ExecIf("SIP/vonage-0000073c", "1 ?Set(CALLERID(name)=447712345678)") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ext-did:4] Set("SIP/vonage-0000073c", "__CALLINGPRES_SV=allowed_not_screened") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ext-did:5] Set("SIP/vonage-0000073c", "CALLERPRES()=allowed_not_screened") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ext-did:6] Goto("SIP/vonage-0000073c", "ivr-3,s,1") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Goto (ivr-3,s,1)

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:1] Set("SIP/vonage-0000073c", "MSG=custom/info-announcement") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:2] Set("SIP/vonage-0000073c", "LOOPCOUNT=0") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:3] Set("SIP/vonage-0000073c", "__DIR-CONTEXT=") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:4] Set("SIP/vonage-0000073c", "_IVR_CONTEXT_ivr-3=") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:5] Set("SIP/vonage-0000073c", "_IVR_CONTEXT=ivr-3") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:6] GotoIf("SIP/vonage-0000073c", "0?begin") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:7] Answer("SIP/vonage-0000073c", "") in new stack

Oct 2 16:06:42 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:8] Wait("SIP/vonage-0000073c", "1") in new stack

Oct 2 16:06:43 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:9] Set("SIP/vonage-0000073c", "TIMEOUT(digit)=3") in new stack

Oct 2 16:06:43 	VERBOSE 	[1224] func_timeout.c: 	
-- Digit timeout set to 3.000

Oct 2 16:06:43 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:10] Set("SIP/vonage-0000073c", "TIMEOUT(response)=15") in new stack

Oct 2 16:06:43 	VERBOSE 	[1224] func_timeout.c: 	
-- Response timeout set to 15.000

Oct 2 16:06:43 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:11] Set("SIP/vonage-0000073c", "__IVR_RETVM=") in new stack

Oct 2 16:06:43 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:12] ExecIf("SIP/vonage-0000073c", "1?Background(custom/info-announcement)") in new stack

Oct 2 16:06:43 	VERBOSE 	[1224] file.c: 	
-- <SIP/vonage-0000073c> Playing 'custom/info-announcement.slin' (language 'en')

Oct 2 16:06:54 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@ivr-3:13] WaitExten("SIP/vonage-0000073c", ",") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
== CDR updated on SIP/vonage-0000073c

Oct 2 16:06:57 	WARNING 	[1224] func_db.c: 	
DB_DELETE requires an argument, DB_DELETE(<family>/<key>)

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [1@ivr-3:1] NoOp("SIP/vonage-0000073c", "Deleting:  ") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [1@ivr-3:2] Set("SIP/vonage-0000073c", "__NODEST=") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [1@ivr-3:3] Goto("SIP/vonage-0000073c", "ext-queues,100,1") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Goto (ext-queues,100,1)

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:1] Macro("SIP/vonage-0000073c", "user-callerid,") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:1] Set("SIP/vonage-0000073c", "AMPUSER=447712345678") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:2] GotoIf("SIP/vonage-0000073c", "0?report") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:3] ExecIf("SIP/vonage-0000073c", "1?Set(REALCALLERIDNUM=447712345678)") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:4] Set("SIP/vonage-0000073c", "AMPUSER=") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:5] Set("SIP/vonage-0000073c", "AMPUSERCIDNAME=") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:6] GotoIf("SIP/vonage-0000073c", "1?report") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Goto (macro-user-callerid,s,10)

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:10] GotoIf("SIP/vonage-0000073c", "0?continue") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:11] Set("SIP/vonage-0000073c", "__TTL=64") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:12] GotoIf("SIP/vonage-0000073c", "1?continue") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Goto (macro-user-callerid,s,19)

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:19] Set("SIP/vonage-0000073c", "CALLERID(number)=447712345678") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:20] Set("SIP/vonage-0000073c", "CALLERID(name)=447712345678") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-user-callerid:21] NoOp("SIP/vonage-0000073c", "Using CallerID "447712345678" <447712345678>") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:2] Answer("SIP/vonage-0000073c", "") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:3] Set("SIP/vonage-0000073c", "__BLKVM_OVERRIDE=BLKVM/100/SIP/vonage-0000073c") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:4] Set("SIP/vonage-0000073c", "__BLKVM_BASE=100") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:5] Set("SIP/vonage-0000073c", "DB(BLKVM/100/SIP/vonage-0000073c)=TRUE") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:6] ExecIf("SIP/vonage-0000073c", "1?Set(_DIAL_OPTIONS=trM(auto-blkvm))") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:7] Set("SIP/vonage-0000073c", "__NODEST=100") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:8] Set("SIP/vonage-0000073c", "MONITOR_FILENAME=/var/spool/asterisk/monitor/q100-20141002-160657-1412262402.2710") in new stack

Oct 2 16:06:57 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:9] Playback("SIP/vonage-0000073c", "custom/support") in new stack

Oct 2 16:06:57 	WARNING 	[1224] mp3/interface.c: 	
Junk at the beginning of frame 00000000

Oct 2 16:06:57 	VERBOSE 	[1224] file.c: 	
-- <SIP/vonage-0000073c> Playing 'custom/support.slin' (language 'en')

Oct 2 16:07:01 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:10] Set("SIP/vonage-0000073c", "__MOHCLASS=default") in new stack

Oct 2 16:07:01 	VERBOSE 	[1224] pbx.c: 	
-- Executing [100@ext-queues:11] Queue("SIP/vonage-0000073c", "100,tn,,") in new stack

Oct 2 16:07:01 	VERBOSE 	[1224] res_musiconhold.c: 	
-- Started music on hold, class 'default', on SIP/vonage-0000073c

Oct 2 16:10:01 	VERBOSE 	[3405] asterisk.c: 	
-- Remote UNIX connection

Oct 2 16:10:01 	VERBOSE 	[1230] asterisk.c: 	
-- Remote UNIX connection disconnected

Oct 2 16:10:07 	NOTICE 	[3598] chan_sip.c: 	
Peer '6002' is now UNREACHABLE!  Last qualify: 1

Oct 2 16:10:17 	NOTICE 	[3598] chan_sip.c: 	
Peer '6002' is now Reachable. (1ms / 2000ms)

Oct 2 16:15:01 	VERBOSE 	[3405] asterisk.c: 	
-- Remote UNIX connection

Oct 2 16:15:01 	VERBOSE 	[1237] asterisk.c: 	
-- Remote UNIX connection disconnected

Oct 2 16:19:39 	VERBOSE 	[1224] res_musiconhold.c: 	
-- Stopped music on hold on SIP/vonage-0000073c

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
== Spawn extension (ext-queues, 100, 11) exited non-zero on 'SIP/vonage-0000073c'

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [h@ext-queues:1] Macro("SIP/vonage-0000073c", "hangupcall,") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/vonage-0000073c", "1?endmixmoncheck") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Goto (macro-hangupcall,s,9)

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:9] NoOp("SIP/vonage-0000073c", "End of MIXMON check") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:10] GotoIf("SIP/vonage-0000073c", "1?nomeetmemon") in new stack
Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Goto (macro-hangupcall,s,28)

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:28] NoOp("SIP/vonage-0000073c", "End of MEETME check") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:29] GotoIf("SIP/vonage-0000073c", "1?noautomon") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Goto (macro-hangupcall,s,34)

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:34] NoOp("SIP/vonage-0000073c", "TOUCH_MONITOR_OUTPUT=") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:35] GotoIf("SIP/vonage-0000073c", "0?noautomon2") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:36] System("SIP/vonage-0000073c", "test -e /var/spool/asterisk/monitor/q100-20141002-160657-1412262402.2710*") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:37] NoOp("SIP/vonage-0000073c", "SYSTEMSTATUS = APPERROR") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:38] GotoIf("SIP/vonage-0000073c", "1?errornoautomon") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Goto (macro-hangupcall,s,40)

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:40] NoOp("SIP/vonage-0000073c", "End of MONITOR QUEUE check") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:41] NoOp("SIP/vonage-0000073c", "MONITOR_FILENAME=/var/spool/asterisk/monitor/q100-20141002-160657-1412262402.2710") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:42] GotoIf("SIP/vonage-0000073c", "1?skiprg") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Goto (macro-hangupcall,s,45)

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:45] GotoIf("SIP/vonage-0000073c", "0?skipblkvm") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:46] NoOp("SIP/vonage-0000073c", "Cleaning Up Block VM Flag: BLKVM/100/SIP/vonage-0000073c") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:47] NoOp("SIP/vonage-0000073c", "Deleting: BLKVM/100/SIP/vonage-0000073c TRUE") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:48] GotoIf("SIP/vonage-0000073c", "1?theend") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Goto (macro-hangupcall,s,50)

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:50] AGI("SIP/vonage-0000073c", "hangup.agi") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] res_agi.c: 	
-- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi

Oct 2 16:19:39 	VERBOSE 	[1224] res_agi.c: 	
-- <SIP/vonage-0000073c>AGI Script hangup.agi completed, returning 0

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
-- Executing [s@macro-hangupcall:51] Hangup("SIP/vonage-0000073c", "") in new stack

Oct 2 16:19:39 	VERBOSE 	[1224] app_macro.c: 	
== Spawn extension (macro-hangupcall, s, 51) exited non-zero on 'SIP/vonage-0000073c' in macro 'hangupcall'

Oct 2 16:19:39 	VERBOSE 	[1224] pbx.c: 	
== Spawn extension (ext-queues, h, 1) exited non-zero on 'SIP/vonage-0000073c'

Oct 2 16:20:01 	VERBOSE 	[3405] asterisk.c: 	
-- Remote UNIX connection

Oct 2 16:20:01 	VERBOSE 	[1245] asterisk.c: 	
-- Remote UNIX connection disconnected

Oct 2 16:25:01 	VERBOSE 	[3405] asterisk.c: 	
-- Remote UNIX connection

Oct 2 16:25:01 	VERBOSE 	[1252] asterisk.c: 	
-- Remote UNIX connection disconnected

Oct 2 16:30:01 	VERBOSE 	[3405] asterisk.c: 	
-- Remote UNIX connection

Instance 2 where the caller was not transferred to any of the extensions in the queue.

[code]Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [441234567890@from-trunk:1] NoOp(“SIP/vonage-0000073d”, “Catch-All DID Match - Found 441234567890 - You probably want a DID for this.”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [441234567890@from-trunk:2] Goto(“SIP/vonage-0000073d”, “ext-did,s,1”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Goto (ext-did,s,1)

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ext-did:1] Set(“SIP/vonage-0000073d”, “__FROM_DID=s”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ext-did:2] Gosub(“SIP/vonage-0000073d”, “app-blacklist-check,s,1”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/vonage-0000073d”, “0?blacklisted”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@app-blacklist-check:2] Set(“SIP/vonage-0000073d”, “CALLED_BLACKLIST=1”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@app-blacklist-check:3] Return(“SIP/vonage-0000073d”, “”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ext-did:3] ExecIf(“SIP/vonage-0000073d”, “1 ?Set(CALLERID(name)=447712345678)”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ext-did:4] Set(“SIP/vonage-0000073d”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ext-did:5] Set(“SIP/vonage-0000073d”, “CALLERPRES()=allowed_not_screened”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ext-did:6] Goto(“SIP/vonage-0000073d”, “ivr-3,s,1”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Goto (ivr-3,s,1)

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:1] Set(“SIP/vonage-0000073d”, “MSG=custom/info-announcement”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:2] Set(“SIP/vonage-0000073d”, “LOOPCOUNT=0”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:3] Set(“SIP/vonage-0000073d”, “__DIR-CONTEXT=”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:4] Set(“SIP/vonage-0000073d”, “_IVR_CONTEXT_ivr-3=”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:5] Set(“SIP/vonage-0000073d”, “_IVR_CONTEXT=ivr-3”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:6] GotoIf(“SIP/vonage-0000073d”, “0?begin”) in new stack

Oct 2 16:55:21 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:7] Answer(“SIP/vonage-0000073d”, “”) in new stack

Oct 2 16:55:22 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:8] Wait(“SIP/vonage-0000073d”, “1”) in new stack

Oct 2 16:55:23 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:9] Set(“SIP/vonage-0000073d”, “TIMEOUT(digit)=3”) in new stack

Oct 2 16:55:23 VERBOSE [1297] func_timeout.c:
– Digit timeout set to 3.000

Oct 2 16:55:23 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:10] Set(“SIP/vonage-0000073d”, “TIMEOUT(response)=15”) in new stack

Oct 2 16:55:23 VERBOSE [1297] func_timeout.c:
– Response timeout set to 15.000

Oct 2 16:55:23 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:11] Set(“SIP/vonage-0000073d”, “__IVR_RETVM=”) in new stack

Oct 2 16:55:23 VERBOSE [1297] pbx.c:
– Executing [s@ivr-3:12] ExecIf(“SIP/vonage-0000073d”, “1?Background(custom/info-announcement)”) in new stack

Oct 2 16:55:23 VERBOSE [1297] file.c:
– <SIP/vonage-0000073d> Playing ‘custom/info-announcement.slin’ (language ‘en’)

Oct 2 16:55:29 WARNING [1297] func_db.c:
DB_DELETE requires an argument, DB_DELETE(/)

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [1@ivr-3:1] NoOp(“SIP/vonage-0000073d”, "Deleting: ") in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [1@ivr-3:2] Set(“SIP/vonage-0000073d”, “__NODEST=”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [1@ivr-3:3] Goto(“SIP/vonage-0000073d”, “ext-queues,100,1”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Goto (ext-queues,100,1)

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:1] Macro(“SIP/vonage-0000073d”, “user-callerid,”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:1] Set(“SIP/vonage-0000073d”, “AMPUSER=447712345678”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/vonage-0000073d”, “0?report”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/vonage-0000073d”, “1?Set(REALCALLERIDNUM=447712345678)”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:4] Set(“SIP/vonage-0000073d”, “AMPUSER=”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:5] Set(“SIP/vonage-0000073d”, “AMPUSERCIDNAME=”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/vonage-0000073d”, “1?report”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Goto (macro-user-callerid,s,10)

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:10] GotoIf(“SIP/vonage-0000073d”, “0?continue”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:11] Set(“SIP/vonage-0000073d”, “__TTL=64”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:12] GotoIf(“SIP/vonage-0000073d”, “1?continue”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Goto (macro-user-callerid,s,19)

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:19] Set(“SIP/vonage-0000073d”, “CALLERID(number)=447712345678”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:20] Set(“SIP/vonage-0000073d”, “CALLERID(name)=447712345678”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-user-callerid:21] NoOp(“SIP/vonage-0000073d”, “Using CallerID “447712345678” <447712345678>”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:2] Answer(“SIP/vonage-0000073d”, “”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:3] Set(“SIP/vonage-0000073d”, “__BLKVM_OVERRIDE=BLKVM/100/SIP/vonage-0000073d”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:4] Set(“SIP/vonage-0000073d”, “__BLKVM_BASE=100”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:5] Set(“SIP/vonage-0000073d”, “DB(BLKVM/100/SIP/vonage-0000073d)=TRUE”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:6] ExecIf(“SIP/vonage-0000073d”, “1?Set(_DIAL_OPTIONS=trM(auto-blkvm))”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:7] Set(“SIP/vonage-0000073d”, “__NODEST=100”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:8] Set(“SIP/vonage-0000073d”, “MONITOR_FILENAME=/var/spool/asterisk/monitor/q100-20141002-165529-1412265321.2711”) in new stack

Oct 2 16:55:29 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:9] Playback(“SIP/vonage-0000073d”, “custom/support”) in new stack

Oct 2 16:55:29 WARNING [1297] mp3/interface.c:
Junk at the beginning of frame 00000000

Oct 2 16:55:29 VERBOSE [1297] file.c:
– <SIP/vonage-0000073d> Playing ‘custom/support.slin’ (language ‘en’)

Oct 2 16:55:33 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:10] Set(“SIP/vonage-0000073d”, “__MOHCLASS=default”) in new stack

Oct 2 16:55:33 VERBOSE [1297] pbx.c:
– Executing [100@ext-queues:11] Queue(“SIP/vonage-0000073d”, “100,tn,”) in new stack

Oct 2 16:55:33 VERBOSE [1297] res_musiconhold.c:
– Started music on hold, class ‘default’, on SIP/vonage-0000073d

Oct 2 16:56:29 VERBOSE [1297] res_musiconhold.c:
– Stopped music on hold on SIP/vonage-0000073d

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
== Spawn extension (ext-queues, 100, 11) exited non-zero on ‘SIP/vonage-0000073d’

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [h@ext-queues:1] Macro(“SIP/vonage-0000073d”, “hangupcall,”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/vonage-0000073d”, “1?endmixmoncheck”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Goto (macro-hangupcall,s,9)

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:9] NoOp(“SIP/vonage-0000073d”, “End of MIXMON check”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:10] GotoIf(“SIP/vonage-0000073d”, “1?nomeetmemon”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Goto (macro-hangupcall,s,28)

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:28] NoOp(“SIP/vonage-0000073d”, “End of MEETME check”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:29] GotoIf(“SIP/vonage-0000073d”, “1?noautomon”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Goto (macro-hangupcall,s,34)

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:34] NoOp(“SIP/vonage-0000073d”, “TOUCH_MONITOR_OUTPUT=”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:35] GotoIf(“SIP/vonage-0000073d”, “0?noautomon2”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:36] System(“SIP/vonage-0000073d”, “test -e /var/spool/asterisk/monitor/q100-20141002-165529-1412265321.2711*”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:37] NoOp(“SIP/vonage-0000073d”, “SYSTEMSTATUS = APPERROR”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:38] GotoIf(“SIP/vonage-0000073d”, “1?errornoautomon”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Goto (macro-hangupcall,s,40)

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:40] NoOp(“SIP/vonage-0000073d”, “End of MONITOR QUEUE check”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:41] NoOp(“SIP/vonage-0000073d”, “MONITOR_FILENAME=/var/spool/asterisk/monitor/q100-20141002-165529-1412265321.2711”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:42] GotoIf(“SIP/vonage-0000073d”, “1?skiprg”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Goto (macro-hangupcall,s,45)

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:45] GotoIf(“SIP/vonage-0000073d”, “0?skipblkvm”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:46] NoOp(“SIP/vonage-0000073d”, “Cleaning Up Block VM Flag: BLKVM/100/SIP/vonage-0000073d”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:47] NoOp(“SIP/vonage-0000073d”, “Deleting: BLKVM/100/SIP/vonage-0000073d TRUE”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:48] GotoIf(“SIP/vonage-0000073d”, “1?theend”) in new stack

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Goto (macro-hangupcall,s,50)

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:50] AGI(“SIP/vonage-0000073d”, “hangup.agi”) in new stack

Oct 2 16:56:29 VERBOSE [1297] res_agi.c:
– Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi

Oct 2 16:56:29 VERBOSE [1297] res_agi.c:
– <SIP/vonage-0000073d>AGI Script hangup.agi completed, returning 0

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
– Executing [s@macro-hangupcall:51] Hangup(“SIP/vonage-0000073d”, “”) in new stack

Oct 2 16:56:29 VERBOSE [1297] app_macro.c:
== Spawn extension (macro-hangupcall, s, 51) exited non-zero on ‘SIP/vonage-0000073d’ in macro ‘hangupcall’

Oct 2 16:56:29 VERBOSE [1297] pbx.c:
== Spawn extension (ext-queues, h, 1) exited non-zero on ‘SIP/vonage-0000073d’

Oct 2 16:59:00 NOTICE [3598] chan_sip.c:
Peer ‘6002’ is now Lagged. (5024ms / 2000ms)

Oct 2 16:59:10 NOTICE [3598] chan_sip.c:
Peer ‘6002’ is now Reachable. (1ms / 2000ms)

Oct 2 17:00:01 VERBOSE [3405] asterisk.c:
– Remote UNIX connection

Oct 2 17:00:01 VERBOSE [1303] asterisk.c:
– Remote UNIX connection disconnected

Oct 2 17:05:01 VERBOSE [3405] asterisk.c:
– Remote UNIX connection

Oct 2 17:05:01 VERBOSE [1314] asterisk.c:
– Remote UNIX connection disconnected
[/code]

david55 · October 2, 2014, 9:14pm

You need to contact the person who wrote the dialplan. In particular, this is not the place to contact people who wrote the FreePBX dialplan. The traces you have provided are just too large to understand without that knowledge, and you haven’t provide the queue definitions that would be needed. You may need to enable debugging in app_queue.

However, you are getting errors suggesting broken database access code (I guess that must be custom) and a flakey network, which you should probably address.

Topic		Replies	Views
Transfering Calls doesnt work Asterisk Support	1	185	July 24, 2006
Call drops when i transfer from queue Asterisk Support	3	369	July 20, 2011
Call transfer problem Asterisk Support	4	449	October 15, 2007
Queues and Call transfer issue Asterisk Support	0	232	August 1, 2008
Queue call: not wanted transfer to another extension Asterisk General	7	978	June 30, 2016

Queue (randomly) does not transfer call to extensions

Related topics