Queue (randomly) does not transfer call to extensions

Hi Everyone… :cry: :cry: :cry:

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. :cry: :cry: 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]

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.