OpenNethome - an open home automation project

Posted: **Mon Mar 22, 2021 6:42 am**

I am having warnings in the log: "Event queue full" every minute.
Hue Bridge seems to be causing them. What could this be? It seems to be interfering with the functions of NetHome as well. Not everything is working as it should (some signals aren't sent etc).
I only have the Hue Bridge and a TellStick Duo.

Code: Select all

21.03.22 08:33:01 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 497.37 ms
21.03.22 08:32:01 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 502.24 ms
21.03.22 08:31:01 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 500.18 ms
21.03.22 08:30:01 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 564.15 ms
21.03.22 08:30:01 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 548.93 ms
21.03.22 08:29:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 499.65 ms
21.03.22 08:28:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 498.76 ms
21.03.22 08:27:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 506.37 ms
21.03.22 08:26:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 500.18 ms
21.03.22 08:25:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 500.06 ms
21.03.22 08:24:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 499.46 ms
21.03.22 08:24:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 484.91 ms
21.03.22 08:23:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 499.58 ms
21.03.22 08:22:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 498.30 ms
21.03.22 08:21:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 503.37 ms
21.03.22 08:20:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 496.08 ms
21.03.22 08:19:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 497.72 ms
21.03.22 08:18:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 550.45 ms
21.03.22 08:17:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 496.61 ms
21.03.22 08:16:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 499.77 ms
21.03.22 08:15:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 532.68 ms
21.03.22 08:14:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 500.30 ms
21.03.22 08:13:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 497.77 ms
21.03.22 08:12:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 508.36 ms
21.03.22 08:11:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 495.87 ms
21.03.22 08:10:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 498.06 ms
21.03.22 08:09:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 499.09 ms
21.03.22 08:08:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 496.64 ms
21.03.22 08:07:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 502.69 ms
21.03.22 08:06:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 498.63 ms
21.03.22 08:05:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 495.88 ms
21.03.22 08:04:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 498.51 ms
21.03.22 08:03:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 498.24 ms
21.03.22 08:02:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 525.17 ms
21.03.22 08:01:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 538.63 ms
21.03.22 08:00:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 578.74 ms
21.03.22 08:00:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 555.27 ms
21.03.22 07:59:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 506.75 ms
21.03.22 07:58:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 497.91 ms
21.03.22 07:57:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 497.73 ms
21.03.22 07:56:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 513.56 ms
21.03.22 07:55:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 496.43 ms
21.03.22 07:54:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 498.01 ms
21.03.22 07:53:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 497.99 ms
21.03.22 07:52:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 496.97 ms
21.03.22 07:51:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 549.07 ms
21.03.22 07:50:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 498.11 ms
21.03.22 07:49:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 496.13 ms
21.03.22 07:49:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 481.62 ms
21.03.22 07:48:00 	HomeServer 	Event queue full. Current Item processing is "Hue Bridge" since 495.94 ms

EDIT: Also, how do I get rid of this annoying problem on this forum? There's a cookie policy that I have to click on the front page, but clicking it does nothing, the policy popup doesn't go away and it doesn't want me to proceed without clicking it. I have to open every link in a new tab in order to browse the forum.

Posted: **Mon Mar 22, 2021 7:35 am**

Quite understandably, the error messages cease to come if I cut the power to the Hue Bridge.

Here's a log of about 10 minutes beginning with NetHome restart:

Code: Select all

2021.03.22 09:51:19.383 INFO:**Starting HomeManager 3.0-c5e43** (HomeManagerStarter 0, TID1) 
2021.03.22 09:51:19.509 INFO:Logging to: /var/log/nethome/HomeManager%g.log (HomeManagerStarter 1, TID1) 
2021.03.22 09:51:20.327 WARNING:Prefs file removed in background /home/nethome/.java/.userPrefs/nu/nethome/home/impl/prefs.xml (FileSystemPreferences$6 2, TID1) 
2021.03.22 09:51:20.332 INFO:Loading Items from /etc/opt/nethome/config.xml (HomeItemFileLoader 3, TID1) 
2021.03.22 09:53:00.603 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 548.67 ms (HomeServer 4, TID41) 
2021.03.22 09:53:17.150 INFO:Started the H2 database server at: tcp://127.0.1.1:9092 (H2DatabaseTCPServer 5, TID1) 
2021.03.22 09:53:17.252 INFO:Started the H2 web server at: https://192.168.1.120:8082, Web Console server running at http://127.0.1.1:8082 (others can connect) (H2DatabaseTCPServer 6, TID1) 
2021.03.22 09:53:19.997 INFO:Activated 116 of 116 Items (HomeServer 7, TID1) 
2021.03.22 09:54:00.528 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 510.58 ms (HomeServer 8, TID41) 
2021.03.22 09:54:00.549 SEVERE:Event queue full. Current Item processing is "rH Trigger PH"  since 1.47 ms (HomeServer 9, TID41) 
2021.03.22 09:55:00.29 WARNING:error,7,No attribute value for:  (Scene 10, TID41) 
2021.03.22 09:55:00.34 WARNING:error,7,No attribute value for:  (ValueTrigger 11, TID41) 
2021.03.22 09:55:00.55 WARNING:error,7,No attribute value for:  (ValueTrigger 12, TID41) 
2021.03.22 09:55:00.573 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 510.76 ms (HomeServer 13, TID41) 
2021.03.22 09:55:00.589 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 527.27 ms (HomeServer 14, TID41) 
2021.03.22 09:55:00.608 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 545.82 ms (HomeServer 15, TID41) 
2021.03.22 09:55:00.623 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 561.28 ms (HomeServer 16, TID41) 
2021.03.22 09:55:00.628 SEVERE:Event queue full. Current Item processing is "PumpHeaterTrigger"  since 1.13 ms (HomeServer 17, TID41) 
2021.03.22 09:56:00.519 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 508.97 ms (HomeServer 18, TID41) 
2021.03.22 09:57:00.545 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 534.79 ms (HomeServer 19, TID41) 
2021.03.22 09:58:00.520 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 510.41 ms (HomeServer 20, TID41) 
2021.03.22 09:59:00.537 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 526.57 ms (HomeServer 21, TID41) 
2021.03.22 10:00:00.4 INFO:Was missing home item id, now set to: 275 (LoggerComponent 30, TID18) 
2021.03.22 10:00:00.6 INFO:Was missing home item id, now set to: 487 (LoggerComponent 40, TID35) 
2021.03.22 10:00:00.6 INFO:Was missing home item id, now set to: 594 (LoggerComponent 38, TID24) 
2021.03.22 10:00:00.5 INFO:Was missing home item id, now set to: 475 (LoggerComponent 37, TID23) 
2021.03.22 10:00:00.5 INFO:Was missing home item id, now set to: 453 (LoggerComponent 36, TID30) 
2021.03.22 10:00:00.5 INFO:Was missing home item id, now set to: 505 (LoggerComponent 35, TID19) 
2021.03.22 10:00:00.4 INFO:Was missing home item id, now set to: 272 (LoggerComponent 32, TID27) 
2021.03.22 10:00:00.4 INFO:Was missing home item id, now set to: 410 (LoggerComponent 33, TID22) 
2021.03.22 10:00:00.4 INFO:Was missing home item id, now set to: 665 (LoggerComponent 34, TID25) 
2021.03.22 10:00:00.1 INFO:Was missing home item id, now set to: 502 (LoggerComponent 22, TID20) 
2021.03.22 10:00:00.4 INFO:Was missing home item id, now set to: 501 (LoggerComponent 31, TID29) 
2021.03.22 10:00:00.4 INFO:Was missing home item id, now set to: 666 (LoggerComponent 29, TID31) 
2021.03.22 10:00:00.3 INFO:Was missing home item id, now set to: 593 (LoggerComponent 27, TID37) 
2021.03.22 10:00:00.3 INFO:Was missing home item id, now set to: 64 (LoggerComponent 28, TID36) 
2021.03.22 10:00:00.3 INFO:Was missing home item id, now set to: 301 (LoggerComponent 26, TID26) 
2021.03.22 10:00:00.2 INFO:Was missing home item id, now set to: 409 (LoggerComponent 25, TID32) 
2021.03.22 10:00:00.2 INFO:Was missing home item id, now set to: 62 (LoggerComponent 24, TID33) 
2021.03.22 10:00:00.1 INFO:Was missing home item id, now set to: 454 (LoggerComponent 23, TID21) 
2021.03.22 10:00:00.29 INFO:Enabled global logging of type: LoggerComponentH2Database with descriptor: jdbc:h2:tcp:localhost/nhs_values;USER=sa;PASSWORD=thx1138 (ValueItemLoggerFactory 43, TID18) 
2021.03.22 10:00:00.7 INFO:Was missing home item id, now set to: 474 (LoggerComponent 42, TID34) 
2021.03.22 10:00:00.6 INFO:Was missing home item id, now set to: 504 (LoggerComponent 39, TID28) 
2021.03.22 10:00:00.7 INFO:Was missing home item id, now set to: 302 (LoggerComponent 41, TID17) 
2021.03.22 10:00:00.624 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 601.03 ms (HomeServer 44, TID41) 
2021.03.22 10:00:02.546 INFO:Enabled global logging of type: LoggerComponentFileBased with descriptor: /var/log/nethome/t_out.log (ValueItemLoggerFactory 45, TID33) 
2021.03.22 10:01:00.521 SEVERE:Event queue full. Current Item processing is "Hue Bridge"  since 506.72 ms (HomeServer 46, TID41)

Posted: **Mon Mar 22, 2021 10:16 am**

Have removed and re-added the Hue Bridge in NetHome. No help.
Have cleared stuff from the Hue Bridge in the Hue app, as well as used the "Clean" function in Hue Essentials. Then I have painully set up all dimmer switches and motion sensors again. No help.
Have used the "Clean-Up" function in the Hue app, and then AGAIN set up all dimmer switches and motion sensors. No help.
Have removed all unneeded app permissions via the Hue website (there were several instances of OpenNetHomeServer. Left only one of them, as well as the Hue iPad app and my Logitech Harmony). No help.

Only thing left would be a complete factory reset of the Hue Bridge, but that is NOT an option. Too many scenes, lights and stuff would have to be reinstalled and that is not something I'm willing to do. Especielly not when I'm beginning to be fairly certain it won't help.

There must be something wrong with how NH handles the messages from the Hue Bridge.
For instance, does the Hue Bridge rebroadcast info on all its lights and accessories? I have quite a few Hue lights and accessories, so it could be a big chunk of data in that package.

The Hue Bridge SW is 1943082030

Posted: **Wed Mar 24, 2021 9:59 am**

Something to do with the latest bridge FW? How does one roll back?

https://www.reddit.com/r/Hue/comments/d ... y_network/

EDIT: The Bridge seems to have updated itself. It is now 1943185030. No help though.

Posted: **Wed Mar 24, 2021 6:51 pm**

Ended up severing the connection between the Hue Bridge and NetHome altogether. No more Event Queue warnings.
They'll just have to live separate lives from now on. The Hue Bridge does most of the stuff anyway, so.

Posted: **Sat Mar 27, 2021 3:45 pm**

Ok, I have not seen this problem on my installation, but I have very few HUE lamps. I have made two changes now: First I have fixed a bug so that the HUE bridge does not update every minute. Now you can set the number of minutes between update in the Hue bridge Item. Second change is that the HUE bridge does the updates on a separate execution thread so it should not disturb the minute job as much. The fix is deployed in the nightly build.

OpenNethome - an open home automation project

"Event queue full"

"Event queue full"

Re: "Event queue full"

Re: "Event queue full"

Re: "Event queue full"

Re: "Event queue full"

Re: "Event queue full"