diyhue / diyHue

Main diyHue software repo
https://diyhue.org/
Other
1.56k stars 278 forks source link

Latest build goes into infinite loop with Philips TV (Seems like Entertainment API is finally kicking in). Hue Stream works fine. #318

Closed rkkoszewski closed 4 years ago

rkkoszewski commented 4 years ago

So far I was running previous builds from diyHue, and I previously opened a bug because my Philips TV would not recognize diyHue as Entertainment API capable, and would default to HTTP/PUT light updates which are very laggy.

But with the latest build something seems to have changed. Now when I enable Ambilight+Hue on my TV and I setup the lights, it seems that it tries to start the "Entertainment API", but something is failing, and DiyHue goes into an infinite loop of some kind. Turning off the TV doesn't helps, and DiyHue keeps spawning more and more Python threads till the whole server slows down to a crawl. An interesting line in the log is "killall: entertain-srv: no process killed" that is shown right before the infinite loop.

Using the mobile app "Hue Stream" works perfectly. So it must be some incompatibility with what the Philips TV is sending that causes the infinite loop. It seems like it cannot stop sending PUT requests to 127.0.0.1. I have some debug logs that could shed some more light.

Here are some logs:

To put into context what is happening here: I previously paired my Philips TV with DiyHue and added the emulated bulb [ESP] to DiyHue and is setup on the TV correctly. So what happens during the log is that I start DiyHue, and then I start my TV. After some seconds the TV starts sending the screen colors and the whole thing explodes (The ESP emulated light completely freezes during the infinite loop).

root@huebridge:/opt/hue-emulator# python3 HueEmulator3.py 
{"ok"}
192.168.250.252 - - [31/Dec/2019 02:50:29] "GET /description.xml HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 02:50:58] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:58] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:50:59] "PUT /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
127.0.0.1 - - [31/Dec/2019 02:51:00] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:00] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:00] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:00] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:01] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:01] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:01] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:02] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:02] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:02] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:03] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:03] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:03] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:03] "PUT /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
127.0.0.1 - - [31/Dec/2019 02:51:03] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:03] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:04] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:04] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:04] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:04] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:04] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:04] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:04] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:05] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:05] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:05] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:05] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:05] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:05] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:05] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:06] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:06] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:06] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:06] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:06] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:06] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:07] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:07] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:07] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:07] "PUT /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
127.0.0.1 - - [31/Dec/2019 02:51:07] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:07] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:07] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:07] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:07] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:08] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:08] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:08] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:08] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:08] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:08] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:08] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:08] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:08] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:08] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:09] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:09] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:09] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:09] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:09] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:09] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:09] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:09] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:09] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:09] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:10] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:10] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:10] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:10] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:10] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:10] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:10] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:10] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:10] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:11] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:11] "PUT /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:11] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:11] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:12] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:14] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:14] "PUT /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
127.0.0.1 - - [31/Dec/2019 02:51:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 02:51:15] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:15] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:51:16] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -

Here is the same as before but running with --debug:

2019-12-31 03:05:24,004 - root - INFO - Using Host 192.168.250.230:80
2019-12-31 03:05:24,020 - root - INFO - d6bfcb9e0f40
2019-12-31 03:05:24,020 - root - INFO - IP range for light discovery: 0-255
2019-12-31 03:05:24,021 - root - INFO - 127.0.0.1
2019-12-31 03:05:24,021 - root - INFO - Online Discovery/Remote API Enabled!
2019-12-31 03:05:24,023 - root - INFO - Config loaded
2019-12-31 03:05:24,026 - root - DEBUG - start ssdp broadcast
2019-12-31 03:05:24,028 - root - DEBUG - starting ssdp...
2019-12-31 03:05:24,029 - root - INFO - sync with lights
2019-12-31 03:05:24,040 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:24,043 - root - INFO - Starting httpd...
2019-12-31 03:05:24,048 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): discovery.diyhue.org:443
2019-12-31 03:05:24,051 - root - INFO - Starting ssl httpd...
2019-12-31 03:05:24,154 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "GET /state HTTP/1.1" 200 91
2019-12-31 03:05:24,368 - urllib3.connectionpool - DEBUG - https://discovery.diyhue.org:443 "POST / HTTP/1.1" 200 6
{"ok"}
2019-12-31 03:05:46,963 - root - DEBUG - Sending M-Search response to 192.168.250.252
2019-12-31 03:05:48,766 - root - DEBUG - Sending M-Search response to 192.168.250.252
2019-12-31 03:05:50,670 - root - DEBUG - Sending M-Search response to 192.168.250.252
2019-12-31 03:05:52,274 - root - DEBUG - Sending M-Search response to 192.168.250.252
2019-12-31 03:05:53,377 - root - DEBUG - Sending M-Search response to 192.168.250.252
2019-12-31 03:05:53,843 - root - INFO - in PUT method
2019-12-31 03:05:53,843 - root - INFO - /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/groups/2
2019-12-31 03:05:53,844 - root - INFO - b'{"stream":{"active":false}}'
2019-12-31 03:05:53,845 - root - INFO - stop hue entertainent
killall: entertain-srv: no process killed
2019-12-31 03:05:54,154 - root - INFO - [
    {
        "success": {
            "/groups/2/stream": {
                "active": false
            }
        }
    }
]
2019-12-31 03:05:54,155 - root - INFO - rule 1 is triggered
2019-12-31 03:05:54,164 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:54,168 - root - INFO - in PUT method
2019-12-31 03:05:54,169 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:54,170 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:54,173 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:54,206 - root - INFO - sync with lights
2019-12-31 03:05:54,215 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:54,331 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:54,343 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "GET /state HTTP/1.1" 200 91
2019-12-31 03:05:54,411 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): discovery.diyhue.org:443
2019-12-31 03:05:54,476 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:54,476 - root - INFO - rule 1 is triggered
2019-12-31 03:05:54,479 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:54,481 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:54,488 - root - INFO - in PUT method
2019-12-31 03:05:54,489 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:54,490 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:54,494 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:54,536 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:54,675 - urllib3.connectionpool - DEBUG - https://discovery.diyhue.org:443 "POST / HTTP/1.1" 200 6
2019-12-31 03:05:54,685 - root - DEBUG - Sending M-Search response to 192.168.250.252
{"ok"}
2019-12-31 03:05:54,797 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:54,798 - root - INFO - rule 1 is triggered
2019-12-31 03:05:54,801 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:54,808 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:54,813 - root - INFO - in PUT method
2019-12-31 03:05:54,814 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:54,814 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:54,818 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:54,945 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:55,123 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:55,126 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:55,127 - root - INFO - rule 1 is triggered
2019-12-31 03:05:55,137 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:55,142 - root - INFO - in PUT method
2019-12-31 03:05:55,143 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:55,143 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:55,147 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:55,250 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:55,449 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:55,449 - root - INFO - rule 1 is triggered
2019-12-31 03:05:55,453 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:55,455 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:55,460 - root - INFO - in PUT method
2019-12-31 03:05:55,461 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:55,461 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:55,465 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:55,479 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:55,769 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:55,772 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:55,774 - root - INFO - rule 1 is triggered
2019-12-31 03:05:55,779 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:55,783 - root - INFO - in PUT method
2019-12-31 03:05:55,784 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:55,785 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:55,790 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:55,809 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:56,093 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:56,094 - root - INFO - rule 1 is triggered
2019-12-31 03:05:56,102 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:56,103 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:56,108 - root - INFO - in PUT method
2019-12-31 03:05:56,109 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:56,109 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:56,113 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:56,129 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:56,416 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:56,417 - root - INFO - rule 1 is triggered
2019-12-31 03:05:56,420 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:56,427 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:56,432 - root - INFO - in PUT method
2019-12-31 03:05:56,433 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:56,434 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:56,438 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:56,454 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:56,742 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:56,745 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:56,746 - root - INFO - rule 1 is triggered
2019-12-31 03:05:56,754 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:56,757 - root - INFO - in PUT method
2019-12-31 03:05:56,758 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:56,758 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:56,762 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:56,778 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:57,064 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:57,065 - root - INFO - rule 1 is triggered
2019-12-31 03:05:57,070 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:57,072 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:57,077 - root - INFO - in PUT method
2019-12-31 03:05:57,078 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:57,078 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:57,082 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:57,099 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:57,385 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:57,386 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:57,387 - root - INFO - rule 1 is triggered
2019-12-31 03:05:57,396 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:57,400 - root - INFO - in PUT method
2019-12-31 03:05:57,400 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:57,401 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:57,404 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:57,419 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:57,598 - root - INFO - in PUT method
2019-12-31 03:05:57,599 - root - INFO - /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/groups/2
2019-12-31 03:05:57,599 - root - INFO - b'{"stream":{"active":false}}'
2019-12-31 03:05:57,600 - root - INFO - stop hue entertainent
killall: entertain-srv: no process killed
2019-12-31 03:05:57,707 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:57,709 - root - INFO - rule 1 is triggered
2019-12-31 03:05:57,718 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:57,721 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:57,728 - root - INFO - in PUT method
2019-12-31 03:05:57,728 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:57,729 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:57,734 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:57,750 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:57,909 - root - INFO - [
    {
        "success": {
            "/groups/2/stream": {
                "active": false
            }
        }
    }
]
2019-12-31 03:05:57,909 - root - INFO - rule 1 is triggered
2019-12-31 03:05:57,914 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:57,917 - root - INFO - in PUT method
2019-12-31 03:05:57,919 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:57,919 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:57,924 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:57,938 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:58,038 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:58,042 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:58,044 - root - INFO - rule 1 is triggered
2019-12-31 03:05:58,049 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:58,053 - root - INFO - in PUT method
2019-12-31 03:05:58,053 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:58,053 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:58,058 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:58,074 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:58,229 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:58,232 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:58,233 - root - INFO - rule 1 is triggered
2019-12-31 03:05:58,238 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:58,244 - root - INFO - in PUT method
2019-12-31 03:05:58,245 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:58,245 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:58,249 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:58,264 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:58,362 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:58,366 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:58,367 - root - INFO - rule 1 is triggered
2019-12-31 03:05:58,374 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:58,378 - root - INFO - in PUT method
2019-12-31 03:05:58,379 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:58,383 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:58,389 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:58,405 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:58,552 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:58,553 - root - INFO - rule 1 is triggered
2019-12-31 03:05:58,558 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:58,560 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:58,565 - root - INFO - in PUT method
2019-12-31 03:05:58,565 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:58,566 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:58,570 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:58,587 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:58,692 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:58,693 - root - INFO - rule 1 is triggered
2019-12-31 03:05:58,701 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:58,703 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:58,707 - root - INFO - in PUT method
2019-12-31 03:05:58,708 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:58,708 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:58,711 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:58,734 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:58,874 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:05:58,875 - root - INFO - rule 1 is triggered
2019-12-31 03:05:58,877 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:05:58,885 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:05:58,890 - root - INFO - in PUT method
2019-12-31 03:05:58,891 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:05:58,892 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:05:58,895 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:05:58,913 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:05:59,013 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
...
and it keeps going on and on like that..
After some time it start showing some timeout messages.

What I find suspicious here is that the TV starts by sending "Group Stream active: FALSE" while the Hue Stream app starts with "Group Stream active: TRUE"..

rkkoszewski commented 4 years ago

Here is in contrast how it looks when I use the "Hue Stream" app for mobile.

192.168.250.210 - - [31/Dec/2019 02:55:12] "PUT /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/2 HTTP/1.1" 200 -

  . Seeding the random number generator... ok
  . Loading the CA root certificate ... ok (0 skipped)
  . Loading the server cert. and key... ok
  . Bind on udp://*:2100/ ... ok
  . Setting up the SSL/TLS structure... ok
  . Waiting for a remote connection ...192.168.250.210 - - [31/Dec/2019 02:55:12] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:12] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:13] "PUT /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
 interrupted by SIGTERM (in net_accept())
  . Cleaning up... done.
127.0.0.1 - - [31/Dec/2019 02:55:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
127.0.0.1 - - [31/Dec/2019 02:55:13] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:14] "PUT /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/2 HTTP/1.1" 200 -

  . Seeding the random number generator... ok
  . Loading the CA root certificate ... ok (0 skipped)
  . Loading the server cert. and key... ok
  . Bind on udp://*:2100/ ... ok
  . Setting up the SSL/TLS structure... ok
  . Waiting for a remote connection ...127.0.0.1 - - [31/Dec/2019 02:55:14] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:14] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:14] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
 ok
  . Performing the SSL/TLS handshake... hello verification requested
  . Waiting for a remote connection ... ok
  . Performing the SSL/TLS handshake... ok
    [ Protocol is DTLSv1.2 ]
    [ Ciphersuite is TLS-PSK-WITH-AES-128-GCM-SHA256 ]
    [ Record expansion is 37 ]
    [ Maximum fragment length is 16384 ]
  . Verifying peer X.509 certificate... ok
192.168.250.252 - - [31/Dec/2019 02:55:26] "GET /description.xml HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:28] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:29] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:29] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:29] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
{"ok"}
192.168.250.210 - - [31/Dec/2019 02:55:34] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:34] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:40] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:40] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:40] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:40] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:40] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:41] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:41] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:41] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:41] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:41] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:43] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:43] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:43] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:43] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:48] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:48] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:49] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:49] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:49] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:49] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:51] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:51] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:52] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:52] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:52] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:52] "GET /api/56abf1382b7611eaac2dd6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:53] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:53] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:53] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:53] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
 connection was closed gracefully
  . Closing the connection... done
  . Waiting for a remote connection ... interrupted by SIGTERM (in net_accept())
  . Cleaning up... done.
192.168.250.210 - - [31/Dec/2019 02:55:59] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 02:55:59] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
{"ok"}
192.168.250.252 - - [31/Dec/2019 02:56:25] "GET /description.xml HTTP/1.1" 200 -
{"ok"}
{"ok"}
192.168.250.252 - - [31/Dec/2019 02:57:25] "GET /description.xml HTTP/1.1" 200 -
{"ok"}

And the debug log:

root@huebridge:/opt/hue-emulator# python3 HueEmulator3.py --debug
2019-12-31 03:03:09,794 - root - INFO - Using Host 192.168.250.230:80
2019-12-31 03:03:09,809 - root - INFO - d6bfcb9e0f40
2019-12-31 03:03:09,810 - root - INFO - IP range for light discovery: 0-255
2019-12-31 03:03:09,810 - root - INFO - 127.0.0.1
2019-12-31 03:03:09,811 - root - INFO - Online Discovery/Remote API Enabled!
2019-12-31 03:03:09,813 - root - INFO - Config loaded
2019-12-31 03:03:09,816 - root - DEBUG - start ssdp broadcast
2019-12-31 03:03:09,819 - root - INFO - sync with lights
2019-12-31 03:03:09,822 - root - DEBUG - starting ssdp...
2019-12-31 03:03:09,830 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:03:09,833 - root - INFO - Starting httpd...
2019-12-31 03:03:09,836 - root - INFO - Starting ssl httpd...
2019-12-31 03:03:09,841 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): discovery.diyhue.org:443
2019-12-31 03:03:09,843 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "GET /state HTTP/1.1" 200 91
2019-12-31 03:03:10,097 - urllib3.connectionpool - DEBUG - https://discovery.diyhue.org:443 "POST / HTTP/1.1" 200 6
{"ok"}
2019-12-31 03:03:14,737 - root - DEBUG - Sending M-Search response to 192.168.250.252
192.168.250.252 - - [31/Dec/2019 03:03:14] "GET /description.xml HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:15] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:15] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:16] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:16] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
2019-12-31 03:03:16,231 - root - DEBUG - Sending M-Search response to 192.168.250.252
2019-12-31 03:03:17,734 - root - DEBUG - Sending M-Search response to 192.168.250.252
192.168.250.210 - - [31/Dec/2019 03:03:18] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:18] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/3 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:18] "PUT /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
2019-12-31 03:03:18,540 - root - INFO - in PUT method
2019-12-31 03:03:18,541 - root - INFO - /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/2
2019-12-31 03:03:18,541 - root - INFO - b'{"stream":{"active":true}}'
2019-12-31 03:03:18,543 - root - INFO - start hue entertainment

  . Seeding the random number generator... ok
  . Loading the CA root certificate ... ok (0 skipped)
  . Loading the server cert. and key... ok
  . Bind on udp://*:2100/ ... ok
  . Setting up the SSL/TLS structure... ok
  . Waiting for a remote connection ...192.168.250.210 - - [31/Dec/2019 03:03:18] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:18] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
2019-12-31 03:03:19,051 - root - INFO - [
    {
        "success": {
            "/groups/2/stream": {
                "active": true
            }
        }
    }
]
192.168.250.210 - - [31/Dec/2019 03:03:19] "PUT /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
2019-12-31 03:03:19,082 - root - INFO - in PUT method
2019-12-31 03:03:19,082 - root - INFO - /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/2
2019-12-31 03:03:19,083 - root - INFO - b'{"stream":{"active":false}}'
2019-12-31 03:03:19,084 - root - INFO - stop hue entertainent
 interrupted by SIGTERM (in net_accept())
  . Cleaning up... done.
2019-12-31 03:03:19,393 - root - INFO - [
    {
        "success": {
            "/groups/2/stream": {
                "active": false
            }
        }
    }
]
2019-12-31 03:03:19,395 - root - INFO - rule 1 is triggered
2019-12-31 03:03:19,405 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
127.0.0.1 - - [31/Dec/2019 03:03:19] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
2019-12-31 03:03:19,409 - root - INFO - in PUT method
2019-12-31 03:03:19,410 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:03:19,410 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:03:19,414 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:03:19,429 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:03:19,717 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:03:19,718 - root - INFO - rule 1 is triggered
2019-12-31 03:03:19,725 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
2019-12-31 03:03:19,727 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
127.0.0.1 - - [31/Dec/2019 03:03:19] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
2019-12-31 03:03:19,734 - root - INFO - in PUT method
2019-12-31 03:03:19,734 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:03:19,735 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:03:19,739 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:03:19,756 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
192.168.250.210 - - [31/Dec/2019 03:03:19] "PUT /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
2019-12-31 03:03:19,945 - root - INFO - in PUT method
2019-12-31 03:03:19,946 - root - INFO - /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups/2
2019-12-31 03:03:19,947 - root - INFO - b'{"stream":{"active":true}}'
2019-12-31 03:03:19,948 - root - INFO - start hue entertainment

  . Seeding the random number generator... ok
  . Loading the CA root certificate ... ok (0 skipped)
  . Loading the server cert. and key... ok
  . Bind on udp://*:2100/ ... ok
  . Setting up the SSL/TLS structure... ok
  . Waiting for a remote connection ...2019-12-31 03:03:20,042 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:03:20,046 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:03:20,047 - root - INFO - rule 1 is triggered
2019-12-31 03:03:20,056 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 127.0.0.1:80
127.0.0.1 - - [31/Dec/2019 03:03:20] "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
2019-12-31 03:03:20,060 - root - INFO - in PUT method
2019-12-31 03:03:20,061 - root - INFO - /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action
2019-12-31 03:03:20,061 - root - INFO - b'{"scene": "13"}'
2019-12-31 03:03:20,067 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:03:20,081 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 12
2019-12-31 03:03:20,371 - urllib3.connectionpool - DEBUG - http://127.0.0.1:80 "PUT /api/0681c8642b7511eaac2dd6bfcb9e0f40/groups/2/action HTTP/1.1" 200 45
2019-12-31 03:03:20,375 - root - INFO - [
    {
        "success": {
            "/groups/2/action/scene": "13"
        }
    }
]
2019-12-31 03:03:20,457 - root - INFO - [
    {
        "success": {
            "/groups/2/stream": {
                "active": true
            }
        }
    }
]
192.168.250.210 - - [31/Dec/2019 03:03:20] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:20] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
 ok
  . Performing the SSL/TLS handshake... hello verification requested
  . Waiting for a remote connection ... ok
  . Performing the SSL/TLS handshake... ok
    [ Protocol is DTLSv1.2 ]
    [ Ciphersuite is TLS-PSK-WITH-AES-128-GCM-SHA256 ]
    [ Record expansion is 37 ]
    [ Maximum fragment length is 16384 ]
  . Verifying peer X.509 certificate... ok
 connection was closed gracefully
  . Closing the connection... done
  . Waiting for a remote connection ...2019-12-31 03:03:21,378 - root - DEBUG - Sending M-Search response to 192.168.250.252
2019-12-31 03:03:21,857 - root - INFO - sync with lights
2019-12-31 03:03:21,864 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2019-12-31 03:03:21,880 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "GET /state HTTP/1.1" 200 91
192.168.250.210 - - [31/Dec/2019 03:03:22] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:22] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
2019-12-31 03:03:24,233 - root - INFO - Entertainment Service was syncing and has timed out, stopping server and clearing state
 interrupted by SIGTERM (in net_accept())
  . Cleaning up... done.
192.168.250.210 - - [31/Dec/2019 03:03:24] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:25] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:27] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 03:03:27] "GET /api/f4cc87182b7811eab6cbd6bfcb9e0f40/groups HTTP/1.1" 200 -
2019-12-31 03:03:31,079 - root - DEBUG - Sending M-Search response to 192.168.250.252
2019-12-31 03:03:40,141 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): discovery.diyhue.org:443
2019-12-31 03:03:40,395 - urllib3.connectionpool - DEBUG - https://discovery.diyhue.org:443 "POST / HTTP/1.1" 200 6
{"ok"}
2019-12-31 03:03:41,480 - root - DEBUG - Sending M-Search response to 192.168.250.252
rkkoszewski commented 4 years ago

I seem to have found the source of the infinite loop. Seems to happen when you set the "Behaviour when sync stops" to turn the "bulb off", then this gets triggered when the TV sends a entertainment API stream off when it starts.

Now I can only see that the entertain-srv got killed again and again. I think the TV is trying to get Entertainment API to run, but some state is expected that never is met.

The TV IP is: 192.168.250.171

/opt/hue-emulator # python3 HueEmulator3.py
{"ok"}
192.168.250.252 - - [31/Dec/2019 13:38:15] "GET /description.xml HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:16] "GET /hue/linkbutton?username=&password=&action=Activate HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:20] "GET /api/f911223a2bd111ea8fead6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:20] "GET /api/f911223a2bd111ea8fead6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:20] "POST /api/ HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:20] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:20] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:23] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
----------------------------------------
Exception happened during processing of request from ('192.168.250.210', 37292)
Traceback (most recent call last):
  File "/socketserver.py", line 654, in process_request_thread
  File "/socketserver.py", line 364, in finish_request
  File "/socketserver.py", line 724, in __init__
  File "/server.py", line 420, in handle
  File "/server.py", line 386, in handle_one_request
  File "/socket.py", line 586, in readinto
  File "/ssl.py", line 1012, in recv_into
  File "/ssl.py", line 874, in read
  File "/ssl.py", line 631, in read
ConnectionResetError: [Errno 104] Connection reset by peer
----------------------------------------
192.168.250.210 - - [31/Dec/2019 13:38:24] "GET /api/nouser/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:25] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:25] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:25] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:25] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:25] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:27] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:27] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:28] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:29] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:29] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:29] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:29] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:29] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:29] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:31] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:31] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:31] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:31] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:34] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:35] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:35] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:35] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:35] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:36] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:36] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:38] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:38] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:39] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:39] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:39] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:39] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:40] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
{"ok"}
192.168.250.210 - - [31/Dec/2019 13:38:40] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:40] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:41] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:41] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
RTTVAR has grown to over 2.3 seconds, decreasing to 2.0
192.168.250.210 - - [31/Dec/2019 13:38:43] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:43] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
RTTVAR has grown to over 2.3 seconds, decreasing to 2.0
192.168.250.210 - - [31/Dec/2019 13:38:43] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:43] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:44] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:45] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:45] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:45] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:46] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:47] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:47] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:48] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:48] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:48] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:50] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:53] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:55] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:55] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:55] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:55] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:56] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:56] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:56] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:56] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:58] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:58] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:58] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:58] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:58] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:58] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:58] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:58] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:58] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:59] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:38:59] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:00] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:00] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:00] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:01] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:01] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:06] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:09] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
{"ok"}
192.168.250.210 - - [31/Dec/2019 13:39:11] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:13] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:13] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:13] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/1 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/2 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/3 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/4 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/5 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/6 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/7 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/8 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/9 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:15] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:15] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:15] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:15] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:15] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/11 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:15] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes/10 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:16] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:16] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:18] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:19] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:19] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/scenes HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:19] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:19] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:20] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:20] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:21] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:21] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:23] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:23] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:23] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:23] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:23] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:25] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:28] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:30] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.252 - - [31/Dec/2019 13:39:30] "GET /description.xml HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:32] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:33] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:37] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:37] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -

  . Seeding the random number generator... ok
  . Loading the CA root certificate ... ok (0 skipped)
  . Loading the server cert. and key... ok
  . Bind on udp://*:2100/ ... ok
  . Setting up the SSL/TLS structure... ok
  . Waiting for a remote connection ...192.168.250.210 - - [31/Dec/2019 13:39:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
 ok
  . Performing the SSL/TLS handshake... hello verification requested
  . Waiting for a remote connection ... ok
  . Performing the SSL/TLS handshake... ok
    [ Protocol is DTLSv1.2 ]
    [ Ciphersuite is TLS-PSK-WITH-AES-128-GCM-SHA256 ]
    [ Record expansion is 37 ]
    [ Maximum fragment length is 16384 ]
  . Verifying peer X.509 certificate... ok
192.168.250.210 - - [31/Dec/2019 13:39:39] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:39] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
{"ok"}
192.168.250.210 - - [31/Dec/2019 13:39:41] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:41] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
 connection was closed gracefully
  . Closing the connection... done
  . Waiting for a remote connection ...192.168.250.210 - - [31/Dec/2019 13:39:42] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -

  . Seeding the random number generator... ok
  . Loading the CA root certificate ... ok (0 skipped)
  . Loading the server cert. and key... ok
  . Bind on udp://*:2100/ ... ok
  . Setting up the SSL/TLS structure... ok
  . Waiting for a remote connection ... ok
  . Performing the SSL/TLS handshake... hello verification requested
  . Waiting for a remote connection ... ok
  . Performing the SSL/TLS handshake... ok
    [ Protocol is DTLSv1.2 ]
    [ Ciphersuite is TLS-PSK-WITH-AES-128-GCM-SHA256 ]
    [ Record expansion is 37 ]
    [ Maximum fragment length is 16384 ]
  . Verifying peer X.509 certificate... ok
192.168.250.210 - - [31/Dec/2019 13:39:43] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:43] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:44] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
 connection was closed gracefully
  . Closing the connection... done
  . Waiting for a remote connection ...192.168.250.210 - - [31/Dec/2019 13:39:45] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
----------------------------------------
Exception happened during processing of request from ('192.168.250.210', 37300)
Traceback (most recent call last):
  File "/socketserver.py", line 654, in process_request_thread
  File "/socketserver.py", line 364, in finish_request
  File "/socketserver.py", line 724, in __init__
  File "/server.py", line 420, in handle
  File "/server.py", line 406, in handle_one_request
  File "HueEmulator3.py", line 1808, in do_PUT
    dxState["groups"][url_pices[4]]["state"]["any_on"] = current_time
KeyError: '2'
----------------------------------------
192.168.250.210 - - [31/Dec/2019 13:39:45] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
----------------------------------------
Exception happened during processing of request from ('192.168.250.210', 37314)
Traceback (most recent call last):
  File "/socketserver.py", line 654, in process_request_thread
  File "/socketserver.py", line 364, in finish_request
  File "/socketserver.py", line 724, in __init__
  File "/server.py", line 420, in handle
  File "/server.py", line 406, in handle_one_request
  File "HueEmulator3.py", line 1808, in do_PUT
    dxState["groups"][url_pices[4]]["state"]["any_on"] = current_time
KeyError: '2'
----------------------------------------
192.168.250.210 - - [31/Dec/2019 13:39:45] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
----------------------------------------
Exception happened during processing of request from ('192.168.250.210', 37366)
Traceback (most recent call last):
  File "/socketserver.py", line 654, in process_request_thread
  File "/socketserver.py", line 364, in finish_request
  File "/socketserver.py", line 724, in __init__
  File "/server.py", line 418, in handle
  File "/server.py", line 406, in handle_one_request
  File "HueEmulator3.py", line 1808, in do_PUT
    dxState["groups"][url_pices[4]]["state"]["any_on"] = current_time
KeyError: '2'
----------------------------------------
192.168.250.210 - - [31/Dec/2019 13:39:45] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/2/action HTTP/1.1" 200 -
----------------------------------------
Exception happened during processing of request from ('192.168.250.210', 37368)
Traceback (most recent call last):
  File "/socketserver.py", line 654, in process_request_thread
  File "/socketserver.py", line 364, in finish_request
  File "/socketserver.py", line 724, in __init__
  File "/server.py", line 418, in handle
  File "/server.py", line 406, in handle_one_request
  File "HueEmulator3.py", line 1808, in do_PUT
    dxState["groups"][url_pices[4]]["state"]["any_on"] = current_time
KeyError: '2'
----------------------------------------
192.168.250.210 - - [31/Dec/2019 13:39:45] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:46] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:46] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:48] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
 interrupted by SIGTERM (in net_accept())
  . Cleaning up... interrupted by SIGTERM (in net_accept()) done.

  . Cleaning up... done.
192.168.250.210 - - [31/Dec/2019 13:39:51] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:53] "GET /api/nouser/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:53] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:54] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:55] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:56] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:57] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:58] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:59] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:59] "POST /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:59] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/3 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:39:59] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -

  . Seeding the random number generator... ok
  . Loading the CA root certificate ... ok (0 skipped)
  . Loading the server cert. and key... ok
  . Bind on udp://*:2100/ ... ok
  . Setting up the SSL/TLS structure... ok
  . Waiting for a remote connection ...192.168.250.210 - - [31/Dec/2019 13:39:59] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
 ok
  . Performing the SSL/TLS handshake... hello verification requested
  . Waiting for a remote connection ... ok
  . Performing the SSL/TLS handshake... ok
    [ Protocol is DTLSv1.2 ]
    [ Ciphersuite is TLS-PSK-WITH-AES-128-GCM-SHA256 ]
    [ Record expansion is 37 ]
    [ Maximum fragment length is 16384 ]
  . Verifying peer X.509 certificate... ok
192.168.250.210 - - [31/Dec/2019 13:40:01] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:01] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:03] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:04] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:04] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
 connection was closed gracefully
  . Closing the connection... done
  . Waiting for a remote connection ...192.168.250.210 - - [31/Dec/2019 13:40:04] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/3 HTTP/1.1" 200 -

  . Seeding the random number generator... ok
  . Loading the CA root certificate ... ok (0 skipped)
  . Loading the server cert. and key... ok
  . Bind on udp://*:2100/ ... ok
  . Setting up the SSL/TLS structure... ok
  . Waiting for a remote connection ... ok
  . Performing the SSL/TLS handshake... hello verification requested
  . Waiting for a remote connection ... ok
  . Performing the SSL/TLS handshake... ok
    [ Protocol is DTLSv1.2 ]
    [ Ciphersuite is TLS-PSK-WITH-AES-128-GCM-SHA256 ]
    [ Record expansion is 37 ]
    [ Maximum fragment length is 16384 ]
  . Verifying peer X.509 certificate... ok
192.168.250.210 - - [31/Dec/2019 13:40:06] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:06] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:08] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:08] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
 connection was closed gracefully
  . Closing the connection... done
  . Waiting for a remote connection ...192.168.250.210 - - [31/Dec/2019 13:40:08] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/3/action HTTP/1.1" 200 -
----------------------------------------
Exception happened during processing of request from ('192.168.250.210', 37370)
Traceback (most recent call last):
  File "/socketserver.py", line 654, in process_request_thread
  File "/socketserver.py", line 364, in finish_request
  File "/socketserver.py", line 724, in __init__
  File "/server.py", line 420, in handle
  File "/server.py", line 406, in handle_one_request
  File "HueEmulator3.py", line 1808, in do_PUT
    dxState["groups"][url_pices[4]]["state"]["any_on"] = current_time
KeyError: '3'
----------------------------------------
192.168.250.210 - - [31/Dec/2019 13:40:08] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/3/action HTTP/1.1" 200 -
----------------------------------------
Exception happened during processing of request from ('192.168.250.210', 37896)
Traceback (most recent call last):
  File "/socketserver.py", line 654, in process_request_thread
  File "/socketserver.py", line 364, in finish_request
  File "/socketserver.py", line 724, in __init__
  File "/server.py", line 418, in handle
  File "/server.py", line 406, in handle_one_request
  File "HueEmulator3.py", line 1808, in do_PUT
    dxState["groups"][url_pices[4]]["state"]["any_on"] = current_time
KeyError: '3'
----------------------------------------
192.168.250.210 - - [31/Dec/2019 13:40:08] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups/3/action HTTP/1.1" 200 -
----------------------------------------
Exception happened during processing of request from ('192.168.250.210', 37898)
Traceback (most recent call last):
  File "/socketserver.py", line 654, in process_request_thread
  File "/socketserver.py", line 364, in finish_request
  File "/socketserver.py", line 724, in __init__
  File "/server.py", line 418, in handle
  File "/server.py", line 406, in handle_one_request
  File "HueEmulator3.py", line 1808, in do_PUT
    dxState["groups"][url_pices[4]]["state"]["any_on"] = current_time
KeyError: '3'
----------------------------------------
192.168.250.210 - - [31/Dec/2019 13:40:09] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:10] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:10] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
{"ok"}
 interrupted by SIGTERM (in net_accept()) interrupted by SIGTERM (in net_accept())

  . Cleaning up...  . Cleaning up... done. done.

192.168.250.210 - - [31/Dec/2019 13:40:12] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:13] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:15] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:15] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:15] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:16] "GET /api/nouser/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:18] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:18] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:18] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:18] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:18] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:18] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:18] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:18] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:18] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:19] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:20] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:20] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors/new HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:20] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:20] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:20] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:20] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:26] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:26] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:26] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:26] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:28] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:28] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:30] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:30] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:30] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:31] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:31] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:31] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:40:32] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.252 - - [31/Dec/2019 13:40:32] "GET /description.xml HTTP/1.1" 200 -
{"ok"}
192.168.250.210 - - [31/Dec/2019 13:41:02] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:41:02] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:41:02] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:41:02] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:41:02] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:41:02] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
{"ok"}
192.168.250.252 - - [31/Dec/2019 13:41:35] "GET /description.xml HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:41:43] "GET /description.xml HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:46] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:49] "GET /api/c23f46a22b7611ea8ed9d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:49] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:49] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:49] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:49] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:49] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:50] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:41:51] "GET /hue/linkbutton?username=&password=&action=Activate HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "POST /api HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:51] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:59] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:41:59] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:00] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:01] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:06] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:07] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:08] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:09] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:09] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:10] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:11] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
{"ok"}
192.168.250.252 - - [31/Dec/2019 13:42:13] "GET /description.xml HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:18] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:19] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:20] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:21] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:24] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:28] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:29] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:29] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:30] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:30] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:30] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:31] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:31] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:31] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:32] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:32] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:33] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:38] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:39] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:39] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:39] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:40] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:40] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:41] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:41] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:41] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:42] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:42] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:42:43] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
{"ok"}
192.168.250.252 - - [31/Dec/2019 13:43:11] "GET /description.xml HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:12] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:12] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:43:16] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:16] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:16] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:16] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:16] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:17] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:20] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:21] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:21] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:24] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:24] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:24] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:27] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:27] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:28] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:31] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:31] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:31] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:34] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:34] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:35] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:38] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:38] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:38] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:41] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:41] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:41] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:43:44] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:45] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:45] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:48] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:48] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.252 - - [31/Dec/2019 13:43:48] "GET /description.xml HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:48] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:51] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:51] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:52] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:55] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:55] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:55] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:58] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:43:58] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:43:59] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:02] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:02] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:02] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:05] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:05] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:06] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:09] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:09] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:09] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:12] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:12] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:13] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:44:16] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:16] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:16] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:19] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:19] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.252 - - [31/Dec/2019 13:44:20] "GET /description.xml HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:20] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:23] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:23] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:23] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:26] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:26] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:27] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:30] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:30] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:30] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:33] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:33] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:33] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:36] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:37] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:37] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:40] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:40] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:40] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:43] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:43] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:44] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:44:47] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:47] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:47] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:50] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:50] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:51] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:54] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:54] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:54] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:57] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:44:57] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:44:58] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:01] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:01] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:01] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:04] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:04] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:05] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:08] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:08] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:08] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:08] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:08] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:09] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:09] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:09] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:09] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:11] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:11] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:11] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:11] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:11] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.210 - - [31/Dec/2019 13:45:11] "PUT /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights/1/state HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:12] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:12] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
{"ok"}
192.168.250.210 - - [31/Dec/2019 13:45:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/sensors HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:14] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:15] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:15] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.210 - - [31/Dec/2019 13:45:15] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:15] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:15] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.252 - - [31/Dec/2019 13:45:18] "GET /description.xml HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:18] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:21] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:21] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:22] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:25] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:25] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:25] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:28] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:28] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:29] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:32] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:32] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:32] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:35] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:35] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:36] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/config HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [31/Dec/2019 13:45:37] "GET /api/cef95ca02bd211ea9247d6bfcb9e0f40/groups HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:39] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:39] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:39] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:42] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:42] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:43] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:45:46] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:46] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:46] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:49] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:49] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:49] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:52] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:53] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:53] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:56] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:56] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:45:56] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:45:59] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:00] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:00] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:03] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:03] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:03] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:06] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:07] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:07] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:10] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:10] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:10] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:13] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:13] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:14] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:46:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:17] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:20] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:20] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:21] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:24] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:24] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:24] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:27] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:27] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:28] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:31] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:31] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:31] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:34] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:34] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:35] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:38] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:38] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:38] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:41] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:41] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:42] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:45] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:45] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:45] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:46:48] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:48] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:49] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:52] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:52] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:52] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:55] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:55] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:55] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:59] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:46:59] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:46:59] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:02] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:02] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:02] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:05] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:06] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:06] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:09] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:09] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:09] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:12] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:12] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:13] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:47:16] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:16] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:16] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:19] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:19] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:20] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:23] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:23] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:23] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:26] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:26] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:27] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:30] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:30] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:30] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:33] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:33] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:34] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:37] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:37] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:37] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:40] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:40] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:41] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:44] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:44] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:44] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:47:47] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:47] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:47] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:50] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:51] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:51] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:54] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:54] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:54] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:57] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:47:58] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:47:58] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:01] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:01] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:01] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:04] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:05] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:05] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:08] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:08] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:08] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:11] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:11] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:12] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:15] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:15] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:15] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
{"ok"}
192.168.250.171 - - [31/Dec/2019 13:48:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:18] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:19] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:22] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:22] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:22] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:25] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:25] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:26] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:29] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:29] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:29] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:32] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:32] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:33] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:36] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:36] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:36] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:39] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:39] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:40] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:43] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [31/Dec/2019 13:48:43] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
killall: entertain-srv: no process killed
192.168.250.171 - - [31/Dec/2019 13:48:43] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities HTTP/1.1" 200 -
rkkoszewski commented 4 years ago

Ok, got some more information from the TV Side. Seems that it is expecting something regarding the Stream State. It throws an exception saying: checkStreamStatus failed result when accessing /api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities

Here are the logs:

12-31 15:39:55.265  3842    29807   org.droidtv.ambihue I   HttpClass   http://192.168.250.230/api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1
12-31 15:39:55.315  3842    29807   org.droidtv.ambihue D   dtls    
12-31 15:39:55.315  3842    29807   org.droidtv.ambihue D   dtls     stop dtls client
12-31 15:39:55.416  3842    29807   org.droidtv.ambihue I   HttpClass   http://192.168.250.230/api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2{"stream":{"active":false}} 500
12-31 15:39:55.730  3842    29807   org.droidtv.ambihue I   HttpClass   http://192.168.250.230/api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities
12-31 15:39:55.741  3842    29807   org.droidtv.ambihue I   HueControl  checkStreamStatus failed result {"groups":{"available":64},"lights":{"available":63},"resourcelinks":{"available":64},"rules":{"actions":{"available":400},"available":200,"conditions":{"available":400}},"scenes":{"available":200,"lightstates":{"available":2048}},"schedules":{"available":100},"sensors":{"available":63,"clip":{"available":63},"zgp":{"available":63},"zll":{"available":63}},"timezones":{"values":["CET","CST6CDT","EET","EST","EST5EDT","HST","MET","MST","MST7MDT","PST8PDT","WET","Africa/Abidjan","Africa/Accra","Africa/Addis_Ababa","Africa/Algiers","Africa/Asmara","Africa/Bamako","Africa/Bangui","Africa/Banjul","Africa/Bissau","Africa/Blantyre","Africa/Brazzaville","Africa/Bujumbura","Africa/Cairo","Africa/Casablanca","Africa/Ceuta","Africa/Conakry","Africa/Dakar","Africa/Dar_es_Salaam","Africa/Djibouti","Africa/Douala","Africa/El_Aaiun","Africa/Freetown","Africa/Gaborone","Africa/Harare","Africa/Johannesburg","Africa/Juba","Africa/Kampala","Africa/Khartoum","Africa/Kigali","Africa/Kinshasa","Africa/Lagos","Africa/Libreville","Africa/Lome","Africa/Luanda","Africa/Lubumbashi","Africa/Lusaka","Africa/Malabo","Africa/Maputo","Africa/Maseru","Africa/Mbabane","Africa/Mogadishu","Africa/Monrovia","Africa/Nairobi","Africa/Ndjamena","Africa/Niamey","Africa/Nouakchott","Africa/Ouagadougou","Africa/Porto-Novo","Africa/Sao_Tome","Africa/Tripoli","Africa/Tunis","Africa/Windhoek","America/Adak","America/Anchorage","America/Anguilla","America/Antigua","America/Araguaina","America/Aruba","America/Asuncion","America/Atikokan","America/Bahia","America/Bahia_Banderas","America/Barbados","America/Belem","America/Belize","America/Blanc-Sablon","America/Boa_Vista","America/Bogota","America/Boise","America/Cambridge_Bay","America/Campo_Grande","America/Cancun","America/Caracas","America/Cayenne","America/Cayman","America/Chicago","America/Chihuahua","America/Costa_Rica","America/Creston","America/Cuiaba","America/Curacao","America/Danmarkshavn","America/Dawson","America/Dawson_Creek","America/Denver","America/Detroit","America/Dominica","America/Edmonton","America/Eirunepe","America/El_Salvador","America/Fort_Nelson","America/Fortaleza","America/Glace_Bay","America/Godthab","America/Goose_Bay","America/Grand_Turk","America/Grenada","America/Guadeloupe","America/Guatemala","America/Guayaquil","America/Guyana","America/Halifax","America/Havana","America/Hermosillo","America/Inuvik","America/Iqaluit","America/Jamaica","America/Juneau","America/Kralendijk","America/La_Paz","America/Lima","America/Los_Angeles","America/Lower_Princes","America/Maceio","America/Managua","America/Manaus","America/Marigot","America/Martinique","America/Matamoros","America/Mazatlan","America/Menominee","America/Merida","America/Metlakatla","America/Mexico_City","America/Miquelon","America/Moncton","America/Monterrey","America/Montevideo","America/Montserrat","America/Nassau","America/New_York","America/Nipigon","America/Nome","America/Noronha","America/Ojinaga","America/Panama","America/Pangnirtung","America/Paramaribo","America/Phoenix","America/Port-au-Prince","America/Port_of_Spain","America/Porto_Velho","America/Puerto_Rico","America/Punta_Arenas","America/Rainy_River","America/Rankin_Inlet","America/Recife","America/Regina","America/Resolute","America/Rio_Branco","America/Santarem","America/Santiago","America/Santo_Domingo","America/Sao_Paulo","America/Scoresbysund","America/Sitka","America/St_Barthelemy","America/St_Johns","America/St_Kitts","America/St_Lucia","America/St_Thomas","America/St_Vincent","America/Swift_Current","America/Tegucigalpa","America/Thule","America/Thunder_Bay","America/Tijuana","America/Toronto","America/Tortola","America/Vancouver","America/Whitehorse","America/Winnipeg","America/Yakutat","America/Yellowknife","America/Argentina/Buenos_Aires","America/Argentina/Catamarca","America/Argentina/Cordoba","America/Argentina/Jujuy","America/Argentina/La_Rioja","America/Argentina/Mendoza","America/Argentina/Rio_Gallegos","America/Argentina/Salta","America/Argentina/San_Juan","America/Argentina/San
12-31 15:39:55.741  3842    29807   org.droidtv.ambihue I   LampUpdateThread    isStreamEnabled false
12-31 15:39:58.741  3842    29807   org.droidtv.ambihue I   HttpClass   http://192.168.250.230/api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1
12-31 15:39:58.790  3842    29807   org.droidtv.ambihue D   dtls    
12-31 15:39:58.790  3842    29807   org.droidtv.ambihue D   dtls     stop dtls client
12-31 15:39:58.891  3842    29807   org.droidtv.ambihue I   HttpClass   http://192.168.250.230/api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2{"stream":{"active":false}} 500
12-31 15:39:59.210  3842    29807   org.droidtv.ambihue I   HttpClass   http://192.168.250.230/api/4d0788ba2bd311ea9247d6bfcb9e0f40/capabilities
12-31 15:39:59.220  3842    29807   org.droidtv.ambihue I   HueControl  checkStreamStatus failed result {"groups":{"available":64},"lights":{"available":63},"resourcelinks":{"available":64},"rules":{"actions":{"available":400},"available":200,"conditions":{"available":400}},"scenes":{"available":200,"lightstates":{"available":2048}},"schedules":{"available":100},"sensors":{"available":63,"clip":{"available":63},"zgp":{"available":63},"zll":{"available":63}},"timezones":{"values":["CET","CST6CDT","EET","EST","EST5EDT","HST","MET","MST","MST7MDT","PST8PDT","WET","Africa/Abidjan","Africa/Accra","Africa/Addis_Ababa","Africa/Algiers","Africa/Asmara","Africa/Bamako","Africa/Bangui","Africa/Banjul","Africa/Bissau","Africa/Blantyre","Africa/Brazzaville","Africa/Bujumbura","Africa/Cairo","Africa/Casablanca","Africa/Ceuta","Africa/Conakry","Africa/Dakar","Africa/Dar_es_Salaam","Africa/Djibouti","Africa/Douala","Africa/El_Aaiun","Africa/Freetown","Africa/Gaborone","Africa/Harare","Africa/Johannesburg","Africa/Juba","Africa/Kampala","Africa/Khartoum","Africa/Kigali","Africa/Kinshasa","Africa/Lagos","Africa/Libreville","Africa/Lome","Africa/Luanda","Africa/Lubumbashi","Africa/Lusaka","Africa/Malabo","Africa/Maputo","Africa/Maseru","Africa/Mbabane","Africa/Mogadishu","Africa/Monrovia","Africa/Nairobi","Africa/Ndjamena","Africa/Niamey","Africa/Nouakchott","Africa/Ouagadougou","Africa/Porto-Novo","Africa/Sao_Tome","Africa/Tripoli","Africa/Tunis","Africa/Windhoek","America/Adak","America/Anchorage","America/Anguilla","America/Antigua","America/Araguaina","America/Aruba","America/Asuncion","America/Atikokan","America/Bahia","America/Bahia_Banderas","America/Barbados","America/Belem","America/Belize","America/Blanc-Sablon","America/Boa_Vista","America/Bogota","America/Boise","America/Cambridge_Bay","America/Campo_Grande","America/Cancun","America/Caracas","America/Cayenne","America/Cayman","America/Chicago","America/Chihuahua","America/Costa_Rica","America/Creston","America/Cuiaba","America/Curacao","America/Danmarkshavn","America/Dawson","America/Dawson_Creek","America/Denver","America/Detroit","America/Dominica","America/Edmonton","America/Eirunepe","America/El_Salvador","America/Fort_Nelson","America/Fortaleza","America/Glace_Bay","America/Godthab","America/Goose_Bay","America/Grand_Turk","America/Grenada","America/Guadeloupe","America/Guatemala","America/Guayaquil","America/Guyana","America/Halifax","America/Havana","America/Hermosillo","America/Inuvik","America/Iqaluit","America/Jamaica","America/Juneau","America/Kralendijk","America/La_Paz","America/Lima","America/Los_Angeles","America/Lower_Princes","America/Maceio","America/Managua","America/Manaus","America/Marigot","America/Martinique","America/Matamoros","America/Mazatlan","America/Menominee","America/Merida","America/Metlakatla","America/Mexico_City","America/Miquelon","America/Moncton","America/Monterrey","America/Montevideo","America/Montserrat","America/Nassau","America/New_York","America/Nipigon","America/Nome","America/Noronha","America/Ojinaga","America/Panama","America/Pangnirtung","America/Paramaribo","America/Phoenix","America/Port-au-Prince","America/Port_of_Spain","America/Porto_Velho","America/Puerto_Rico","America/Punta_Arenas","America/Rainy_River","America/Rankin_Inlet","America/Recife","America/Regina","America/Resolute","America/Rio_Branco","America/Santarem","America/Santiago","America/Santo_Domingo","America/Sao_Paulo","America/Scoresbysund","America/Sitka","America/St_Barthelemy","America/St_Johns","America/St_Kitts","America/St_Lucia","America/St_Thomas","America/St_Vincent","America/Swift_Current","America/Tegucigalpa","America/Thule","America/Thunder_Bay","America/Tijuana","America/Toronto","America/Tortola","America/Vancouver","America/Whitehorse","America/Winnipeg","America/Yakutat","America/Yellowknife","America/Argentina/Buenos_Aires","America/Argentina/Catamarca","America/Argentina/Cordoba","America/Argentina/Jujuy","America/Argentina/La_Rioja","America/Argentina/Mendoza","America/Argentina/Rio_Gallegos","America/Argentina/Salta","America/Argentina/San_Juan","America/Argentina/San
12-31 15:39:59.220  3842    29807   org.droidtv.ambihue I   LampUpdateThread    isStreamEnabled false
mariusmotea commented 4 years ago

Thanks for logs, this line catch my attention:

HueControl checkStreamStatus failed result {"groups":{"available":64},"lights":{"available":63},.....

I will check on a real bridge to see if they added something new in capabilities

rkkoszewski commented 4 years ago

So if I'm not wrong, in "/capabilities", similar as "groups > available", it is checking for "streaming > available" which is missing right now in DiyHue.

mariusmotea commented 4 years ago

I hope so, i start my hue bridge but i don't see such value in output. Maybe because is not updated to latest firmware version? Currently i cannot update it as i'm not home and the Hue app require to have at last one light connected in order to skip the lights wizard page.

rkkoszewski commented 4 years ago

Good news!! Seems that adding

      "streaming":{
         "available":64
      },

To "capabilities" in (default-)config.json finally makes my Philips TV use the Entertainment API properly!! (I can open a PR later in the evening with the fix)

The infinite loop bug seems to reappear for some strange reason after a while. I have no idea what call caused it. Most likely when I turn off the TV it calls stream-stop and it goes into the loop again (I think). Still need to investigate what is going on there.

rkkoszewski commented 4 years ago

So it seems that there is no infinite loop, but DiyHue is silently hammering the ESP01 light with GET requests (Even though I have the firmware that had the UDP protocol for the entertain mode). So I guess something changed in DiyHue? How do I force DiyHue to use the entertainment UDP methods?

The reason why HueStream works in this case is because it sends much lower frame rate, while the TV sends at fast frame rates, with which the light cannot keep up with Get requests.

So what is happening is that rather than an infinite loop, the color requests are piling up and are sent much slower, depleting CPU resources on DiyHue and the ESP01.

How do I force DiyHue to use UDP?

rkkoszewski commented 4 years ago

I added to "functions/entertainment.py" the log line "LOOP" (After the first "while True:") and "NATIVE" (After "if len(nativeLights) is not 0:").

What I was able to observe is that with "Hue Stream" UDP is supposedly bein used and is working perfectly fine.

The logs look like this:

192.168.250.210 - - [03/Jan/2020 18:15:38] "PUT /api/003b63a42e4d11ea9a8ad6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
2020-01-03 18:15:38,579 - root - INFO - in PUT method
2020-01-03 18:15:38,580 - root - INFO - /api/003b63a42e4d11ea9a8ad6bfcb9e0f40/groups/2
2020-01-03 18:15:38,581 - root - INFO - b'{"stream":{"active":false}}'
2020-01-03 18:15:38,581 - root - INFO - stop hue entertainent
 interrupted by SIGTERM (in net_accept())
  . Cleaning up... done.
2020-01-03 18:15:38,877 - root - INFO - sync with lights
2020-01-03 18:15:38,884 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:15:38,889 - root - INFO - [
    {
        "success": {
            "/groups/2/stream": {
                "active": false
            }
        }
    }
]
2020-01-03 18:15:38,965 - root - DEBUG - Sending M-Search response to 192.168.250.210
2020-01-03 18:15:39,185 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "GET /state HTTP/1.1" 200 90
192.168.250.210 - - [03/Jan/2020 18:15:39] "PUT /api/003b63a42e4d11ea9a8ad6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
2020-01-03 18:15:39,507 - root - INFO - in PUT method
2020-01-03 18:15:39,508 - root - INFO - /api/003b63a42e4d11ea9a8ad6bfcb9e0f40/groups/2
2020-01-03 18:15:39,509 - root - INFO - b'{"stream":{"active":true}}'
2020-01-03 18:15:39,510 - root - INFO - start hue entertainment

  . Seeding the random number generator... ok
  . Loading the CA root certificate ... ok (0 skipped)
  . Loading the server cert. and key... ok
  . Bind on udp://*:2100/ ... ok
  . Setting up the SSL/TLS structure... ok
  . Waiting for a remote connection ...192.168.250.210 - - [03/Jan/2020 18:15:39] "GET /api/003b63a42e4d11ea9a8ad6bfcb9e0f40/lights HTTP/1.1" 200 -
192.168.250.210 - - [03/Jan/2020 18:15:39] "GET /api/003b63a42e4d11ea9a8ad6bfcb9e0f40/groups HTTP/1.1" 200 -
2020-01-03 18:15:40,018 - root - INFO - [
    {
        "success": {
            "/groups/2/stream": {
                "active": true
            }
        }
    }
]
 ok
  . Performing the SSL/TLS handshake... hello verification requested
  . Waiting for a remote connection ... ok
  . Performing the SSL/TLS handshake... ok
    [ Protocol is DTLSv1.2 ]
    [ Ciphersuite is TLS-PSK-WITH-AES-128-GCM-SHA256 ]
    [ Record expansion is 37 ]
    [ Maximum fragment length is 16384 ]
  . Verifying peer X.509 certificate... ok
2020-01-03 18:15:40,600 - root - INFO - NATIVE
2020-01-03 18:15:40,601 - root - INFO - LOOP
2020-01-03 18:15:40,602 - root - INFO - NATIVE
2020-01-03 18:15:40,603 - root - INFO - LOOP
2020-01-03 18:15:40,619 - root - INFO - NATIVE
2020-01-03 18:15:40,620 - root - INFO - LOOP
2020-01-03 18:15:40,638 - root - INFO - NATIVE
2020-01-03 18:15:40,640 - root - INFO - LOOP
2020-01-03 18:15:40,661 - root - INFO - NATIVE
2020-01-03 18:15:40,663 - root - INFO - LOOP
2020-01-03 18:15:40,668 - root - DEBUG - Sending M-Search response to 192.168.250.210
2020-01-03 18:15:40,688 - root - INFO - NATIVE
2020-01-03 18:15:40,690 - root - INFO - LOOP
2020-01-03 18:15:40,703 - root - INFO - NATIVE
2020-01-03 18:15:40,705 - root - INFO - LOOP
2020-01-03 18:15:40,724 - root - INFO - NATIVE
2020-01-03 18:15:40,725 - root - INFO - LOOP
2020-01-03 18:15:40,739 - root - INFO - NATIVE
2020-01-03 18:15:40,740 - root - INFO - LOOP
2020-01-03 18:15:40,763 - root - INFO - NATIVE
2020-01-03 18:15:40,765 - root - INFO - LOOP
2020-01-03 18:15:40,780 - root - INFO - NATIVE
2020-01-03 18:15:40,781 - root - INFO - LOOP
2020-01-03 18:15:40,813 - root - INFO - NATIVE
2020-01-03 18:15:40,814 - root - INFO - LOOP
2020-01-03 18:15:40,822 - root - INFO - NATIVE
2020-01-03 18:15:40,823 - root - INFO - LOOP
2020-01-03 18:15:40,844 - root - INFO - NATIVE
2020-01-03 18:15:40,845 - root - INFO - LOOP
2020-01-03 18:15:40,870 - root - INFO - NATIVE
2020-01-03 18:15:40,871 - root - INFO - LOOP
2020-01-03 18:15:40,880 - root - INFO - NATIVE
2020-01-03 18:15:40,882 - root - INFO - LOOP
2020-01-03 18:15:40,919 - root - INFO - NATIVE
2020-01-03 18:15:40,921 - root - INFO - LOOP
2020-01-03 18:15:40,928 - root - INFO - NATIVE
2020-01-03 18:15:40,929 - root - INFO - LOOP
2020-01-03 18:15:40,948 - root - INFO - NATIVE
2020-01-03 18:15:40,949 - root - INFO - LOOP
2020-01-03 18:15:40,980 - root - INFO - NATIVE
2020-01-03 18:15:40,981 - root - INFO - LOOP
2020-01-03 18:15:40,982 - root - INFO - NATIVE
2020-01-03 18:15:40,983 - root - INFO - LOOP
2020-01-03 18:15:41,007 - root - INFO - NATIVE
2020-01-03 18:15:41,008 - root - INFO - LOOP
2020-01-03 18:15:41,023 - root - INFO - NATIVE
2020-01-03 18:15:41,025 - root - INFO - LOOP
2020-01-03 18:15:41,059 - root - INFO - NATIVE
2020-01-03 18:15:41,060 - root - INFO - LOOP
2020-01-03 18:15:41,099 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:15:41,142 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 30
2020-01-03 18:15:41,166 - root - INFO - NATIVE
2020-01-03 18:15:41,167 - root - INFO - LOOP
2020-01-03 18:15:41,168 - root - INFO - NATIVE
2020-01-03 18:15:41,170 - root - INFO - LOOP
2020-01-03 18:15:41,170 - root - INFO - NATIVE
2020-01-03 18:15:41,171 - root - INFO - LOOP
2020-01-03 18:15:41,172 - root - INFO - NATIVE
2020-01-03 18:15:41,173 - root - INFO - LOOP
2020-01-03 18:15:41,174 - root - INFO - NATIVE
2020-01-03 18:15:41,175 - root - INFO - LOOP
2020-01-03 18:15:41,192 - root - INFO - NATIVE
2020-01-03 18:15:41,194 - root - INFO - LOOP
2020-01-03 18:15:41,194 - root - INFO - NATIVE
2020-01-03 18:15:41,196 - root - INFO - LOOP
2020-01-03 18:15:41,378 - root - INFO - NATIVE
2020-01-03 18:15:41,379 - root - INFO - LOOP
2020-01-03 18:15:41,391 - root - INFO - NATIVE
2020-01-03 18:15:41,392 - root - INFO - LOOP
2020-01-03 18:15:41,393 - root - INFO - NATIVE
2020-01-03 18:15:41,394 - root - INFO - LOOP
2020-01-03 18:15:41,395 - root - INFO - NATIVE
2020-01-03 18:15:41,396 - root - INFO - LOOP
2020-01-03 18:15:41,396 - root - INFO - NATIVE
2020-01-03 18:15:41,397 - root - INFO - LOOP
2020-01-03 18:15:41,398 - root - INFO - NATIVE
2020-01-03 18:15:41,399 - root - INFO - LOOP
2020-01-03 18:15:41,399 - root - INFO - NATIVE
2020-01-03 18:15:41,400 - root - INFO - LOOP
2020-01-03 18:15:41,401 - root - INFO - NATIVE
2020-01-03 18:15:41,402 - root - INFO - LOOP
2020-01-03 18:15:41,402 - root - INFO - NATIVE
2020-01-03 18:15:41,403 - root - INFO - LOOP
2020-01-03 18:15:41,415 - root - INFO - NATIVE
2020-01-03 18:15:41,416 - root - INFO - LOOP
2020-01-03 18:15:41,417 - root - INFO - NATIVE
2020-01-03 18:15:41,418 - root - INFO - LOOP
2020-01-03 18:15:41,471 - root - INFO - NATIVE
2020-01-03 18:15:41,473 - root - INFO - LOOP
2020-01-03 18:15:41,474 - root - INFO - NATIVE
2020-01-03 18:15:41,475 - root - INFO - LOOP
2020-01-03 18:15:41,495 - root - INFO - NATIVE
2020-01-03 18:15:41,496 - root - INFO - LOOP
2020-01-03 18:15:41,497 - root - INFO - NATIVE
2020-01-03 18:15:41,499 - root - INFO - LOOP
2020-01-03 18:15:41,526 - root - INFO - NATIVE
2020-01-03 18:15:41,527 - root - INFO - LOOP
2020-01-03 18:15:41,539 - root - INFO - NATIVE
2020-01-03 18:15:41,541 - root - INFO - LOOP
2020-01-03 18:15:41,570 - root - INFO - NATIVE
2020-01-03 18:15:41,572 - root - INFO - LOOP
2020-01-03 18:15:41,592 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
192.168.250.210 - - [03/Jan/2020 18:15:41] "GET /api/003b63a42e4d11ea9a8ad6bfcb9e0f40/lights HTTP/1.1" 200 -
2020-01-03 18:15:41,878 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 30
2020-01-03 18:15:41,896 - root - INFO - NATIVE
2020-01-03 18:15:41,898 - root - INFO - LOOP
2020-01-03 18:15:41,899 - root - INFO - NATIVE
2020-01-03 18:15:41,900 - root - INFO - LOOP
2020-01-03 18:15:41,901 - root - INFO - NATIVE
2020-01-03 18:15:41,902 - root - INFO - LOOP
2020-01-03 18:15:41,903 - root - INFO - NATIVE
2020-01-03 18:15:41,903 - root - INFO - LOOP
2020-01-03 18:15:41,904 - root - INFO - NATIVE
2020-01-03 18:15:41,905 - root - INFO - LOOP
2020-01-03 18:15:41,905 - root - INFO - NATIVE
2020-01-03 18:15:41,906 - root - INFO - LOOP
2020-01-03 18:15:41,907 - root - INFO - NATIVE
2020-01-03 18:15:41,908 - root - INFO - LOOP
2020-01-03 18:15:41,908 - root - INFO - NATIVE
2020-01-03 18:15:41,909 - root - INFO - LOOP
2020-01-03 18:15:41,909 - root - INFO - NATIVE
2020-01-03 18:15:41,910 - root - INFO - LOOP
2020-01-03 18:15:41,911 - root - INFO - NATIVE
2020-01-03 18:15:41,911 - root - INFO - LOOP
2020-01-03 18:15:41,912 - root - INFO - NATIVE
2020-01-03 18:15:41,913 - root - INFO - LOOP
2020-01-03 18:15:41,914 - root - INFO - NATIVE
2020-01-03 18:15:41,914 - root - INFO - LOOP
2020-01-03 18:15:41,915 - root - INFO - NATIVE
2020-01-03 18:15:41,916 - root - INFO - LOOP
2020-01-03 18:15:41,916 - root - INFO - NATIVE
2020-01-03 18:15:41,917 - root - INFO - LOOP
2020-01-03 18:15:41,918 - root - INFO - NATIVE
2020-01-03 18:15:41,918 - root - INFO - LOOP
2020-01-03 18:15:41,919 - root - INFO - NATIVE
2020-01-03 18:15:41,920 - root - INFO - LOOP
2020-01-03 18:15:41,920 - root - INFO - NATIVE
2020-01-03 18:15:41,921 - root - INFO - LOOP
2020-01-03 18:15:41,924 - root - INFO - NATIVE
2020-01-03 18:15:41,925 - root - INFO - LOOP
192.168.250.210 - - [03/Jan/2020 18:15:41] "GET /api/003b63a42e4d11ea9a8ad6bfcb9e0f40/groups HTTP/1.1" 200 -
2020-01-03 18:15:41,956 - root - INFO - NATIVE
2020-01-03 18:15:41,957 - root - INFO - LOOP
2020-01-03 18:15:41,970 - root - INFO - NATIVE
2020-01-03 18:15:41,971 - root - INFO - LOOP
2020-01-03 18:15:42,006 - root - INFO - NATIVE
2020-01-03 18:15:42,007 - root - INFO - LOOP
2020-01-03 18:15:42,008 - root - INFO - NATIVE
2020-01-03 18:15:42,010 - root - INFO - LOOP
2020-01-03 18:15:42,023 - root - INFO - NATIVE
2020-01-03 18:15:42,024 - root - INFO - LOOP
2020-01-03 18:15:42,045 - root - INFO - NATIVE
2020-01-03 18:15:42,047 - root - INFO - LOOP
2020-01-03 18:15:42,067 - root - INFO - NATIVE
2020-01-03 18:15:42,069 - root - INFO - LOOP
2020-01-03 18:15:42,072 - root - DEBUG - Sending M-Search response to 192.168.250.210
2020-01-03 18:15:42,091 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:15:42,110 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 45
2020-01-03 18:15:42,114 - root - INFO - NATIVE
2020-01-03 18:15:42,115 - root - INFO - LOOP
2020-01-03 18:15:42,116 - root - INFO - NATIVE
2020-01-03 18:15:42,117 - root - INFO - LOOP
2020-01-03 18:15:42,156 - root - INFO - NATIVE
2020-01-03 18:15:42,157 - root - INFO - LOOP
2020-01-03 18:15:42,158 - root - INFO - NATIVE
2020-01-03 18:15:42,159 - root - INFO - LOOP
2020-01-03 18:15:42,192 - root - INFO - NATIVE
2020-01-03 18:15:42,193 - root - INFO - LOOP
2020-01-03 18:15:42,194 - root - INFO - NATIVE
2020-01-03 18:15:42,195 - root - INFO - LOOP
2020-01-03 18:15:42,211 - root - INFO - NATIVE
2020-01-03 18:15:42,212 - root - INFO - LOOP
2020-01-03 18:15:42,250 - root - INFO - NATIVE
2020-01-03 18:15:42,251 - root - INFO - LOOP
2020-01-03 18:15:42,252 - root - INFO - NATIVE
2020-01-03 18:15:42,254 - root - INFO - LOOP
2020-01-03 18:15:42,271 - root - INFO - NATIVE
2020-01-03 18:15:42,273 - root - INFO - LOOP
2020-01-03 18:15:42,289 - root - INFO - NATIVE
2020-01-03 18:15:42,290 - root - INFO - LOOP
2020-01-03 18:15:42,309 - root - INFO - NATIVE

Now, this is how it looks when the TV activates the Entertainment API:


192.168.250.171 - - [03/Jan/2020 18:16:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:17] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
2020-01-03 18:16:18,269 - root - INFO - LOOP
192.168.250.171 - - [03/Jan/2020 18:16:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:18] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
192.168.250.171 - - [03/Jan/2020 18:16:18] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
2020-01-03 18:16:18,563 - root - INFO - in PUT method
2020-01-03 18:16:18,564 - root - INFO - /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2
2020-01-03 18:16:18,565 - root - INFO - b'{"stream":{"active":false}}'
2020-01-03 18:16:18,565 - root - INFO - stop hue entertainent
killall: entertain-srv: no process killed
2020-01-03 18:16:18,844 - root - DEBUG - Sending M-Search response to 192.168.250.252
2020-01-03 18:16:18,874 - root - INFO - [
    {
        "success": {
            "/groups/2/stream": {
                "active": false
            }
        }
    }
]
192.168.250.171 - - [03/Jan/2020 18:16:18] "PUT /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2 HTTP/1.1" 200 -
2020-01-03 18:16:18,883 - root - INFO - in PUT method
2020-01-03 18:16:18,884 - root - INFO - /api/4d0788ba2bd311ea9247d6bfcb9e0f40/groups/2
2020-01-03 18:16:18,884 - root - INFO - b'{"stream":{"active":true}}'
2020-01-03 18:16:18,885 - root - INFO - start hue entertainment

  . Seeding the random number generator... ok
  . Loading the CA root certificate ... ok (0 skipped)
  . Loading the server cert. and key... ok
  . Bind on udp://*:2100/ ... ok
  . Setting up the SSL/TLS structure... ok
  . Waiting for a remote connection ...2020-01-03 18:16:19,288 - root - INFO - sync with lights
2020-01-03 18:16:19,301 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
 ok
  . Performing the SSL/TLS handshake... hello verification requested
  . Waiting for a remote connection ... ok
  . Performing the SSL/TLS handshake... ok
    [ Protocol is DTLSv1.2 ]
    [ Ciphersuite is TLS-PSK-WITH-AES-128-GCM-SHA256 ]
    [ Record expansion is 37 ]
    [ Maximum fragment length is 16384 ]
  . Verifying peer X.509 certificate... ok
2020-01-03 18:16:19,393 - root - INFO - [
    {
        "success": {
            "/groups/2/stream": {
                "active": true
            }
        }
    }
]
2020-01-03 18:16:19,411 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:19,790 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "GET /state HTTP/1.1" 200 90
2020-01-03 18:16:19,817 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:19,833 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:19,852 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:19,865 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:19,887 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:19,896 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:19,930 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:19,943 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:19,979 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:19,997 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:20,061 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:20,078 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:20,164 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:20,202 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:20,270 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:20,281 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:20,323 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:20,367 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:20,431 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:20,465 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:20,501 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:20,517 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,314 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,339 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,356 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,367 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,384 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,393 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,419 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,439 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,477 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,489 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,516 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,525 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,552 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,562 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,599 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,612 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,644 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,669 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,705 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,720 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,752 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,773 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,818 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,834 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,908 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,921 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,939 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,953 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:21,971 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:21,990 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,033 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,069 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,127 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,138 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,182 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,194 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,243 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,259 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,325 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,354 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
192.168.250.171 - - [03/Jan/2020 18:16:22] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
2020-01-03 18:16:22,393 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,446 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,778 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,810 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,828 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,837 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,857 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,866 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,899 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,914 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,940 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,950 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:22,970 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:22,992 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:23,043 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:23,063 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:23,135 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:23,165 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:23,208 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:23,218 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:23,258 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:23,291 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:23,326 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:23,344 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:23,412 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:23,422 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:23,478 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:23,524 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:23,583 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:23,594 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:23,635 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:23,669 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:23,768 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:23,969 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:24,113 - urllib3.connectionpool - DEBUG - Starting new HTTPS connection (1): discovery.diyhue.org:443
2020-01-03 18:16:24,313 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:24,329 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:24,357 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:24,370 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:24,372 - urllib3.connectionpool - DEBUG - https://discovery.diyhue.org:443 "POST / HTTP/1.1" 200 6
{"ok"}
2020-01-03 18:16:24,401 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:24,412 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:24,435 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:24,445 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:24,478 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:24,496 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:24,537 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:24,554 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:24,635 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:24,651 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:24,712 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:24,745 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:24,765 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:24,788 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,004 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,242 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,336 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,365 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,382 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,389 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
192.168.250.171 - - [03/Jan/2020 18:16:25] "GET /api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1 HTTP/1.1" 200 -
2020-01-03 18:16:25,408 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,416 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,439 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,450 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,487 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,503 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,541 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,573 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,626 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,677 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,729 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,756 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,814 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,830 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,906 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:25,923 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:25,979 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,002 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:26,033 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,079 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:26,130 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,150 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:26,167 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,174 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:26,188 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,195 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:26,238 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,268 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:26,321 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,449 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:26,782 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,821 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:26,852 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,870 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:26,921 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,940 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:26,966 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:26,993 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,055 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,072 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,122 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,151 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,167 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,177 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,194 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,202 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,251 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,296 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,396 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,410 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,474 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,488 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,543 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,560 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,634 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,646 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,694 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,729 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80
2020-01-03 18:16:27,793 - urllib3.connectionpool - DEBUG - http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26
2020-01-03 18:16:27,811 - urllib3.connectionpool - DEBUG - Starting new HTTP connection (1): 192.168.250.231:80

The amount of "PUT" requests seems to be stalling the Entertainment.py thread or for whatever reason that thread just silently crashes or something like that. Any idea what could be going on here @mariusmotea?

mariusmotea commented 4 years ago

I arrived home and i paired a color Tradfri bulb to the hue bridge. I don't see anything related to entertainment in capabilities. I

In your last output i think you changed something in the config and the bulb is controlled using the http protocol instead of native udp. For this reason i think the bridge is overloaded. You don't get the same output also if you use Hue Sync ?

rkkoszewski commented 4 years ago

@mariusmotea Tradfri bulb? Are those supported by the original Philips Hue Bridge? Or are you talking about DiyHue?

I don't know the way DiyHue discovers the bulb capabilities, but if it's hardcoded then it won't appear of course. I added that line to the capabilities as per the comment https://github.com/diyhue/diyHue/issues/318#issuecomment-570595110

With that added my TV started streaming the colors via the entertainment api. At least now the TV is able to start the entertain-srv, and connect to it (I can see it both in DiyHue and the TV Android Logs). In the TV Android logs I don't see that it is doing any Get requests other than ocasional calls (every 3 to 5 seconds) to http://192.168.250.230/api/4d0788ba2bd311ea9247d6bfcb9e0f40/lights/1.

From the logs you can also see it is DiyHue sending "http://192.168.250.231:80 "PUT /state HTTP/1.1" 200 26", but there are barely any Get or Put requests from the TV (.171).

To clarify: .230 is DiyHue .231 is the ESP01 Light .171 is the Philips TV

From the logs it looks like the TV is able to communicate with DiyHue via the Entertainment Api but rather than sending from DiyHue to the ESP01 via UDP (Which works with Hue Stream perfectly fine), it tries to bombard the ESP01 with HTTP/PUT requests.

Is that even possible?

mariusmotea commented 4 years ago

@rkkoszewski i did not know you added that line. Probably i need to add one genuine Philips bulb.

can you show me http://192.168.250/detect output and what had this bulb in config.json => "lights_address" ?

For your reference please look at following lines:

https://github.com/diyhue/diyHue/blob/master/BridgeEmulator/functions/entertainment.py#L35 (for RGB mode)

and:

https://github.com/diyhue/diyHue/blob/master/BridgeEmulator/functions/entertainment.py#L75 (for CIE color mode)

if protocol is "native", "native_multi" or "native_single" it must use UDP protocol to send data not HTTP. Do you use firmware from this repo for your ESP light?

GitHub
diyhue/diyHue
Main diyHue software repo. Contribute to diyhue/diyHue development by creating an account on GitHub.
GitHub
diyhue/diyHue
Main diyHue software repo. Contribute to diyhue/diyHue development by creating an account on GitHub.
rkkoszewski commented 4 years ago

The output of "detect" is:

{"name":"JekoPainting","protocol":"native_single","modelid":"LCT015","type":"rgb","mac":"60:01:94:7A:91:00","version":3} As the firmware I'm using a slightly modified version of "Generic_RGB_Light", where instead of using the analog output I'm controlling a P9813 by using FastLED. (P9813_ledstrip_chainable did not have the UDP media api support).

From the config.json

"lights": {
        "1": {
            "capabilities": {
                "certified": true,
                "control": {
                    "colorgamut": [
                        [
                            0.6915,
                            0.30830000000000002
                        ],
                        [
                            0.17000000000000001,
                            0.69999999999999996
                        ],
                        [
                            0.1532,
                            0.047500000000000001
                        ]
                    ],
                    "colorgamuttype": "C",
                    "ct": {
                        "max": 500,
                        "min": 153
                    },
                    "maxlumen": 800,
                    "mindimlevel": 1000
                },
                "streaming": {
                    "proxy": true,
                    "renderer": true
                }
            },
            "config": {
                "archetype": "sultanbulb",
                "direction": "omnidirectional",
                "function": "mixed",
                "startup": {
                    "configured": false,
                    "mode": "safety"
                }
            },
            "manufacturername": "Philips",
            "modelid": "LCT015",
            "name": "JekoPainting",
            "productname": "Hue color lamp",
            "state": {
                "alert": "none",
                "bri": 183,
                "colormode": "xy",
                "ct": 447,
                "effect": "none",
                "hue": 0,
                "mode": "homeautomation",
                "on": true,
                "reachable": true,
                "sat": 254,
                "transitiontime": 5,
                "xy": [
                    0.40613412680247196,
                    0.39688715953307391
                ]
            },
            "swversion": "1.46.13_r26312",
            "type": "Extended color light",
            "uniqueid": "00:17:88:01:00:65:d6:05-0b"
        }
    },
    "lights_address": {
        "1": {
            "ip": "192.168.250.231:80",
            "light_nr": 1,
            "mac": "60:01:94:7A:91:00",
            "protocol": "native_single"
        }
    },

Is there some way to debug the entertain-srv? To see somehow what it is receiving or sending out?

What I find curious is that you can see a lot of requests to the bulb (.231), but barely any from the TV (.171).

mariusmotea commented 4 years ago

entertain-srv is used just for dtls decryption as the python dtls library don't support the entertainment protocol cipher. Once it receive traffic it send the traffic in raw mode to entertainment udp server from hue emulator dedicated for entertainment. So is just a proxy that do dtls offloading. I think the issue may be caused by some changes you applied. For sure the issue is caused by huge number of HTTP PUT requests sent to the bulb when normally must be used UDP protocol. This is what you need to debug, why is going on HTTP way instead of UDP way. Reading the code i don't see any possible cause.

rkkoszewski commented 4 years ago

@mariusmotea I haven't made any other changes than what can be seen in https://github.com/diyhue/diyHue/commit/2be6638bb67fc988934c9f13790e9e5cd900bfdb

The LOOP and NATIVE lines that can be seen in the log have been added later to the code (To debug why it is failing, but it was doing the PUT request before that change). The only change there was that I just print those lines for debugging, but no other changes have been performed.

Not sure where to search for the cause anymore.

mariusmotea commented 4 years ago

Use print lines to understand what is happening. For example i will try to understand why is not using UDP with following print lines:

https://github.com/diyhue/diyHue/blob/master/BridgeEmulator/functions/entertainment.py#L35

                                print("light protocol is " + addresses[str(lightId)]["protocol"] )
                                if addresses[str(lightId)]["protocol"] in ["native", "native_multi", "native_single"]:
                                    print("match first if condition")
                                    if addresses[str(lightId)]["ip"] not in nativeLights:
                                        print("is not in nativeLights)
                                        nativeLights[addresses[str(lightId)]["ip"]] = {}

add at the end of the loop print("#####-------------------------------------------------#####" so you can understand better where the frame begin and ends .

GitHub
diyhue/diyHue
Main diyHue software repo. Contribute to diyhue/diyHue development by creating an account on GitHub.
stale[bot] commented 4 years ago

This issue has been automatically marked as stale because it has not had recent activity. It will be closed in 2 days if no further activity occurs. Thank you for your contributions.

stale[bot] commented 4 years ago

This issue has been automatically closed as it has not had any recent activity. Thank you for your contributions.