BLF or *45 not logging out

FreePBX 13.0.190.7
Phone - GrandStream GXP2140
Queue - 700
Agent - 204 (Dynamic agent of Queue 700)

When BLF key (red) is pressed recording states agent is logged in (CLI queue show confirms). Pressing BLF key (still red) again, same recording “agent logged in…”.
Same results if agent dials *45204*700

Phone setup:
VPK - BLF, *45204*700

 core show hints
    -= Registered Asterisk Dial Plan Hints =-
                   ---
                    [email protected]           : SIP/204&Custom:DND20  State:Idle            Watchers  0
                 _*46*XXX*[email protected]          : qpause:${EXTEN:8}:Lo  State:Unavailable     Watchers  0
             
           _*46*XXX*[email protected]          : qpause:${EXTEN:8}:Lo  State:Unavailable     Watchers  0
                _*45*[email protected]          : ${DB(AMPUSER/${EXTEN  State:Unavailable     Watchers  0     *** I don't know if this if why. If so, how to enable? ***
               _*46*[email protected]es          : ${DB(AMPUSER/${EXTEN  State:Unavailable     Watchers  0
             *45204*[email protected]          : Custom:QUEUE204*700   State:InUse           Watchers  2

             *45203*[email protected]          : Custom:QUEUE203*700   State:InUse           Watchers  3

            _*45XXX*[email protected]          : Custom:QUEUE${EXTEN:  State:Unavailable     Watchers  0  *** same as above ***

CLI output -

Pressing BLF first time

== Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Executing [*45204*[email protected]:1] Set("SIP/204-00005641", "QUEUENO=700") in new stack
    -- Executing [*45204*[email protected]:2] Set("SIP/204-00005641", "QUEUEUSER=204") in new stack
    -- Executing [*45204*[email protected]:3] Goto("SIP/204-00005641", "app-queue-toggle,s,start") in new stack
    -- Goto (app-queue-toggle,s,1)
    -- Executing [[email protected]:1] Answer("SIP/204-00005641", "") in new stack
       > 0xb67e3558 -- Probation passed - setting RTP source address to 10.215.72.204:5004
    -- Executing [[email protected]:2] Wait("SIP/204-00005641", "1") in new stack
    -- Executing [[email protected]:3] Macro("SIP/204-00005641", "user-callerid,") in new stack
    -- Executing [[email protected]:1] Set("SIP/204-00005641", "TOUCH_MONITOR=1484698483.46852") in new stack
    -- Executing [[email protected]:2] Set("SIP/204-00005641", "AMPUSER=204") in new stack
    -- Executing [[email protected]:3] GotoIf("SIP/204-00005641", "0?report") in new stack
    -- Executing [[email protected]:4] ExecIf("SIP/204-00005641", "1?Set(REALCALLERIDNUM=204)") in new stack
    -- Executing [[email protected]:5] Set("SIP/204-00005641", "AMPUSER=204") in new stack
    -- Executing [[email protected]:6] GotoIf("SIP/204-00005641", "0?limit") in new stack
    -- Executing [[email protected]:7] Set("SIP/204-00005641", "AMPUSERCIDNAME=Rod") in new stack
    -- Executing [[email protected]:8] GotoIf("SIP/204-00005641", "0?report") in new stack
    -- Executing [[email protected]:9] Set("SIP/204-00005641", "AMPUSERCID=204") in new stack
    -- Executing [[email protected]:10] Set("SIP/204-00005641", "__DIAL_OPTIONS=Ttr") in new stack
    -- Executing [[email protected]:11] Set("SIP/204-00005641", "CALLERID(all)="Rod" <204>") in new stack
    -- Executing [[email protected]:12] GotoIf("SIP/204-00005641", "0?limit") in new stack
    -- Executing [[email protected]:13] ExecIf("SIP/204-00005641", "0?Set(GROUP(concurrency_limit)=204)") in new stack
    -- Executing [[email protected]:14] ExecIf("SIP/204-00005641", "0?Set(CHANNEL(language)=)") in new stack
    -- Executing [[email protected]:15] GotoIf("SIP/204-00005641", "0?continue") in new stack
    -- Executing [[email protected]:16] ExecIf("SIP/204-00005641", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
    -- Executing [[email protected]:17] Set("SIP/204-00005641", "__TTL=64") in new stack
    -- Executing [[email protected]:18] GotoIf("SIP/204-00005641", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,29)
    -- Executing [[email protected]:29] Set("SIP/204-00005641", "CALLERID(number)=204") in new stack
    -- Executing [[email protected]:30] Set("SIP/204-00005641", "CALLERID(name)=Rod") in new stack
    -- Executing [[email protected]:31] GotoIf("SIP/204-00005641", "0?cnum") in new stack
    -- Executing [[email protected]:32] Set("SIP/204-00005641", "CDR(cnam)=Rod") in new stack
    -- Executing [[email protected]:33] Set("SIP/204-00005641", "CDR(cnum)=204") in new stack
    -- Executing [[email protected]:34] Set("SIP/204-00005641", "CHANNEL(language)=en") in new stack
    -- Executing [[email protected]:4] Set("SIP/204-00005641", "QUEUEUSER=204") in new stack
    -- Executing [[email protected]:5] Set("SIP/204-00005641", "QUEUESTAT=LOGGEDOUT") in new stack
    -- Executing [[email protected]:6] AGI("SIP/204-00005641", "queue_devstate.agi,getqueues,204") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/queue_devstate.agi
    -- <SIP/204-00005641>AGI Script queue_devstate.agi completed, returning 0
    -- Executing [[email protected]:7] GotoIf("SIP/204-00005641", "1?activate") in new stack
    -- Goto (app-queue-toggle,s,16)
    -- Executing [[email protected]:16] NoOp("SIP/204-00005641", "Agent Logged In") in new stack
    -- Executing [[email protected]:17] Macro("SIP/204-00005641", "toggle-add-agent,") in new stack
    -- Executing [[email protected]:1] Macro("SIP/204-00005641", "user-callerid,SKIPTTL,") in new stack
    -- Executing [[email protected]:1] Set("SIP/204-00005641", "TOUCH_MONITOR=1484698483.46852") in new stack
    -- Executing [[email protected]:2] Set("SIP/204-00005641", "AMPUSER=204") in new stack
    -- Executing [[email protected]:3] GotoIf("SIP/204-00005641", "3?report") in new stack
    -- Goto (macro-user-callerid,s,15)
    -- Executing [[email protected]:15] GotoIf("SIP/204-00005641", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,29)
    -- Executing [[email protected]:29] Set("SIP/204-00005641", "CALLERID(number)=204") in new stack
    -- Executing [[email protected]:30] Set("SIP/204-00005641", "CALLERID(name)=Rod") in new stack
    -- Executing [[email protected]:31] GotoIf("SIP/204-00005641", "0?cnum") in new stack
    -- Executing [[email protected]:32] Set("SIP/204-00005641", "CDR(cnam)=Rod") in new stack
    -- Executing [[email protected]:33] Set("SIP/204-00005641", "CDR(cnum)=204") in new stack
    -- Executing [[email protected]:34] Set("SIP/204-00005641", "CHANNEL(language)=en") in new stack
    -- Executing [[email protected]:2] Set("SIP/204-00005641", "QUEUEUSER=204") in new stack
    -- Executing [[email protected]:3] Set("SIP/204-00005641", "QUEUEUSERCIDNAME=Rod") in new stack
    -- Executing [[email protected]:4] GotoIf("SIP/204-00005641", "0?invalid") in new stack
    -- Executing [[email protected]:5] ExecIf("SIP/204-00005641", "1?AddQueueMember(700,Local/[email protected]/n,1,,Rod,hint:[email protected])") in new stack
[2017-01-17 17:14:50] NOTICE[32073][C-000010c8]: app_queue.c:6899 aqm_exec: Added interface 'Local/[email protected]/n' to queue '700'
    -- Executing [[email protected]:6] ExecIf("SIP/204-00005641", "0?AddQueueMember(700,Local/[email protected]/n,1,,Rod)") in new stack
    -- Executing [[email protected]:7] UserEvent("SIP/204-00005641", "AgentLogin,Agent: 204") in new stack
    -- Executing [[email protected]:8] QueueLog("SIP/204-00005641", "700,MANAGER,Rod,ADDMEMBER,") in new stack
    -- Executing [[email protected]:9] MacroExit("SIP/204-00005641", "") in new stack
    -- Executing [[email protected]:18] GotoIf("SIP/204-00005641", "0?logout") in new stack
    -- Executing [[email protected]:19] Set("SIP/204-00005641", "STATE=INUSE") in new stack
    -- Executing [[email protected]:20] Gosub("SIP/204-00005641", "sstate,1()") in new stack
    -- Executing [[email protected]:1] Set("SIP/204-00005641", "DEVICES=204") in new stack
    -- Executing [[email protected]:2] GotoIf("SIP/204-00005641", "0?return") in new stack
    -- Executing [[email protected]:3] Set("SIP/204-00005641", "LOOPCNT=1") in new stack
    -- Executing [[email protected]:4] Set("SIP/204-00005641", "ITER=1") in new stack
    -- Executing [[email protected]:5] Set("SIP/204-00005641", "DEVICE_STATE(Custom:QUEUE204*700)=INUSE") in new stack
    -- Executing [[email protected]:6] Set("SIP/204-00005641", "ITER=2") in new stack
    -- Executing [[email protected]:7] GotoIf("SIP/204-00005641", "0?begin") in new stack
    -- Executing [[email protected]:8] Return("SIP/204-00005641", "") in new stack
    -- Executing [[email protected]:21] Playback("SIP/204-00005641", "agent-loginok") in new stack
    -- <SIP/204-00005641> Playing 'agent-loginok.ulaw' (language 'en')
    -- Executing [[email protected]:22] SayDigits("SIP/204-00005641", "204") in new stack
    -- <SIP/204-00005641> Playing 'digits/2.ulaw' (language 'en')
    -- <SIP/204-00005641> Playing 'digits/0.ulaw' (language 'en')
    -- <SIP/204-00005641> Playing 'digits/4.ulaw' (language 'en')
    -- Executing [[email protected]:23] Macro("SIP/204-00005641", "hangupcall,") in new stack
    -- Executing [[email protected]:1] ExecIf("SIP/204-00005641", "0?Set(CDR(recordingfile)=.)") in new stack
    -- Executing [[email protected]:2] GotoIf("SIP/204-00005641", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,4)
    -- Executing [[email protected]:4] ExecIf("SIP/204-00005641", "0?Set(CDR(recordingfile)=)") in new stack
    -- Executing [[email protected]:5] Hangup("SIP/204-00005641", "") in new stack
  == Spawn extension (macro-hangupcall, s, 5) exited non-zero on 'SIP/204-00005641' in macro 'hangupcall'
  == Spawn extension (app-queue-toggle, s, 23) exited non-zero on 'SIP/204-00005641'

Pressing BLF second time

  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Executing [*45204*[email protected]:1] Set("SIP/204-00005642", "QUEUENO=700") in new stack
    -- Executing [*45204*[email protected]:2] Set("SIP/204-00005642", "QUEUEUSER=204") in new stack
    -- Executing [*45204*[email protected]:3] Goto("SIP/204-00005642", "app-queue-toggle,s,start") in new stack
    -- Goto (app-queue-toggle,s,1)
    -- Executing [[email protected]:1] Answer("SIP/204-00005642", "") in new stack
       > 0xb67e3558 -- Probation passed - setting RTP source address to 10.215.72.204:5004
    -- Executing [[email protected]:2] Wait("SIP/204-00005642", "1") in new stack
    -- Executing [[email protected]:3] Macro("SIP/204-00005642", "user-callerid,") in new stack
    -- Executing [[email protected]:1] Set("SIP/204-00005642", "TOUCH_MONITOR=1484698503.46853") in new stack
    -- Executing [[email protected]:2] Set("SIP/204-00005642", "AMPUSER=204") in new stack
    -- Executing [[email protected]:3] GotoIf("SIP/204-00005642", "0?report") in new stack
    -- Executing [[email protected]:4] ExecIf("SIP/204-00005642", "1?Set(REALCALLERIDNUM=204)") in new stack
    -- Executing [[email protected]:5] Set("SIP/204-00005642", "AMPUSER=204") in new stack
    -- Executing [[email protected]:6] GotoIf("SIP/204-00005642", "0?limit") in new stack
    -- Executing [[email protected]:7] Set("SIP/204-00005642", "AMPUSERCIDNAME=Rod") in new stack
    -- Executing [[email protected]:8] GotoIf("SIP/204-00005642", "0?report") in new stack
    -- Executing [[email protected]:9] Set("SIP/204-00005642", "AMPUSERCID=204") in new stack
    -- Executing [[email protected]:10] Set("SIP/204-00005642", "__DIAL_OPTIONS=Ttr") in new stack
    -- Executing [[email protected]:11] Set("SIP/204-00005642", "CALLERID(all)="Rod" <204>") in new stack
    -- Executing [[email protected]:12] GotoIf("SIP/204-00005642", "0?limit") in new stack
    -- Executing [[email protected]:13] ExecIf("SIP/204-00005642", "0?Set(GROUP(concurrency_limit)=204)") in new stack
    -- Executing [[email protected]:14] ExecIf("SIP/204-00005642", "0?Set(CHANNEL(language)=)") in new stack
    -- Executing [[email protected]:15] GotoIf("SIP/204-00005642", "0?continue") in new stack
    -- Executing [[email protected]:16] ExecIf("SIP/204-00005642", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
    -- Executing [[email protected]:17] Set("SIP/204-00005642", "__TTL=64") in new stack
    -- Executing [[email protected]:18] GotoIf("SIP/204-00005642", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,29)
    -- Executing [[email protected]:29] Set("SIP/204-00005642", "CALLERID(number)=204") in new stack
    -- Executing [[email protected]:30] Set("SIP/204-00005642", "CALLERID(name)=Rod") in new stack
    -- Executing [[email protected]:31] GotoIf("SIP/204-00005642", "0?cnum") in new stack
    -- Executing [[email protected]:32] Set("SIP/204-00005642", "CDR(cnam)=Rod") in new stack
    -- Executing [[email protected]:33] Set("SIP/204-00005642", "CDR(cnum)=204") in new stack
    -- Executing [[email protected]cro-user-callerid:34] Set("SIP/204-00005642", "CHANNEL(language)=en") in new stack
    -- Executing [[email protected]:4] Set("SIP/204-00005642", "QUEUEUSER=204") in new stack
    -- Executing [[email protected]:5] Set("SIP/204-00005642", "QUEUESTAT=LOGGEDOUT") in new stack
    -- Executing [[email protected]:6] AGI("SIP/204-00005642", "queue_devstate.agi,getqueues,204") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/queue_devstate.agi
    -- <SIP/204-00005642>AGI Script queue_devstate.agi completed, returning 0
    -- Executing [[email protected]:7] GotoIf("SIP/204-00005642", "1?activate") in new stack
    -- Goto (app-queue-toggle,s,16)
    -- Executing [[email protected]:16] NoOp("SIP/204-00005642", "Agent Logged In") in new stack
    -- Executing [[email protected]:17] Macro("SIP/204-00005642", "toggle-add-agent,") in new stack
    -- Executing [[email protected]:1] Macro("SIP/204-00005642", "user-callerid,SKIPTTL,") in new stack
    -- Executing [[email protected]:1] Set("SIP/204-00005642", "TOUCH_MONITOR=1484698503.46853") in new stack
    -- Executing [[email protected]:2] Set("SIP/204-00005642", "AMPUSER=204") in new stack
    -- Executing [[email protected]:3] GotoIf("SIP/204-00005642", "3?report") in new stack
    -- Goto (macro-user-callerid,s,15)
    -- Executing [[email protected]:15] GotoIf("SIP/204-00005642", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,29)
    -- Executing [[email protected]:29] Set("SIP/204-00005642", "CALLERID(number)=204") in new stack
    -- Executing [[email protected]:30] Set("SIP/204-00005642", "CALLERID(name)=Rod") in new stack
    -- Executing [[email protected]:31] GotoIf("SIP/204-00005642", "0?cnum") in new stack
    -- Executing [[email protected]:32] Set("SIP/204-00005642", "CDR(cnam)=Rod") in new stack
    -- Executing [[email protected]:33] Set("SIP/204-00005642", "CDR(cnum)=204") in new stack
    -- Executing [[email protected]:34] Set("SIP/204-00005642", "CHANNEL(language)=en") in new stack
    -- Executing [[email protected]:2] Set("SIP/204-00005642", "QUEUEUSER=204") in new stack
    -- Executing [[email protected]:3] Set("SIP/204-00005642", "QUEUEUSERCIDNAME=Rod") in new stack
    -- Executing [[email protected]:4] GotoIf("SIP/204-00005642", "0?invalid") in new stack
    -- Executing [[email protected]:5] ExecIf("SIP/204-00005642", "1?AddQueueMember(700,Local/[email protected]/n,1,,Rod,hint:[email protected])") in new stack
[2017-01-17 17:15:05] WARNING[32078][C-000010c9]: app_queue.c:6904 aqm_exec: Unable to add interface 'Local/[email protected]/n' to queue '700': Already there                 *** agree ***
    -- Executing [[email protected]:6] ExecIf("SIP/204-00005642", "0?AddQueueMember(700,Local/[email protected]/n,1,,Rod)") in new stack
    -- Executing [[email protected]:7] UserEvent("SIP/204-00005642", "AgentLogin,Agent: 204") in new stack
    -- Executing [[email protected]:8] QueueLog("SIP/204-00005642", "700,MANAGER,Rod,ADDMEMBER,") in new stack
    -- Executing [[email protected]:9] MacroExit("SIP/204-00005642", "") in new stack
    -- Executing [[email protected]:18] GotoIf("SIP/204-00005642", "0?logout") in new stack
    -- Executing [[email protected]:19] Set("SIP/204-00005642", "STATE=INUSE") in new stack
    -- Executing [[email protected]:20] Gosub("SIP/204-00005642", "sstate,1()") in new stack
    -- Executing [[email protected]:1] Set("SIP/204-00005642", "DEVICES=204") in new stack
    -- Executing [[email protected]:2] GotoIf("SIP/204-00005642", "0?return") in new stack
    -- Executing [[email protected]:3] Set("SIP/204-00005642", "LOOPCNT=1") in new stack
    -- Executing [[email protected]:4] Set("SIP/204-00005642", "ITER=1") in new stack
    -- Executing [[email protected]:5] Set("SIP/204-00005642", "DEVICE_STATE(Custom:QUEUE204*700)=INUSE") in new stack
    -- Executing [[email protected]:6] Set("SIP/204-00005642", "ITER=2") in new stack
    -- Executing [[email protected]eue-toggle:7] GotoIf("SIP/204-00005642", "0?begin") in new stack
    -- Executing [[email protected]:8] Return("SIP/204-00005642", "") in new stack
    -- Executing [[email protected]:21] Playback("SIP/204-00005642", "agent-loginok") in new stack
    -- <SIP/204-00005642> Playing 'agent-loginok.ulaw' (language 'en')
    -- Executing [[email protected]:22] SayDigits("SIP/204-00005642", "204") in new stack
    -- <SIP/204-00005642> Playing 'digits/2.ulaw' (language 'en')
    -- <SIP/204-00005642> Playing 'digits/0.ulaw' (language 'en')
    -- <SIP/204-00005642> Playing 'digits/4.ulaw' (language 'en')
    -- Executing [[email protected]:23] Macro("SIP/204-00005642", "hangupcall,") in new stack
    -- Executing [[email protected]:1] ExecIf("SIP/204-00005642", "0?Set(CDR(recordingfile)=.)") in new stack
    -- Executing [[email protected]:2] GotoIf("SIP/204-00005642", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,4)
    -- Executing [[email protected]:4] ExecIf("SIP/204-00005642", "0?Set(CDR(recordingfile)=)") in new stack
    -- Executing [[email protected]:5] Hangup("SIP/204-00005642", "") in new stack
  == Spawn extension (macro-hangupcall, s, 5) exited non-zero on 'SIP/204-00005642' in macro 'hangupcall'
  == Spawn extension (app-queue-toggle, s, 23) exited non-zero on 'SIP/204-00005642'

CORRECTION -
Same results if agent dials *45204*700

Phone setup:
VPK - BLF, *45204*700

Bet your agents are static not dynamic. Only dynamic agents can log out.

Queue 700 -
Static Agents 0
Dynamic Agents 204,0 | 203,0 | 205,0
In addition-
Queue 700 General Settings > Restrict Dynamic Agents = NO

Where the BLF isn’t working the agents have to Login with 700*204# and Logout with 700**204#, would be nice to have BLF and Login/Logout.

I just tested with Queues module ver. 13.0.32.2 and the *45<ext_num>*<queue_num> feature codes work fine for me. From your log snippets:

-- Executing [[email protected]:6] AGI("SIP/204-00005642", "queue_devstate.agi,getqueues,204") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/queue_devstate.agi
-- <SIP/204-00005642>AGI Script queue_devstate.agi completed, returning 0
-- Executing [[email protected]:7] GotoIf("SIP/204-00005642", "1?activate") in new stack

It is returning from the AGI thinking that your extension is not logged into the queue, and so then logs you in (again). I have no explanation for this, but perhaps if you enable AGI debug with:

agi set debug on

and try to login again with teh feature code, we might see a reason why.

Thank you for helping with this, there is probably a ‘duh’ moment in my near future.

AGI Debugging Enabled
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [45204[email protected]:1] Set(“SIP/204-00005ecf”, “QUEUENO=700”) in new stack
– Executing [45204[email protected]:2] Set(“SIP/204-00005ecf”, “QUEUEUSER=204”) in new stack
– Executing [45204[email protected]:3] Goto(“SIP/204-00005ecf”, “app-queue-toggle,s,start”) in new stack
– Goto (app-queue-toggle,s,1)
– Executing [[email protected]:1] Answer(“SIP/204-00005ecf”, “”) in new stack
> 0xb354a2b8 – Probation passed - setting RTP source address to 10.215.72.204:5004
– Executing [[email protected]:2] Wait(“SIP/204-00005ecf”, “1”) in new stack
– Executing [[email protected]:3] Macro(“SIP/204-00005ecf”, “user-callerid,”) in new stack
– Executing [[email protected]:1] Set(“SIP/204-00005ecf”, “TOUCH_MONITOR=1484867870.50596”) in new stack
– Executing [[email protected]:2] Set(“SIP/204-00005ecf”, “AMPUSER=204”) in new stack
– Executing [[email protected]:3] GotoIf(“SIP/204-00005ecf”, “0?report”) in new stack
– Executing [[email protected]:4] ExecIf(“SIP/204-00005ecf”, “1?Set(REALCALLERIDNUM=204)”) in new stack
– Executing [[email protected]:5] Set(“SIP/204-00005ecf”, “AMPUSER=204”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/204-00005ecf”, “0?limit”) in new stack
– Executing [[email protected]:7] Set(“SIP/204-00005ecf”, “AMPUSERCIDNAME=Rod”) in new stack
– Executing [[email protected]:8] GotoIf(“SIP/204-00005ecf”, “0?report”) in new stack
– Executing [[email protected]:9] Set(“SIP/204-00005ecf”, “AMPUSERCID=204”) in new stack
– Executing [[email protected]:10] Set(“SIP/204-00005ecf”, “__DIAL_OPTIONS=Ttr”) in new stack
– Executing [[email protected]:11] Set(“SIP/204-00005ecf”, “CALLERID(all)=“Rod” <204>”) in new stack
– Executing [[email protected]:12] GotoIf(“SIP/204-00005ecf”, “0?limit”) in new stack
– Executing [[email protected]:13] ExecIf(“SIP/204-00005ecf”, “0?Set(GROUP(concurrency_limit)=204)”) in new stack
– Executing [[email protected]:14] ExecIf(“SIP/204-00005ecf”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [[email protected]:15] GotoIf(“SIP/204-00005ecf”, “0?continue”) in new stack
– Executing [[email protected]:16] ExecIf(“SIP/204-00005ecf”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
– Executing [[email protected]:17] Set(“SIP/204-00005ecf”, “__TTL=64”) in new stack
– Executing [[email protected]:18] GotoIf(“SIP/204-00005ecf”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [[email protected]:29] Set(“SIP/204-00005ecf”, “CALLERID(number)=204”) in new stack
– Executing [[email protected]:30] Set(“SIP/204-00005ecf”, “CALLERID(name)=Rod”) in new stack
– Executing [[email protected]:31] GotoIf(“SIP/204-00005ecf”, “0?cnum”) in new stack
– Executing [[email protected]:32] Set(“SIP/204-00005ecf”, “CDR(cnam)=Rod”) in new stack
– Executing [[email protected]:33] Set(“SIP/204-00005ecf”, “CDR(cnum)=204”) in new stack
– Executing [[email protected]:34] Set(“SIP/204-00005ecf”, “CHANNEL(language)=en”) in new stack
– Executing [[email protected]:4] Set(“SIP/204-00005ecf”, “QUEUEUSER=204”) in new stack
– Executing [[email protected]:5] Set(“SIP/204-00005ecf”, “QUEUESTAT=LOGGEDOUT”) in new stack
– Executing [[email protected]:6] AGI(“SIP/204-00005ecf”, “queue_devstate.agi,getqueues,204”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/queue_devstate.agi
<SIP/204-00005ecf>AGI Tx >> agi_request: queue_devstate.agi
<SIP/204-00005ecf>AGI Tx >> agi_channel: SIP/204-00005ecf
<SIP/204-00005ecf>AGI Tx >> agi_language: en
<SIP/204-00005ecf>AGI Tx >> agi_type: SIP
<SIP/204-00005ecf>AGI Tx >> agi_uniqueid: 1484867870.50596
<SIP/204-00005ecf>AGI Tx >> agi_version: 11.12.0
<SIP/204-00005ecf>AGI Tx >> agi_callerid: 204
<SIP/204-00005ecf>AGI Tx >> agi_calleridname: Rod
<SIP/204-00005ecf>AGI Tx >> agi_callingpres: 0
<SIP/204-00005ecf>AGI Tx >> agi_callingani2: 0
<SIP/204-00005ecf>AGI Tx >> agi_callington: 0
<SIP/204-00005ecf>AGI Tx >> agi_callingtns: 0
<SIP/204-00005ecf>AGI Tx >> agi_dnid: 45204700
<SIP/204-00005ecf>AGI Tx >> agi_rdnis: unknown
<SIP/204-00005ecf>AGI Tx >> agi_context: app-queue-toggle
<SIP/204-00005ecf>AGI Tx >> agi_extension: s
<SIP/204-00005ecf>AGI Tx >> agi_priority: 6
<SIP/204-00005ecf>AGI Tx >> agi_enhanced: 0.0
<SIP/204-00005ecf>AGI Tx >> agi_accountcode:
<SIP/204-00005ecf>AGI Tx >> agi_threadid: -1266369680
<SIP/204-00005ecf>AGI Tx >> agi_arg_1: getqueues
<SIP/204-00005ecf>AGI Tx >> agi_arg_2: 204
<SIP/204-00005ecf>AGI Tx >>
<SIP/204-00005ecf>AGI Rx << GET VARIABLE AMPMGRUSER
<SIP/204-00005ecf>AGI Tx >> 200 result=1 (admin)
<SIP/204-00005ecf>AGI Rx << GET VARIABLE AMPMGRPASS
<SIP/204-00005ecf>AGI Tx >> 200 result=1 (*****6b)
<SIP/204-00005ecf>AGI Rx << GET VARIABLE ASTSPOOLDIR
<SIP/204-00005ecf>AGI Tx >> 200 result=1 (/var/spool/asterisk)
<SIP/204-00005ecf>AGI Rx << GET VARIABLE ASTMANAGERHOST
<SIP/204-00005ecf>AGI Tx >> 200 result=1 (localhost)
<SIP/204-00005ecf>AGI Rx << GET VARIABLE ASTVERSION
<SIP/204-00005ecf>AGI Tx >> 200 result=1 (11.12.0)
– <SIP/204-00005ecf>AGI Script queue_devstate.agi completed, returning 0
– Executing [[email protected]:7] GotoIf(“SIP/204-00005ecf”, “1?activate”) in new stack
– Goto (app-queue-toggle,s,16)
– Executing [[email protected]:16] NoOp(“SIP/204-00005ecf”, “Agent Logged In”) in new stack
– Executing [[email protected]:17] Macro(“SIP/204-00005ecf”, “toggle-add-agent,”) in new stack
– Executing [[email protected]:1] Macro(“SIP/204-00005ecf”, “user-callerid,SKIPTTL,”) in new stack
– Executing [[email protected]:1] Set(“SIP/204-00005ecf”, “TOUCH_MONITOR=1484867870.50596”) in new stack
– Executing [[email protected]:2] Set(“SIP/204-00005ecf”, “AMPUSER=204”) in new stack
– Executing [[email protected]:3] GotoIf(“SIP/204-00005ecf”, “3?report”) in new stack
– Goto (macro-user-callerid,s,15)
– Executing [[email protected]:15] GotoIf(“SIP/204-00005ecf”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [[email protected]:29] Set(“SIP/204-00005ecf”, “CALLERID(number)=204”) in new stack
– Executing [[email protected]:30] Set(“SIP/204-00005ecf”, “CALLERID(name)=Rod”) in new stack
– Executing [[email protected]:31] GotoIf(“SIP/204-00005ecf”, “0?cnum”) in new stack
– Executing [[email protected]:32] Set(“SIP/204-00005ecf”, “CDR(cnam)=Rod”) in new stack
– Executing [[email protected]:33] Set(“SIP/204-00005ecf”, “CDR(cnum)=204”) in new stack
– Executing [[email protected]:34] Set(“SIP/204-00005ecf”, “CHANNEL(language)=en”) in new stack
– Executing [[email protected]:2] Set(“SIP/204-00005ecf”, “QUEUEUSER=204”) in new stack
– Executing [[email protected]:3] Set(“SIP/204-00005ecf”, “QUEUEUSERCIDNAME=Rod”) in new stack
– Executing [[email protected]:4] GotoIf(“SIP/204-00005ecf”, “0?invalid”) in new stack
– Executing [[email protected]:5] ExecIf(“SIP/204-00005ecf”, “1?AddQueueMember(700,Local/[email protected]/n,0,Rod,hint:[email protected])”) in new stack
[2017-01-19 16:17:56] NOTICE[22512][C-000011ee]: app_queue.c:6899 aqm_exec: Added interface ‘Local/[email protected]/n’ to queue ‘700’
– Executing [[email protected]:6] ExecIf(“SIP/204-00005ecf”, “0?AddQueueMember(700,Local/[email protected]/n,0,Rod)”) in new stack
– Executing [[email protected]:7] UserEvent(“SIP/204-00005ecf”, “AgentLogin,Agent: 204”) in new stack
– Executing [[email protected]:8] QueueLog(“SIP/204-00005ecf”, “700,MANAGER,Rod,ADDMEMBER,”) in new stack
– Executing [[email protected]:9] MacroExit(“SIP/204-00005ecf”, “”) in new stack
– Executing [[email protected]:18] GotoIf(“SIP/204-00005ecf”, “0?logout”) in new stack
– Executing [[email protected]:19] Set(“SIP/204-00005ecf”, “STATE=INUSE”) in new stack
– Executing [[email protected]:20] Gosub(“SIP/204-00005ecf”, “sstate,1()”) in new stack
– Executing [[email protected]:1] Set(“SIP/204-00005ecf”, “DEVICES=204”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/204-00005ecf”, “0?return”) in new stack
– Executing [[email protected]:3] Set(“SIP/204-00005ecf”, “LOOPCNT=1”) in new stack
– Executing [[email protected]:4] Set(“SIP/204-00005ecf”, “ITER=1”) in new stack
– Executing [[email protected]:5] Set(“SIP/204-00005ecf”, "DEVICE_STATE(Custom:QUEUE204
700)=INUSE") in new stack
– Executing [[email protected]:6] Set(“SIP/204-00005ecf”, “ITER=2”) in new stack
– Executing [[email protected]:7] GotoIf(“SIP/204-00005ecf”, “0?begin”) in new stack
– Executing [[email protected]:8] Return(“SIP/204-00005ecf”, “”) in new stack
– Executing [[email protected]:21] Playback(“SIP/204-00005ecf”, “agent-loginok”) in new stack
– <SIP/204-00005ecf> Playing ‘agent-loginok.ulaw’ (language ‘en’)
– Executing [[email protected]:22] SayDigits(“SIP/204-00005ecf”, “204”) in new stack
– <SIP/204-00005ecf> Playing ‘digits/2.ulaw’ (language ‘en’)
– <SIP/204-00005ecf> Playing ‘digits/0.ulaw’ (language ‘en’)
– <SIP/204-00005ecf> Playing ‘digits/4.ulaw’ (language ‘en’)
– Executing [[email protected]:23] Macro(“SIP/204-00005ecf”, “hangupcall,”) in new stack
– Executing [[email protected]:1] ExecIf(“SIP/204-00005ecf”, “0?Set(CDR(recordingfile)=.)”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/204-00005ecf”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,4)
– Executing [[email protected]:4] ExecIf(“SIP/204-00005ecf”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [[email protected]:5] Hangup(“SIP/204-00005ecf”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 5) exited non-zero on ‘SIP/204-00005ecf’ in macro ‘hangupcall’
== Spawn extension (app-queue-toggle, s, 23) exited non-zero on ‘SIP/204-00005ecf’
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [45204[email protected]:1] Set(“SIP/204-00005ed0”, “QUEUENO=700”) in new stack
– Executing [45204[email protected]:2] Set(“SIP/204-00005ed0”, “QUEUEUSER=204”) in new stack
– Executing [45204[email protected]:3] Goto(“SIP/204-00005ed0”, “app-queue-toggle,s,start”) in new stack
– Goto (app-queue-toggle,s,1)
– Executing [[email protected]:1] Answer(“SIP/204-00005ed0”, “”) in new stack
> 0xb354a2b8 – Probation passed - setting RTP source address to 10.215.72.204:5004
– Executing [[email protected]:2] Wait(“SIP/204-00005ed0”, “1”) in new stack
– Executing [[email protected]:3] Macro(“SIP/204-00005ed0”, “user-callerid,”) in new stack
– Executing [[email protected]:1] Set(“SIP/204-00005ed0”, “TOUCH_MONITOR=1484867884.50597”) in new stack
– Executing [[email protected]:2] Set(“SIP/204-00005ed0”, “AMPUSER=204”) in new stack
– Executing [[email protected]:3] GotoIf(“SIP/204-00005ed0”, “0?report”) in new stack
– Executing [[email protected]:4] ExecIf(“SIP/204-00005ed0”, “1?Set(REALCALLERIDNUM=204)”) in new stack
– Executing [[email protected]:5] Set(“SIP/204-00005ed0”, “AMPUSER=204”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/204-00005ed0”, “0?limit”) in new stack
– Executing [[email protected]:7] Set(“SIP/204-00005ed0”, “AMPUSERCIDNAME=Rod”) in new stack
– Executing [[email protected]:8] GotoIf(“SIP/204-00005ed0”, “0?report”) in new stack
– Executing [[email protected]:9] Set(“SIP/204-00005ed0”, “AMPUSERCID=204”) in new stack
– Executing [[email protected]:10] Set(“SIP/204-00005ed0”, “__DIAL_OPTIONS=Ttr”) in new stack
– Executing [[email protected]:11] Set(“SIP/204-00005ed0”, “CALLERID(all)=“Rod” <204>”) in new stack
– Executing [[email protected]:12] GotoIf(“SIP/204-00005ed0”, “0?limit”) in new stack
– Executing [[email protected]:13] ExecIf(“SIP/204-00005ed0”, “0?Set(GROUP(concurrency_limit)=204)”) in new stack
– Executing [[email protected]:14] ExecIf(“SIP/204-00005ed0”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [[email protected]:15] GotoIf(“SIP/204-00005ed0”, “0?continue”) in new stack
– Executing [[email protected]:16] ExecIf(“SIP/204-00005ed0”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
– Executing [[email protected]:17] Set(“SIP/204-00005ed0”, “__TTL=64”) in new stack
– Executing [[email protected]:18] GotoIf(“SIP/204-00005ed0”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [[email protected]:29] Set(“SIP/204-00005ed0”, “CALLERID(number)=204”) in new stack
– Executing [[email protected]:30] Set(“SIP/204-00005ed0”, “CALLERID(name)=Rod”) in new stack
– Executing [[email protected]:31] GotoIf(“SIP/204-00005ed0”, “0?cnum”) in new stack
– Executing [[email protected]:32] Set(“SIP/204-00005ed0”, “CDR(cnam)=Rod”) in new stack
– Executing [[email protected]:33] Set(“SIP/204-00005ed0”, “CDR(cnum)=204”) in new stack
– Executing [[email protected]:34] Set(“SIP/204-00005ed0”, “CHANNEL(language)=en”) in new stack
– Executing [[email protected]:4] Set(“SIP/204-00005ed0”, “QUEUEUSER=204”) in new stack
– Executing [[email protected]:5] Set(“SIP/204-00005ed0”, “QUEUESTAT=LOGGEDOUT”) in new stack
– Executing [[email protected]:6] AGI(“SIP/204-00005ed0”, “queue_devstate.agi,getqueues,204”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/queue_devstate.agi
<SIP/204-00005ed0>AGI Tx >> agi_request: queue_devstate.agi
<SIP/204-00005ed0>AGI Tx >> agi_channel: SIP/204-00005ed0
<SIP/204-00005ed0>AGI Tx >> agi_language: en
<SIP/204-00005ed0>AGI Tx >> agi_type: SIP
<SIP/204-00005ed0>AGI Tx >> agi_uniqueid: 1484867884.50597
<SIP/204-00005ed0>AGI Tx >> agi_version: 11.12.0
<SIP/204-00005ed0>AGI Tx >> agi_callerid: 204
<SIP/204-00005ed0>AGI Tx >> agi_calleridname: Rod
<SIP/204-00005ed0>AGI Tx >> agi_callingpres: 0
<SIP/204-00005ed0>AGI Tx >> agi_callingani2: 0
<SIP/204-00005ed0>AGI Tx >> agi_callington: 0
<SIP/204-00005ed0>AGI Tx >> agi_callingtns: 0
<SIP/204-00005ed0>AGI Tx >> agi_dnid: 45204700
<SIP/204-00005ed0>AGI Tx >> agi_rdnis: unknown
<SIP/204-00005ed0>AGI Tx >> agi_context: app-queue-toggle
<SIP/204-00005ed0>AGI Tx >> agi_extension: s
<SIP/204-00005ed0>AGI Tx >> agi_priority: 6
<SIP/204-00005ed0>AGI Tx >> agi_enhanced: 0.0
<SIP/204-00005ed0>AGI Tx >> agi_accountcode:
<SIP/204-00005ed0>AGI Tx >> agi_threadid: -1266369680
<SIP/204-00005ed0>AGI Tx >> agi_arg_1: getqueues
<SIP/204-00005ed0>AGI Tx >> agi_arg_2: 204
<SIP/204-00005ed0>AGI Tx >>
<SIP/204-00005ed0>AGI Rx << GET VARIABLE AMPMGRUSER
<SIP/204-00005ed0>AGI Tx >> 200 result=1 (admin)
<SIP/204-00005ed0>AGI Rx << GET VARIABLE AMPMGRPASS
<SIP/204-00005ed0>AGI Tx >> 200 result=1 (*****6b)
<SIP/204-00005ed0>AGI Rx << GET VARIABLE ASTSPOOLDIR
<SIP/204-00005ed0>AGI Tx >> 200 result=1 (/var/spool/asterisk)
<SIP/204-00005ed0>AGI Rx << GET VARIABLE ASTMANAGERHOST
<SIP/204-00005ed0>AGI Tx >> 200 result=1 (localhost)
<SIP/204-00005ed0>AGI Rx << GET VARIABLE ASTVERSION
<SIP/204-00005ed0>AGI Tx >> 200 result=1 (11.12.0)
– <SIP/204-00005ed0>AGI Script queue_devstate.agi completed, returning 0
– Executing [[email protected]:7] GotoIf(“SIP/204-00005ed0”, “1?activate”) in new stack
– Goto (app-queue-toggle,s,16)
– Executing [[email protected]:16] NoOp(“SIP/204-00005ed0”, “Agent Logged In”) in new stack
– Executing [[email protected]:17] Macro(“SIP/204-00005ed0”, “toggle-add-agent,”) in new stack
– Executing [[email protected]:1] Macro(“SIP/204-00005ed0”, “user-callerid,SKIPTTL,”) in new stack
– Executing [[email protected]:1] Set(“SIP/204-00005ed0”, “TOUCH_MONITOR=1484867884.50597”) in new stack
– Executing [[email protected]:2] Set(“SIP/204-00005ed0”, “AMPUSER=204”) in new stack
– Executing [[email protected]:3] GotoIf(“SIP/204-00005ed0”, “3?report”) in new stack
– Goto (macro-user-callerid,s,15)
– Executing [[email protected]:15] GotoIf(“SIP/204-00005ed0”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [[email protected]:29] Set(“SIP/204-00005ed0”, “CALLERID(number)=204”) in new stack
– Executing [[email protected]:30] Set(“SIP/204-00005ed0”, “CALLERID(name)=Rod”) in new stack
– Executing [[email protected]:31] GotoIf(“SIP/204-00005ed0”, “0?cnum”) in new stack
– Executing [[email protected]:32] Set(“SIP/204-00005ed0”, “CDR(cnam)=Rod”) in new stack
– Executing [[email protected]:33] Set(“SIP/204-00005ed0”, “CDR(cnum)=204”) in new stack
– Executing [[email protected]:34] Set(“SIP/204-00005ed0”, “CHANNEL(language)=en”) in new stack
– Executing [[email protected]le-add-agent:2] Set(“SIP/204-00005ed0”, “QUEUEUSER=204”) in new stack
– Executing [[email protected]:3] Set(“SIP/204-00005ed0”, “QUEUEUSERCIDNAME=Rod”) in new stack
– Executing [[email protected]:4] GotoIf(“SIP/204-00005ed0”, “0?invalid”) in new stack
– Executing [[email protected]:5] ExecIf(“SIP/204-00005ed0”, “1?AddQueueMember(700,Local/[email protected]/n,0,Rod,hint:[email protected])”) in new stack
[2017-01-19 16:18:05] WARNING[22514][C-000011ef]: app_queue.c:6904 aqm_exec: Unable to add interface ‘Local/[email protected]/n’ to queue ‘700’: Already there
– Executing [[email protected]:6] ExecIf(“SIP/204-00005ed0”, “0?AddQueueMember(700,Local/[email protected]/n,0,Rod)”) in new stack
– Executing [[email protected]:7] UserEvent(“SIP/204-00005ed0”, “AgentLogin,Agent: 204”) in new stack
– Executing [[email protected]:8] QueueLog(“SIP/204-00005ed0”, “700,MANAGER,Rod,ADDMEMBER,”) in new stack
– Executing [[email protected]:9] MacroExit(“SIP/204-00005ed0”, “”) in new stack
– Executing [[email protected]:18] GotoIf(“SIP/204-00005ed0”, “0?logout”) in new stack
– Executing [[email protected]:19] Set(“SIP/204-00005ed0”, “STATE=INUSE”) in new stack
– Executing [[email protected]:20] Gosub(“SIP/204-00005ed0”, “sstate,1()”) in new stack
– Executing [[email protected]:1] Set(“SIP/204-00005ed0”, “DEVICES=204”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/204-00005ed0”, “0?return”) in new stack
– Executing [[email protected]:3] Set(“SIP/204-00005ed0”, “LOOPCNT=1”) in new stack
– Executing [[email protected]:4] Set(“SIP/204-00005ed0”, “ITER=1”) in new stack
– Executing [[email protected]:5] Set(“SIP/204-00005ed0”, "DEVICE_STATE(Custom:QUEUE204
700)=INUSE") in new stack
– Executing [[email protected]:6] Set(“SIP/204-00005ed0”, “ITER=2”) in new stack
– Executing [[email protected]:7] GotoIf(“SIP/204-00005ed0”, “0?begin”) in new stack
– Executing [[email protected]:8] Return(“SIP/204-00005ed0”, “”) in new stack
– Executing [[email protected]:21] Playback(“SIP/204-00005ed0”, “agent-loginok”) in new stack
– <SIP/204-00005ed0> Playing ‘agent-loginok.ulaw’ (language ‘en’)
– Executing [[email protected]:22] SayDigits(“SIP/204-00005ed0”, “204”) in new stack
– <SIP/204-00005ed0> Playing ‘digits/2.ulaw’ (language ‘en’)
– <SIP/204-00005ed0> Playing ‘digits/0.ulaw’ (language ‘en’)
– <SIP/204-00005ed0> Playing ‘digits/4.ulaw’ (language ‘en’)
– Executing [[email protected]:23] Macro(“SIP/204-00005ed0”, “hangupcall,”) in new stack
– Executing [[email protected]:1] ExecIf(“SIP/204-00005ed0”, “0?Set(CDR(recordingfile)=.)”) in new stack
– Executing [[email protected]:2] GotoIf(“SIP/204-00005ed0”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,4)
– Executing [[email protected]:4] ExecIf(“SIP/204-00005ed0”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [[email protected]:5] Hangup(“SIP/204-00005ed0”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 5) exited non-zero on ‘SIP/204-00005ed0’ in macro ‘hangupcall’
== Spawn extension (app-queue-toggle, s, 23) exited non-zero on ‘SIP/204-00005ed0’

The AGI debug output is only about half of what it should be. Your Asterisk version is way out of date, which might be contributing, are your FreePBX modules up to date?

The ‘duh’ moment I spoke of I guess, “Your Asterisk version is way out of date…”

PIAF Installed Version = 3.0.6.5 under HARDWARE
FreePBX Version = 13.0.190.
Running Asterisk Version = 11.12.0
Asterisk Source Version = 11.12.0
Operating System = CentOS release 6.5 (Final) ><|
Kernel Version = 2.6.32-431.23.3.el6.i686 - 32 Bit

“are your FreePBX modules up to date?” modules are 13.0.+

The fact that you are running PIAF needed to be noted in the first post. You are running an unmaintained fork of FreePBX. This feature code works just fine on the FreePBX distro.

Thanks for setting it straight.
I’ll proceed with making needed corrections.