Transferring a call that originates from a ring-group fails to remove BLKVM flag

Essentially my issue is this: when someone calls into our IVR and presses 0, they get routed to the operator group. From there, if any of the operators tries to transfer the call to a particular extension, the extension will ring, but it will not allow the call to proceed to voicemail, due to maintaining the BLKVM flag from the ring group. I’ve attached an extremely verbose log below in hopes of someone helping me out here; I have looked it over numerous times and I don’t see any effort by asterisk to remove the flag and fail, so I don’t know how to correct this issue short of editing the exten-vm macro to ignore the BLKVM flag.

:00] VERBOSE[7153] logger.c: – Accepting call from ‘xxxxxxxxxx’ to ‘xxxxxxx’ on channel 0/3, span 1
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [xxxxxxx@from-zaptel:1] Set(“Zap/3-1”, “DID=xxxxxxx”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [xxxxxxx@from-zaptel:2] Goto(“Zap/3-1”, “s|1”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Goto (from-zaptel,s,1)
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:1] NoOp(“Zap/3-1”, “Entering from-zaptel with DID == xxxxxxx”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:2] Ringing(“Zap/3-1”, “”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:3] Set(“Zap/3-1”, “DID=xxxxxxx”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:4] NoOp(“Zap/3-1”, “DID is now xxxxxxx”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:5] GotoIf(“Zap/3-1”, “1?zapok:notzap”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Goto (from-zaptel,s,8)
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:8] NoOp(“Zap/3-1”, “Is a Zaptel Channel”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:9] Set(“Zap/3-1”, “CHAN=3-1”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:10] Set(“Zap/3-1”, “CHAN=3”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:11] Macro(“Zap/3-1”, “from-zaptel-3|xxxxxxx|1”) in new stack
[Mar 5 14:40:00] WARNING[17001] app_macro.c: No such context ‘macro-from-zaptel-3’ for macro ‘from-zaptel-3’
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:12] NoOp(“Zap/3-1”, “Returned from Macro from-zaptel-3”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@from-zaptel:13] Goto(“Zap/3-1”, “from-pstn|xxxxxxx|1”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Goto (from-pstn,xxxxxxx,1)
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [xxxxxxx@from-pstn:1] Set(“Zap/3-1”, “__FROM_DID=xxxxxxx”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [xxxxxxx@from-pstn:2] GotoIf(“Zap/3-1”, “0 ?cidok”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [xxxxxxx@from-pstn:3] Set(“Zap/3-1”, “CALLERID(name)=xxxxxxxxxx”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [xxxxxxx@from-pstn:4] NoOp(“Zap/3-1”, "CallerID is “xxxxxxxxxx” ") in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [xxxxxxx@from-pstn:5] Goto(“Zap/3-1”, “ivr-2|s|1”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Goto (ivr-2,s,1)
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@ivr-2:1] Set(“Zap/3-1”, “LOOPCOUNT=0”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@ivr-2:2] Set(“Zap/3-1”, “__DIR-CONTEXT=default”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@ivr-2:3] Set(“Zap/3-1”, “_IVR_CONTEXT_ivr-2=”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@ivr-2:4] Set(“Zap/3-1”, “_IVR_CONTEXT=ivr-2”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@ivr-2:5] GotoIf(“Zap/3-1”, “0?begin”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@ivr-2:6] Answer(“Zap/3-1”, “”) in new stack
[Mar 5 14:40:00] VERBOSE[17001] logger.c: – Executing [s@ivr-2:7] Wait(“Zap/3-1”, “1”) in new stack
[Mar 5 14:40:01] DEBUG[7153] chan_zap.c: Echo cancellation already on
[Mar 5 14:40:02] VERBOSE[17001] logger.c: – Executing [s@ivr-2:8] Set(“Zap/3-1”, “TIMEOUT(digit)=3”) in new stack
[Mar 5 14:40:02] VERBOSE[17001] logger.c: – Digit timeout set to 3
[Mar 5 14:40:02] VERBOSE[17001] logger.c: – Executing [s@ivr-2:9] Set(“Zap/3-1”, “TIMEOUT(response)=15”) in new stack
[Mar 5 14:40:02] VERBOSE[17001] logger.c: – Response timeout set to 15
[Mar 5 14:40:02] VERBOSE[17001] logger.c: – Executing [s@ivr-2:10] BackGround(“Zap/3-1”, “custom/DBgreet”) in new stack
[Mar 5 14:40:02] VERBOSE[17001] logger.c: – <Zap/3-1> Playing ‘custom/DBgreet’ (language ‘en’)
[Mar 5 14:40:06] VERBOSE[17001] logger.c: == CDR updated on Zap/3-1
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ivr-2:1] DBdel(“Zap/3-1”, “”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_db.c: Ignoring, no parameters
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ivr-2:2] Set(“Zap/3-1”, “__NODEST=”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ivr-2:3] Goto(“Zap/3-1”, “ext-group|0|1”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (ext-group,0,1)
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:1] Macro(“Zap/3-1”, “user-callerid|”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:1] NoOp(“Zap/3-1”, “user-callerid: Cell Phone VA xxxxxxxxxx”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:2] Set(“Zap/3-1”, “AMPUSER=xxxxxxxxxx”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:3] GotoIf(“Zap/3-1”, “0?report”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:4] ExecIf(“Zap/3-1”, “1|Set|REALCALLERIDNUM=xxxxxxxxxx”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: ExecIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:5] NoOp(“Zap/3-1”, “REALCALLERIDNUM is xxxxxxxxxx”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘xxxxxxxxxx/user’ in family ‘DEVICE’
[Mar 5 14:40:06] DEBUG[17001] func_db.c: DB: DEVICE/xxxxxxxxxx/user not found in database.
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:6] Set(“Zap/3-1”, “AMPUSER=”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘/cidname’ in family ‘AMPUSER’
[Mar 5 14:40:06] DEBUG[17001] func_db.c: DB: AMPUSER//cidname not found in database.
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:7] Set(“Zap/3-1”, “AMPUSERCIDNAME=”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:8] GotoIf(“Zap/3-1”, “1?report”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (macro-user-callerid,s,13)
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:13] NoOp(“Zap/3-1”, "TTL: ARG1: ") in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:14] GotoIf(“Zap/3-1”, “0?continue”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:15] Set(“Zap/3-1”, “__TTL=64”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:16] GotoIf(“Zap/3-1”, “1?continue”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (macro-user-callerid,s,23)
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:23] NoOp(“Zap/3-1”, "Using CallerID “Cell Phone VA” ") in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:2] GotoIf(“Zap/3-1”, “1?skipdb”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (ext-group,0,4)
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:4] Set(“Zap/3-1”, “__NODEST=”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:5] Set(“Zap/3-1”, “__BLKVM_OVERRIDE=BLKVM/0/Zap/3-1”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:6] Set(“Zap/3-1”, “__BLKVM_BASE=0”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:7] Set(“Zap/3-1”, “DB(BLKVM/0/Zap/3-1)=TRUE”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:8] Set(“Zap/3-1”, “RRNODEST=”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:9] Set(“Zap/3-1”, “__NODEST=0”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:10] GotoIf(“Zap/3-1”, “1?REPCID”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (ext-group,0,15)
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:15] NoOp(“Zap/3-1”, “CALLERID(name) is Cell Phone VA”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:16] Set(“Zap/3-1”, "_RGPREFIX=DB ") in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:17] Set(“Zap/3-1”, “CALLERID(name)=DB Cell Phone VA”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:18] Set(“Zap/3-1”, “RecordMethod=Group”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:19] Macro(“Zap/3-1”, “record-enable|317-321-301-309-305-318-322-319-327|Group”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-record-enable:1] GotoIf(“Zap/3-1”, “0?2:4”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (macro-record-enable,s,4)
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-record-enable:4] AGI(“Zap/3-1”, “recordingcheck|20080305-144006|1204746000.812”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Launched AGI Script /usr/share/asterisk/agi-bin/recordingcheck
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – AGI Script recordingcheck completed, returning 0
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: AGI
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-record-enable:5] NoOp(“Zap/3-1”, “No recording needed”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:20] Set(“Zap/3-1”, “RingGroupMethod=memoryhunt”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [0@ext-group:21] Macro(“Zap/3-1”, “dial|5|tr|317-321-301-309-305-318-322-319-327”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:1] GotoIf(“Zap/3-1”, “1?dial”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (macro-dial,s,3)
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:3] AGI(“Zap/3-1”, “dialparties.agi”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Launched AGI Script /usr/share/asterisk/agi-bin/dialparties.agi
[Mar 5 14:40:06] VERBOSE[17001] logger.c: dialparties.agi: Starting New Dialparties.agi
[Mar 5 14:40:06] VERBOSE[17006] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [Mar 5 14:40:06] VERBOSE[17006] logger.c: Found
[Mar 5 14:40:06] VERBOSE[17006] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[Mar 5 14:40:06] VERBOSE[17001] logger.c: dialparties.agi: Caller ID name is ‘DB Cell Phone VA’ number is ‘xxxxxxxxxx’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: dialparties.agi: Methodology of ring is ‘memoryhunt’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Added extension 317 to extension map
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Added extension 321 to extension map
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Added extension 301 to extension map
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Added extension 309 to extension map
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Added extension 305 to extension map
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Added extension 318 to extension map
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Added extension 322 to extension map
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Added extension 319 to extension map
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Added extension 327 to extension map
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘317’ in family ‘CF’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 317 cf is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘321’ in family ‘CF’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 321 cf is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘301’ in family ‘CF’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 301 cf is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘309’ in family ‘CF’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 309 cf is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘305’ in family ‘CF’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 305 cf is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘318’ in family ‘CF’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 318 cf is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘322’ in family ‘CF’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 322 cf is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘319’ in family ‘CF’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 319 cf is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘327’ in family ‘CF’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 327 cf is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘317’ in family ‘DND’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 317 do not disturb is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘321’ in family ‘DND’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 321 do not disturb is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘301’ in family ‘DND’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 301 do not disturb is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘309’ in family ‘DND’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 309 do not disturb is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘305’ in family ‘DND’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 305 do not disturb is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘318’ in family ‘DND’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 318 do not disturb is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘322’ in family ‘DND’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 322 do not disturb is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘319’ in family ‘DND’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 319 do not disturb is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘327’ in family ‘DND’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Extension 327 do not disturb is disabled
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘317’ in family ‘CFB’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘317’ in family ‘CFU’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘321’ in family ‘CFB’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘321’ in family ‘CFU’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘301’ in family ‘CFB’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘301’ in family ‘CFU’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘309’ in family ‘CFB’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘309’ in family ‘CFU’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘305’ in family ‘CFB’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘305’ in family ‘CFU’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘318’ in family ‘CFB’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘318’ in family ‘CFU’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘322’ in family ‘CFB’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘322’ in family ‘CFU’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘319’ in family ‘CFB’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘319’ in family ‘CFU’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘327’ in family ‘CFB’
[Mar 5 14:40:06] DEBUG[17001] db.c: Unable to find key ‘327’ in family ‘CFU’
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – dialparties.agi: Filtered ARG3: 317-321-301-309-305-318-322-319-327
[Mar 5 14:40:06] VERBOSE[17006] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – AGI Script dialparties.agi completed, returning 0
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: AGI
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:20] NoOp(“Zap/3-1”, "Returned from dialparties with hunt groups to dial ") in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: NoOp
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:21] Set(“Zap/3-1”, “HuntLoop=0”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:22] GotoIf(“Zap/3-1”, “1?a30”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (macro-dial,s,26)
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:26] Set(“Zap/3-1”, “HuntMember=HuntMember0”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:27] GotoIf(“Zap/3-1”, “0?a32:a35”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (macro-dial,s,31)
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:31] GotoIf(“Zap/3-1”, “1?a36:a50”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (macro-dial,s,32)
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:32] Set(“Zap/3-1”, “CTLoop=0”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:33] GotoIf(“Zap/3-1”, “0?a42”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:34] Set(“Zap/3-1”, “CT_EXTEN=317”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:35] Set(“Zap/3-1”, “DB(CALLTRACE/317)=xxxxxxxxxx”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:36] Set(“Zap/3-1”, “CTLoop=1”) in new stack
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:37] Goto(“Zap/3-1”, “s|a37”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (macro-dial,s,33)
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: Goto
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:33] GotoIf(“Zap/3-1”, “1?a42”) in new stack
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Goto (macro-dial,s,38)
[Mar 5 14:40:06] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Executing [s@macro-dial:38] Dial(“Zap/3-1”, “SIP/317|5|tr”) in new stack
[Mar 5 14:40:06] WARNING[17001] rtp.c: Unable to set TOS to 184
[Mar 5 14:40:06] VERBOSE[7151] logger.c: Extension Changed 317 new state Ringing for Notify User 317
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – Called 317
[Mar 5 14:40:06] VERBOSE[17001] logger.c: – SIP/317-008a0140 is ringing
[Mar 5 14:40:07] VERBOSE[17001] logger.c: – SIP/317-008a0140 is ringing
[Mar 5 14:40:07] VERBOSE[17001] logger.c: – SIP/317-008a0140 is ringing
[Mar 5 14:40:08] VERBOSE[17001] logger.c: – SIP/317-008a0140 is ringing
[Mar 5 14:40:10] VERBOSE[17001] logger.c: – SIP/317-008a0140 is ringing
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Nobody picked up in 5000 ms
[Mar 5 14:40:11] VERBOSE[7151] logger.c: Extension Changed 317 new state Idle for Notify User 317
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Dial
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:39] Set(“Zap/3-1”, “HuntLoop=1”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:40] GotoIf(“Zap/3-1”, “1?a46”) in new stack
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Goto (macro-dial,s,42)
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:42] Set(“Zap/3-1”, “HuntMembers=8”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:43] Goto(“Zap/3-1”, “s|a22”) in new stack
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Goto (macro-dial,s,22)
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Goto
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:22] GotoIf(“Zap/3-1”, “1?a30”) in new stack
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Goto (macro-dial,s,26)
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:26] Set(“Zap/3-1”, “HuntMember=HuntMember1”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:27] GotoIf(“Zap/3-1”, “0?a32:a35”) in new stack
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Goto (macro-dial,s,31)
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:31] GotoIf(“Zap/3-1”, “1?a36:a50”) in new stack
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Goto (macro-dial,s,32)
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:32] Set(“Zap/3-1”, “CTLoop=0”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:33] GotoIf(“Zap/3-1”, “0?a42”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:34] Set(“Zap/3-1”, “CT_EXTEN=317”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:35] Set(“Zap/3-1”, “DB(CALLTRACE/317)=xxxxxxxxxx”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:36] Set(“Zap/3-1”, “CTLoop=1”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:37] Goto(“Zap/3-1”, “s|a37”) in new stack
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Goto (macro-dial,s,33)
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Goto
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:33] GotoIf(“Zap/3-1”, “0?a42”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:34] Set(“Zap/3-1”, “CT_EXTEN=321”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:35] Set(“Zap/3-1”, “DB(CALLTRACE/321)=xxxxxxxxxx”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:36] Set(“Zap/3-1”, “CTLoop=2”) in new stack
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:37] Goto(“Zap/3-1”, “s|a37”) in new stack
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Goto (macro-dial,s,33)
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: Goto
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:33] GotoIf(“Zap/3-1”, “1?a42”) in new stack
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Goto (macro-dial,s,38)
[Mar 5 14:40:11] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Executing [s@macro-dial:38] Dial(“Zap/3-1”, “SIP/317&SIP/321|5|tr”) in new stack
[Mar 5 14:40:11] WARNING[17001] rtp.c: Unable to set TOS to 184
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Called 317
[Mar 5 14:40:11] WARNING[17001] rtp.c: Unable to set TOS to 184
[Mar 5 14:40:11] VERBOSE[17001] logger.c: – Called 321
[Mar 5 14:40:11] VERBOSE[7151] logger.c: Extension Changed 317 new state Ringing for Notify User 317
[Mar 5 14:40:11] VERBOSE[7151] logger.c: Extension Changed 321 new state Ringing for Notify User 317
[Mar 5 14:40:12] VERBOSE[17001] logger.c: – SIP/321-009a3de0 is ringing
[Mar 5 14:40:12] VERBOSE[17001] logger.c: – SIP/321-009a3de0 is ringing
[Mar 5 14:40:12] VERBOSE[17001] logger.c: – SIP/321-009a3de0 is ringing
[Mar 5 14:40:12] VERBOSE[17001] logger.c: – SIP/317-0090f270 is ringing
[Mar 5 14:40:13] VERBOSE[17001] logger.c: – SIP/317-0090f270 is ringing
[Mar 5 14:40:13] VERBOSE[17001] logger.c: – SIP/317-0090f270 is ringing
[Mar 5 14:40:13] VERBOSE[17001] logger.c: – SIP/317-0090f270 is ringing
[Mar 5 14:40:13] VERBOSE[17001] logger.c: – SIP/321-009a3de0 is ringing
[Mar 5 14:40:13] VERBOSE[7151] logger.c: Extension Changed 317 new state InUse for Notify User 317
[Mar 5 14:40:13] VERBOSE[17001] logger.c: – SIP/317-0090f270 answered Zap/3-1
[Mar 5 14:40:13] VERBOSE[7151] logger.c: Extension Changed 321 new state Idle for Notify User 317
[Mar 5 14:40:14] VERBOSE[7153] logger.c: – Channel 0/2, span 1 got hangup request, cause 16
[Mar 5 14:40:14] VERBOSE[16978] logger.c: – User hung up
[Mar 5 14:40:14] VERBOSE[16978] logger.c: == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘Zap/2-1’ in macro ‘vm’
[Mar 5 14:40:14] VERBOSE[16978] logger.c: == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘Zap/2-1’ in macro ‘exten-vm’
[Mar 5 14:40:14] VERBOSE[16978] logger.c: == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘Zap/2-1’
[Mar 5 14:40:14] DEBUG[16978] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/2-1
[Mar 5 14:40:14] DEBUG[16978] chan_zap.c: Not yet hungup… Calling hangup once with icause, and clearing call
[Mar 5 14:40:14] DEBUG[16978] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
[Mar 5 14:40:14] VERBOSE[16978] logger.c: – Hungup ‘Zap/2-1’
[Mar 5 14:40:29] VERBOSE[17001] logger.c: – Started music on hold, class ‘default’, on Zap/3-1
[Mar 5 14:40:29] VERBOSE[7151] logger.c: Extension Changed 317 new state Hold for Notify User 317
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Stopped music on hold on Zap/3-1
[Mar 5 14:40:31] VERBOSE[17001] logger.c: == Spawn extension (from-internal-xfer, 302, 0) exited non-zero on ‘Zap/3-1’ in macro ‘dial’
[Mar 5 14:40:31] VERBOSE[17001] logger.c: == Spawn extension (from-internal-xfer, 302, 0) exited non-zero on ‘Zap/3-1’
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [302@from-internal-xfer:1] Macro(“Zap/3-1”, “exten-vm|302|302”) in new stack
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:1] Macro(“Zap/3-1”, “user-callerid”) in new stack
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:1] NoOp(“Zap/3-1”, “user-callerid: DB Cell Phone VA xxxxxxxxxx”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:2] Set(“Zap/3-1”, “AMPUSER=xxxxxxxxxx”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:3] GotoIf(“Zap/3-1”, “0?report”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:4] ExecIf(“Zap/3-1”, “0|Set|REALCALLERIDNUM=xxxxxxxxxx”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: ExecIf
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:5] NoOp(“Zap/3-1”, “REALCALLERIDNUM is xxxxxxxxxx”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:31] DEBUG[17001] db.c: Unable to find key ‘xxxxxxxxxx/user’ in family ‘DEVICE’
[Mar 5 14:40:31] DEBUG[17001] func_db.c: DB: DEVICE/xxxxxxxxxx/user not found in database.
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:6] Set(“Zap/3-1”, “AMPUSER=”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:31] DEBUG[17001] db.c: Unable to find key ‘/cidname’ in family ‘AMPUSER’
[Mar 5 14:40:31] DEBUG[17001] func_db.c: DB: AMPUSER//cidname not found in database.
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:7] Set(“Zap/3-1”, “AMPUSERCIDNAME=”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:8] GotoIf(“Zap/3-1”, “1?report”) in new stack
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Goto (macro-user-callerid,s,13)
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:13] NoOp(“Zap/3-1”, “TTL: 64 ARG1: 302”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:14] GotoIf(“Zap/3-1”, “0?continue”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:15] Set(“Zap/3-1”, “__TTL=63”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:16] GotoIf(“Zap/3-1”, “1?continue”) in new stack
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Goto (macro-user-callerid,s,23)
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:23] NoOp(“Zap/3-1”, "Using CallerID “DB Cell Phone VA” ") in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Macro
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:2] Set(“Zap/3-1”, “FROMCONTEXT=exten-vm”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:3] Set(“Zap/3-1”, “VMBOX=302”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:4] Set(“Zap/3-1”, “EXTTOCALL=302”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:31] DEBUG[17001] db.c: Unable to find key ‘302’ in family ‘CFU’
[Mar 5 14:40:31] DEBUG[17001] func_db.c: DB: CFU/302 not found in database.
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:5] Set(“Zap/3-1”, “CFUEXT=”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:31] DEBUG[17001] db.c: Unable to find key ‘302’ in family ‘CFB’
[Mar 5 14:40:31] DEBUG[17001] func_db.c: DB: CFB/302 not found in database.
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:6] Set(“Zap/3-1”, “CFBEXT=”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:7] Set(“Zap/3-1”, “RT=15”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:8] Macro(“Zap/3-1”, “record-enable|302|IN”) in new stack
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-record-enable:1] GotoIf(“Zap/3-1”, “1?2:4”) in new stack
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Goto (macro-record-enable,s,2)
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-record-enable:2] ResetCDR(“Zap/3-1”, “w”) in new stack
[Mar 5 14:40:31] VERBOSE[7151] logger.c: Extension Changed 317 new state Idle for Notify User 317
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: ResetCDR
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-record-enable:3] StopMonitor(“Zap/3-1”, “”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: StopMonitor
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-record-enable:4] AGI(“Zap/3-1”, “recordingcheck|20080305-144031|1204746000.812”) in new stack
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Launched AGI Script /usr/share/asterisk/agi-bin/recordingcheck
[Mar 5 14:40:31] VERBOSE[17001] logger.c: recordingcheck|20080305-144031|1204746000.812: Inbound recording not enabled
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – AGI Script recordingcheck completed, returning 0
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: AGI
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-record-enable:5] NoOp(“Zap/3-1”, “No recording needed”) in new stack
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: Macro
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:9] Macro(“Zap/3-1”, “dial|15|tr|302”) in new stack
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-dial:1] GotoIf(“Zap/3-1”, “1?dial”) in new stack
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Goto (macro-dial,s,3)
[Mar 5 14:40:31] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Executing [s@macro-dial:3] AGI(“Zap/3-1”, “dialparties.agi”) in new stack
[Mar 5 14:40:31] VERBOSE[17001] logger.c: – Launched AGI Script /usr/share/asterisk/agi-bin/dialparties.agi
[Mar 5 14:40:32] VERBOSE[17001] logger.c: dialparties.agi: Starting New Dialparties.agi
[Mar 5 14:40:32] VERBOSE[17035] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [Mar 5 14:40:32] VERBOSE[17035] logger.c: Found
[Mar 5 14:40:32] VERBOSE[17035] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[Mar 5 14:40:32] VERBOSE[17001] logger.c: dialparties.agi: Caller ID name is ‘DB Cell Phone VA’ number is ‘xxxxxxxxxx’
[Mar 5 14:40:32] VERBOSE[17001] logger.c: dialparties.agi: Methodology of ring is ‘memoryhunt’
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – dialparties.agi: Added extension 302 to extension map
[Mar 5 14:40:32] DEBUG[17001] db.c: Unable to find key ‘302’ in family ‘CF’
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – dialparties.agi: Extension 302 cf is disabled
[Mar 5 14:40:32] DEBUG[17001] db.c: Unable to find key ‘302’ in family ‘DND’
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – dialparties.agi: Extension 302 do not disturb is disabled
[Mar 5 14:40:32] DEBUG[17001] db.c: Unable to find key ‘302’ in family ‘CFB’
[Mar 5 14:40:32] DEBUG[17001] db.c: Unable to find key ‘302’ in family ‘CFU’
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – dialparties.agi: Filtered ARG3: 302
[Mar 5 14:40:32] VERBOSE[17035] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – AGI Script dialparties.agi completed, returning 0
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: AGI
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:20] NoOp(“Zap/3-1”, "Returned from dialparties with hunt groups to dial ") in new stack
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: NoOp
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:21] Set(“Zap/3-1”, “HuntLoop=0”) in new stack
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:22] GotoIf(“Zap/3-1”, “1?a30”) in new stack
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Goto (macro-dial,s,26)
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:26] Set(“Zap/3-1”, “HuntMember=HuntMember0”) in new stack
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:27] GotoIf(“Zap/3-1”, “0?a32:a35”) in new stack
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Goto (macro-dial,s,31)
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:31] GotoIf(“Zap/3-1”, “1?a36:a50”) in new stack
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Goto (macro-dial,s,32)
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:32] Set(“Zap/3-1”, “CTLoop=0”) in new stack
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:33] GotoIf(“Zap/3-1”, “0?a42”) in new stack
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:34] Set(“Zap/3-1”, “CT_EXTEN=302”) in new stack
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:35] Set(“Zap/3-1”, “DB(CALLTRACE/302)=xxxxxxxxxx”) in new stack
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:36] Set(“Zap/3-1”, “CTLoop=1”) in new stack
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:37] Goto(“Zap/3-1”, “s|a37”) in new stack
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Goto (macro-dial,s,33)
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: Goto
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:33] GotoIf(“Zap/3-1”, “1?a42”) in new stack
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Goto (macro-dial,s,38)
[Mar 5 14:40:32] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Executing [s@macro-dial:38] Dial(“Zap/3-1”, “SIP/302|15|tr”) in new stack
[Mar 5 14:40:32] WARNING[17001] rtp.c: Unable to set TOS to 184
[Mar 5 14:40:32] VERBOSE[7151] logger.c: Extension Changed 302 new state Ringing for Notify User 317
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – Called 302
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – SIP/302-008a0140 is ringing
[Mar 5 14:40:32] VERBOSE[17001] logger.c: – SIP/302-008a0140 is ringing
[Mar 5 14:40:33] VERBOSE[17001] logger.c: – SIP/302-008a0140 is ringing
[Mar 5 14:40:35] VERBOSE[17001] logger.c: – SIP/302-008a0140 is ringing
[Mar 5 14:40:39] VERBOSE[17001] logger.c: – SIP/302-008a0140 is ringing
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – SIP/302-008a0140 is ringing
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Nobody picked up in 15000 ms
[Mar 5 14:40:47] VERBOSE[7151] logger.c: Extension Changed 302 new state Idle for Notify User 317
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Dial
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-dial:39] Set(“Zap/3-1”, “HuntLoop=1”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-dial:40] GotoIf(“Zap/3-1”, “1?a46”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Goto (macro-dial,s,42)
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-dial:42] Set(“Zap/3-1”, “HuntMembers=0”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-dial:43] Goto(“Zap/3-1”, “s|a22”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Goto (macro-dial,s,22)
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Goto
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-dial:22] GotoIf(“Zap/3-1”, “0?a30”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-dial:23] NoOp(“Zap/3-1”, “Returning there are no members left in the hunt group to ring”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: NoOp
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Macro
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:10] Set(“Zap/3-1”, “SV_DIALSTATUS=NOANSWER”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:11] GosubIf(“Zap/3-1”, “0?docfu|1”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GosubIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:12] GosubIf(“Zap/3-1”, “0?docfb|1”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GosubIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:13] Set(“Zap/3-1”, “DIALSTATUS=NOANSWER”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:14] NoOp(“Zap/3-1”, “Voicemail is 302”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: NoOp
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:15] GotoIf(“Zap/3-1”, “0?s-NOANSWER|1”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:16] NoOp(“Zap/3-1”, “Sending to Voicemail box 302”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: NoOp
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-exten-vm:17] Macro(“Zap/3-1”, “vm|302|NOANSWER”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-vm:1] Macro(“Zap/3-1”, “user-callerid|SKIPTTL”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:1] NoOp(“Zap/3-1”, “user-callerid: DB Cell Phone VA xxxxxxxxxx”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:2] Set(“Zap/3-1”, “AMPUSER=xxxxxxxxxx”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:3] GotoIf(“Zap/3-1”, “0?report”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:4] ExecIf(“Zap/3-1”, “0|Set|REALCALLERIDNUM=xxxxxxxxxx”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: ExecIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:5] NoOp(“Zap/3-1”, “REALCALLERIDNUM is xxxxxxxxxx”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:47] DEBUG[17001] db.c: Unable to find key ‘xxxxxxxxxx/user’ in family ‘DEVICE’
[Mar 5 14:40:47] DEBUG[17001] func_db.c: DB: DEVICE/xxxxxxxxxx/user not found in database.
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:6] Set(“Zap/3-1”, “AMPUSER=”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:47] DEBUG[17001] db.c: Unable to find key ‘/cidname’ in family ‘AMPUSER’
[Mar 5 14:40:47] DEBUG[17001] func_db.c: DB: AMPUSER//cidname not found in database.
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:7] Set(“Zap/3-1”, “AMPUSERCIDNAME=”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:8] GotoIf(“Zap/3-1”, “1?report”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Goto (macro-user-callerid,s,13)
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:13] NoOp(“Zap/3-1”, “TTL: 63 ARG1: SKIPTTL”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:14] GotoIf(“Zap/3-1”, “1?continue”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Goto (macro-user-callerid,s,23)
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-user-callerid:23] NoOp(“Zap/3-1”, "Using CallerID “DB Cell Phone VA” ") in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Macro
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-vm:2] Set(“Zap/3-1”, “VMGAIN=”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Set
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-vm:3] GotoIf(“Zap/3-1”, “0?vmx|1”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:47] WARNING[17001] func_db.c: DB requires an argument, DB(/)
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-vm:4] NoOp(“Zap/3-1”, "CAME FROM: 0 - Blocking VM cause of key: ") in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Macro
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [302@from-internal-xfer:2] Hangup(“Zap/3-1”, “”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: == Spawn extension (from-internal-xfer, 302, 2) exited non-zero on ‘Zap/3-1’
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [h@from-internal-xfer:1] Macro(“Zap/3-1”, “hangupcall”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-hangupcall:1] ResetCDR(“Zap/3-1”, “w”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: ResetCDR
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-hangupcall:2] NoCDR(“Zap/3-1”, “”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: NoCDR
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-hangupcall:3] GotoIf(“Zap/3-1”, “1?skiprg”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Goto (macro-hangupcall,s,6)
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-hangupcall:6] GotoIf(“Zap/3-1”, “0?skipblkvm”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: GotoIf
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-hangupcall:7] NoOp(“Zap/3-1”, “Cleaning Up Block VM Flag: BLKVM/0/Zap/3-1”) in new stack
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: Noop
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-hangupcall:8] DBdel(“Zap/3-1”, “BLKVM/0/Zap/3-1”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – DBdel: family=BLKVM, key=0/Zap/3-1
[Mar 5 14:40:47] DEBUG[17001] app_macro.c: Executed application: DBDel
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Executing [s@macro-hangupcall:9] GotoIf(“Zap/3-1”, “1?theend”) in new stack
[Mar 5 14:40:47] VERBOSE[17001] logger.c: – Goto (macro-hangupcall,s,11)

Can anyone shed some light on this? I don’t know why it wouldn’t strip that database value. Please, I would really prefer to not “break” FreePBX to fix this.

just a hunch to explore, can you make the exact same ringgroup don’t use 0 as the ring group number and see if you have the same behavior?

That did it! Thank you so much.

well please file a bug against the issue you are having when the ringgoup is numbered 0, seems like there is a corner case issue going on that needs to be investigated.