vdcd and zigbee server (like lightify/hue/deconz)
122 views
Skip to first unread message
thomas r. hollensteiner
Aug 14, 2015, 8:11:28 AM8/14/15
to plan44_vdcd
hello,
so i marked a line red where a delay was - if i read the output correct - but have no idea what took so long - just the logon to deconz?
i use the p44 to connect to a DECONZ server - i have there apx. 70 zigbee devices and each device is replicated to dss - so far so good but i have rooms (like bath) with 19 devices (spots) and on using the dss app to turn room on the max. devices switched on are 14 so through dss i miss 5 lights to come on (see the log 'bad-7' f.ex)
i only have this issue through dss (vdcd) because if i use the webapi from deconz i get always all lights on/off...
i believe it would be a better approach to have the vdcd to put zigbee groups as a single device to dss and make the secens available for this groups - maybe choosable by a switch because of call 19x times the zigbee server you can just do one single call to turn on/off or call a scene - this would increase the response time a lot and the effect that lights are coming on like on a airport would be complete avoided.
here is an example log for tuning on my bathroom lights:
Code hier eingeben...========== changed log level from 5 to 7 ===============[2015-08-14 10:47:45.477] Config API response: { "result": 7 }[2015-08-14 10:47:45.477] Client connection terminated (now 0 connections)[2015-08-14 10:48:05.588] vdSM -> vDC (pbuf) notification received: method='ping', params={ dSUID:"E5C30F7708155DEAC0C35650A7DF63B800" }[2015-08-14 10:48:05.589] ping to vDChost E5C30F7708155DEAC0C35650A7DF63B800 -> checking presence...[2015-08-14 10:48:05.589] ping: vDChost E5C30F7708155DEAC0C35650A7DF63B800 is present -> sending pong[2015-08-14 10:48:05.590] vdSM <- vDC (pbuf) notification sent: method='pong', params={ dSUID:E5C30F7708155DEAC0C35650A7DF63B800 }[2015-08-14 10:48:11.269] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "166005009E4050AEC0033DD65F4DC60600" ], force:false, scene:5 }[2015-08-14 10:48:11.269] CallScene(5) (non-dimming!) in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte):[2015-08-14 10:48:11.269] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.269] requestUpdatingChannels entered in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)[2015-08-14 10:48:11.270] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)[2015-08-14 10:48:11.270] +++++ Serializer watchdog started for update with ticket #3937[2015-08-14 10:48:11.270]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/43: [2015-08-14 10:48:11.271] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "91D3B29D9BEE5874C0F268E6CCAAF6A000" ], force:false, scene:5 }[2015-08-14 10:48:11.271] CallScene(5) (non-dimming!) in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links):[2015-08-14 10:48:11.271] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.271] requestUpdatingChannels entered in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links)[2015-08-14 10:48:11.272] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links)[2015-08-14 10:48:11.272] +++++ Serializer watchdog started for update with ticket #3939[2015-08-14 10:48:11.272] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "D6B79F585C76554CC0BB64F6C5A73E6500" ], force:false, scene:5 }[2015-08-14 10:48:11.273] CallScene(5) (non-dimming!) in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1):[2015-08-14 10:48:11.273] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.273] requestUpdatingChannels entered in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1)[2015-08-14 10:48:11.273] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1)[2015-08-14 10:48:11.273] +++++ Serializer watchdog started for update with ticket #3941[2015-08-14 10:48:11.274] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "6F7761C68F6A53A4C09B5FAB8596ABF100" ], force:false, scene:5 }[2015-08-14 10:48:11.274] CallScene(5) (non-dimming!) in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6):[2015-08-14 10:48:11.274] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.274] requestUpdatingChannels entered in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6)[2015-08-14 10:48:11.274] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6)[2015-08-14 10:48:11.275] +++++ Serializer watchdog started for update with ticket #3943[2015-08-14 10:48:11.275] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "3A2E1ED4F10B5B73C0B40E029D5E92C700" ], force:false, scene:5 }[2015-08-14 10:48:11.275] CallScene(5) (non-dimming!) in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang):[2015-08-14 10:48:11.276] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.276] requestUpdatingChannels entered in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang)[2015-08-14 10:48:11.276] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang)[2015-08-14 10:48:11.276] +++++ Serializer watchdog started for update with ticket #3945[2015-08-14 10:48:11.277] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "185E7743EDFD5F86800599DDF555AD9E00" ], force:false, scene:5 }[2015-08-14 10:48:11.277] CallScene(5) (non-dimming!) in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links):[2015-08-14 10:48:11.277] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.277] requestUpdatingChannels entered in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links)[2015-08-14 10:48:11.277] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links)[2015-08-14 10:48:11.278] +++++ Serializer watchdog started for update with ticket #3947[2015-08-14 10:48:11.278] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "C248342D5EC7533880E26FF9FA9C26DA00" ], force:false, scene:5 }[2015-08-14 10:48:11.278] CallScene(5) (non-dimming!) in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9):[2015-08-14 10:48:11.278] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.279] requestUpdatingChannels entered in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9)[2015-08-14 10:48:11.279] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9)[2015-08-14 10:48:11.279] +++++ Serializer watchdog started for update with ticket #3949[2015-08-14 10:48:11.279] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "AA56487AD6775D8880BA98024A5DD49C00" ], force:false, scene:5 }[2015-08-14 10:48:11.280] CallScene(5) (non-dimming!) in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5):[2015-08-14 10:48:11.280] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.280] requestUpdatingChannels entered in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5)[2015-08-14 10:48:11.280] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5)[2015-08-14 10:48:11.280] +++++ Serializer watchdog started for update with ticket #3951[2015-08-14 10:48:11.281] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "8C6389F789605E5980BAC4C76078F6FB00" ], force:false, scene:5 }[2015-08-14 10:48:11.281] CallScene(5) (non-dimming!) in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links):[2015-08-14 10:48:11.281] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.281] requestUpdatingChannels entered in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links)[2015-08-14 10:48:11.282] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links)[2015-08-14 10:48:11.282] +++++ Serializer watchdog started for update with ticket #3953[2015-08-14 10:48:11.282] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "3E6FABEE25335712C00AE7750A27C9A300" ], force:false, scene:5 }[2015-08-14 10:48:11.282] CallScene(5) (non-dimming!) in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts):[2015-08-14 10:48:11.282] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.283] requestUpdatingChannels entered in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts)[2015-08-14 10:48:11.283] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts)[2015-08-14 10:48:11.283] +++++ Serializer watchdog started for update with ticket #3955[2015-08-14 10:48:11.283] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "8BDC4A7ECFF8531FC0EAE2302788B49A00" ], force:false, scene:5 }[2015-08-14 10:48:11.284] CallScene(5) (non-dimming!) in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10):[2015-08-14 10:48:11.284] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.284] requestUpdatingChannels entered in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10)[2015-08-14 10:48:11.284] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10)[2015-08-14 10:48:11.284] +++++ Serializer watchdog started for update with ticket #3957[2015-08-14 10:48:11.285] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "2A1C4C27EE685556C093DE08454A661700" ], force:false, scene:5 }[2015-08-14 10:48:11.285] CallScene(5) (non-dimming!) in device 2A1C4C27EE685556C093DE08454A661700 (bad-2):[2015-08-14 10:48:11.285] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.285] requestUpdatingChannels entered in device 2A1C4C27EE685556C093DE08454A661700 (bad-2)[2015-08-14 10:48:11.286] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 2A1C4C27EE685556C093DE08454A661700 (bad-2)[2015-08-14 10:48:11.286] +++++ Serializer watchdog started for update with ticket #3959[2015-08-14 10:48:11.286] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "B1800406481E55C5C07C43547D4D0C4700" ], force:false, scene:5 }[2015-08-14 10:48:11.286] CallScene(5) (non-dimming!) in device B1800406481E55C5C07C43547D4D0C4700 (bad-8):[2015-08-14 10:48:11.286] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.287] requestUpdatingChannels entered in device B1800406481E55C5C07C43547D4D0C4700 (bad-8)[2015-08-14 10:48:11.287] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device B1800406481E55C5C07C43547D4D0C4700 (bad-8)[2015-08-14 10:48:11.287] +++++ Serializer watchdog started for update with ticket #3961[2015-08-14 10:48:11.287] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "33E1DED83EB75A62C0F108D03D33927900" ], force:false, scene:5 }[2015-08-14 10:48:11.288] CallScene(5) (non-dimming!) in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4):[2015-08-14 10:48:11.288] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.288] requestUpdatingChannels entered in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4)[2015-08-14 10:48:11.288] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4)[2015-08-14 10:48:11.288] +++++ Serializer watchdog started for update with ticket #3963[2015-08-14 10:48:11.289] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "87A9F0F76D4F5A9780CC7F4331F8B66D00" ], force:false, scene:5 }[2015-08-14 10:48:11.289] CallScene(5) (non-dimming!) in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte):[2015-08-14 10:48:11.289] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.289] requestUpdatingChannels entered in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte)[2015-08-14 10:48:11.289] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte)[2015-08-14 10:48:11.290] +++++ Serializer watchdog started for update with ticket #3965[2015-08-14 10:48:11.290] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "431E66B8F7E451B380CAC4A840F26BC500" ], force:false, scene:5 }[2015-08-14 10:48:11.290] CallScene(5) (non-dimming!) in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7):[2015-08-14 10:48:11.290] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.291] requestUpdatingChannels entered in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7)[2015-08-14 10:48:11.291] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7)[2015-08-14 10:48:11.291] +++++ Serializer watchdog started for update with ticket #3967[2015-08-14 10:48:11.291] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "F5A5039D23C75A4BC03CCC5265BC202300" ], force:false, scene:5 }[2015-08-14 10:48:11.292] CallScene(5) (non-dimming!) in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte):[2015-08-14 10:48:11.292] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.292] requestUpdatingChannels entered in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte)[2015-08-14 10:48:11.292] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte)[2015-08-14 10:48:11.292] +++++ Serializer watchdog started for update with ticket #3969[2015-08-14 10:48:11.293] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "FABF236F7CE750FEC09DC6B3D99480B800" ], force:false, scene:5 }[2015-08-14 10:48:11.293] CallScene(5) (non-dimming!) in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3):[2015-08-14 10:48:11.293] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.293] requestUpdatingChannels entered in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3)[2015-08-14 10:48:11.293] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3)[2015-08-14 10:48:11.294] +++++ Serializer watchdog started for update with ticket #3971[2015-08-14 10:48:11.294] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "917E73D48704500FC0E8CF788591F0E900" ], force:false, scene:5 }[2015-08-14 10:48:11.294] CallScene(5) (non-dimming!) in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc):[2015-08-14 10:48:11.294] - effective normalized scene to apply to output is 5, dontCare=0[2015-08-14 10:48:11.295] requestUpdatingChannels entered in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc)[2015-08-14 10:48:11.295] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc)[2015-08-14 10:48:11.295] +++++ Serializer watchdog started for update with ticket #3973[2015-08-14 10:48:11.296]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-dusche-mitte","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:4A:62-03"}[2015-08-14 10:48:11.296] Channel 'brightness' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.297] Channel 'color temperature' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.297] ----- Serializer watchdog ticket #3937 cancelled - update complete[2015-08-14 10:48:11.297] endUpdatingChannels in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte) (while actually waiting for these updates!)[2015-08-14 10:48:11.297] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.297] Channel 'brightness' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.298] Channel 'color temperature' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.298] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.298] requestApplyingChannels entered in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)[2015-08-14 10:48:11.298] - ready, calling applyChannelValues() in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)[2015-08-14 10:48:11.298] +++++ Serializer watchdog started for apply with ticket #3974[2015-08-14 10:48:11.299] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.299] hue device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.299] - color mode color temperature: mired=370[2015-08-14 10:48:11.299]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/46: [2015-08-14 10:48:11.300] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.300] checkForReapply in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte) - missed 0 apply attempts in between[2015-08-14 10:48:11.305]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-wanne-links","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:00:1B:B2-03"}[2015-08-14 10:48:11.306] Channel 'brightness' in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.306] Channel 'color temperature' in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.306] ----- Serializer watchdog ticket #3939 cancelled - update complete[2015-08-14 10:48:11.306] endUpdatingChannels in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links) (while actually waiting for these updates!)[2015-08-14 10:48:11.307] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.307] Channel 'brightness' in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.307] Channel 'color temperature' in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.307] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.307] requestApplyingChannels entered in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links)[2015-08-14 10:48:11.308] - ready, calling applyChannelValues() in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links)[2015-08-14 10:48:11.308] +++++ Serializer watchdog started for apply with ticket #3975[2015-08-14 10:48:11.308] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.309] hue device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.309] - color mode color temperature: mired=370[2015-08-14 10:48:11.309]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/36: [2015-08-14 10:48:11.309] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.309] checkForReapply in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links) - missed 0 apply attempts in between[2015-08-14 10:48:11.314]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-1","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.07","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:4A:8F-03"}[2015-08-14 10:48:11.315] Channel 'brightness' in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.315] Channel 'color temperature' in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.315] ----- Serializer watchdog ticket #3941 cancelled - update complete[2015-08-14 10:48:11.316] endUpdatingChannels in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1) (while actually waiting for these updates!)[2015-08-14 10:48:11.316] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.316] Channel 'brightness' in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.316] Channel 'color temperature' in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.317] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.317] requestApplyingChannels entered in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1)[2015-08-14 10:48:11.317] - ready, calling applyChannelValues() in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1)[2015-08-14 10:48:11.317] +++++ Serializer watchdog started for apply with ticket #3976[2015-08-14 10:48:11.317] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.318] hue device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.318] - color mode color temperature: mired=370[2015-08-14 10:48:11.318]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/49: [2015-08-14 10:48:11.318] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.319] checkForReapply in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1) - missed 0 apply attempts in between[2015-08-14 10:48:11.324]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-6","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:55:C4-03"}[2015-08-14 10:48:11.324] Channel 'brightness' in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.325] Channel 'color temperature' in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.325] ----- Serializer watchdog ticket #3943 cancelled - update complete[2015-08-14 10:48:11.325] endUpdatingChannels in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6) (while actually waiting for these updates!)[2015-08-14 10:48:11.325] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.325] Channel 'brightness' in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.326] Channel 'color temperature' in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.326] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.326] requestApplyingChannels entered in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6)[2015-08-14 10:48:11.326] - ready, calling applyChannelValues() in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6)[2015-08-14 10:48:11.326] +++++ Serializer watchdog started for apply with ticket #3977[2015-08-14 10:48:11.327] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.327] hue device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.327] - color mode color temperature: mired=370[2015-08-14 10:48:11.327]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/42: [2015-08-14 10:48:11.328] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.328] checkForReapply in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6) - missed 0 apply attempts in between[2015-08-14 10:48:11.333]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-dusche-eingang","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0,0]},"swversion":"V1.03.07","type":"Color temperature light","uniqueid":"84:18:26:00:00:D9:9A:BE-03"}[2015-08-14 10:48:11.333] Channel 'brightness' in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.334] Channel 'color temperature' in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.334] ----- Serializer watchdog ticket #3945 cancelled - update complete[2015-08-14 10:48:11.334] endUpdatingChannels in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang) (while actually waiting for these updates!)[2015-08-14 10:48:11.334] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.335] Channel 'brightness' in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.335] Channel 'color temperature' in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.335] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.335] requestApplyingChannels entered in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang)[2015-08-14 10:48:11.335] - ready, calling applyChannelValues() in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang)[2015-08-14 10:48:11.336] +++++ Serializer watchdog started for apply with ticket #3978[2015-08-14 10:48:11.336] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.336] hue device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.336] - color mode color temperature: mired=370[2015-08-14 10:48:11.336]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/37: [2015-08-14 10:48:11.337] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.337] checkForReapply in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang) - missed 0 apply attempts in between[2015-08-14 10:48:11.343]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-wanne-links","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:57:2E-03"}[2015-08-14 10:48:11.343] Channel 'brightness' in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.343] Channel 'color temperature' in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.343] ----- Serializer watchdog ticket #3947 cancelled - update complete[2015-08-14 10:48:11.344] endUpdatingChannels in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links) (while actually waiting for these updates!)[2015-08-14 10:48:11.344] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.344] Channel 'brightness' in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.344] Channel 'color temperature' in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.345] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.345] requestApplyingChannels entered in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links)[2015-08-14 10:48:11.345] - ready, calling applyChannelValues() in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links)[2015-08-14 10:48:11.345] +++++ Serializer watchdog started for apply with ticket #3979[2015-08-14 10:48:11.345] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.346] hue device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.346] - color mode color temperature: mired=370[2015-08-14 10:48:11.346]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/40: [2015-08-14 10:48:11.346] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.347] checkForReapply in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links) - missed 0 apply attempts in between[2015-08-14 10:48:11.353]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-9","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0,0]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:54:C6-03"}[2015-08-14 10:48:11.353] Channel 'brightness' in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.353] Channel 'color temperature' in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.353] ----- Serializer watchdog ticket #3949 cancelled - update complete[2015-08-14 10:48:11.353] endUpdatingChannels in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9) (while actually waiting for these updates!)[2015-08-14 10:48:11.354] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.354] Channel 'brightness' in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.354] Channel 'color temperature' in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.354] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.354] requestApplyingChannels entered in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9)[2015-08-14 10:48:11.355] - ready, calling applyChannelValues() in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9)[2015-08-14 10:48:11.355] +++++ Serializer watchdog started for apply with ticket #3980[2015-08-14 10:48:11.355] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.355] hue device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.355] - color mode color temperature: mired=370[2015-08-14 10:48:11.356]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/45: [2015-08-14 10:48:11.356] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.356] checkForReapply in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9) - missed 0 apply attempts in between[2015-08-14 10:48:11.362]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-5","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0,0]},"swversion":"V1.03.07","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:49:C4-03"}[2015-08-14 10:48:11.362] Channel 'brightness' in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.362] Channel 'color temperature' in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.363] ----- Serializer watchdog ticket #3951 cancelled - update complete[2015-08-14 10:48:11.363] endUpdatingChannels in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5) (while actually waiting for these updates!)[2015-08-14 10:48:11.363] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.363] Channel 'brightness' in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.363] Channel 'color temperature' in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.364] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.364] requestApplyingChannels entered in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5)[2015-08-14 10:48:11.364] - ready, calling applyChannelValues() in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5)[2015-08-14 10:48:11.364] +++++ Serializer watchdog started for apply with ticket #3981[2015-08-14 10:48:11.364] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.365] hue device AA56487AD6775D8880BA98024A5DD49C00 (bad-5): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.365] - color mode color temperature: mired=370[2015-08-14 10:48:11.365]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/39: [2015-08-14 10:48:11.365] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.366] checkForReapply in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5) - missed 0 apply attempts in between[2015-08-14 10:48:11.371]JsonWebClient: <- received JSON answer:{"etag":"31c9b5eba48ea2759bbba9406fa37e32","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-dusche-links","pointsymbol":{},"state":{"alert":"none","bri":254,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:5A:7F-03"}[2015-08-14 10:48:11.372] Channel 'brightness' in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.372] Channel 'color temperature' in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.372] ----- Serializer watchdog ticket #3953 cancelled - update complete[2015-08-14 10:48:11.372] endUpdatingChannels in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links) (while actually waiting for these updates!)[2015-08-14 10:48:11.373] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.373] Channel 'brightness' in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.373] Channel 'color temperature' in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.373] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.373] requestApplyingChannels entered in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links)[2015-08-14 10:48:11.374] - ready, calling applyChannelValues() in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links)[2015-08-14 10:48:11.374] +++++ Serializer watchdog started for apply with ticket #3982[2015-08-14 10:48:11.374] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.374] hue device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.374] - color mode color temperature: mired=370[2015-08-14 10:48:11.375]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/35: [2015-08-14 10:48:11.375] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.375] checkForReapply in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links) - missed 0 apply attempts in between[2015-08-14 10:48:11.381]JsonWebClient: <- received JSON answer:{"etag":"c0b679cf73c8071fc6824a265da16327","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-dusche-rechts","pointsymbol":{},"state":{"alert":"none","bri":254,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:00:09:50-03"}[2015-08-14 10:48:11.381] Channel 'brightness' in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.381] Channel 'color temperature' in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.382] ----- Serializer watchdog ticket #3955 cancelled - update complete[2015-08-14 10:48:11.382] endUpdatingChannels in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts) (while actually waiting for these updates!)[2015-08-14 10:48:11.382] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.382] Channel 'brightness' in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.383] Channel 'color temperature' in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.383] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.383] requestApplyingChannels entered in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts)[2015-08-14 10:48:11.383] - ready, calling applyChannelValues() in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts)[2015-08-14 10:48:11.383] +++++ Serializer watchdog started for apply with ticket #3983[2015-08-14 10:48:11.383] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.384] hue device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.384] - color mode color temperature: mired=370[2015-08-14 10:48:11.384]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/50: [2015-08-14 10:48:11.384] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.385] checkForReapply in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts) - missed 0 apply attempts in between[2015-08-14 10:48:11.390]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-10","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:4B:B6-03"}[2015-08-14 10:48:11.390] Channel 'brightness' in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.391] Channel 'color temperature' in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.391] ----- Serializer watchdog ticket #3957 cancelled - update complete[2015-08-14 10:48:11.391] endUpdatingChannels in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10) (while actually waiting for these updates!)[2015-08-14 10:48:11.391] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.391] Channel 'brightness' in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.392] Channel 'color temperature' in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.392] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.392] requestApplyingChannels entered in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10)[2015-08-14 10:48:11.392] - ready, calling applyChannelValues() in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10)[2015-08-14 10:48:11.392] +++++ Serializer watchdog started for apply with ticket #3984[2015-08-14 10:48:11.393] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.393] hue device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.393] - color mode color temperature: mired=370[2015-08-14 10:48:11.393]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/47: [2015-08-14 10:48:11.393] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.394] checkForReapply in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10) - missed 0 apply attempts in between[2015-08-14 10:48:11.399]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"Unknown","modelid":"PAR16 50 TW","name":"bad-2","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.07","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:4F:D0-03"}[2015-08-14 10:48:11.400] Channel 'brightness' in device 2A1C4C27EE685556C093DE08454A661700 (bad-2): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.400] Channel 'color temperature' in device 2A1C4C27EE685556C093DE08454A661700 (bad-2): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.400] ----- Serializer watchdog ticket #3959 cancelled - update complete[2015-08-14 10:48:11.401] endUpdatingChannels in device 2A1C4C27EE685556C093DE08454A661700 (bad-2) (while actually waiting for these updates!)[2015-08-14 10:48:11.401] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.401] Channel 'brightness' in device 2A1C4C27EE685556C093DE08454A661700 (bad-2): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.401] Channel 'color temperature' in device 2A1C4C27EE685556C093DE08454A661700 (bad-2): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.401] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.402] requestApplyingChannels entered in device 2A1C4C27EE685556C093DE08454A661700 (bad-2)[2015-08-14 10:48:11.402] - ready, calling applyChannelValues() in device 2A1C4C27EE685556C093DE08454A661700 (bad-2)[2015-08-14 10:48:11.402] +++++ Serializer watchdog started for apply with ticket #3985[2015-08-14 10:48:11.402] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.402] hue device 2A1C4C27EE685556C093DE08454A661700 (bad-2): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.403] - color mode color temperature: mired=370[2015-08-14 10:48:11.403]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/38: [2015-08-14 10:48:11.403] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.403] checkForReapply in device 2A1C4C27EE685556C093DE08454A661700 (bad-2) - missed 0 apply attempts in between[2015-08-14 10:48:11.408]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-8","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":0,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0,0]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:55:A5-03"}[2015-08-14 10:48:11.409] Channel 'brightness' in device B1800406481E55C5C07C43547D4D0C4700 (bad-8): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.409] Channel 'color temperature' in device B1800406481E55C5C07C43547D4D0C4700 (bad-8): cached value synchronized from 370.00 -> 0.00[2015-08-14 10:48:11.409] ----- Serializer watchdog ticket #3961 cancelled - update complete[2015-08-14 10:48:11.410] endUpdatingChannels in device B1800406481E55C5C07C43547D4D0C4700 (bad-8) (while actually waiting for these updates!)[2015-08-14 10:48:11.410] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.410] Channel 'brightness' in device B1800406481E55C5C07C43547D4D0C4700 (bad-8): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.410] Channel 'color temperature' in device B1800406481E55C5C07C43547D4D0C4700 (bad-8): is requested to change from 100.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.410] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.411] requestApplyingChannels entered in device B1800406481E55C5C07C43547D4D0C4700 (bad-8)[2015-08-14 10:48:11.411] - ready, calling applyChannelValues() in device B1800406481E55C5C07C43547D4D0C4700 (bad-8)[2015-08-14 10:48:11.411] +++++ Serializer watchdog started for apply with ticket #3986[2015-08-14 10:48:11.411] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.411] hue device B1800406481E55C5C07C43547D4D0C4700 (bad-8): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.412] - color mode color temperature: mired=370[2015-08-14 10:48:11.412]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/51: [2015-08-14 10:48:11.412] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.412] checkForReapply in device B1800406481E55C5C07C43547D4D0C4700 (bad-8) - missed 0 apply attempts in between[2015-08-14 10:48:11.417]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-4","pointsymbol":{},"state":{"alert":"none","bri":191,"colormode":"ct","ct":0,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.07","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:47:2E-03"}[2015-08-14 10:48:11.418] Channel 'brightness' in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.418] Channel 'color temperature' in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4): cached value synchronized from 370.00 -> 0.00[2015-08-14 10:48:11.418] ----- Serializer watchdog ticket #3963 cancelled - update complete[2015-08-14 10:48:11.419] endUpdatingChannels in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4) (while actually waiting for these updates!)[2015-08-14 10:48:11.419] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.419] Channel 'brightness' in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.419] Channel 'color temperature' in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4): is requested to change from 100.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.420] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.420] requestApplyingChannels entered in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4)[2015-08-14 10:48:11.420] - ready, calling applyChannelValues() in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4)[2015-08-14 10:48:11.420] +++++ Serializer watchdog started for apply with ticket #3987[2015-08-14 10:48:11.420] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.421] hue device 33E1DED83EB75A62C0F108D03D33927900 (bad-4): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.421] - color mode color temperature: mired=370[2015-08-14 10:48:11.421]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/52: [2015-08-14 10:48:11.421] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.421] checkForReapply in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4) - missed 0 apply attempts in between[2015-08-14 10:48:11.426]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-mitte","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":0,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0,0]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:5F:3F-03"}[2015-08-14 10:48:11.427] Channel 'brightness' in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.427] Channel 'color temperature' in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte): cached value synchronized from 370.00 -> 0.00[2015-08-14 10:48:11.427] ----- Serializer watchdog ticket #3965 cancelled - update complete[2015-08-14 10:48:11.428] endUpdatingChannels in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte) (while actually waiting for these updates!)[2015-08-14 10:48:11.428] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.428] Channel 'brightness' in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.428] Channel 'color temperature' in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte): is requested to change from 100.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.428] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.429] requestApplyingChannels entered in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte)[2015-08-14 10:48:11.429] - ready, calling applyChannelValues() in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte)[2015-08-14 10:48:11.429] +++++ Serializer watchdog started for apply with ticket #3988[2015-08-14 10:48:11.429] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.429] hue device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.430] - color mode color temperature: mired=370[2015-08-14 10:48:11.430]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/34: [2015-08-14 10:48:11.430] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.430] checkForReapply in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte) - missed 0 apply attempts in between[2015-08-14 10:48:11.436]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-7","pointsymbol":{},"state":{"alert":"none","bri":191,"colormode":"ct","ct":0,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:47:E2-03"}[2015-08-14 10:48:11.436] Channel 'brightness' in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.437] Channel 'color temperature' in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7): cached value synchronized from 370.00 -> 0.00[2015-08-14 10:48:11.437] ----- Serializer watchdog ticket #3967 cancelled - update complete[2015-08-14 10:48:11.437] endUpdatingChannels in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7) (while actually waiting for these updates!)[2015-08-14 10:48:11.437] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.437] Channel 'brightness' in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.438] Channel 'color temperature' in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7): is requested to change from 100.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.438] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.438] requestApplyingChannels entered in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7)[2015-08-14 10:48:11.438] - ready, calling applyChannelValues() in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7)[2015-08-14 10:48:11.438] +++++ Serializer watchdog started for apply with ticket #3989[2015-08-14 10:48:11.439] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.439] hue device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.439] - color mode color temperature: mired=370[2015-08-14 10:48:11.439]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/41: [2015-08-14 10:48:11.440] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.440] checkForReapply in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7) - missed 0 apply attempts in between[2015-08-14 10:48:11.445]JsonWebClient: <- received JSON answer:{"etag":"49b29c99f74e19db42f414c614faa58c","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-wanne-mitte","pointsymbol":{},"state":{"alert":"none","bri":254,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:00:0B:85-03"}[2015-08-14 10:48:11.446] Channel 'brightness' in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.446] Channel 'color temperature' in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.446] ----- Serializer watchdog ticket #3969 cancelled - update complete[2015-08-14 10:48:11.446] endUpdatingChannels in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte) (while actually waiting for these updates!)[2015-08-14 10:48:11.446] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.447] Channel 'brightness' in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.447] Channel 'color temperature' in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.447] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.447] requestApplyingChannels entered in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte)[2015-08-14 10:48:11.447] - ready, calling applyChannelValues() in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte)[2015-08-14 10:48:11.448] +++++ Serializer watchdog started for apply with ticket #3990[2015-08-14 10:48:11.448] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.448] hue device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.448] - color mode color temperature: mired=370[2015-08-14 10:48:11.449]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/48: [2015-08-14 10:48:11.449] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.449] checkForReapply in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte) - missed 0 apply attempts in between[2015-08-14 10:48:11.454]JsonWebClient: <- received JSON answer:{"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-3","pointsymbol":{},"state":{"alert":"none","bri":191,"colormode":"ct","ct":0,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.07","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:44:CD-03"}[2015-08-14 10:48:11.455] Channel 'brightness' in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.455] Channel 'color temperature' in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3): cached value synchronized from 370.00 -> 0.00[2015-08-14 10:48:11.455] ----- Serializer watchdog ticket #3971 cancelled - update complete[2015-08-14 10:48:11.455] endUpdatingChannels in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3) (while actually waiting for these updates!)[2015-08-14 10:48:11.455] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.456] Channel 'brightness' in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.456] Channel 'color temperature' in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3): is requested to change from 100.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.456] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.456] requestApplyingChannels entered in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3)[2015-08-14 10:48:11.457] - ready, calling applyChannelValues() in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3)[2015-08-14 10:48:11.457] +++++ Serializer watchdog started for apply with ticket #3991[2015-08-14 10:48:11.457] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.457] hue device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.457] - color mode color temperature: mired=370[2015-08-14 10:48:11.458]JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/44: [2015-08-14 10:48:11.458] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.458] checkForReapply in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3) - missed 0 apply attempts in between[2015-08-14 10:48:11.463]JsonWebClient: <- received JSON answer:{"etag":"0a0d5f731e70542f004a00fc435c674a","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-wc","pointsymbol":{},"state":{"alert":"none","bri":254,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:00:0F:8B-03"}[2015-08-14 10:48:11.464] Channel 'brightness' in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc): cached value synchronized from 0.00 -> 0.00[2015-08-14 10:48:11.464] Channel 'color temperature' in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.464] ----- Serializer watchdog ticket #3973 cancelled - update complete[2015-08-14 10:48:11.464] endUpdatingChannels in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc) (while actually waiting for these updates!)[2015-08-14 10:48:11.464] - confirming channels updated from hardware (= calling callback now)[2015-08-14 10:48:11.465] Channel 'brightness' in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc): is requested to change from 0.00 -> 100.00 (transition time=100 mS)[2015-08-14 10:48:11.465] Channel 'color temperature' in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc): is requested to change from 370.00 -> 370.00 (transition time=100 mS)[2015-08-14 10:48:11.465] - Scene(5): new channel value(s) loaded from scene, ready to apply[2015-08-14 10:48:11.465] requestApplyingChannels entered in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc)[2015-08-14 10:48:11.465] - ready, calling applyChannelValues() in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc)[2015-08-14 10:48:11.466] +++++ Serializer watchdog started for apply with ticket #3992[2015-08-14 10:48:11.466] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'[2015-08-14 10:48:11.466] hue device 917E73D48704500FC0E8CF788591F0E900 (bad-wc): sending new light state: light is ON, brightness=100, transition in 100 mS[2015-08-14 10:48:11.466] - color mode color temperature: mired=370[2015-08-14 10:48:11.467]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/43/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.467] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)[2015-08-14 10:48:11.467] checkForReapply in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc) - missed 0 apply attempts in between[2015-08-14 10:48:11.472]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/43\/state\/on":true}},{"success":{"\/lights\/43\/state\/bri":255}},{"success":{"\/lights\/43\/state\/ct":370}}][2015-08-14 10:48:11.473] Channel 'brightness' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.473] Channel 'color temperature' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.473] applyingChannelsComplete entered in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)[2015-08-14 10:48:11.474] ----- Serializer watchdog ticket #3974 cancelled - apply complete[2015-08-14 10:48:11.474] checkForReapply in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte) - missed 0 apply attempts in between[2015-08-14 10:48:11.474] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.474] - confirming apply (really) finalized[2015-08-14 10:48:11.474] - scene actions for scene 5 complete[2015-08-14 10:48:11.474] - confirmed apply (really) finalized[2015-08-14 10:48:11.475]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/46/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.481]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/46\/state\/on":true}},{"success":{"\/lights\/46\/state\/bri":255}},{"success":{"\/lights\/46\/state\/ct":370}}][2015-08-14 10:48:11.481] Channel 'brightness' in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.481] Channel 'color temperature' in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.482] applyingChannelsComplete entered in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links)[2015-08-14 10:48:11.482] ----- Serializer watchdog ticket #3975 cancelled - apply complete[2015-08-14 10:48:11.482] checkForReapply in device 91D3B29D9BEE5874C0F268E6CCAAF6A000 (bad-wanne-links) - missed 0 apply attempts in between[2015-08-14 10:48:11.482] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.482] - confirming apply (really) finalized[2015-08-14 10:48:11.482] - scene actions for scene 5 complete[2015-08-14 10:48:11.483] - confirmed apply (really) finalized[2015-08-14 10:48:11.483]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/36/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.489]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/36\/state\/on":true}},{"success":{"\/lights\/36\/state\/bri":255}},{"success":{"\/lights\/36\/state\/ct":370}}][2015-08-14 10:48:11.489] Channel 'brightness' in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.489] Channel 'color temperature' in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.490] applyingChannelsComplete entered in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1)[2015-08-14 10:48:11.490] ----- Serializer watchdog ticket #3976 cancelled - apply complete[2015-08-14 10:48:11.490] checkForReapply in device D6B79F585C76554CC0BB64F6C5A73E6500 (bad-1) - missed 0 apply attempts in between[2015-08-14 10:48:11.490] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.490] - confirming apply (really) finalized[2015-08-14 10:48:11.490] - scene actions for scene 5 complete[2015-08-14 10:48:11.491] - confirmed apply (really) finalized[2015-08-14 10:48:11.491]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/49/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.497]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/49\/state\/on":true}},{"success":{"\/lights\/49\/state\/bri":255}},{"success":{"\/lights\/49\/state\/ct":370}}][2015-08-14 10:48:11.497] Channel 'brightness' in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.497] Channel 'color temperature' in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.497] applyingChannelsComplete entered in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6)[2015-08-14 10:48:11.498] ----- Serializer watchdog ticket #3977 cancelled - apply complete[2015-08-14 10:48:11.498] checkForReapply in device 6F7761C68F6A53A4C09B5FAB8596ABF100 (bad-6) - missed 0 apply attempts in between[2015-08-14 10:48:11.498] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.498] - confirming apply (really) finalized[2015-08-14 10:48:11.498] - scene actions for scene 5 complete[2015-08-14 10:48:11.499] - confirmed apply (really) finalized[2015-08-14 10:48:11.499]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/42/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.504]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/42\/state\/on":true}},{"success":{"\/lights\/42\/state\/bri":255}},{"success":{"\/lights\/42\/state\/ct":370}}][2015-08-14 10:48:11.505] Channel 'brightness' in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.505] Channel 'color temperature' in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.505] applyingChannelsComplete entered in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang)[2015-08-14 10:48:11.506] ----- Serializer watchdog ticket #3978 cancelled - apply complete[2015-08-14 10:48:11.506] checkForReapply in device 3A2E1ED4F10B5B73C0B40E029D5E92C700 (bad-dusche-eingang) - missed 0 apply attempts in between[2015-08-14 10:48:11.506] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.506] - confirming apply (really) finalized[2015-08-14 10:48:11.506] - scene actions for scene 5 complete[2015-08-14 10:48:11.506] - confirmed apply (really) finalized[2015-08-14 10:48:11.507]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/37/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.512]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/37\/state\/on":true}},{"success":{"\/lights\/37\/state\/bri":255}},{"success":{"\/lights\/37\/state\/ct":370}}][2015-08-14 10:48:11.513] Channel 'brightness' in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.513] Channel 'color temperature' in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.513] applyingChannelsComplete entered in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links)[2015-08-14 10:48:11.513] ----- Serializer watchdog ticket #3979 cancelled - apply complete[2015-08-14 10:48:11.514] checkForReapply in device 185E7743EDFD5F86800599DDF555AD9E00 (bad-wanne-links) - missed 0 apply attempts in between[2015-08-14 10:48:11.514] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.514] - confirming apply (really) finalized[2015-08-14 10:48:11.514] - scene actions for scene 5 complete[2015-08-14 10:48:11.514] - confirmed apply (really) finalized[2015-08-14 10:48:11.514]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/40/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.520]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/40\/state\/on":true}},{"success":{"\/lights\/40\/state\/bri":255}},{"success":{"\/lights\/40\/state\/ct":370}}][2015-08-14 10:48:11.521] Channel 'brightness' in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.521] Channel 'color temperature' in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.521] applyingChannelsComplete entered in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9)[2015-08-14 10:48:11.521] ----- Serializer watchdog ticket #3980 cancelled - apply complete[2015-08-14 10:48:11.521] checkForReapply in device C248342D5EC7533880E26FF9FA9C26DA00 (bad-9) - missed 0 apply attempts in between[2015-08-14 10:48:11.522] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.522] - confirming apply (really) finalized[2015-08-14 10:48:11.522] - scene actions for scene 5 complete[2015-08-14 10:48:11.522] - confirmed apply (really) finalized[2015-08-14 10:48:11.522]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/45/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.528]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/45\/state\/on":true}},{"success":{"\/lights\/45\/state\/bri":255}},{"success":{"\/lights\/45\/state\/ct":370}}][2015-08-14 10:48:11.529] Channel 'brightness' in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.529] Channel 'color temperature' in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.529] applyingChannelsComplete entered in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5)[2015-08-14 10:48:11.529] ----- Serializer watchdog ticket #3981 cancelled - apply complete[2015-08-14 10:48:11.530] checkForReapply in device AA56487AD6775D8880BA98024A5DD49C00 (bad-5) - missed 0 apply attempts in between[2015-08-14 10:48:11.530] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.530] - confirming apply (really) finalized[2015-08-14 10:48:11.530] - scene actions for scene 5 complete[2015-08-14 10:48:11.530] - confirmed apply (really) finalized[2015-08-14 10:48:11.530]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/39/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.535]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/39\/state\/on":true}},{"success":{"\/lights\/39\/state\/bri":255}},{"success":{"\/lights\/39\/state\/ct":370}}][2015-08-14 10:48:11.535] Channel 'brightness' in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.536] Channel 'color temperature' in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.536] applyingChannelsComplete entered in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links)[2015-08-14 10:48:11.536] ----- Serializer watchdog ticket #3982 cancelled - apply complete[2015-08-14 10:48:11.536] checkForReapply in device 8C6389F789605E5980BAC4C76078F6FB00 (bad-dusche-links) - missed 0 apply attempts in between[2015-08-14 10:48:11.536] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.537] - confirming apply (really) finalized[2015-08-14 10:48:11.537] - scene actions for scene 5 complete[2015-08-14 10:48:11.537] - confirmed apply (really) finalized[2015-08-14 10:48:11.537]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/35/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.542]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/35\/state\/on":true}},{"success":{"\/lights\/35\/state\/bri":255}},{"success":{"\/lights\/35\/state\/ct":370}}][2015-08-14 10:48:11.542] Channel 'brightness' in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.542] Channel 'color temperature' in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.543] applyingChannelsComplete entered in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts)[2015-08-14 10:48:11.543] ----- Serializer watchdog ticket #3983 cancelled - apply complete[2015-08-14 10:48:11.543] checkForReapply in device 3E6FABEE25335712C00AE7750A27C9A300 (bad-dusche-rechts) - missed 0 apply attempts in between[2015-08-14 10:48:11.543] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.543] - confirming apply (really) finalized[2015-08-14 10:48:11.543] - scene actions for scene 5 complete[2015-08-14 10:48:11.543] - confirmed apply (really) finalized[2015-08-14 10:48:11.544]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/50/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.548]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/50\/state\/on":true}},{"success":{"\/lights\/50\/state\/bri":255}},{"success":{"\/lights\/50\/state\/ct":370}}][2015-08-14 10:48:11.549] Channel 'brightness' in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.549] Channel 'color temperature' in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.549] applyingChannelsComplete entered in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10)[2015-08-14 10:48:11.549] ----- Serializer watchdog ticket #3984 cancelled - apply complete[2015-08-14 10:48:11.550] checkForReapply in device 8BDC4A7ECFF8531FC0EAE2302788B49A00 (bad-10) - missed 0 apply attempts in between[2015-08-14 10:48:11.550] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.550] - confirming apply (really) finalized[2015-08-14 10:48:11.550] - scene actions for scene 5 complete[2015-08-14 10:48:11.550] - confirmed apply (really) finalized[2015-08-14 10:48:11.550]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/47/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.555]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/47\/state\/on":true}},{"success":{"\/lights\/47\/state\/bri":255}},{"success":{"\/lights\/47\/state\/ct":370}}][2015-08-14 10:48:11.555] Channel 'brightness' in device 2A1C4C27EE685556C093DE08454A661700 (bad-2): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.556] Channel 'color temperature' in device 2A1C4C27EE685556C093DE08454A661700 (bad-2): cached value synchronized from 370.00 -> 370.00[2015-08-14 10:48:11.556] applyingChannelsComplete entered in device 2A1C4C27EE685556C093DE08454A661700 (bad-2)[2015-08-14 10:48:11.556] ----- Serializer watchdog ticket #3985 cancelled - apply complete[2015-08-14 10:48:11.556] checkForReapply in device 2A1C4C27EE685556C093DE08454A661700 (bad-2) - missed 0 apply attempts in between[2015-08-14 10:48:11.556] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.557] - confirming apply (really) finalized[2015-08-14 10:48:11.557] - scene actions for scene 5 complete[2015-08-14 10:48:11.557] - confirmed apply (really) finalized[2015-08-14 10:48:11.557]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/38/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.562]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/38\/state\/on":true}},{"success":{"\/lights\/38\/state\/bri":255}},{"error":{"address":"\/lights\/38","description":"Internal error, 951","type":901}}][2015-08-14 10:48:11.562] Channel 'brightness' in device B1800406481E55C5C07C43547D4D0C4700 (bad-8): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.562] applyingChannelsComplete entered in device B1800406481E55C5C07C43547D4D0C4700 (bad-8)[2015-08-14 10:48:11.562] ----- Serializer watchdog ticket #3986 cancelled - apply complete[2015-08-14 10:48:11.563] checkForReapply in device B1800406481E55C5C07C43547D4D0C4700 (bad-8) - missed 0 apply attempts in between[2015-08-14 10:48:11.563] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.563] - confirming apply (really) finalized[2015-08-14 10:48:11.563] - scene actions for scene 5 complete[2015-08-14 10:48:11.563] - confirmed apply (really) finalized[2015-08-14 10:48:11.563]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/51/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.568]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/51\/state\/on":true}},{"error":{"address":"\/lights\/51","description":"Internal error, 951","type":901}},{"error":{"address":"\/lights\/51","description":"parameter, \/lights\/51\/ct, is not modifiable. Device is set to off.","type":201}}][2015-08-14 10:48:11.568] applyingChannelsComplete entered in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4)[2015-08-14 10:48:11.569] ----- Serializer watchdog ticket #3987 cancelled - apply complete[2015-08-14 10:48:11.569] checkForReapply in device 33E1DED83EB75A62C0F108D03D33927900 (bad-4) - missed 0 apply attempts in between[2015-08-14 10:48:11.569] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.569] - confirming apply (really) finalized[2015-08-14 10:48:11.569] - scene actions for scene 5 complete[2015-08-14 10:48:11.569] - confirmed apply (really) finalized[2015-08-14 10:48:11.570]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/52/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.574]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/52\/state\/on":true}},{"error":{"address":"\/lights\/52","description":"Internal error, 951","type":901}},{"error":{"address":"\/lights\/52","description":"Internal error, 951","type":901}}][2015-08-14 10:48:11.575] applyingChannelsComplete entered in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte)[2015-08-14 10:48:11.575] ----- Serializer watchdog ticket #3988 cancelled - apply complete[2015-08-14 10:48:11.575] checkForReapply in device 87A9F0F76D4F5A9780CC7F4331F8B66D00 (bad-mitte) - missed 0 apply attempts in between[2015-08-14 10:48:11.575] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.575] - confirming apply (really) finalized[2015-08-14 10:48:11.576] - scene actions for scene 5 complete[2015-08-14 10:48:11.576] - confirmed apply (really) finalized[2015-08-14 10:48:11.576]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/34/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.581]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/34\/state\/on":true}},{"error":{"address":"\/lights\/34","description":"Internal error, 951","type":901}},{"error":{"address":"\/lights\/34","description":"parameter, \/lights\/34\/ct, is not modifiable. Device is set to off.","type":201}}][2015-08-14 10:48:11.581] applyingChannelsComplete entered in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7)[2015-08-14 10:48:11.581] ----- Serializer watchdog ticket #3989 cancelled - apply complete[2015-08-14 10:48:11.581] checkForReapply in device 431E66B8F7E451B380CAC4A840F26BC500 (bad-7) - missed 0 apply attempts in between[2015-08-14 10:48:11.582] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.582] - confirming apply (really) finalized[2015-08-14 10:48:11.582] - scene actions for scene 5 complete[2015-08-14 10:48:11.582] - confirmed apply (really) finalized[2015-08-14 10:48:11.582]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/41/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.587]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/41\/state\/on":true}},{"error":{"address":"\/lights\/41","description":"Internal error, 951","type":901}},{"error":{"address":"\/lights\/41","description":"Internal error, 951","type":901}}][2015-08-14 10:48:11.587] applyingChannelsComplete entered in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte)[2015-08-14 10:48:11.587] ----- Serializer watchdog ticket #3990 cancelled - apply complete[2015-08-14 10:48:11.588] checkForReapply in device F5A5039D23C75A4BC03CCC5265BC202300 (bad-wanne-mitte) - missed 0 apply attempts in between[2015-08-14 10:48:11.588] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.588] - confirming apply (really) finalized[2015-08-14 10:48:11.588] - scene actions for scene 5 complete[2015-08-14 10:48:11.588] - confirmed apply (really) finalized[2015-08-14 10:48:11.588]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/48/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.594]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/48\/state\/on":true}},{"success":{"\/lights\/48\/state\/bri":255}},{"error":{"address":"\/lights\/48","description":"Internal error, 951","type":901}}][2015-08-14 10:48:11.594] Channel 'brightness' in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3): cached value synchronized from 100.00 -> 100.00[2015-08-14 10:48:11.594] applyingChannelsComplete entered in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3)[2015-08-14 10:48:11.595] ----- Serializer watchdog ticket #3991 cancelled - apply complete[2015-08-14 10:48:11.595] checkForReapply in device FABF236F7CE750FEC09DC6B3D99480B800 (bad-3) - missed 0 apply attempts in between[2015-08-14 10:48:11.595] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.595] - confirming apply (really) finalized[2015-08-14 10:48:11.595] - scene actions for scene 5 complete[2015-08-14 10:48:11.595] - confirmed apply (really) finalized[2015-08-14 10:48:11.596]JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/44/state:{"on":true,"bri":255,"ct":370,"transitiontime":1}[2015-08-14 10:48:11.600]JsonWebClient: <- received JSON answer:[{"success":{"\/lights\/44\/state\/on":true}},{"error":{"address":"\/lights\/44","description":"Internal error, 951","type":901}},{"error":{"address":"\/lights\/44","description":"parameter, \/lights\/44\/ct, is not modifiable. Device is set to off.","type":201}}][2015-08-14 10:48:11.601] applyingChannelsComplete entered in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc)[2015-08-14 10:48:11.601] ----- Serializer watchdog ticket #3992 cancelled - apply complete[2015-08-14 10:48:11.601] checkForReapply in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc) - missed 0 apply attempts in between[2015-08-14 10:48:11.601] - applyingChannelsComplete - really completed, now checking callbacks[2015-08-14 10:48:11.601] - confirming apply (really) finalized[2015-08-14 10:48:11.602] - scene actions for scene 5 complete[2015-08-14 10:48:11.602] - confirmed apply (really) finalized[2015-08-14 10:48:55.643] vdSM -> vDC (pbuf) notification received: method='ping', params={ dSUID:"E5C30F7708155DEAC0C35650A7DF63B800" }[2015-08-14 10:48:55.643] ping to vDChost E5C30F7708155DEAC0C35650A7DF63B800 -> checking presence...[2015-08-14 10:48:55.643] ping: vDChost E5C30F7708155DEAC0C35650A7DF63B800 is present -> sending pong[2015-08-14 10:48:55.644] vdSM <- vDC (pbuf) notification sent: method='pong', params={ dSUID:E5C30F7708155DEAC0C35650A7DF63B800 }[2015-08-14 10:49:45.729] vdSM -> vDC (pbuf) notification received: method='ping', params={ dSUID:"E5C30F7708155DEAC0C35650A7DF63B800" }[2015-08-14 10:49:45.729] ping to vDChost E5C30F7708155DEAC0C35650A7DF63B800 -> checking presence...[2015-08-14 10:49:45.729] ping: vDChost E5C30F7708155DEAC0C35650A7DF63B800 is present -> sending pong[2015-08-14 10:49:45.730] vdSM <- vDC (pbuf) notification sent: method='pong', params={ dSUID:E5C30F7708155DEAC0C35650A7DF63B800 }[2015-08-14 10:50:01.154]MainLoop: loopCycleTime : 0.033333 S- statistics period : 305.171909 S (9217 cycles)- actual/specified cycle time : 99% (actual average = 0.033110 S)- idle handlers : 0%- one time handlers : 0%- I/O poll handlers : 0%- wait handlers : 0%- thread signal handlers : 0%- number of idle handlers : 4- number of one-time handlers : 0 earliest in : 0.000000 S from now latest in : 0.000000 S from now max waiting in period : 21- number of I/O poll handlers : 3- number of wait
handlers : 0so i marked a line red where a delay was - if i read the output correct - but have no idea what took so long - just the logon to deconz?
but from the output i don't know why 'BAD-7' was not coming on.
hope this all makes sense.
thanks
holli
Lukas Zeller
Aug 14, 2015, 5:29:19 PM8/14/15
to plan44_vdcd
Hello Thomas,
> On 14.08.2015, at 12:42, thomas r. hollensteiner <ho...@holli.at> wrote:
>
> i use the p44 to connect to a DECONZ server - i have there apx. 70 zigbee devices and each device is replicated to dss - so far so good but i have rooms (like bath) with 19 devices (spots) and on using the dss app to turn room on the max. devices switched on are 14 so through dss i miss 5 lights to come on (see the log 'bad-7' f.ex)
>
> i only have this issue through dss (vdcd) because if i use the webapi from deconz i get always all lights on/off...
>
> i believe it would be a better approach to have the vdcd to put zigbee groups as a single device to dss and make the secens available for this groups - maybe choosable by a switch because of call 19x times the zigbee server you can just do one single call to turn on/off or call a scene - this would increase the response time a lot and the effect that lights are coming on like on a airport would be complete avoided.
So far, the vdcd implementation is targeted at Philips hue, not ZLL in general. As such, fully grouped lights (i.e. always with same color and brightness) are not a common use case. That's why implementing the groups API did not have priority so far, but it's definitely an interesing idea to make groups available as a single dS device.
However, from your log it seems that the delay is not really in the communication between vdcd and DECONZ.
Your second red marked line is where the vdcd receives the first callScene() from the vdsm (at 10:48:11.269). The last callScene() completes at 10:48:11.602, so the overall processing for 19 lights is only a third of a second, which would be hardly noticeable.
When you still see a airfield effect, it must be due to delay between DECONZ receiving the command and actual change reaching the light over ZLL. Maybe ZLL itself is the bottleneck, and it is not possible at all to switch many lamps quickly except via a ZLL group?
Lukas
PS: due to the asynchronous execution, the log is hard to read when 19 lights are triggered to change together. To make it more clear what happens, here's the edited and somehwat commented log for a single light:
> here is an example log for tuning on my bathroom lights:
> [snip]
here the first callScene() arrives.
> [2015-08-14 10:48:11.269] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "166005009E4050AEC0033DD65F4DC60600" ], force:false, scene:5 }
> [2015-08-14 10:48:11.269] CallScene(5) (non-dimming!) in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte):
> [2015-08-14 10:48:11.269] - effective normalized scene to apply to output is 5, dontCare=0
> [2015-08-14 10:48:11.269] requestUpdatingChannels entered in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)
> [2015-08-14 10:48:11.270] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)
> [2015-08-14 10:48:11.270] +++++ Serializer watchdog started for update with ticket #3937
It queries DECONZ for the status one millisecond later:
> [2015-08-14 10:48:11.270]
> JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/43:
And while other callScenes arrive...
> [2015-08-14 10:48:11.271] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "91D3B29D9BEE5874C0F268E6CCAAF6A000" ], force:false, scene:5 }
[snip]
about 25mS later DECONZ already returns status (so I guess it has this data cached, otherwise it would take longer)
> [2015-08-14 10:48:11.296]
> JsonWebClient: <- received JSON answer:
> {"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-dusche-mitte","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:4A:62-03"}
> [2015-08-14 10:48:11.296] Channel 'brightness' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 0.00 -> 0.00
> [2015-08-14 10:48:11.297] Channel 'color temperature' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 370.00 -> 370.00
> [2015-08-14 10:48:11.297] ----- Serializer watchdog ticket #3937 cancelled - update complete
> [2015-08-14 10:48:11.297] endUpdatingChannels in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte) (while actually waiting for these updates!)
> [2015-08-14 10:48:11.297] - confirming channels updated from hardware (= calling callback now)
Now that current status is known, the actual change is requested (but not immediately executed)
> [2015-08-14 10:48:11.297] Channel 'brightness' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): is requested to change from 0.00 -> 100.00 (transition time=100 mS)
> [2015-08-14 10:48:11.298] Channel 'color temperature' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): is requested to change from 370.00 -> 370.00 (transition time=100 mS)
> [2015-08-14 10:48:11.298] - Scene(5): new channel value(s) loaded from scene, ready to apply
> [2015-08-14 10:48:11.298] requestApplyingChannels entered in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)
> [2015-08-14 10:48:11.298] - ready, calling applyChannelValues() in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)
> [2015-08-14 10:48:11.298] +++++ Serializer watchdog started for apply with ticket #3974
> [2015-08-14 10:48:11.299] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'
> [2015-08-14 10:48:11.299] hue device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): sending new light state: light is ON, brightness=100, transition in 100 mS
> [2015-08-14 10:48:11.299] - color mode color temperature: mired=370
the request remains pending until all previous state query requests have been handled.
[snip]
then, 168ms later, all 19 lights have returned status, and the request can be sent out:
> JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/43/state:
> {"on":true,"bri":255,"ct":370,"transitiontime":1}
> [2015-08-14 10:48:11.467] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)
> [2015-08-14 10:48:11.467] checkForReapply in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc) - missed 0 apply attempts in between
> [2015-08-14 10:48:11.472]
> JsonWebClient: <- received JSON answer:
> [{"success":{"\/lights\/43\/state\/on":true}},{"success":{"\/lights\/43\/state\/bri":255}},{"success":{"\/lights\/43\/state\/ct":370}}]
> [2015-08-14 10:48:11.473] Channel 'brightness' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 100.00 -> 100.00
> [2015-08-14 10:48:11.473] Channel 'color temperature' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 370.00 -> 370.00
> [2015-08-14 10:48:11.473] applyingChannelsComplete entered in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)
> [2015-08-14 10:48:11.474] ----- Serializer watchdog ticket #3974 cancelled - apply complete
> [2015-08-14 10:48:11.474] checkForReapply in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte) - missed 0 apply attempts in between
> [2015-08-14 10:48:11.474] - applyingChannelsComplete - really completed, now checking callbacks
> [2015-08-14 10:48:11.474] - confirming apply (really) finalized
> [2015-08-14 10:48:11.474] - scene actions for scene 5 complete
> [2015-08-14 10:48:11.474] - confirmed apply (really) finalized
> [2015-08-14 10:48:11.475]
Now the first light state change is complete.
the log continues with the same for all other 19 lights...
[snip]
...until the last one is confirmed:
> [2015-08-14 10:48:11.601] applyingChannelsComplete entered in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc)
> [2015-08-14 10:48:11.601] ----- Serializer watchdog ticket #3992 cancelled - apply complete
> [2015-08-14 10:48:11.601] checkForReapply in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc) - missed 0 apply attempts in between
> [2015-08-14 10:48:11.601] - applyingChannelsComplete - really completed, now checking callbacks
> [2015-08-14 10:48:11.601] - confirming apply (really) finalized
> [2015-08-14 10:48:11.602] - scene actions for scene 5 complete
> [2015-08-14 10:48:11.602] - confirmed apply (really) finalized
Now 333mS have passed since start.
> On 14.08.2015, at 12:42, thomas r. hollensteiner <ho...@holli.at> wrote:
>
> i use the p44 to connect to a DECONZ server - i have there apx. 70 zigbee devices and each device is replicated to dss - so far so good but i have rooms (like bath) with 19 devices (spots) and on using the dss app to turn room on the max. devices switched on are 14 so through dss i miss 5 lights to come on (see the log 'bad-7' f.ex)
>
> i only have this issue through dss (vdcd) because if i use the webapi from deconz i get always all lights on/off...
>
> i believe it would be a better approach to have the vdcd to put zigbee groups as a single device to dss and make the secens available for this groups - maybe choosable by a switch because of call 19x times the zigbee server you can just do one single call to turn on/off or call a scene - this would increase the response time a lot and the effect that lights are coming on like on a airport would be complete avoided.
However, from your log it seems that the delay is not really in the communication between vdcd and DECONZ.
Your second red marked line is where the vdcd receives the first callScene() from the vdsm (at 10:48:11.269). The last callScene() completes at 10:48:11.602, so the overall processing for 19 lights is only a third of a second, which would be hardly noticeable.
When you still see a airfield effect, it must be due to delay between DECONZ receiving the command and actual change reaching the light over ZLL. Maybe ZLL itself is the bottleneck, and it is not possible at all to switch many lamps quickly except via a ZLL group?
Lukas
PS: due to the asynchronous execution, the log is hard to read when 19 lights are triggered to change together. To make it more clear what happens, here's the edited and somehwat commented log for a single light:
> here is an example log for tuning on my bathroom lights:
here the first callScene() arrives.
> [2015-08-14 10:48:11.269] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "166005009E4050AEC0033DD65F4DC60600" ], force:false, scene:5 }
> [2015-08-14 10:48:11.269] CallScene(5) (non-dimming!) in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte):
> [2015-08-14 10:48:11.269] - effective normalized scene to apply to output is 5, dontCare=0
> [2015-08-14 10:48:11.269] requestUpdatingChannels entered in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)
> [2015-08-14 10:48:11.270] requestUpdatingChannels: hardware ready, calling syncChannelValues() in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)
> [2015-08-14 10:48:11.270] +++++ Serializer watchdog started for update with ticket #3937
> [2015-08-14 10:48:11.270]
> JsonWebClient: -> sending GET JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/43:
> [2015-08-14 10:48:11.271] vdSM -> vDC (pbuf) notification received: method='callScene', params={ dSUID:[ "91D3B29D9BEE5874C0F268E6CCAAF6A000" ], force:false, scene:5 }
about 25mS later DECONZ already returns status (so I guess it has this data cached, otherwise it would take longer)
> [2015-08-14 10:48:11.296]
> JsonWebClient: <- received JSON answer:
> {"etag":"a951181e537ebc7d996a01d32ec8c80d","hascolor":true,"manufacturer":"OSRAM","modelid":"PAR16 50 TW","name":"bad-dusche-mitte","pointsymbol":{},"state":{"alert":"none","bri":255,"colormode":"ct","ct":370,"effect":"none","hue":0,"on":false,"reachable":true,"sat":0,"xy":[0.382037,0.378391]},"swversion":"V1.03.20","type":"Color temperature light","uniqueid":"84:18:26:00:00:DA:4A:62-03"}
> [2015-08-14 10:48:11.296] Channel 'brightness' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 0.00 -> 0.00
> [2015-08-14 10:48:11.297] Channel 'color temperature' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 370.00 -> 370.00
> [2015-08-14 10:48:11.297] ----- Serializer watchdog ticket #3937 cancelled - update complete
> [2015-08-14 10:48:11.297] endUpdatingChannels in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte) (while actually waiting for these updates!)
> [2015-08-14 10:48:11.297] - confirming channels updated from hardware (= calling callback now)
> [2015-08-14 10:48:11.297] Channel 'brightness' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): is requested to change from 0.00 -> 100.00 (transition time=100 mS)
> [2015-08-14 10:48:11.298] Channel 'color temperature' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): is requested to change from 370.00 -> 370.00 (transition time=100 mS)
> [2015-08-14 10:48:11.298] - Scene(5): new channel value(s) loaded from scene, ready to apply
> [2015-08-14 10:48:11.298] requestApplyingChannels entered in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)
> [2015-08-14 10:48:11.298] - ready, calling applyChannelValues() in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)
> [2015-08-14 10:48:11.298] +++++ Serializer watchdog started for apply with ticket #3974
> [2015-08-14 10:48:11.299] needsToApplyChannels() returns true because of channel 'Channel 'brightness' (channelType=1): min: 0.0, max: 100.0, resolution: 0.391'
> [2015-08-14 10:48:11.299] hue device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): sending new light state: light is ON, brightness=100, transition in 100 mS
> [2015-08-14 10:48:11.299] - color mode color temperature: mired=370
[snip]
then, 168ms later, all 19 lights have returned status, and the request can be sent out:
> JsonWebClient: -> sending PUT JSON request to http://192.168.250.135:8080/api/D49D1AD6137F5C8080C8773AF100090F00/lights/43/state:
> {"on":true,"bri":255,"ct":370,"transitiontime":1}
> [2015-08-14 10:48:11.467] - confirmed channels updated from hardware (= callback has possibly launched apply already and returned now)
> [2015-08-14 10:48:11.467] checkForReapply in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc) - missed 0 apply attempts in between
> [2015-08-14 10:48:11.472]
> JsonWebClient: <- received JSON answer:
> [{"success":{"\/lights\/43\/state\/on":true}},{"success":{"\/lights\/43\/state\/bri":255}},{"success":{"\/lights\/43\/state\/ct":370}}]
> [2015-08-14 10:48:11.473] Channel 'brightness' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 100.00 -> 100.00
> [2015-08-14 10:48:11.473] Channel 'color temperature' in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte): cached value synchronized from 370.00 -> 370.00
> [2015-08-14 10:48:11.473] applyingChannelsComplete entered in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte)
> [2015-08-14 10:48:11.474] ----- Serializer watchdog ticket #3974 cancelled - apply complete
> [2015-08-14 10:48:11.474] checkForReapply in device 166005009E4050AEC0033DD65F4DC60600 (bad-dusche-mitte) - missed 0 apply attempts in between
> [2015-08-14 10:48:11.474] - applyingChannelsComplete - really completed, now checking callbacks
> [2015-08-14 10:48:11.474] - confirming apply (really) finalized
> [2015-08-14 10:48:11.474] - scene actions for scene 5 complete
> [2015-08-14 10:48:11.474] - confirmed apply (really) finalized
> [2015-08-14 10:48:11.475]
the log continues with the same for all other 19 lights...
[snip]
...until the last one is confirmed:
> [2015-08-14 10:48:11.601] applyingChannelsComplete entered in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc)
> [2015-08-14 10:48:11.601] ----- Serializer watchdog ticket #3992 cancelled - apply complete
> [2015-08-14 10:48:11.601] checkForReapply in device 917E73D48704500FC0E8CF788591F0E900 (bad-wc) - missed 0 apply attempts in between
> [2015-08-14 10:48:11.601] - applyingChannelsComplete - really completed, now checking callbacks
> [2015-08-14 10:48:11.601] - confirming apply (really) finalized
> [2015-08-14 10:48:11.602] - scene actions for scene 5 complete
> [2015-08-14 10:48:11.602] - confirmed apply (really) finalized
thomas r. hollensteiner
Aug 15, 2015, 6:00:07 AM8/15/15
to plan44_vdcd
hello Luz,
thanks for the information - i contacted deconz support as well - if they can give a why single calls do not success and group calls do - maybe this is just a limitation of the zigbee network (mesh)...
i did look what deconz is sending for on/off - and it is a group command - so i believe that this is the reason for slowness (even 333 ms are not slow!) but this method is actually not reaching all lights in a timely fashion or even not reaching them - the deonz webinterface shows the lights off which are missing frrom the dss call - so anyway why would the interface return ON but in their own interface the light is off...
i made 2 videos not realy good ones but this should illustrate the behavior best
thanks
holli
thomas r. hollensteiner
Aug 15, 2015, 6:13:18 AM8/15/15
to plan44_vdcd
hello,
the video embed did not work i believe so here is a google drive link
for the 2 videos (dss call and deconz call)
best regards
holli
thomas r. hollensteiner
Aug 17, 2015, 3:24:59 AM8/17/15
to plan44_vdcd
hello,
this is what i got from the DECONZ support - sorry for german but i believe Luz you understand german:
Hallo Herr Hollensteiner, Sie haben das schon ganz richtig erkannt. Wenn eine größere Anzahl von Lichtern gleichzeitig geschalten werden soll, sind Group commands sinnvoll. Die Verzögerung passiert da das Gateway die einzelnen Lampen nacheinander anspricht und dazwischen ist immer eine gewisse Verzögerung. Bei Group commands hingegen wird nur ein Kommando ins Netzwerk abgesetzt, welches auch durch das Mesh sehr schnell geroutet wird.
i did some test's as well - it looks like that if you overcome 6-8 devices the problem starts escalating like a snow ball effect - i believe the zigbee (mesh) network is not the best technology to fire some many request in such a short time - so the best option i still believe is to replicate groups to DSS and use group commands and store the scenes in the zigbee server env. and just call them from dss.
thanks
holli
Lukas Zeller
Aug 17, 2015, 8:51:08 AM8/17/15
to plan44_vdcd
Hello,
> On 17.08.2015, at 09:24, thomas r. hollensteiner <ho...@holli.at> wrote:
>
> this is what i got from the DECONZ support - sorry for german but i believe luz you understand german:
I do :-)
>> Hallo Herr Hollensteiner,
>>
>> Sie haben das schon ganz richtig erkannt. Wenn eine größere Anzahl von Lichtern
>> gleichzeitig geschalten werden soll, sind Group commands sinnvoll. Die
>> Verzögerung passiert da das Gateway die einzelnen Lampen nacheinander anspricht
>> und dazwischen ist immer eine gewisse Verzögerung. Bei Group commands hingegen
>> wird nur ein Kommando ins Netzwerk abgesetzt, welches auch durch das Mesh sehr
>> schnell geroutet wird.
>
> i did some test's as well - it looks like that if you overcome 6-8 devices the problem starts escalating like a snow ball effect - i believe the zigbee (mesh) network is not the best technology to fire some many request in such a short time - so the best option i still believe is to replicate groups to DSS and use group commands and store the scenes in the zigbee server env. and just call them from dss.
I agree that using group commands is probably the way to go. However, for a real dS integration (i.e. one that really conforms to dS behaviour as required by the specs) I would not try to use ZLL scenes, as the concepts differ too much.
Of course, if all you need is forwarding scene calls to a third party server, you could do that with a dSS app written in javascript, much like the original hue app did.
I had a look at the philips hue groups API (which I assume DECONZ replicates). It seems that a group accepts pretty much the same commands like a single lamp. Apparently, it's even possible to query a group in the same way as a single lamp.
So I think the vdcd could offer a way to use groups instead of (or in addition to) single lamps, just by sending new color/brightness requests to the group instead of the individual lamp. The scene table and the entire dS-specific behaviour that goes with it would remain in the vdcd.
How does that sound to you?
The only limitation would be that grouped ZLL lights would no longer be individually addressable from dS, but appear as a single light with all members having always show the same brightness/color.
Best Regards,
Lukas
> On 17.08.2015, at 09:24, thomas r. hollensteiner <ho...@holli.at> wrote:
>
> this is what i got from the DECONZ support - sorry for german but i believe luz you understand german:
I do :-)
>> Hallo Herr Hollensteiner,
>>
>> Sie haben das schon ganz richtig erkannt. Wenn eine größere Anzahl von Lichtern
>> gleichzeitig geschalten werden soll, sind Group commands sinnvoll. Die
>> Verzögerung passiert da das Gateway die einzelnen Lampen nacheinander anspricht
>> und dazwischen ist immer eine gewisse Verzögerung. Bei Group commands hingegen
>> wird nur ein Kommando ins Netzwerk abgesetzt, welches auch durch das Mesh sehr
>> schnell geroutet wird.
>
> i did some test's as well - it looks like that if you overcome 6-8 devices the problem starts escalating like a snow ball effect - i believe the zigbee (mesh) network is not the best technology to fire some many request in such a short time - so the best option i still believe is to replicate groups to DSS and use group commands and store the scenes in the zigbee server env. and just call them from dss.
Of course, if all you need is forwarding scene calls to a third party server, you could do that with a dSS app written in javascript, much like the original hue app did.
I had a look at the philips hue groups API (which I assume DECONZ replicates). It seems that a group accepts pretty much the same commands like a single lamp. Apparently, it's even possible to query a group in the same way as a single lamp.
So I think the vdcd could offer a way to use groups instead of (or in addition to) single lamps, just by sending new color/brightness requests to the group instead of the individual lamp. The scene table and the entire dS-specific behaviour that goes with it would remain in the vdcd.
How does that sound to you?
The only limitation would be that grouped ZLL lights would no longer be individually addressable from dS, but appear as a single light with all members having always show the same brightness/color.
Best Regards,
Lukas
thomas r. hollensteiner
Aug 17, 2015, 11:18:10 AM8/17/15
to plan44_vdcd
this sounds great the DECONZ api is documented at http://dresden-elektronik.github.io/deconz-rest-doc/
The only limitation would be that grouped ZLL lights would no longer be individually addressable from dS, but appear as a single light with all members having always show the same brightness/color.
this is OK as well
Best Regards,
Lukas
for 'stimmung 1-4' - i'm fine and for other's i will do webcall's to the szene because i would like to have some szenes/scenaries where certain lamps have different COLOR/BRIGHTNESS defined but for the main/most cases it would be already great to have the GROUPS as devices and the airfield effect is gone...
thanks a lot
holli
thomas r. hollensteiner
Sep 14, 2015, 4:13:08 PM9/14/15
to plan44_vdcd
hello,
any update on this topic?
if it is ok i might fork your project to change the single items to group calls
thanks
holli
Reply all
Reply to author
Forward
0 new messages