2020-12-12 21:52:35.356 - debug: twinkly.0 (5978) Redis Objects: Use Redis connection: 0.0.0.0:9001
2020-12-12 21:52:35.400 - debug: twinkly.0 (5978) Objects client ready ... initialize now
2020-12-12 21:52:35.404 - debug: twinkly.0 (5978) Objects create System PubSub Client
2020-12-12 21:52:35.405 - debug: twinkly.0 (5978) Objects create User PubSub Client
2020-12-12 21:52:35.407 - debug: twinkly.0 (5978) Objects client initialize lua scripts
2020-12-12 21:52:35.431 - debug: twinkly.0 (5978) Objects connected to redis: 0.0.0.0:9001
2020-12-12 21:52:35.439 - debug: twinkly.0 (5978) objectDB connected
2020-12-12 21:52:35.442 - debug: twinkly.0 (5978) Redis States: Use Redis connection: 0.0.0.0:9000
2020-12-12 21:52:35.455 - debug: twinkly.0 (5978) States create User PubSub Client
2020-12-12 21:52:35.459 - debug: twinkly.0 (5978) States create System PubSub Client
2020-12-12 21:52:35.479 - debug: twinkly.0 (5978) States connected to redis: 0.0.0.0:9000
2020-12-12 21:52:35.480 - debug: twinkly.0 (5978) statesDB connected
2020-12-12 21:52:38.402 - debug: twinkly.0 (5978) Plugin sentry Initialize Plugin (enabled=true)
2020-12-12 21:52:38.727 - info: twinkly.0 (5978) starting. Version 0.0.5 in /opt/iobroker/node_modules/iobroker.twinkly, node: v12.20.0, js-controller: 3.1.6
2020-12-12 21:52:38.766 - debug: twinkly.0 (5978) config devices: [{"enabled":true,"name":"Twinkly_37EFF1","host":"192.168.178.105","connectedState":""}]
2020-12-12 21:52:38.767 - debug: twinkly.0 (5978) config interval: 60
2020-12-12 21:52:38.767 - debug: twinkly.0 (5978) config deviceInfo: true
2020-12-12 21:52:38.768 - debug: twinkly.0 (5978) config mqtt: false
2020-12-12 21:52:38.769 - debug: twinkly.0 (5978) config timer: true
2020-12-12 21:52:38.769 - debug: twinkly.0 (5978) config reset: false
2020-12-12 21:52:38.771 - debug: twinkly.0 (5978) Prepare objects
2020-12-12 21:52:38.773 - debug: twinkly.0 (5978) Get existing objects
2020-12-12 21:52:38.930 - debug: twinkly.0 (5978) Prepare tasks of objects update
2020-12-12 21:52:38.932 - debug: twinkly.0 (5978) Start tasks of objects update
2020-12-12 21:52:38.934 - debug: twinkly.0 (5978) Task: {"type":"update_state","id":{"device":"Twinkly_37EFF1","state":"on"},"data":{"common":{"name":"Twinkly_37EFF1 eingeschaltet","read":true,"write":true,"type":"boolean","role":"switch","def":false}}}, ID: twinkly.0.Twinkly_37EFF1.on
2020-12-12 21:52:38.934 - debug: twinkly.0 (5978) Update state id=twinkly.0.Twinkly_37EFF1.on
2020-12-12 21:52:38.936 - debug: twinkly.0 (5978) Task: {"type":"update_state","id":{"device":"Twinkly_37EFF1","state":"mode"},"data":{"common":{"name":"Twinkly_37EFF1 Mode","read":true,"write":true,"type":"string","role":"state","def":"off","states":{"rt":"Real Time","movie":"Eingeschaltet","off":"Ausgeschaltet","playlist":"Playlist","demo":"Demo","effect":"Effect"}}}}, ID: twinkly.0.Twinkly_37EFF1.mode
2020-12-12 21:52:38.937 - debug: twinkly.0 (5978) Update state id=twinkly.0.Twinkly_37EFF1.mode
2020-12-12 21:52:38.937 - debug: twinkly.0 (5978) Task: {"type":"update_state","id":{"device":"Twinkly_37EFF1","state":"bri"},"data":{"common":{"name":"Twinkly_37EFF1 Brightness","read":true,"write":true,"type":"number","role":"level.dimmer","min":0,"max":100,"def":0}}}, ID: twinkly.0.Twinkly_37EFF1.bri
2020-12-12 21:52:38.938 - debug: twinkly.0 (5978) Update state id=twinkly.0.Twinkly_37EFF1.bri
2020-12-12 21:52:38.939 - debug: twinkly.0 (5978) Task: {"type":"update_state","id":{"device":"Twinkly_37EFF1","state":"name"},"data":{"common":{"name":"Twinkly_37EFF1 Name","read":true,"write":true,"type":"string","role":"info.name","def":""}}}, ID: twinkly.0.Twinkly_37EFF1.name
2020-12-12 21:52:38.939 - debug: twinkly.0 (5978) Update state id=twinkly.0.Twinkly_37EFF1.name
2020-12-12 21:52:38.940 - debug: twinkly.0 (5978) Task: {"type":"update_state","id":{"device":"Twinkly_37EFF1","state":"timer"},"data":{"common":{"name":"Twinkly_37EFF1 Timer","read":true,"write":true,"type":"string","role":"json","def":"{}"}}}, ID: twinkly.0.Twinkly_37EFF1.timer
2020-12-12 21:52:38.941 - debug: twinkly.0 (5978) Update state id=twinkly.0.Twinkly_37EFF1.timer
2020-12-12 21:52:38.941 - debug: twinkly.0 (5978) Task: {"type":"update_state","id":{"device":"Twinkly_37EFF1","state":"details"},"data":{"common":{"name":"Twinkly_37EFF1 Details","read":true,"write":false,"type":"string","role":"json","def":"{}"}}}, ID: twinkly.0.Twinkly_37EFF1.details
2020-12-12 21:52:38.942 - debug: twinkly.0 (5978) Update state id=twinkly.0.Twinkly_37EFF1.details
2020-12-12 21:52:38.942 - debug: twinkly.0 (5978) Task: {"type":"update_state","id":{"device":"Twinkly_37EFF1","state":"firmware"},"data":{"common":{"name":"Twinkly_37EFF1 Firmware","read":true,"write":false,"type":"string","role":"state","def":""}}}, ID: twinkly.0.Twinkly_37EFF1.firmware
2020-12-12 21:52:38.943 - debug: twinkly.0 (5978) Update state id=twinkly.0.Twinkly_37EFF1.firmware
2020-12-12 21:52:38.944 - debug: twinkly.0 (5978) Task: {"type":"update_state","id":{"device":"Twinkly_37EFF1","state":"connected"},"data":{"common":{"name":"Twinkly_37EFF1 Connected","read":true,"write":false,"type":"boolean","role":"indicator.connected","def":false}}}, ID: twinkly.0.Twinkly_37EFF1.connected
2020-12-12 21:52:38.944 - debug: twinkly.0 (5978) Update state id=twinkly.0.Twinkly_37EFF1.connected
2020-12-12 21:52:38.945 - debug: twinkly.0 (5978) Finished tasks of objects update
2020-12-12 21:52:43.778 - debug: twinkly.0 (5978) Start polling...
2020-12-12 21:52:43.779 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.on
2020-12-12 21:52:43.779 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.mode
2020-12-12 21:52:43.780 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:52:43.781 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token expired, will refresh
2020-12-12 21:52:43.820 - debug: twinkly.0 (5978) [sendPostHTTP] {"authentication_token":"RlUGEMhNU34=","authentication_token_expires_in":14400,"challenge-response":"2ce38f8fddc9a6f9ab0065ed35901787ad14b6fe","code":1000}
2020-12-12 21:52:43.822 - debug: twinkly.0 (5978) [Twinkly_37EFF1._post] , {"challenge-response":"2ce38f8fddc9a6f9ab0065ed35901787ad14b6fe"}, {"X-Auth-Token":"RlUGEMhNU34="}
2020-12-12 21:52:43.826 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:52:43.845 - debug: twinkly.0 (5978) [sendPostHTTP] {"code":1000}
2020-12-12 21:52:43.870 - debug: twinkly.0 (5978) [sendGetHTTP] {"mode":"off","shop_mode":0,"code":1000}
2020-12-12 21:52:43.872 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.bri
2020-12-12 21:52:43.873 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:52:43.874 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:52:43.894 - debug: twinkly.0 (5978) [sendGetHTTP] {"value":100,"mode":"disabled","code":1000}
2020-12-12 21:52:43.896 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.name
2020-12-12 21:52:43.896 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:52:43.897 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:52:43.918 - debug: twinkly.0 (5978) [sendGetHTTP] {"name":"Twinkly_37EFF1","code":1000}
2020-12-12 21:52:43.919 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.details
2020-12-12 21:52:43.920 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:52:43.920 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:52:43.939 - debug: twinkly.0 (5978) [sendGetHTTP] {"product_name":"Twinkly","hardware_version":"100","bytes_per_led":4,"hw_id":"37eff0","flash_size":64,"led_type":12,"product_code":"TWS250SPP","fw_family":"G","device_name":"Twinkly_37EFF1","uptime":"76075549","mac":"98:f4:ab:37:ef:f1","uuid":"","max_supported_led":1200,"number_of_led":250,"led_profile":"RGBW","frame_rate":19,"measured_frame_rate":21.28,"movie_capacity":992,"wire_type":1,"copyright":"LEDWORKS 2018","code":1000}
2020-12-12 21:52:43.941 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.firmware
2020-12-12 21:52:43.942 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:52:43.943 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:52:43.963 - debug: twinkly.0 (5978) [sendGetHTTP] {"version":"2.5.9","code":1000}
2020-12-12 21:52:43.965 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.timer
2020-12-12 21:52:43.966 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:52:43.967 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:52:43.986 - debug: twinkly.0 (5978) [sendGetHTTP] {"time_now":78759,"time_on":-1,"time_off":-1,"code":1000}
2020-12-12 21:52:43.988 - debug: twinkly.0 (5978) Finished polling...
2020-12-12 21:53:10.126 - debug: twinkly.0 (5978) state twinkly.0.Twinkly_37EFF1.on changed: true (ack = false)
2020-12-12 21:53:10.128 - debug: twinkly.0 (5978) [Twinkly_37EFF1._post] , {"mode":"movie"}, {"X-Auth-Token":"RlUGEMhNU34=","Content-Type":"application/json"}
2020-12-12 21:53:10.129 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:53:10.155 - debug: twinkly.0 (5978) [sendPostHTTP] {"code":1104}
2020-12-12 21:53:10.157 - error: twinkly.0 (5978) Could not set Twinkly_37EFF1.on [Twinkly_37EFF1.POST.led/mode] 1104 (Error - malformed JSON on input), Data: {"mode":"movie"}, Headers: {"X-Auth-Token":"RlUGEMhNU34=","Content-Type":"application/json"}
2020-12-12 21:53:17.833 - debug: twinkly.0 (5978) state twinkly.0.Twinkly_37EFF1.on changed: false (ack = false)
2020-12-12 21:53:17.835 - debug: twinkly.0 (5978) [Twinkly_37EFF1._post] , {"mode":"off"}, {"X-Auth-Token":"RlUGEMhNU34=","Content-Type":"application/json"}
2020-12-12 21:53:17.836 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:53:17.865 - debug: twinkly.0 (5978) [sendPostHTTP] {"code":1000}
2020-12-12 21:53:43.989 - debug: twinkly.0 (5978) Start polling...
2020-12-12 21:53:43.990 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.on
2020-12-12 21:53:43.991 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.mode
2020-12-12 21:53:43.992 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:53:43.992 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:53:44.014 - debug: twinkly.0 (5978) [sendGetHTTP] {"mode":"off","shop_mode":0,"code":1000}
2020-12-12 21:53:44.016 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.bri
2020-12-12 21:53:44.017 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:53:44.018 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:53:44.045 - debug: twinkly.0 (5978) [sendGetHTTP] {"value":100,"mode":"disabled","code":1000}
2020-12-12 21:53:44.047 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.name
2020-12-12 21:53:44.047 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:53:44.048 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:53:44.070 - debug: twinkly.0 (5978) [sendGetHTTP] {"name":"Twinkly_37EFF1","code":1000}
2020-12-12 21:53:44.072 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.details
2020-12-12 21:53:44.072 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:53:44.073 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:53:44.094 - debug: twinkly.0 (5978) [sendGetHTTP] {"product_name":"Twinkly","hardware_version":"100","bytes_per_led":4,"hw_id":"37eff0","flash_size":64,"led_type":12,"product_code":"TWS250SPP","fw_family":"G","device_name":"Twinkly_37EFF1","uptime":"76135703","mac":"98:f4:ab:37:ef:f1","uuid":"","max_supported_led":1200,"number_of_led":250,"led_profile":"RGBW","frame_rate":19,"measured_frame_rate":21.28,"movie_capacity":992,"wire_type":1,"copyright":"LEDWORKS 2018","code":1000}
2020-12-12 21:53:44.096 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.firmware
2020-12-12 21:53:44.097 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:53:44.098 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:53:44.122 - debug: twinkly.0 (5978) [sendGetHTTP] {"version":"2.5.9","code":1000}
2020-12-12 21:53:44.125 - debug: twinkly.0 (5978) Polling Twinkly_37EFF1.timer
2020-12-12 21:53:44.126 - debug: twinkly.0 (5978) [Twinkly_37EFF1._get]
2020-12-12 21:53:44.127 - debug: twinkly.0 (5978) [Twinkly_37EFF1.ensure_token] Authentication token still valid (2020-12-13 1:52:43)
2020-12-12 21:53:44.169 - debug: twinkly.0 (5978) [sendGetHTTP] {"time_now":78819,"time_on":-1,"time_off":-1,"code":1000}
2020-12-12 21:53:44.170 - debug: twinkly.0 (5978) Finished polling...