Unable to view managed devices

sunqan · September 6, 2021, 10:04pm

I upgraded my vera secure to the latest beta while ago today i wanted to have a look at the managed devices of my RFXtrx433XL and got this

Error executing function RFX_showManagedDevices(): Cannot set properties of undefined (setting ‘disabled’)

I am running 1.97 of the plugin and the proxl1 firmware.

Any ideas?

tinman · September 7, 2021, 12:53pm

I have not seen this problem nor have I seen it reported by others. It’s difficult to know the exact cause of this error without seeing data from the log. Does this error occur whenever you open the Managed devices tab or does it happen when you select a particular device?

sunqan · September 8, 2021, 8:31pm

It happens whenever I try to open “managed devices” and only then…
I’ll reinstall 1.97 and see what happens…

sunqan · September 9, 2021, 1:23pm

re-uploading the 1.97 files made no difference. I had a quick look in the log but I really can’t see anything out of the oirdinary.

For the heck of it I opened Managed devices in altui, and the message there is a bit more verbose
an error occurred while displaying the javascript tab. devid: 0-167 err:Cannot set properties of undefined (setting ‘disabled’)
stack:TypeError: Cannot set properties of undefined (setting ‘disabled’)
at RFX_selectDevices (:2230:41)
at RFX_updateDevicesTable (:1261:2)
at RFX_showManagedDevices (:1050:2)
at eval (eval at _deviceDrawControlPanelJSTab (http://192.168.1.20/port_3480/J_ALTUI_uimgr.js:3024:17), :1:1)
at _deviceDrawControlPanelJSTab (http://192.168.1.20/port_3480/J_ALTUI_uimgr.js:3024:17)
at _deviceDrawControlPanelOneTabContent (http://192.168.1.20/port_3480/J_ALTUI_uimgr.js:3718:8)
at _displayActiveDeviceTab (http://192.168.1.20/port_3480/J_ALTUI_uimgr.js:3745:4)
at HTMLAnchorElement. (http://192.168.1.20/port_3480/J_ALTUI_uimgr.js:7037:4)
at HTMLDivElement.dispatch (https://cdnjs.cloudflare.com/ajax/libs/jquery/3.5.1/jquery.min.js:2:43090)
at HTMLDivElement.v.handle (https://cdnjs.cloudflare.com/ajax/libs/jquery/3.5.1/jquery.min.js:2:41074)

sunqan · September 9, 2021, 2:37pm

the log entries in luaupnp.log with “167” the device number of my rfxtrx from when I tried to look at the managed devices at around 14:32

06 09/09/21 14:32:07.148 Device_Variable::m_szValue_set device: 595 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastAverage was: 13.149301675984 now: 13.149511854958 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x73eaf520>
50 09/09/21 14:32:20.485 luup_log:167: RFXtrx: dbg: Received message: 0A 52 07 1C 38 0E 00 E1 3B 01 69 <0x6bfe0520>
50 09/09/21 14:32:20.486 luup_log:167: RFXtrx: dbg: typeof value: string <0x6bfe0520>
50 09/09/21 14:32:20.486 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: LastReceivedMsg value: 0A 52 07 1C 38 0E 00 E1 3B 01 69 <0x6bfe0520>
06 09/09/21 14:32:20.487 Device_Variable::m_szValue_set device: 167 service: urn:rfxcom-com:serviceId:rfxtrx1 variable: LastReceivedMsg was: 0A 52 08 1B 06 02 00 E4 45 01 69 now: 0A 52 07 1C 38 0E 00 E1 3B 01 69 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:20.487 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: VeraTime value: 1631190740 <0x6bfe0520>
06 09/09/21 14:32:20.488 Device_Variable::m_szValue_set device: 167 service: upnp-rfxcom-com:serviceId:rfxtrx1 variable: VeraTime was: 1631190720 now: 1631190740 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:20.489 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH7/14350 deviceType: urn:schemas-micasaverde-com:device:TemperatureSensor:1 <0x6bfe0520>
50 09/09/21 14:32:20.489 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH7/14350urn:schemas-micasaverde-com:device:TemperatureSensor:1 devicenum: 0 myDevice: false <0x6bfe0520>
50 09/09/21 14:32:20.489 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH7/14350 deviceType: urn:schemas-micasaverde-com:device:HumiditySensor:1 <0x6bfe0520>
50 09/09/21 14:32:20.490 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH7/14350urn:schemas-micasaverde-com:device:HumiditySensor:1 devicenum: 0 myDevice: false <0x6bfe0520>
50 09/09/21 14:32:20.490 luup_log:167: RFXtrx: dbg: actOnCommands:cmds: TH7/14350 Temperature 22.5 TH7/14350 Humidity 59 TH7/14350 CommStrength 6 TH7/14350 BatteryLevel 100 <0x6bfe0520>
50 09/09/21 14:32:31.614 luup_log:167: RFXtrx: dbg: Received message: 0A 52 09 1D F7 00 01 07 33 01 69 <0x6bfe0520>
50 09/09/21 14:32:31.614 luup_log:167: RFXtrx: dbg: typeof value: string <0x6bfe0520>
50 09/09/21 14:32:31.615 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: LastReceivedMsg value: 0A 52 09 1D F7 00 01 07 33 01 69 <0x6bfe0520>
06 09/09/21 14:32:31.615 Device_Variable::m_szValue_set device: 167 service: urn:rfxcom-com:serviceId:rfxtrx1 variable: LastReceivedMsg was: 0A 52 07 1C 38 0E 00 E1 3B 01 69 now: 0A 52 09 1D F7 00 01 07 33 01 69 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:31.616 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: VeraTime value: 1631190751 <0x6bfe0520>
06 09/09/21 14:32:31.616 Device_Variable::m_szValue_set device: 167 service: upnp-rfxcom-com:serviceId:rfxtrx1 variable: VeraTime was: 1631190740 now: 1631190751 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:31.617 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH9/63232 deviceType: urn:schemas-micasaverde-com:device:TemperatureSensor:1 <0x6bfe0520>
50 09/09/21 14:32:31.618 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH9/63232urn:schemas-micasaverde-com:device:TemperatureSensor:1 devicenum: 626 myDevice: true <0x6bfe0520>
50 09/09/21 14:32:31.620 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH9/63232 deviceType: urn:schemas-micasaverde-com:device:HumiditySensor:1 <0x6bfe0520>
50 09/09/21 14:32:31.620 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH9/63232urn:schemas-micasaverde-com:device:HumiditySensor:1 devicenum: 627 myDevice: true <0x6bfe0520>
50 09/09/21 14:32:31.622 luup_log:167: RFXtrx: dbg: actOnCommands:cmds: TH9/63232 Temperature 26.3 TH9/63232 Humidity 51 TH9/63232 CommStrength 6 TH9/63232 BatteryLevel 100 <0x6bfe0520>
50 09/09/21 14:32:31.623 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 626 variable: CurrentTemperature value: 26.3 <0x6bfe0520>
50 09/09/21 14:32:31.623 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 626 variable: CommStrength value: 6 <0x6bfe0520>
50 09/09/21 14:32:31.623 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 626 variable: BatteryLevel value: 100 <0x6bfe0520>
50 09/09/21 14:32:31.624 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 626 variable: BatteryDate value: 1631190751 <0x6bfe0520>
50 09/09/21 14:32:31.625 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 627 variable: CurrentLevel value: 51 <0x6bfe0520>
50 09/09/21 14:32:31.625 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 627 variable: CommStrength value: 6 <0x6bfe0520>
50 09/09/21 14:32:31.626 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 627 variable: BatteryLevel value: 100 <0x6bfe0520>
50 09/09/21 14:32:31.626 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 627 variable: BatteryDate value: 1631190751 <0x6bfe0520>
50 09/09/21 14:32:52.582 luup_log:167: RFXtrx: dbg: Received message: 0A 52 08 1E 06 01 00 DA 42 01 69 <0x6bfe0520>
50 09/09/21 14:32:52.582 luup_log:167: RFXtrx: dbg: typeof value: string <0x6bfe0520>
50 09/09/21 14:32:52.583 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: LastReceivedMsg value: 0A 52 08 1E 06 01 00 DA 42 01 69 <0x6bfe0520>
06 09/09/21 14:32:52.583 Device_Variable::m_szValue_set device: 167 service: urn:rfxcom-com:serviceId:rfxtrx1 variable: LastReceivedMsg was: 0A 52 09 1D F7 00 01 07 33 01 69 now: 0A 52 08 1E 06 01 00 DA 42 01 69 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:52.584 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: VeraTime value: 1631190772 <0x6bfe0520>
06 09/09/21 14:32:52.584 Device_Variable::m_szValue_set device: 167 service: upnp-rfxcom-com:serviceId:rfxtrx1 variable: VeraTime was: 1631190751 now: 1631190772 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:52.585 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH8/1537 deviceType: urn:schemas-micasaverde-com:device:TemperatureSensor:1 <0x6bfe0520>
50 09/09/21 14:32:52.585 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH8/1537urn:schemas-micasaverde-com:device:TemperatureSensor:1 devicenum: 180 myDevice: true <0x6bfe0520>
50 09/09/21 14:32:52.588 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH8/1537 deviceType: urn:schemas-micasaverde-com:device:HumiditySensor:1 <0x6bfe0520>
50 09/09/21 14:32:52.588 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH8/1537urn:schemas-micasaverde-com:device:HumiditySensor:1 devicenum: 181 myDevice: true <0x6bfe0520>
50 09/09/21 14:32:52.589 luup_log:167: RFXtrx: dbg: actOnCommands:cmds: TH8/1537 Temperature 21.8 TH8/1537 Humidity 66 TH8/1537 CommStrength 6 TH8/1537 BatteryLevel 100 <0x6bfe0520>
50 09/09/21 14:32:52.593 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 180 variable: CurrentTemperature value: 21.8 <0x6bfe0520>
50 09/09/21 14:32:52.646 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 180 variable: CommStrength value: 6 <0x6bfe0520>
50 09/09/21 14:32:52.646 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 180 variable: BatteryLevel value: 100 <0x6bfe0520>
50 09/09/21 14:32:52.647 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 180 variable: BatteryDate value: 1631190772 <0x6bfe0520>
50 09/09/21 14:32:52.648 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 181 variable: CurrentLevel value: 66 <0x6bfe0520>
50 09/09/21 14:32:52.649 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 181 variable: CommStrength value: 6 <0x6bfe0520>
50 09/09/21 14:32:52.649 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 181 variable: BatteryLevel value: 100 <0x6bfe0520>
50 09/09/21 14:32:52.649 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 181 variable: BatteryDate value: 1631190772 <0x6bfe0520>
50 09/09/21 14:33:00.712 luup_log:167: RFXtrx: dbg: Received message: 0A 52 08 1F 06 02 00 E4 45 01 69 <0x6bfe0520>
50 09/09/21 14:33:00.712 luup_log:167: RFXtrx: dbg: typeof value: string <0x6bfe0520>
50 09/09/21 14:33:00.712 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: LastReceivedMsg value: 0A 52 08 1F 06 02 00 E4 45 01 69 <0x6bfe0520>
06 09/09/21 14:33:00.713 Device_Variable::m_szValue_set device: 167 service: urn:rfxcom-com:serviceId:rfxtrx1 variable: LastReceivedMsg was: 0A 52 08 1E 06 01 00 DA 42 01 69 now: 0A 52 08 1F 06 02 00 E4 45 01 69 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:33:00.713 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: VeraTime value: 1631190780 <0x6bfe0520>
06 09/09/21 14:33:00.714 Device_Variable::m_szValue_set device: 167 service: upnp-rfxcom-com:serviceId:rfxtrx1 variable: VeraTime was: 1631190772 now: 1631190780 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:33:00.715 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH8/1538 deviceType: urn:schemas-micasaverde-com:device:TemperatureSensor:1 <0x6bfe0520>
50 09/09/21 14:33:00.715 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH8/1538urn:schemas-micasaverde-com:device:TemperatureSensor:1 devicenum: 187 myDevice: true <0x6bfe0520>
50 09/09/21 14:33:00.717 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH8/1538 deviceType: urn:schemas-micasaverde-com:device:HumiditySensor:1 <0x6bfe0520>
50 09/09/21 14:33:00.718 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH8/1538urn:schemas-micasaverde-com:device:HumiditySensor:1 devicenum: 188 myDevice: true <0x6bfe0520>
50 09/09/21 14:33:00.719 luup_log:167: RFXtrx: dbg: actOnCommands:cmds: TH8/1538 Temperature 22.8 TH8/1538 Humidity 69 TH8/1538 CommStrength 6 TH8/1538 BatteryLevel 100 <0x6bfe0520>
50 09/09/21 14:33:00.723 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 188 variable: CurrentLevel value: 69 <0x6bfe0520>
50 09/09/21 14:33:00.724 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 188 variable: CommStrength value: 6 <0x6bfe0520>
50 09/09/21 14:33:00.724 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 188 variable: BatteryLevel value: 100 <0x6bfe0520>
50 09/09/21 14:33:00.724 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 188 variable: BatteryDate value: 1631190780 <0x6bfe0520>
50 09/09/21 14:33:00.796 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 187 variable: CurrentTemperature value: 22.8 <0x6bfe0520>
50 09/09/21 14:33:00.797 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 187 variable: CommStrength value: 6 <0x6bfe0520>
50 09/09/21 14:33:00.798 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 187 variable: BatteryLevel value: 100 <0x6bfe0520>
50 09/09/21 14:33:00.798 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 187 variable: BatteryDate value: 1631190780 <0x6bfe0520>
50 09/09/21 14:33:03.477 luup_log:167: RFXtrx: dbg: Received message: 0A 52 07 20 38 0E 00 E1 3B 01 69 <0x6bfe0520>
50 09/09/21 14:33:03.478 luup_log:167: RFXtrx: dbg: typeof value: string <0x6bfe0520>
50 09/09/21 14:33:03.479 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: LastReceivedMsg value: 0A 52 07 20 38 0E 00 E1 3B 01 69 <0x6bfe0520>
06 09/09/21 14:33:03.479 Device_Variable::m_szValue_set device: 167 service: urn:rfxcom-com:serviceId:rfxtrx1 variable: LastReceivedMsg was: 0A 52 08 1F 06 02 00 E4 45 01 69 now: 0A 52 07 20 38 0E 00 E1 3B 01 69 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:33:03.480 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: VeraTime value: 1631190783 <0x6bfe0520>
06 09/09/21 14:33:03.480 Device_Variable::m_szValue_set device: 167 service: upnp-rfxcom-com:serviceId:rfxtrx1 variable: VeraTime was: 1631190780 now: 1631190783 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:33:03.481 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH7/14350 deviceType: urn:schemas-micasaverde-com:device:TemperatureSensor:1 <0x6bfe0520>
50 09/09/21 14:33:03.481 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH7/14350urn:schemas-micasaverde-com:device:TemperatureSensor:1 devicenum: 0 myDevice: false <0x6bfe0520>
50 09/09/21 14:33:03.481 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH7/14350 deviceType: urn:schemas-micasaverde-com:device:HumiditySensor:1 <0x6bfe0520>
50 09/09/21 14:33:03.482 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH7/14350urn:schemas-micasaverde-com:device:HumiditySensor:1 devicenum: 0 myDevice: false <0x6bfe0520>
50 09/09/21 14:33:03.482 luup_log:167: RFXtrx: dbg: actOnCommands:cmds: TH7/14350 Temperature 22.5 TH7/14350 Humidity 59 TH7/14350 CommStrength 6 TH7/14350 BatteryLevel 100 <0x6bfe0520>

sunqan · September 9, 2021, 2:38pm

and this is the entire logfor the period 14:32:07 - 14:33
06 09/09/21 14:32:07.102 Device_Variable::m_szValue_set device: 598 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 12.60 now: 12.60 #hooks: 0 upnp: 0 skip: 0 v:0xe6e368/NONE duplicate:1 <0x73caf520>
06 09/09/21 14:32:07.102 Device_Variable::m_szValue_set device: 598 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: pollCount was: 324 now: 325 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x73caf520>
06 09/09/21 14:32:07.103 Device_Variable::m_szValue_set device: 598 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastDay was: 9 now: 9 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x73caf520>
06 09/09/21 14:32:07.103 Device_Variable::m_szValue_set device: 598 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastAverage was: 12.79259259259 now: 12.791999999997 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x73caf520>
06 09/09/21 14:32:07.103 Device_Variable::m_szValue_set device: 598 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysHeat was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x73caf520>
06 09/09/21 14:32:07.107 Device_Variable::m_szValue_set device: 598 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysCool was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x73caf520>
06 09/09/21 14:32:07.102 Device_Variable::m_szValue_set device: 510 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 23.50 now: 23.50 #hooks: 0 upnp: 0 skip: 0 v:0xe6e368/NONE duplicate:1 <0x748af520>
06 09/09/21 14:32:07.112 Device_Variable::m_szValue_set device: 510 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: pollCount was: 201 now: 202 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x748af520>
06 09/09/21 14:32:07.113 Device_Variable::m_szValue_set device: 510 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastDay was: 9 now: 9 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x748af520>
06 09/09/21 14:32:07.113 Device_Variable::m_szValue_set device: 510 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastAverage was: 23.5 now: 23.5 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x748af520>
06 09/09/21 14:32:07.113 Device_Variable::m_szValue_set device: 510 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysHeat was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x748af520>
06 09/09/21 14:32:07.114 Device_Variable::m_szValue_set device: 510 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysCool was: 1.5 now: 1.5 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x748af520>
06 09/09/21 14:32:07.106 Device_Variable::m_szValue_set device: 599 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 11.10 now: 11.10 #hooks: 0 upnp: 0 skip: 0 v:0xe6e368/NONE duplicate:1 <0x73aaf520>
06 09/09/21 14:32:07.106 Device_Variable::m_szValue_set device: 509 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 24.30 now: 24.30 #hooks: 0 upnp: 0 skip: 0 v:0xe6e368/NONE duplicate:1 <0x74aaf520>
06 09/09/21 14:32:07.116 Device_Variable::m_szValue_set device: 509 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: pollCount was: 777 now: 778 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74aaf520>
06 09/09/21 14:32:07.117 Device_Variable::m_szValue_set device: 509 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastDay was: 9 now: 9 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x74aaf520>
06 09/09/21 14:32:07.118 Device_Variable::m_szValue_set device: 509 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastAverage was: 24.3 now: 24.3 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x74aaf520>
06 09/09/21 14:32:07.118 Device_Variable::m_szValue_set device: 509 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysHeat was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x74aaf520>
06 09/09/21 14:32:07.118 Device_Variable::m_szValue_set device: 509 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysCool was: 2.3 now: 2.3 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x74aaf520>
06 09/09/21 14:32:07.103 Device_Variable::m_szValue_set device: 505 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 26.56 now: 26.56 #hooks: 0 upnp: 0 skip: 0 v:0xe6e368/NONE duplicate:1 <0x74caf520>
06 09/09/21 14:32:07.124 Device_Variable::m_szValue_set device: 505 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: pollCount was: 22 now: 23 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74caf520>
06 09/09/21 14:32:07.124 Device_Variable::m_szValue_set device: 505 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastDay was: 9 now: 9 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x74caf520>
06 09/09/21 14:32:07.125 Device_Variable::m_szValue_set device: 505 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastAverage was: 26.557272727273 now: 26.557391304348 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74caf520>
06 09/09/21 14:32:07.125 Device_Variable::m_szValue_set device: 505 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysHeat was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x74caf520>
06 09/09/21 14:32:07.125 Device_Variable::m_szValue_set device: 505 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysCool was: 4.6 now: 4.6 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x74caf520>
06 09/09/21 14:32:07.116 Device_Variable::m_szValue_set device: 599 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: pollCount was: 10 now: 11 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x73aaf520>
06 09/09/21 14:32:07.127 Device_Variable::m_szValue_set device: 599 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastDay was: 9 now: 9 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x73aaf520>
06 09/09/21 14:32:07.127 Device_Variable::m_szValue_set device: 599 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastAverage was: 11.1 now: 11.1 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x73aaf520>
06 09/09/21 14:32:07.127 Device_Variable::m_szValue_set device: 599 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysHeat was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x73aaf520>
06 09/09/21 14:32:07.128 Device_Variable::m_szValue_set device: 599 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysCool was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x73aaf520>
06 09/09/21 14:32:07.106 Device_Variable::m_szValue_set device: 512 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 24.00 now: 24.00 #hooks: 0 upnp: 0 skip: 0 v:0xe6e368/NONE duplicate:1 <0x746af520>
06 09/09/21 14:32:07.137 Device_Variable::m_szValue_set device: 512 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: pollCount was: 444 now: 445 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x746af520>
06 09/09/21 14:32:07.138 Device_Variable::m_szValue_set device: 512 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastDay was: 9 now: 9 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x746af520>
06 09/09/21 14:32:07.138 Device_Variable::m_szValue_set device: 512 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastAverage was: 24 now: 24 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x746af520>
06 09/09/21 14:32:07.138 Device_Variable::m_szValue_set device: 512 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysHeat was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x746af520>
06 09/09/21 14:32:07.139 Device_Variable::m_szValue_set device: 512 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysCool was: 2 now: 2 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x746af520>
06 09/09/21 14:32:07.107 Device_Variable::m_szValue_set device: 595 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13.30 now: 13.30 #hooks: 0 upnp: 0 skip: 0 v:0xe6e368/NONE duplicate:1 <0x73eaf520>
06 09/09/21 14:32:07.148 Device_Variable::m_szValue_set device: 595 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: pollCount was: 716 now: 717 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x73eaf520>
06 09/09/21 14:32:07.148 Device_Variable::m_szValue_set device: 595 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastDay was: 9 now: 9 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x73eaf520>
06 09/09/21 14:32:07.148 Device_Variable::m_szValue_set device: 595 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: lastAverage was: 13.149301675984 now: 13.149511854958 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x73eaf520>
06 09/09/21 14:32:07.149 Device_Variable::m_szValue_set device: 595 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysHeat was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x73eaf520>
06 09/09/21 14:32:07.149 Device_Variable::m_szValue_set device: 595 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: degreeDaysCool was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x73eaf520>
02 09/09/21 14:32:12.102 ZWaveNode::AddPollingCommand cannot find child sensor 4 for node 51 0x10d5ec8 <0x768af520>
06 09/09/21 14:32:13.012 Device_Variable::m_szValue_set device: 290 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 141.2 now: 111.8 #hooks: 1 upnp: 0 skip: 0 v:0xe0d500/NONE duplicate:0 <0x770af520>
07 09/09/21 14:32:13.023 Event::Evaluate 3 kÃ¶k scene kÃ¶k is false repeat 1/1 <0x770af520>
06 09/09/21 14:32:13.024 Device_Variable::m_szValue_set device: 290 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 141,141,141,1631190600,1 now: 111,141,141,1631190733,134 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x770af520>
06 09/09/21 14:32:13.025 Device_Variable::m_szValue_set device: 290 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: ActualUsage was: 1 now: 1 #hooks: 0 upnp: 0 skip: 0 v:0xe0d568/NONE duplicate:1 <0x770af520>
06 09/09/21 14:32:14.556 Device_Variable::m_szValue_set device: 666 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: KWH was: 1697.6100 now: 1697.6200 #hooks: 0 upnp: 0 skip: 0 v:0xe110f8/NONE duplicate:0 <0x770af520>
06 09/09/21 14:32:14.567 Device_Variable::m_szValue_set device: 666 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: KWHReading was: 1631190664 now: 1631190734 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x770af520>
50 09/09/21 14:32:16.233 luup_log:6: G550> Battery level info updated <0x758af520>
50 09/09/21 14:32:16.261 luup_log:6: G550> Storage level info updated <0x758af520>
06 09/09/21 14:32:16.684 Device_Variable::m_szValue_set device: 666 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 336.0 now: 329.8 #hooks: 0 upnp: 0 skip: 0 v:0xe0d500/NONE duplicate:0 <0x770af520>
06 09/09/21 14:32:16.685 Device_Variable::m_szValue_set device: 666 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 336,335,336,1631190666,67 now: 329,335,336,1631190736,137 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x770af520>
06 09/09/21 14:32:16.686 Device_Variable::m_szValue_set device: 666 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: ActualUsage was: 1 now: 1 #hooks: 0 upnp: 0 skip: 0 v:0xe0d568/NONE duplicate:1 <0x770af520>
04 09/09/21 14:32:16.728 <0x770af520>
02 09/09/21 14:32:16.729 Device_Basic::AddPoll 666 poll list full, deleting old one <0x770af520>
06 09/09/21 14:32:16.730 Device_Variable::m_szValue_set device: 666 service: urn:micasaverde-com:serviceId:HaDevice1 variable: PollRatings was: 5.00 now: 5.00 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x770af520>
06 09/09/21 14:32:16.730 Device_Variable::m_szValue_set device: 666 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: LastPollSuccess was: 1631190666 now: 1631190736 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x770af520>
06 09/09/21 14:32:16.731 Device_Variable::m_szValue_set device: 666 service: urn:micasaverde-com:serviceId:ZWaveNetwork1 variable: ConsecutivePollFails was: 0 now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:1 <0x770af520>
50 09/09/21 14:32:20.485 luup_log:167: RFXtrx: dbg: Received message: 0A 52 07 1C 38 0E 00 E1 3B 01 69 <0x6bfe0520>
50 09/09/21 14:32:20.486 luup_log:167: RFXtrx: dbg: typeof value: string <0x6bfe0520>
50 09/09/21 14:32:20.486 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: LastReceivedMsg value: 0A 52 07 1C 38 0E 00 E1 3B 01 69 <0x6bfe0520>
06 09/09/21 14:32:20.487 Device_Variable::m_szValue_set device: 167 service: urn:rfxcom-com:serviceId:rfxtrx1 variable: LastReceivedMsg was: 0A 52 08 1B 06 02 00 E4 45 01 69 now: 0A 52 07 1C 38 0E 00 E1 3B 01 69 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:20.487 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: VeraTime value: 1631190740 <0x6bfe0520>
06 09/09/21 14:32:20.488 Device_Variable::m_szValue_set device: 167 service: upnp-rfxcom-com:serviceId:rfxtrx1 variable: VeraTime was: 1631190720 now: 1631190740 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:20.489 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH7/14350 deviceType: urn:schemas-micasaverde-com:device:TemperatureSensor:1 <0x6bfe0520>
50 09/09/21 14:32:20.489 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH7/14350urn:schemas-micasaverde-com:device:TemperatureSensor:1 devicenum: 0 myDevice: false <0x6bfe0520>
50 09/09/21 14:32:20.489 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH7/14350 deviceType: urn:schemas-micasaverde-com:device:HumiditySensor:1 <0x6bfe0520>
50 09/09/21 14:32:20.490 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH7/14350urn:schemas-micasaverde-com:device:HumiditySensor:1 devicenum: 0 myDevice: false <0x6bfe0520>
50 09/09/21 14:32:20.490 luup_log:167: RFXtrx: dbg: actOnCommands:cmds: TH7/14350 Temperature 22.5 TH7/14350 Humidity 59 TH7/14350 CommStrength 6 TH7/14350 BatteryLevel 100 <0x6bfe0520>
06 09/09/21 14:32:23.000 Device_Variable::m_szValue_set device: 290 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 111.8 now: 129.0 #hooks: 1 upnp: 0 skip: 0 v:0xe0d500/NONE duplicate:0 <0x770af520>
07 09/09/21 14:32:23.011 Event::Evaluate 3 kÃ¶k scene kÃ¶k is true users: allow:1 <0x770af520>
08 09/09/21 14:32:23.012 Scene::RunScene running 10 kÃ¶k <0x770af520>
06 09/09/21 14:32:23.013 Device_Variable::m_szValue_set device: 290 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 111,141,141,1631190733,134 now: 129,138,141,1631190743,144 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x770af520>
06 09/09/21 14:32:23.025 Device_Variable::m_szValue_set device: 290 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: ActualUsage was: 1 now: 1 #hooks: 0 upnp: 0 skip: 0 v:0xe0d568/NONE duplicate:1 <0x770af520>
06 09/09/21 14:32:27.312 Device_Variable::m_szValue_set device: 99 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 0.5 now: 0.5 #hooks: 0 upnp: 0 skip: 0 v:0xe0d500/NONE duplicate:1 <0x770af520>
06 09/09/21 14:32:27.313 Device_Variable::m_szValue_set device: 99 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: ActualUsage was: 1 now: 1 #hooks: 0 upnp: 0 skip: 0 v:0xe0d568/NONE duplicate:1 <0x770af520>
50 09/09/21 14:32:31.254 luup_log:6: G550> Battery level info updated <0x758af520>
50 09/09/21 14:32:31.282 luup_log:6: G550> Storage level info updated <0x758af520>
50 09/09/21 14:32:31.614 luup_log:167: RFXtrx: dbg: Received message: 0A 52 09 1D F7 00 01 07 33 01 69 <0x6bfe0520>
50 09/09/21 14:32:31.614 luup_log:167: RFXtrx: dbg: typeof value: string <0x6bfe0520>
50 09/09/21 14:32:31.615 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: LastReceivedMsg value: 0A 52 09 1D F7 00 01 07 33 01 69 <0x6bfe0520>
06 09/09/21 14:32:31.615 Device_Variable::m_szValue_set device: 167 service: urn:rfxcom-com:serviceId:rfxtrx1 variable: LastReceivedMsg was: 0A 52 07 1C 38 0E 00 E1 3B 01 69 now: 0A 52 09 1D F7 00 01 07 33 01 69 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:31.616 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: VeraTime value: 1631190751 <0x6bfe0520>
06 09/09/21 14:32:31.616 Device_Variable::m_szValue_set device: 167 service: upnp-rfxcom-com:serviceId:rfxtrx1 variable: VeraTime was: 1631190740 now: 1631190751 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:31.617 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH9/63232 deviceType: urn:schemas-micasaverde-com:device:TemperatureSensor:1 <0x6bfe0520>
50 09/09/21 14:32:31.618 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH9/63232urn:schemas-micasaverde-com:device:TemperatureSensor:1 devicenum: 626 myDevice: true <0x6bfe0520>
50 09/09/21 14:32:31.620 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH9/63232 deviceType: urn:schemas-micasaverde-com:device:HumiditySensor:1 <0x6bfe0520>
50 09/09/21 14:32:31.620 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH9/63232urn:schemas-micasaverde-com:device:HumiditySensor:1 devicenum: 627 myDevice: true <0x6bfe0520>
50 09/09/21 14:32:31.622 luup_log:167: RFXtrx: dbg: actOnCommands:cmds: TH9/63232 Temperature 26.3 TH9/63232 Humidity 51 TH9/63232 CommStrength 6 TH9/63232 BatteryLevel 100 <0x6bfe0520>
50 09/09/21 14:32:31.623 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 626 variable: CurrentTemperature value: 26.3 <0x6bfe0520>
50 09/09/21 14:32:31.623 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 626 variable: CommStrength value: 6 <0x6bfe0520>
50 09/09/21 14:32:31.623 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 626 variable: BatteryLevel value: 100 <0x6bfe0520>
50 09/09/21 14:32:31.624 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 626 variable: BatteryDate value: 1631190751 <0x6bfe0520>
06 09/09/21 14:32:31.624 Device_Variable::m_szValue_set device: 626 service: urn:micasaverde-com:serviceId:HaDevice1 variable: BatteryDate was: 1631190703 now: 1631190751 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:31.625 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 627 variable: CurrentLevel value: 51 <0x6bfe0520>
50 09/09/21 14:32:31.625 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 627 variable: CommStrength value: 6 <0x6bfe0520>
50 09/09/21 14:32:31.626 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 627 variable: BatteryLevel value: 100 <0x6bfe0520>
50 09/09/21 14:32:31.626 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 627 variable: BatteryDate value: 1631190751 <0x6bfe0520>
06 09/09/21 14:32:31.626 Device_Variable::m_szValue_set device: 627 service: urn:micasaverde-com:serviceId:HaDevice1 variable: BatteryDate was: 1631190703 now: 1631190751 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
06 09/09/21 14:32:34.313 Device_Variable::m_szValue_set device: 99 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: KWH was: 3977.2600 now: 3977.2600 #hooks: 0 upnp: 0 skip: 0 v:0xe110f8/NONE duplicate:1 <0x770af520>
06 09/09/21 14:32:34.313 Device_Variable::m_szValue_set device: 99 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: KWHReading was: 1631190694 now: 1631190754 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x770af520>
08 09/09/21 14:32:39.582 JobHandler_LuaUPnP::HandleActionRequest device: 0 service: urn:micasaverde-com:serviceId:HomeAutomationGateway1 action: LogIpRequest <0x6e452520>
08 09/09/21 14:32:39.583 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:micasaverde-com:serviceId:HomeAutomationGateway1 <0x6e452520>
08 09/09/21 14:32:39.583 JobHandler_LuaUPnP::HandleActionRequest argument action=LogIpRequest <0x6e452520>
08 09/09/21 14:32:39.583 JobHandler_LuaUPnP::HandleActionRequest argument IpAddress=192.168.1.109 <0x6e452520>
08 09/09/21 14:32:39.583 JobHandler_LuaUPnP::HandleActionRequest argument MacAddress=f0:b3:ec:13:ba:0e <0x6e452520>
50 09/09/21 14:32:46.256 luup_log:6: G550> Battery level info updated <0x758af520>
50 09/09/21 14:32:46.299 luup_log:6: G550> Storage level info updated <0x758af520>
50 09/09/21 14:32:52.582 luup_log:167: RFXtrx: dbg: Received message: 0A 52 08 1E 06 01 00 DA 42 01 69 <0x6bfe0520>
50 09/09/21 14:32:52.582 luup_log:167: RFXtrx: dbg: typeof value: string <0x6bfe0520>
50 09/09/21 14:32:52.583 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: LastReceivedMsg value: 0A 52 08 1E 06 01 00 DA 42 01 69 <0x6bfe0520>
06 09/09/21 14:32:52.583 Device_Variable::m_szValue_set device: 167 service: urn:rfxcom-com:serviceId:rfxtrx1 variable: LastReceivedMsg was: 0A 52 09 1D F7 00 01 07 33 01 69 now: 0A 52 08 1E 06 01 00 DA 42 01 69 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:52.584 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 167 variable: VeraTime value: 1631190772 <0x6bfe0520>
06 09/09/21 14:32:52.584 Device_Variable::m_szValue_set device: 167 service: upnp-rfxcom-com:serviceId:rfxtrx1 variable: VeraTime was: 1631190751 now: 1631190772 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:52.585 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH8/1537 deviceType: urn:schemas-micasaverde-com:device:TemperatureSensor:1 <0x6bfe0520>
50 09/09/21 14:32:52.585 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH8/1537urn:schemas-micasaverde-com:device:TemperatureSensor:1 devicenum: 180 myDevice: true <0x6bfe0520>
50 09/09/21 14:32:52.588 luup_log:167: RFXtrx: dbg: findChild-> parentDevice: 167 altid: TH8/1537 deviceType: urn:schemas-micasaverde-com:device:HumiditySensor:1 <0x6bfe0520>
50 09/09/21 14:32:52.588 luup_log:167: RFXtrx: dbg: devicenum found in table - key: TH8/1537urn:schemas-micasaverde-com:device:HumiditySensor:1 devicenum: 181 myDevice: true <0x6bfe0520>
50 09/09/21 14:32:52.589 luup_log:167: RFXtrx: dbg: actOnCommands:cmds: TH8/1537 Temperature 21.8 TH8/1537 Humidity 66 TH8/1537 CommStrength 6 TH8/1537 BatteryLevel 100 <0x6bfe0520>
50 09/09/21 14:32:52.593 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 180 variable: CurrentTemperature value: 21.8 <0x6bfe0520>
06 09/09/21 14:32:52.594 Device_Variable::m_szValue_set device: 180 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 22 now: 21.8 #hooks: 0 upnp: 0 skip: 0 v:0xe6e368/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:52.646 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 180 variable: CommStrength value: 6 <0x6bfe0520>
50 09/09/21 14:32:52.646 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 180 variable: BatteryLevel value: 100 <0x6bfe0520>
50 09/09/21 14:32:52.647 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 180 variable: BatteryDate value: 1631190772 <0x6bfe0520>
06 09/09/21 14:32:52.647 Device_Variable::m_szValue_set device: 180 service: urn:micasaverde-com:serviceId:HaDevice1 variable: BatteryDate was: 1631190712 now: 1631190772 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:52.648 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 181 variable: CurrentLevel value: 66 <0x6bfe0520>
06 09/09/21 14:32:52.648 Device_Variable::m_szValue_set device: 181 service: urn:micasaverde-com:serviceId:HumiditySensor1 variable: CurrentLevel was: 65 now: 66 #hooks: 0 upnp: 0 skip: 0 v:0xe8eef0/NONE duplicate:0 <0x6bfe0520>
50 09/09/21 14:32:52.649 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 181 variable: CommStrength value: 6 <0x6bfe0520>
50 09/09/21 14:32:52.649 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 181 variable: BatteryLevel value: 100 <0x6bfe0520>
50 09/09/21 14:32:52.649 luup_log:167: RFXtrx: dbg: setVariable - deviceNum: 181 variable: BatteryDate value: 1631190772 <0x6bfe0520>
06 09/09/21 14:32:52.649 Device_Variable::m_szValue_set device: 181 service: urn:micasaverde-com:serviceId:HaDevice1 variable: BatteryDate was: 1631190712 now: 1631190772 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6bfe0520>
08 09/09/21 14:32:52.780 JobHandler_LuaUPnP::HandleActionRequest device: 0 service: urn:micasaverde-com:serviceId:HomeAutomationGateway1 action: LogIpRequest <0x71852520>
08 09/09/21 14:32:52.780 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:micasaverde-com:serviceId:HomeAutomationGateway1 <0x71852520>
08 09/09/21 14:32:52.780 JobHandler_LuaUPnP::HandleActionRequest argument action=LogIpRequest <0x71852520>
08 09/09/21 14:32:52.781 JobHandler_LuaUPnP::HandleActionRequest argument IpAddress=192.168.1.125 <0x71852520>
08 09/09/21 14:32:52.781 JobHandler_LuaUPnP::HandleActionRequest argument MacAddress=f0:b3:ec:10:83:eb <0x71852520>
02 09/09/21 14:32:55.786 LPRFSerial::ReceiveData skipping unknown 0x37 <0x75aaf520>
08 09/09/21 14:32:59.916 JobHandler_LuaUPnP::HandleActionRequest device: 0 service: urn:micasaverde-com:serviceId:HomeAutomationGateway1 action: LogIpRequest <0x72452520>
08 09/09/21 14:32:59.916 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:micasaverde-com:serviceId:HomeAutomationGateway1 <0x72452520>
08 09/09/21 14:32:59.917 JobHandler_LuaUPnP::HandleActionRequest argument action=LogIpRequest <0x72452520>
08 09/09/21 14:32:59.917 JobHandler_LuaUPnP::HandleActionRequest argument IpAddress=192.168.1.109 <0x72452520>
08 09/09/21 14:32:59.917 JobHandler_LuaUPnP::HandleActionRequest argument MacAddress=f0:b3:ec:13:ba:0e <0x72452520>

tinman · September 9, 2021, 3:13pm

I don’t see anything in these Vera logs that looks suspicious. The messages from altui do seem to point to a specific line in the J_RFXtrx.js file (at RFX_selectDevices (:2230:41)). But the error seems to me to indicate a possible corrupted javascript file. The line causing the error is just trying to disable a displayed button. The button is defined on line 1019 of that file:

>  html += '<button id="changeType" type="button" style="margin-left: 10px; background-color: ' + RFX.buttonBgColor + '; color: white; height: 25px; width: 75px; border-radius: 6px; -khtml-border-radius: 6px; border-radius: 6px" onclick="RFX_changeDeviceType(' + device + ');">Change</button>';

This code has been unchanged for a few years. I’ve not touched it since taking over development of this plugin.

sunqan · September 9, 2021, 9:18pm

I changed the j_rfxtrx.js a bit - added a try-catch around lines 2230 and 2232, then I get a bit further.
this is what it looks like at the moment:
try {
jQuery(‘#changeType’).get(0).disabled = true;
} catch (err) {
console.log("TEST "+ err)
return;
}
jQuery(‘#commands’).html(‘’);
try {
jQuery(‘#runCommand’).get(0).disabled = true;
} catch (err) {
console.log("TEST "+err);
return;
}

I have tried without the return statements as well, to no avail. How do I get things logged into luaupnp.log from the js-file?

now I actually manage to access the manged devices page (after a few lua restarts) and I have removed the sensors I wanted to remove.
However - it is behaving erratically. if I remove the try catch, it goes back to the same error as before.

I had some time to look at the logs, and I found this:
02 09/09/21 22:44:31.624 luup_log:167: RFXtrx: warning: No decode message for message: 0A 52 09 27 F7 00 01 03 31 01 79 Error: [string “module(“L_RFXtrx”, package.seeall)…”]:2852: attempt to index field ‘?’ (a nil value) <0x6b752520>

the managed devices looks like this (sorry about the bad formatting, this is what a cut-n-paste gives…):
these are just the sensors:
sensors 626 and 627 are a telldus sensor, and as you can see, the termometer is reporting 51% battery and the humidity sensor 71% plus the HS is not reporting any humidity.
663 and 664 are also “bad”.

|180|Förråd 61/1537h|Förrådet|Temperature sensor|21°C|-72dBm|100%|
||181|Humidity 61/1537h|Förrådet|Humidity sensor|64%|-72dBm|100%|
||182|Sovrum 63/1539h|Sovrummet|Temperature sensor|25.7°C|-72dBm|100%|
||183|Humidity 63/1539h|Sovrummet|Humidity sensor|24%|-72dBm|100%|
||187|Ute 62/1538h|Utomhus|Temperature sensor|14.5°C|-72dBm|100%|
||188|Humidity 62/1538h|Utomhus|Humidity sensor|84%|-72dBm|100%|
||432|slingan vid entrén|Utomhus|Switch light|OFF|||
||623|vera off|Vardagsrummet|Switch light||-64dBm|100%|
||626|TV TS/TH9/63232|TV-rummet|Temperature sensor|19.9°C||51%|
||627|HS/TH9/63232|TV-rummet|Humidity sensor|||71%|
||663|Vardagsrum 64/1540|Vardagsrummet|Temperature sensor||-72dBm|100%|
||664|Humidity 64/1540|Vardagsrummet|Humidity sensor||||
||705|Förråd TS/TH7/14350|Förrådet|Temperature sensor|21.7°C|-72dBm|100%|
||706|Förråd HS/TH7/14350|Förrådet|Humidity sensor|58%|-72dBm|100%|

sunqan · September 9, 2021, 9:32pm

could there be some corruption in the rfxtrx433xl?

sunqan · September 9, 2021, 9:37pm

and oh…
altui shows a different story.
all sensors have values, all sensors have signal strengh (-72dbm) all sensors have 100% battery…

ID	Name	Room	Type	State	Signal	Battery
180	Förråd 61/1537h	Förrådet	Temperature sensor	20.9°C	-72dBm	100%
706	Förråd HS/TH7/14350	Förrådet	Humidity sensor	58%	-72dBm	100%
705	Förråd TS/TH7/14350	Förrådet	Temperature sensor	21.5°C	-72dBm	100%
171	Grupp 1	NONE	Group control		-120dBm	false%
173	Grupp 2	NONE	Group control		-120dBm	false%
178	Grupp 3	NONE	Group control		-120dBm	false%
627	HS/TH9/63232	TV-rummet	Humidity sensor	49%	-72dBm	100%
181	Humidity 61/1537h	Förrådet	Humidity sensor	64%	-72dBm	100%
188	Humidity 62/1538h	Utomhus	Humidity sensor	84%	-72dBm	100%
183	Humidity 63/1539h	Sovrummet	Humidity sensor	24%	-72dBm	100%
664	Humidity 64/1540	Vardagsrummet	Humidity sensor	40%	-72dBm	100%
169	Huskod E	NONE	Group control		-120dBm	false%
176	Julbelysning E_2	Utomhus	Switch light	OFF	-120dBm	false%
168	Ledlisten i trappan	Trappan	Dimmable light	0%	-120dBm	false%
172	Ljusstake E_3	Sovrummet	Switch light	OFF	-120dBm	false%
175	Ljusstake G1_1	Köket	Switch light	OFF	-120dBm	false%
170	Ljusttake G1_2	TV-rummet	Switch light	OFF	-120dBm	false%
174	Pendlar E_1	Vardagsrummet	Switch light	OFF	-120dBm	false%
432	slingan vid entrén	Utomhus	Switch light	OFF	-120dBm	false%
182	Sovrum 63/1539h	Sovrummet	Temperature sensor	25.7°C	-72dBm	100%
179	Stjärna bak G3_2	Vardagsrummet	Dimmable light	0%	-120dBm	false%
177	Stjärna fram G3_1	Vardagsrummet	Dimmable light	0%	-120dBm	false%
626	TV TS/TH9/63232	TV-rummet	Temperature sensor	25.9°C	-72dBm	100%
187	Ute 62/1538h	Utomhus	Temperature sensor	14.1°C	-72dBm	100%
663	Vardagsrum 64/1540	Vardagsrummet	Temperature sensor	22.4°C	-72dBm	100%
623	vera off	Vardagsrummet	Switch light	OFF	-120dBm	false%

tinman · September 9, 2021, 10:13pm

I’ve never attempted try, catch structures in js on the Vera so I can’t comment on what you should expect when using them.

I don’t know how altui accesses/reports values for devices if it doesn’t simply read the values of variables defined for each device. If it just reports these values then the plugin is decoding them properly and storing them.

The “No decode message…” occurs when the plugin receives a message it cannot decode OR it receives a message it can decode but there is no device with a matching ID.

The error at line 2852 should never happen. This is yet another bizarre error. I don’t know why you seeing strange things like this. It just seems as if there is something basic that’s wrong on your Vera.

sunqan · September 9, 2021, 10:34pm

I agree - something is seriously wrong.

regarding the 0a 52 09 … message, from what I can see 52 09 will result in a no decode error, so that is the expected behaviour…

but - how do I get messages from the js code into luaupnp.log?
I have tried console.log and util.logerror but I cannot find the messages (I have added some debugging code)

tinman · September 10, 2021, 12:10am

I think you can use the console.log() method and view the console in whatever browser you’re using.

sunqan · September 11, 2021, 9:46pm

So - help an ignorant old man here… how do I view the console?

tinman · September 11, 2021, 10:30pm

I have Firefox and Microsoft Edge. In Firefox click the menu (upper left). Select More Tools then Browser console. In Edge select the menu at upper left, again More Tools, then Developer Tools, then Console.

sunqan · September 12, 2021, 6:05pm

Of course, stupid me… i assumed it was somewhere on the vera
The js code is running in the browser…

Thanks @tinman

sunqan · September 14, 2021, 8:15am

@tinman I spent some time on this, and I found the cause for my problem. I really can’t say if it affects every one, or if it behaves erratically all the time…

in 1.97 lines 1018 and 1021 in j_rfxtrx.js were changed from this:
html += '<tr><td>New device type:</td><td><select id="newDeviceType"/>';
and
html += '<tr><td>Command:</td><td><select id="commands"/>';
to this
html += '<tr><td>New device type:</td><td><select id="newDeviceType"/select>';
and
html += '<tr><td>Command:</td><td><select id="commands"/select>';

I don’t have that much knowledge of html, but as fasr as I understand this makes the select tag incorrect (and unterminated)
the 1.96 version is correct, and the 1.97 version would have been correct if it looked like this:
html += '<tr><td>Command:</td><td><select id="commands"></select>';

when I change the two lines back to the 1.96 version it works and is consistent. I cannot tell why the change was made, so I can’t say which variant (closing the tag with /> or using ></select>) should be used.

I also found this in lines 1031-1040 (not changed in 1.,97), that looks like bad html:
html += '<input name="autoCreate" id="autoCreateOn" type="radio" onchange="RFX_setAutoCreate(' + device + ');"';
if (autoCreate == '1') {
html += ' checked';
}
html += '>ON';
html += '<input name="autoCreate" id="autoCreateOff" type="radio" onchange="RFX_setAutoCreate(' + device + ');"';
if (autoCreate == '0') {
html += ' checked';
}
html += '>OFF';

this is the html for the two radio buttons to turn auto creation on or off. the fault is that both tags are unterminated… It does not seem to cause any problems (today) but the correction would be to change to this: [edit - the input should probably be terminated before the ON and OFF]
html += '>ON';
to
html += '/>ON;

and
html += '>OFF';
to
html += '/>OFF;

-anders
(I hate the forum edit window… I had to add some “preformatted text” tags to make the result a bit more understandable)

sunqan · September 14, 2021, 8:22am

unfortunately, these two changes (reverting back to the 1.96 variant of select, and adding the terminating “/” for the input tag, causes altui to break and give this when trying to view the managed devices there

an error occurred while displaying the javascript tab. devid: 0-167 err:Cannot set properties of undefined (setting ‘disabled’)
stack:TypeError: Cannot set properties of undefined (setting ‘disabled’)
at RFX_selectDevices (:2230:41)
at RFX_updateDevicesTable (:1261:2)
at RFX_showManagedDevices (:1050:2)
at eval (eval at _deviceDrawControlPanelJSTab (http://192.168.1.20/port_3480/J_ALTUI_uimgr.js:3024:17), :1:1)
at _deviceDrawControlPanelJSTab (http://192.168.1.20/port_3480/J_ALTUI_uimgr.js:3024:17)
at _deviceDrawControlPanelOneTabContent (http://192.168.1.20/port_3480/J_ALTUI_uimgr.js:3718:8)
at _displayActiveDeviceTab (http://192.168.1.20/port_3480/J_ALTUI_uimgr.js:3745:4)
at HTMLAnchorElement. (http://192.168.1.20/port_3480/J_ALTUI_uimgr.js:7037:4)
at HTMLDivElement.dispatch (https://cdnjs.cloudflare.com/ajax/libs/jquery/3.5.1/jquery.min.js:2:43090)
at HTMLDivElement.v.handle (https://cdnjs.cloudflare.com/ajax/libs/jquery/3.5.1/jquery.min.js:2:41074)

sunqan · September 14, 2021, 8:45am

and changing <select ... /> into <select ... ></select> seems to fix the ALTUI problem. sigh.
so I guess that was the intended change to the 1.96 code.

sunqan · September 14, 2021, 8:59am

this is the file (zipped) after my changes
J_RFXtrx.zip (17.0 KB)