Checkdir opens multiple popups for the same event

[sln123]

Oh and FOP2 version 2.31.22, Asterisk version 11.22.0, FreePBX version 2.11.0.43, CentOS 6.8.

We use Ring Group rather than Queue because of a known bug in this Asterisk version queue handling that caused Asterisk to crash, so at some point we plan to update Asterisk and use Queues again.

[sln123]

No takers, so does anyone have any tips where to look in logs or Asterisk to debug this myself?

[nicolas]

Hi,

A little bit late to the party... Maybe you can make some tests to see if we can determine what is triggering the bug.. and for that I have also a questions:
Do you get a double popup on a freshly restarted FOP2 server?
What happens if you reload FOP2 with the command:

#> service fop2 reload

Do you get a doulbe popup after a reload?
Finally, one way to troubleshot directly on the browser would be to enable consoleDebug in FOP2 Manager Settings, then opening the javascript/developer console on the browser experiencing the issue, and looking at the event log to see if you get multiple notifyringing events.

[sln123]

Sorry, I stopped monitoring this thread. I don't know the answer. I just did a service fop2 reload and will let you know what I see tomorrow. I also just enabled consoleDebug and I'll get our user to look at his event log tomorrow if we get any duplicates.

We have been continuing to see 6-8 duplicates per day, all for this one user. We were getting some duplicates for other users earlier, but those may be due to multiple logins to the same extension, which are minimal now.

[sln123]

No duplicate popups today. I'll check again tomorrow.

[sln123]

Duplicate popup this morning at 7:54 AM our local time and my user provided the console output from his browser. It is long and I'm not sure what to trim since I don't see any timestamps, so I am attaching rather than inline. There may be output for some time before the duplicate.

Also, I did a reload per your earlier request, so still seeing duplicates after a reload. But time has gone by, so not sure if this met your intent.

Phil

[sln123]

I have a second consoleDebug capture for an additional duplicate. It contains slightly fewer lines than the first one. Let me know if it is useful to post it as well.

[sln123]

I worked my way through the second consoleDebug log. I don't see duplicate RINGING events, but I do see the first reference to the callerid that showed the duplicate, then working through queue members with last queue member, then two link commands for that number, then the call answered. Showing only the last queue sequence of msgs, here are the few lines that show the repeated link commands:

26,queuemember=WyB7ICJwYXVzZWQiOiAiMCIsICJzdGF0ZSI6ICJmcmVlIiwgImxvYyI6ICJMb2NhbC8yMjZAZnJvbS1xdWV1ZS9uIiwgInF1ZXVlIjogIlFVRVVFLzIwM15MT0NBTEhPU1QiLCAicHR5IjogIjAiLCAibmFtZSI6InRlc3QiLCAiY2FsbHN0YWtlbiI6ICIwIiwgImxhc3RjYWxsIjogIjAiLCAiY3VycmVudGNhbGwiOiAiMCIsICJmcm9tcXVldWUiOiAiIiwgImNsaWRudW0iOiAiIiwgICJjbGlkbmFtZSI6ICIiLCAicmVhc29uIjogIiIsICJub3ciOiAiMTU3Mzg0NjkxOCIgfSBd en slot jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,settimer=0@UP en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,state=UP en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,settext=&inuse!1 en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,details=WyB7ICJkaXJlY3Rpb24iOiAiaW5ib3VuZCIsICJjaGFubmVsIjogIlNJUC92b2lwbXMtMDAwMDEzMDAiLCAiY2FsbGVyaWQiOiAiNDA4NjIwMzM2MyIgfSBd en slot 2 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
18,settext=4086203363  en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
18,direction=inbound en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
18,link=4086203363  en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
Ejecutando Callback Comando link jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
received link command from server with 4086203363 , button number18 in slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,setvar=VU5JUVVFSUQ9MTU3Mzg0NjkxNS41NjEw en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,state=UP en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,settext=&inuse!1 en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,details=WyB7ICJkaXJlY3Rpb24iOiAiaW5ib3VuZCIsICJjaGFubmVsIjogIlNJUC92b2lwbXMtMDAwMDEzMDAiLCAiY2FsbGVyaWQiOiAiNDA4NjIwMzM2MyIgfSBd en slot 2 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
18,settext=4086203363  en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
18,direction=inbound en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
18,link=4086203363  en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
Ejecutando Callback Comando link jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
received link command from server with 4086203363 , button number18 in slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,setvar=VU5JUVVFSUQ9MTU3Mzg0NjkxNS41NjEw en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,notifyconnect=SIP/271 en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769

[sln123]

Still receiving duplicate popups. Here is another instance of the log, this time with 3 popups shown. This appears consistent to me with the 3 lines that say "received link command from server ...". So I'm guessing that the link command is telling us something:

en slot 3 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
11,settext=34943784075 Finance en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
11,direction=inbound en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
11,link=34943784075 Finance en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
Ejecutando Callback Comando link jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
received link command from server with 34943784075 Finance, button number11 in slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,setvar=VU5JUVVFSUQ9MTU3NTM5ODUzMi44NDc3 en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,notifyconnect=SIP/242 en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
11,settext=34943784075 Finance en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
11,direction=inbound en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
11,link=34943784075 Finance en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
Ejecutando Callback Comando link jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
received link command from server with 34943784075 Finance, button number11 in slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,setvar=VU5JUVVFSUQ9MTU3NTM5ODUzMi44NDc3 en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,notifyconnect=SIP/242 en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
11,settext=34943784075 Finance en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
11,direction=inbound en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
11,link=34943784075 Finance en slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
Ejecutando Callback Comando link jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
received link command from server with 34943784075 Finance, button number11 in slot 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,setvar=VU5JUVVFSUQ9MTU3NTM5ODUzMi44NDc3 en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
29,notifyconnect=SIP/242 en slot 0 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
ws send <msg data="1|ping||" /> jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
envio ping 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
ws send <msg data="1|ping||" /> jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
envio ping 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
ws send <msg data="1|ping||" /> jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
envio ping 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
ws send <msg data="1|ping||" /> jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769
envio ping 1 jquery-1.11.3.min.js line 2 > eval line 1 > eval:1:6769

[sln123]

Still consistently seeing duplicates. Any comments on the consoleDebug output?