Agent tries to log out of queue but keeps saying "agent logged in"

Hello,

I have an issue where an agent tries to log out at the end of the day and cannot. They all have BLF buttons programmed as * 45Ext*Queue. The BLF light is red, fop2 shows them as logged in. They press the button, system says “Agent logged in…” Press the button again… “Agent logged in…”

It seems to happen randomly throughout the day for about 10-20 minutes. After 10-20 minutes, if they press the button, it works as it should. and they don’t see it happen again for a few days.

The agents are dynamic members.

For what its worth, they had an old server that was replaced (for a different reason) where the problem happened as well. I didn’t do any backup or restores. Only used bulk handler for extensions and DIDs. Everything else was reprogrammed.

FreePBX 15.0.17.55
Asterisk 16.20.0
Linux freepbx.sangoma.local 3.10.0-1127.19.1.el7.x86_64

Here are what the logs and AGI debug are showing.

This one was ext 283 logging out of 369.

1087722[2021-10-19 17:02:53] VERBOSE[13556][C-00001cb0] pbx.c: Executing [*45283*[email protected]:1] Set("SIP/283-00002d4a", "QUEUENO=369") in new stack

1087723[2021-10-19 17:02:53] VERBOSE[13556][C-00001cb0] pbx.c: Executing [*45283*[email protected]:2] Set("SIP/283-00002d4a", "QUEUEUSER=283") in new stack

1087724[2021-10-19 17:02:53] VERBOSE[13556][C-00001cb0] pbx.c: Executing [*45283*[email protected]:3] Goto("SIP/283-00002d4a", "app-queue-toggle,s,start") in new stack

1087725[2021-10-19 17:02:54] VERBOSE[13556][C-00001cb0] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Getting Queue Status for user 283 in queue 369

1087726[2021-10-19 17:02:54] VERBOSE[13556][C-00001cb0] pbx.c: Executing [[email protected]:5] ExecIf("SIP/283-00002d4a", "1?AddQueueMember(369,Local/[email protected]/n,0,,"Stacey ",hint:[email protected])") in new stack

1087727[2021-10-19 17:02:54] WARNING[13556][C-00001cb0] app_queue.c: Unable to add interface 'Local/[email protected]/n' to queue '369': Already there

1087728[2021-10-19 17:02:54] VERBOSE[13556][C-00001cb0] pbx.c: Executing [[email protected]:6] ExecIf("SIP/283-00002d4a", "0?AddQueueMember(369,Local/[email protected]/n,0,,"Stacey ")") in new stack

1087729[2021-10-19 17:02:54] VERBOSE[13556][C-00001cb0] pbx.c: Executing [[email protected]:5] Set("SIP/283-00002d4a", "DEVICE_STATE(Custom:QUEUE283*369)=INUSE") in new stack

1087730[2021-10-19 17:03:01] VERBOSE[13583][C-00001cb1] pbx.c: Executing [*45283*[email protected]:1] Set("SIP/283-00002d4b", "QUEUENO=369") in new stack

1087731[2021-10-19 17:03:01] VERBOSE[13583][C-00001cb1] pbx.c: Executing [*45283*[email protected]:2] Set("SIP/283-00002d4b", "QUEUEUSER=283") in new stack

1087732[2021-10-19 17:03:01] VERBOSE[13583][C-00001cb1] pbx.c: Executing [*45283*[email protected]:3] Goto("SIP/283-00002d4b", "app-queue-toggle,s,start") in new stack

1087733[2021-10-19 17:03:03] VERBOSE[13583][C-00001cb1] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Getting Queue Status for user 283 in queue 369

1087734[2021-10-19 17:03:03] VERBOSE[13583][C-00001cb1] pbx.c: Executing [[email protected]:5] ExecIf("SIP/283-00002d4b", "1?AddQueueMember(369,Local/[email protected]/n,0,,"Stacey ",hint:[email protected])") in new stack

1087735[2021-10-19 17:03:03] WARNING[13583][C-00001cb1] app_queue.c: Unable to add interface 'Local/[email protected]/n' to queue '369': Already there

1087736[2021-10-19 17:03:03] VERBOSE[13583][C-00001cb1] pbx.c: Executing [[email protected]:6] ExecIf("SIP/283-00002d4b", "0?AddQueueMember(369,Local/[email protected]/n,0,,"Stacey ")") in new stack

1087737[2021-10-19 17:03:03] VERBOSE[13583][C-00001cb1] pbx.c: Executing [[email protected]:5] Set("SIP/283-00002d4b", "DEVICE_STATE(Custom:QUEUE283*369)=INUSE") in new stack

1087738[2021-10-19 17:03:19] VERBOSE[13690][C-00001cb2] pbx.c: Executing [*45283*[email protected]:1] Set("SIP/283-00002d4c", "QUEUENO=369") in new stack

1087739[2021-10-19 17:03:19] VERBOSE[13690][C-00001cb2] pbx.c: Executing [*45283*[email protected]:2] Set("SIP/283-00002d4c", "QUEUEUSER=283") in new stack

1087740[2021-10-19 17:03:19] VERBOSE[13690][C-00001cb2] pbx.c: Executing [*45283*[email protected]:3] Goto("SIP/283-00002d4c", "app-queue-toggle,s,start") in new stack

1087741[2021-10-19 17:03:19] VERBOSE[13690][C-00001cb2] pbx_builtins.c: Goto (app-queue-toggle,s,1)

1087742[2021-10-19 17:03:19] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:1] Answer("SIP/283-00002d4c", "") in new stack

1087743[2021-10-19 17:03:20] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:2] Wait("SIP/283-00002d4c", "1") in new stack

1087744[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:3] Macro("SIP/283-00002d4c", "user-callerid,") in new stack

1087745[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:1] Set("SIP/283-00002d4c", "TOUCH_MONITOR=1634677399.32603") in new stack

1087746[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:2] Set("SIP/283-00002d4c", "CHANCONTEXT=") in new stack

1087747[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:3] Set("SIP/283-00002d4c", "CHANCONTEXT=") in new stack

1087748[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:4] Set("SIP/283-00002d4c", "CHANEXTENCONTEXT=283-00002d4c") in new stack

1087749[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:5] Set("SIP/283-00002d4c", "CHANEXTEN=283-00002d4c") in new stack

1087750[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:6] Set("SIP/283-00002d4c", "CALLERID(number)=283") in new stack

1087751[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:7] Set("SIP/283-00002d4c", "AMPUSER=283") in new stack

1087752[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:8] Set("SIP/283-00002d4c", "HOTDESCKCHAN=283-00002d4c") in new stack

1087753[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:9] Set("SIP/283-00002d4c", "HOTDESKEXTEN=283") in new stack

1087754[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:10] Set("SIP/283-00002d4c", "HOTDESKCALL=0") in new stack

1087755[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:11] ExecIf("SIP/283-00002d4c", "0?Set(HOTDESKCALL=1)") in new stack

1087756[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:12] ExecIf("SIP/283-00002d4c", "0?Set(CALLERID(name)=)") in new stack

1087757[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:13] GotoIf("SIP/283-00002d4c", "0?report") in new stack

1087758[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:14] ExecIf("SIP/283-00002d4c", "1?Set(REALCALLERIDNUM=283)") in new stack

1087759[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:15] Set("SIP/283-00002d4c", "AMPUSER=283") in new stack

1087760[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:16] GotoIf("SIP/283-00002d4c", "0?limit") in new stack

1087761[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:17] Set("SIP/283-00002d4c", "AMPUSERCIDNAME=Stacey ") in new stack

1087762[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:18] ExecIf("SIP/283-00002d4c", "0?Set(__CIDMASQUERADING=TRUE)") in new stack

1087763[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:19] GotoIf("SIP/283-00002d4c", "0?report") in new stack

1087764[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:20] Set("SIP/283-00002d4c", "AMPUSERCID=283") in new stack

1087765[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:21] Set("SIP/283-00002d4c", "__DIAL_OPTIONS=HhTtr") in new stack

1087766[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:22] Set("SIP/283-00002d4c", "CALLERID(all)="Stacey " <283>") in new stack

1087767[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:23] ExecIf("SIP/283-00002d4c", "0?Set(CUSDIAL=)") in new stack

1087768[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:24] ExecIf("SIP/283-00002d4c", "0?Set(CALLERID(all)="Stacey " <283>)") in new stack

1087769[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:25] GotoIf("SIP/283-00002d4c", "0?limit") in new stack

1087770[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:26] ExecIf("SIP/283-00002d4c", "0?Set(GROUP(concurrency_limit)=283)") in new stack

1087771[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:27] ExecIf("SIP/283-00002d4c", "0?Set(CHANNEL(language)=)") in new stack

1087772[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:28] NoOp("SIP/283-00002d4c", "Macro Depth is 1") in new stack

1087773[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:29] GotoIf("SIP/283-00002d4c", "1?report2:macroerror") in new stack

1087774[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx_builtins.c: Goto (macro-user-callerid,s,30)

1087775[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:30] GotoIf("SIP/283-00002d4c", "0?continue") in new stack

1087776[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:31] ExecIf("SIP/283-00002d4c", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack

1087777[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:32] Set("SIP/283-00002d4c", "__TTL=64") in new stack

1087778[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:33] GotoIf("SIP/283-00002d4c", "1?continue") in new stack

1087779[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx_builtins.c: Goto (macro-user-callerid,s,49)

1087780[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:49] Set("SIP/283-00002d4c", "CALLERID(number)=283") in new stack

1087781[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:50] Set("SIP/283-00002d4c", "CALLERID(name)=Stacey ") in new stack

1087782[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:51] GotoIf("SIP/283-00002d4c", "0?cnum") in new stack

1087783[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:52] Set("SIP/283-00002d4c", "CDR(cnam)=Stacey ") in new stack

1087784[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:53] Set("SIP/283-00002d4c", "CDR(cnum)=283") in new stack

1087785[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:54] Set("SIP/283-00002d4c", "CHANNEL(language)=en") in new stack

1087786[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:4] Set("SIP/283-00002d4c", "QUEUEUSER=283") in new stack

1087787[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:5] Set("SIP/283-00002d4c", "QUEUESTAT=LOGGEDOUT") in new stack

1087788[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:6] AGI("SIP/283-00002d4c", "agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283)") in new stack

1087789[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Initiating queue: 2001

1087790[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://queue_devstate.agi%2Cgetqueues%2C283/): Queue 2001 has no one logged in

1087791[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Initiating queue: 2002

1087792[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Agent 200 is static

1087793[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate..agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Agent 200 is assigned to queue 2002

1087794[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://queue_devstate.agi%2Cgetqueues%2C283/): Finished processing members for 2002

1087795[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Initiating queue: 360

1087796[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Queue 360 has no one logged in

1087797[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0..0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Initiating queue: 1

1087798[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Agent 354 is dynamic

1087799[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Agent 354 is assigned to queue 1

1087800[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Agent 283 is dynamic

1087801[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Agent 283 is assigned to queue 1

1087802[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Agent 239 is dynamic

1087803[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Agent 239 is assigned to queue 1

1087804[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Finished processing members for 1

1087805[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Finished parsing queues

1087806[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Getting Queue Status for user 283 in queue 369

1087807[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: agi://[127.0.0.1/queue_devstate.agi,getqueues,283](http://127.0.0.1/queue_devstate.agi,getqueues,283): Agent 283 is LOGGEDOUT

1087808[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] res_agi.c: <SIP/283-00002d4c>AGI Script agi://[127.0.0.1/queue_devstate.agi](http://127.0.0.1/queue_devstate.agi) completed, returning 0

1087809[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:7] GotoIf("SIP/283-00002d4c", "1?activate") in new stack

1087810[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx_builtins.c: Goto (app-queue-toggle,s,16)

1087811[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:16] NoOp("SIP/283-00002d4c", "Agent Logged In") in new stack

1087812[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:17] Macro("SIP/283-00002d4c", "toggle-add-agent,") in new stack

1087813[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:1] Macro("SIP/283-00002d4c", "user-callerid,SKIPTTL,") in new stack

1087814[2021-10-19 17:03:21] VERBOSE[13690][C-00001cb2] pbx.c: Executing [[email protected]:1] Set("SIP/283-00002d4c", "TOUCH_MONITOR=1634677399.32603") in new stack

1087815[2021-10-19 17:03:21] VERBOSE[13690

I attempted to log ext 354 out of queue 369 twice here.

> freepbx*CLI> agi set debug on
> AGI Debugging Enabled
> [2021-11-03 17:01:08] WARNING[22667][C-00004fbe]: chan_sip.c:7619 sip_write: Can't send 10 type frames with SIP write
> AGI Tx >> agi_network: yes
> AGI Tx >> agi_network_script: queue_devstate.agi
> <SIP/205-000092ce>AGI Tx >> agi_request: agi://[127.0.0.1/queue_devstate.agi](http://127.0.0.1/queue_devstate.agi)
> <SIP/205-000092ce>AGI Tx >> agi_channel: SIP/205-000092ce
> <SIP/205-000092ce>AGI Tx >> agi_language: en
> <SIP/205-000092ce>AGI Tx >> agi_type: SIP
> <SIP/205-000092ce>AGI Tx >> agi_uniqueid: 1635973270.125835
> <SIP/205-000092ce>AGI Tx >> agi_version: 16.20.0
> <SIP/205-000092ce>AGI Tx >> agi_callerid: 205
> <SIP/205-000092ce>AGI Tx >> agi_calleridname: Greg Test
> <SIP/205-000092ce>AGI Tx >> agi_callingpres: 0
> <SIP/205-000092ce>AGI Tx >> agi_callingani2: 0
> <SIP/205-000092ce>AGI Tx >> agi_callington: 0
> <SIP/205-000092ce>AGI Tx >> agi_callingtns: 0
> <SIP/205-000092ce>AGI Tx >> agi_dnid: *45354*369
> <SIP/205-000092ce>AGI Tx >> agi_rdnis: unknown
> <SIP/205-000092ce>AGI Tx >> agi_context: app-queue-toggle
> <SIP/205-000092ce>AGI Tx >> agi_extension: s
> <SIP/205-000092ce>AGI Tx >> agi_priority: 6
> <SIP/205-000092ce>AGI Tx >> agi_enhanced: 0.0
> <SIP/205-000092ce>AGI Tx >> agi_accountcode:
> <SIP/205-000092ce>AGI Tx >> agi_threadid: 140349828605696
> <SIP/205-000092ce>AGI Tx >> agi_arg_1: getqueues
> <SIP/205-000092ce>AGI Tx >> agi_arg_2: 354
> <SIP/205-000092ce>AGI Tx >>
> <SIP/205-000092ce>AGI Rx << VERBOSE "Initiating queue: 2001" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Queue 2001 has no one logged in" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Initiating queue: 2002" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 200 is static" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 200 is assigned to queue 2002" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Finished processing members for 2002" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Initiating queue: 360" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Queue 360 has no one logged in" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Initiating queue: 1" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 354 is dynamic" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 354 is assigned to queue 1" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 444 is dynamic" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 444 is assigned to queue 1" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 283 is dynamic" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 283 is assigned to queue 1" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 367 is dynamic" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 367 is assigned to queue 1" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Finished processing members for 1" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Finished parsing queues" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << GET VARIABLE QUEUENO
> <SIP/205-000092ce>AGI Tx >> 200 result=1 (369)
> <SIP/205-000092ce>AGI Rx << VERBOSE "Getting Queue Status for user 354 in queue 369" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << VERBOSE "Agent 354 is LOGGEDOUT" 3
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Rx << SET VARIABLE QUEUESTAT "LOGGEDOUT"
> <SIP/205-000092ce>AGI Tx >> 200 result=1
> <SIP/205-000092ce>AGI Tx >> HANGUP
> [2021-11-03 17:01:11] WARNING[22951][C-00004fc0]: app_queue.c:8208 aqm_exec: Unable to add interface 'Local/[email protected]/n' to queue '369': Already there
> [2021-11-03 17:01:11] WARNING[22667][C-00004fbe]: chan_sip.c:7619 sip_write: Can't send 10 type frames with SIP write
> [2021-11-03 17:01:14] WARNING[22667][C-00004fbe]: chan_sip.c:7619 sip_write: Can't send 10 type frames with SIP write
> [2021-11-03 17:01:18] WARNING[22667][C-00004fbe]: chan_sip.c:7619 sip_write: Can't send 10 type frames with SIP write
> [2021-11-03 17:01:19] WARNING[22667][C-00004fbe]: chan_sip.c:7619 sip_write: Can't send 10 type frames with SIP write
> AGI Tx >> agi_network: yes
> AGI Tx >> agi_network_script: queue_devstate.agi
> <SIP/205-000092cf>AGI Tx >> agi_request: agi://[127.0.0.1/queue_devstate.agi](http://127.0.0.1/queue_devstate.agi)
> <SIP/205-000092cf>AGI Tx >> agi_channel: SIP/205-000092cf
> <SIP/205-000092cf>AGI Tx >> agi_language: en
> <SIP/205-000092cf>AGI Tx >> agi_type: SIP
> <SIP/205-000092cf>AGI Tx >> agi_uniqueid: 1635973278.125836
> <SIP/205-000092cf>AGI Tx >> agi_version: 16.20.0
> <SIP/205-000092cf>AGI Tx >> agi_callerid: 205
> <SIP/205-000092cf>AGI Tx >> agi_calleridname: Greg Test
> <SIP/205-000092cf>AGI Tx >> agi_callingpres: 0
> <SIP/205-000092cf>AGI Tx >> agi_callingani2: 0
> <SIP/205-000092cf>AGI Tx >> agi_callington: 0
> <SIP/205-000092cf>AGI Tx >> agi_callingtns: 0
> <SIP/205-000092cf>AGI Tx >> agi_dnid: *45354*369
> <SIP/205-000092cf>AGI Tx >> agi_rdnis: unknown
> <SIP/205-000092cf>AGI Tx >> agi_context: app-queue-toggle
> <SIP/205-000092cf>AGI Tx >> agi_extension: s
> <SIP/205-000092cf>AGI Tx >> agi_priority: 6
> <SIP/205-000092cf>AGI Tx >> agi_enhanced: 0.0
> <SIP/205-000092cf>AGI Tx >> agi_accountcode:
> <SIP/205-000092cf>AGI Tx >> agi_threadid: 140349838333696
> <SIP/205-000092cf>AGI Tx >> agi_arg_1: getqueues
> <SIP/205-000092cf>AGI Tx >> agi_arg_2: 354
> <SIP/205-000092cf>AGI Tx >>
> [2021-11-03 17:01:19] WARNING[22667][C-00004fbe]: chan_sip.c:7619 sip_write: Can't send 10 type frames with SIP write
> <SIP/205-000092cf>AGI Rx << VERBOSE "Initiating queue: 2001" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Queue 2001 has no one logged in" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Initiating queue: 2002" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 200 is static" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 200 is assigned to queue 2002" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Finished processing members for 2002" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Initiating queue: 360" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Queue 360 has no one logged in" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Initiating queue: 1" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 354 is dynamic" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 354 is assigned to queue 1" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 444 is dynamic" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 444 is assigned to queue 1" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 283 is dynamic" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 283 is assigned to queue 1" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 367 is dynamic" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 367 is assigned to queue 1" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Finished processing members for 1" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Finished parsing queues" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << GET VARIABLE QUEUENO
> <SIP/205-000092cf>AGI Tx >> 200 result=1 (369)
> <SIP/205-000092cf>AGI Rx << VERBOSE "Getting Queue Status for user 354 in queue 369" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << VERBOSE "Agent 354 is LOGGEDOUT" 3
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Rx << SET VARIABLE QUEUESTAT "LOGGEDOUT"
> <SIP/205-000092cf>AGI Tx >> 200 result=1
> <SIP/205-000092cf>AGI Tx >> HANGUP

Any help would be much appreciated!
Thanks

Has anyone seen this before?