Creating device sensorBinary ZWayVDev_zway_2-0-48-1 [2016-02-25 07:08:23.304] [I] [core] Notification: device-info (device-OnOff): {"dev":"Flood Motion (2.0.48.1)","l":"off"} [2016-02-25 07:08:23.308] [D] [zway] RECEIVED: ( 01 06 00 49 81 00 00 31 ) [2016-02-25 07:08:23.313] [D] [zway] SENT ACK [2016-02-25 07:08:23.313] [I] [zway] Node info request failed [2016-02-25 07:08:23.314] [I] [zway] Job 0x60 (Request NIF from a node): Node info request failed [2016-02-25 07:08:23.315] [D] [zway] SENDING: ( 01 04 00 60 0A 91 ) [2016-02-25 07:08:23.324] [D] [zway] RECEIVED ACK [2016-02-25 07:08:23.330] [D] [zway] RECEIVED: ( 01 04 01 60 01 9B ) [2016-02-25 07:08:23.335] [D] [zway] SENT ACK [2016-02-25 07:08:23.336] [I] [zway] Job 0x60 (Request NIF from a node): Request for node 10 information frame sent [2016-02-25 07:08:23.467] [I] [core] Creating device battery ZWayVDev_zway_2-0-128 [2016-02-25 07:08:23.735] [I] [core] Creating device sensorBinary ZWayVDev_zway_2-0-156-5-A [2016-02-25 07:08:23.769] [I] [core] Notification: device-info (device-OnOff): {"dev":"Flood Alarm (2.0.156.5)","l":"off"} [2016-02-25 07:08:23.879] [I] [core] Creating device sensorMultilevel ZWayVDev_zway_2-2-49-1 [2016-02-25 07:08:23.914] [I] [core] Notification: device-info (device-temperature): {"dev":"Flood Temperature (2.2.49.1)","l":"22.2 °C"} [2016-02-25 07:08:24.030] [I] [core] Creating device switchBinary ZWayVDev_zway_3-0-37 [2016-02-25 07:08:24.054] [I] [core] Notification: device-info (device-OnOff): {"dev":"Z-Wave.Me Switch (3.0)","l":"off"} [2016-02-25 07:08:24.263] [I] [core] Creating device sensorBinary ZWayVDev_zway_3-0-48-2 [2016-02-25 07:08:24.286] [I] [core] Notification: device-info (device-OnOff): {"dev":"Z-Wave.Me Smoke (3.0.48.2)","l":"off"} [2016-02-25 07:08:24.352] [I] [core] Creating device sensorBinary ZWayVDev_zway_3-0-48-8 [2016-02-25 07:08:24.377] [I] [core] Notification: device-info (device-OnOff): {"dev":"Z-Wave.Me Tamper (3.0.48.8)","l":"off"} [2016-02-25 07:08:24.552] [I] [core] Creating device sensorBinary ZWayVDev_zway_3-0-113-1-2-A [2016-02-25 07:08:24.586] [I] [core] Creating device sensorBinary ZWayVDev_zway_3-0-113-7-3-A [2016-02-25 07:08:24.612] [I] [core] Creating device sensorBinary ZWayVDev_zway_3-0-113-9-1-A [2016-02-25 07:08:24.692] [I] [core] Creating device battery ZWayVDev_zway_3-0-128 [2016-02-25 07:08:24.998] [I] [core] Creating device sensorMultilevel ZWayVDev_zway_4-0-49-1 [2016-02-25 07:08:25.024] [I] [core] Notification: device-info (device-temperature): {"dev":"Fibar Group Temperature (4.0.49.1)","l":"21.4 °C"} [2016-02-25 07:08:25.203] [I] [core] Creating device battery ZWayVDev_zway_4-0-128 [2016-02-25 07:08:25.440] [I] [core] Creating device sensorBinary ZWayVDev_zway_4-0-113-1-2-A [2016-02-25 07:08:25.464] [I] [core] Creating device sensorBinary ZWayVDev_zway_4-0-113-4-2-A [2016-02-25 07:08:25.576] [I] [core] Creating device switchBinary ZWayVDev_zway_6-0-37 [2016-02-25 07:08:25.602] [I] [core] Notification: device-info (device-OnOff): {"dev":"Everspring Switch (6.0)","l":"on"} [2016-02-25 07:08:25.830] [I] [core] Creating device sensorMultilevel ZWayVDev_zway_6-0-50-0 [2016-02-25 07:08:25.919] [I] [core] Creating device sensorMultilevel ZWayVDev_zway_6-0-50-2 [2016-02-25 07:08:26.184] [I] [core] Detected AV capable Sonos player on 192.168.10.205 [2016-02-25 07:08:26.195] [I] [core] Creating device switchBinary Sonos_Device_Play_192.168.10.205_29 [2016-02-25 07:08:26.213] [I] [core] Creating device switchMultilevel Sonos_Device_Volume_192.168.10.205_29 [2016-02-25 07:08:26.244] [I] [core] [BaseModule-11] Set lastLevel to 22.2099984 for ZWayVDev_zway_2-2-49-1 (was 21.3) [2016-02-25 07:08:26.367] [I] [core] [BaseModule-11] Set lastLevel to 21.4 for ZWayVDev_zway_4-0-49-1 (was 18.4) [2016-02-25 07:08:26.491] [I] [core] Detected own hostname: 192.168.10.41 [2016-02-25 07:08:27.145] [I] [core] Notification: device-info (device-status): {"dev":"Sonos Volume 192.168.10.205 29","l":"6%"} [2016-02-25 07:08:27.400] [D] [zway] RECEIVED: ( 01 06 00 49 81 00 00 31 ) [2016-02-25 07:08:27.408] [D] [zway] SENT ACK [2016-02-25 07:08:27.410] [I] [zway] Node info request failed [2016-02-25 07:08:27.418] [I] [zway] Job 0x60 (Request NIF from a node): Node info request failed [2016-02-25 07:08:27.422] [D] [zway] SENDING: ( 01 04 00 60 0A 91 ) [2016-02-25 07:08:27.427] [D] [zway] RECEIVED ACK [2016-02-25 07:08:27.432] [D] [zway] RECEIVED: ( 01 04 01 60 01 9B ) [2016-02-25 07:08:27.436] [D] [zway] SENT ACK [2016-02-25 07:08:27.437] [I] [zway] Job 0x60 (Request NIF from a node): Request for node 10 information frame sent [2016-02-25 07:08:27.452] [I] [core] Notification: device-info (device-OnOff): {"dev":"Sonos Play 192.168.10.205 29","l":"off"} [New Thread 0xb19ff450 (LWP 25502)] [New Thread 0xb0fff450 (LWP 25503)] [Thread 0xb0fff450 (LWP 25503) exited] [2016-02-25 07:08:31.532] [D] [zway] RECEIVED: ( 01 06 00 49 81 00 00 31 ) [2016-02-25 07:08:31.541] [D] [zway] SENT ACK [2016-02-25 07:08:31.541] [I] [zway] Node info request failed [2016-02-25 07:08:31.542] [I] [zway] Job 0x60 (Request NIF from a node): Node info request failed [2016-02-25 07:08:31.544] [I] [zway] Job 0x60 (Request NIF from a node): Removing job due to too much retransmitions [2016-02-25 07:08:31.545] [D] [zway] SETDATA devices.10.data.failureCount = 1 (0x00000001) [2016-02-25 07:08:31.547] [D] [zway] SETDATA devices.10.data.lastSend = 1609 (0x00000649) [2016-02-25 07:08:31.548] [D] [zway] Job 0x60 (Request NIF from a node): fail [2016-02-25 07:08:31.549] [I] [zway] Removing job: Request NIF from a node [2016-02-25 07:08:31.550] [D] [zway] SENDING: ( 01 1D 00 03 03 02 01 16 5E 86 60 8F 81 46 98 26 25 72 8A 2B 77 20 22 5B 56 73 85 59 8E 5A 4C ) [2016-02-25 07:08:31.565] [D] [zway] RECEIVED ACK [2016-02-25 07:08:31.569] [I] [zway] Removing job: Set controller node information frame [2016-02-25 07:08:31.583] [D] [zway] SENDING: ( 01 03 00 05 F9 ) [2016-02-25 07:08:31.589] [D] [zway] RECEIVED ACK [2016-02-25 07:08:31.591] [D] [zway] RECEIVED: ( 01 04 01 05 1C E3 ) [2016-02-25 07:08:31.593] [D] [zway] SENT ACK [2016-02-25 07:08:31.593] [D] [zway] SETDATA controller.data.isPrimary = True [2016-02-25 07:08:31.594] [D] [zway] SETDATA controller.data.isInOthersNetwork = False [2016-02-25 07:08:31.595] [D] [zway] SETDATA controller.data.SISPresent = True [2016-02-25 07:08:31.596] [D] [zway] SETDATA controller.data.isRealPrimary = True [2016-02-25 07:08:31.597] [D] [zway] SETDATA controller.data.isSUC = True [2016-02-25 07:08:31.598] [D] [zway] Job 0x05 (Get controller capabilities): success [2016-02-25 07:08:31.598] [I] [zway] Removing job: Get controller capabilities [Thread 0xb19ff450 (LWP 25502) exited] [2016-02-25 07:08:36.824] [D] [zway] RECEIVED: ( 01 0C 00 04 00 04 06 31 05 01 22 00 AE 4C ) [2016-02-25 07:08:36.832] [D] [zway] SENT ACK [2016-02-25 07:08:36.832] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000) [2016-02-25 07:08:36.833] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.deviceScale = 0 (0x00000000) [2016-02-25 07:08:36.839] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.scaleString = "°C" [2016-02-25 07:08:36.843] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.val = 17.400000 [2016-02-25 07:08:36.848] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1 = Empty [2016-02-25 07:08:36.881] [I] [core] Notification: device-info (device-temperature): {"dev":"Fibar Group Temperature (4.0.49.1)","l":"17.4 °C"} [2016-02-25 07:08:36.969] [I] [core] [BaseModule-11] Set lastLevel to 17.4 for ZWayVDev_zway_4-0-49-1 (was 21.4) [2016-02-25 07:08:38.472] [D] [zway] Job 0x07: deleted from queue [2016-02-25 07:08:38.652] [D] [zway] Job 0x06: deleted from queue [2016-02-25 07:08:38.666] [D] [zway] Job 0x17: deleted from queue [2016-02-25 07:08:38.680] [D] [zway] Job 0xd2: deleted from queue [2016-02-25 07:08:38.694] [D] [zway] Job 0x20: deleted from queue [2016-02-25 07:08:38.717] [D] [zway] Job 0x05: deleted from queue [2016-02-25 07:08:38.730] [D] [zway] Job 0x15: deleted from queue [2016-02-25 07:08:38.753] [D] [zway] Job 0x56: deleted from queue [2016-02-25 07:08:38.817] [D] [zway] Job 0x02: deleted from queue [2016-02-25 07:08:38.821] [D] [zway] Job 0x62: deleted from queue [2016-02-25 07:08:38.844] [D] [zway] Job 0x62: deleted from queue [2016-02-25 07:08:38.867] [D] [zway] Job 0x62: deleted from queue [2016-02-25 07:08:38.890] [D] [zway] Job 0x62: deleted from queue [2016-02-25 07:08:38.914] [D] [zway] Job 0x62: deleted from queue [2016-02-25 07:08:38.937] [D] [zway] Job 0x62: deleted from queue [2016-02-25 07:08:38.960] [D] [zway] Job 0x62: deleted from queue [2016-02-25 07:08:38.983] [D] [zway] Job 0x62: deleted from queue [2016-02-25 07:08:39.017] [D] [zway] Job 0x41: deleted from queue [2016-02-25 07:08:39.061] [D] [zway] Job 0x41: deleted from queue [2016-02-25 07:08:39.096] [D] [zway] Job 0x41: deleted from queue [2016-02-25 07:08:39.152] [D] [zway] Job 0x41: deleted from queue [2016-02-25 07:08:39.197] [D] [zway] Job 0x41: deleted from queue [2016-02-25 07:08:39.242] [D] [zway] Job 0x41: deleted from queue [2016-02-25 07:08:39.308] [D] [zway] Job 0x41: deleted from queue [2016-02-25 07:08:39.384] [D] [zway] Job 0x41: deleted from queue [2016-02-25 07:08:39.439] [D] [zway] Job 0x41: deleted from queue [2016-02-25 07:08:39.494] [D] [zway] Job 0x80: deleted from queue [2016-02-25 07:08:39.528] [D] [zway] Job 0x80: deleted from queue [2016-02-25 07:08:39.563] [D] [zway] Job 0x80: deleted from queue [2016-02-25 07:08:39.587] [D] [zway] Job 0x80: deleted from queue [2016-02-25 07:08:39.632] [D] [zway] Job 0x80: deleted from queue [2016-02-25 07:08:39.667] [D] [zway] Job 0x80: deleted from queue [2016-02-25 07:08:39.701] [D] [zway] Job 0x80: deleted from queue [2016-02-25 07:08:39.746] [D] [zway] Job 0x80: deleted from queue [2016-02-25 07:08:39.771] [D] [zway] Job 0x80: deleted from queue [2016-02-25 07:08:42.687] [I] [core] after restart [2016-02-25 07:08:42.703] [I] [core] Deleting device sensorMultiline ClimateControl_35 [2016-02-25 07:08:42.827] [I] [core] --- Stopping module Climate Control [2016-02-25 07:08:42.836] [I] [core] --- Starting module Climate Control [2016-02-25 07:08:42.990] [I] [core] Creating device sensorMultiline ClimateControl_35 [2016-02-25 07:08:52.113] [D] [zway] Job 0x60: deleted from queue [2016-02-25 07:08:52.137] [D] [zway] Job 0x03: deleted from queue [2016-02-25 07:08:52.162] [D] [zway] Job 0x05: deleted from queue [2016-02-25 07:09:05.234] [I] [core] [Astronomy] Calculate [2016-02-25 07:09:05.353] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"-0.1 °"} [2016-02-25 07:10:01.339] [I] [core] --- histories polled [2016-02-25 07:10:05.222] [I] [core] [Astronomy] Calculate [2016-02-25 07:10:05.300] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"0.0 °"} [2016-02-25 07:10:06.378] [I] [core] [BaseModule-11] Set lastLevel to 0.03991104923436819 for Astronomy_37 (was -0.11301606398696298) [2016-02-25 07:10:07.122] [D] [zway] RECEIVED: ( 01 12 00 04 00 02 0C 60 0D 02 02 31 05 01 44 00 00 07 4F B3 ) [2016-02-25 07:10:07.131] [D] [zway] SENT ACK [2016-02-25 07:10:07.132] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000) [2016-02-25 07:10:07.135] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.deviceScale = 0 (0x00000000) [2016-02-25 07:10:07.140] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.scaleString = "°C" [2016-02-25 07:10:07.144] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.val = 18.709999 [2016-02-25 07:10:07.148] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1 = Empty [2016-02-25 07:10:07.290] [I] [core] Notification: device-info (device-temperature): {"dev":"Flood Temperature (2.2.49.1)","l":"18.7 °C"} [2016-02-25 07:10:07.379] [I] [core] [BaseModule-11] Set lastLevel to 18.7099984 for ZWayVDev_zway_2-2-49-1 (was 22.2099984) [2016-02-25 07:11:05.262] [I] [core] [Astronomy] Calculate [2016-02-25 07:11:05.442] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"0.2 °"} [2016-02-25 07:11:06.500] [I] [core] [BaseModule-11] Set lastLevel to 0.19273050202670988 for Astronomy_37 (was 0.03991104923436819) [New Thread 0xb19ff450 (LWP 25545)] [New Thread 0xb0fff450 (LWP 25546)] [Thread 0xb0fff450 (LWP 25546) exited] [2016-02-25 07:11:26.766] [I] [core] Downloaded contents for 'Presence' [2016-02-25 07:11:26.774] [I] [core] Extracting automation/userModules/Presence/CHANGES [2016-02-25 07:11:26.779] [I] [core] Extracting automation/userModules/Presence/README.md [2016-02-25 07:11:26.789] [I] [core] Extracting automation/userModules/Presence/htdocs/ [2016-02-25 07:11:26.794] [I] [core] Extracting automation/userModules/Presence/htdocs/icon.png [2016-02-25 07:11:26.799] [I] [core] Extracting automation/userModules/Presence/htdocs/night_off.png [2016-02-25 07:11:26.809] [I] [core] Extracting automation/userModules/Presence/htdocs/night_on.png [2016-02-25 07:11:26.814] [I] [core] Extracting automation/userModules/Presence/htdocs/presence_off.png [2016-02-25 07:11:26.818] [I] [core] Extracting automation/userModules/Presence/htdocs/presence_on.png [2016-02-25 07:11:26.829] [I] [core] Extracting automation/userModules/Presence/htdocs/vacation_off.png [2016-02-25 07:11:26.834] [I] [core] Extracting automation/userModules/Presence/htdocs/vacation_on.png [2016-02-25 07:11:26.839] [I] [core] Extracting automation/userModules/Presence/index.js [2016-02-25 07:11:26.848] [I] [core] Extracting automation/userModules/Presence/lang/ [2016-02-25 07:11:26.853] [I] [core] Extracting automation/userModules/Presence/lang/de.json [2016-02-25 07:11:26.859] [I] [core] Extracting automation/userModules/Presence/lang/en.json [2016-02-25 07:11:26.865] [I] [core] Extracting automation/userModules/Presence/module.json [2016-02-25 07:11:26.873] [I] [core] Module successfully unpacked [Thread 0xb19ff450 (LWP 25545) exited] [2016-02-25 07:11:26.894] [I] [core] Load app "Presence" from folder ... [2016-02-25 07:11:26.930] [I] [core] Loading module Presence from userModules/Presence [2016-02-25 07:11:26.942] [I] [core] Executing script: /*** Presence Z-Way HA module ******************************************* ... [2016-02-25 07:11:49.667] [I] [core] Instantiating module 38 from class Presence [2016-02-25 07:11:49.678] [I] [core] --- Starting module Presence state [2016-02-25 07:11:49.760] [I] [core] Creating device switchBinary Presence_presence_38 [2016-02-25 07:11:49.992] [I] [core] Creating device switchBinary Presence_vacation_38 [2016-02-25 07:11:50.096] [I] [core] Creating device switchBinary Presence_night_38 [2016-02-25 07:12:05.275] [I] [core] [Astronomy] Calculate [2016-02-25 07:12:05.445] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"0.3 °"} [2016-02-25 07:12:06.572] [I] [core] [BaseModule-11] Set lastLevel to 0.3453921034822776 for Astronomy_37 (was 0.19273050202670988) [2016-02-25 07:12:08.661] [I] [core] --- Presence_vacation_38 performCommand processing: {"0":"on","1":{}} [2016-02-25 07:12:08.668] [I] [core] [Presence-38] Switch vacation via vDev [2016-02-25 07:12:08.676] [I] [core] [Presence-38] Switching on vacation [2016-02-25 07:12:08.696] [I] [core] Notification: device-info (device-OnOff): {"dev":"Vacation","l":"on"} [2016-02-25 07:12:13.629] [I] [core] --- Presence_presence_38 performCommand processing: {"0":"off","1":{}} [2016-02-25 07:12:13.634] [I] [core] [Presence-38] Switch presence via vDev [2016-02-25 07:12:13.640] [I] [core] [Presence-38] Switching off presence [2016-02-25 07:12:13.650] [I] [core] Notification: device-info (device-OnOff): {"dev":"Presence","l":"off"} [2016-02-25 07:12:14.023] [I] [core] [Presence-38] Setting new mode to vacation (was home) [2016-02-25 07:12:16.387] [I] [core] --- Presence_night_38 performCommand processing: {"0":"on","1":{}} [2016-02-25 07:12:16.391] [I] [core] [Presence-38] Switch night via vDev [2016-02-25 07:12:16.396] [I] [core] [Presence-38] Switching on night [2016-02-25 07:12:16.406] [I] [core] Notification: device-info (device-OnOff): {"dev":"Night","l":"on"} [2016-02-25 07:12:20.165] [I] [core] --- Presence_night_38 performCommand processing: {"0":"off","1":{}} [2016-02-25 07:12:20.169] [I] [core] [Presence-38] Switch night via vDev [2016-02-25 07:12:20.174] [I] [core] [Presence-38] Switching off night [2016-02-25 07:12:20.191] [I] [core] Notification: device-info (device-OnOff): {"dev":"Night","l":"off"} [2016-02-25 07:12:20.487] [I] [core] [BaseModule-11] Set lastLevel to off for Presence_night_38 (was on) [2016-02-25 07:13:05.272] [I] [core] [Astronomy] Calculate [2016-02-25 07:13:05.346] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"0.5 °"} [2016-02-25 07:13:06.442] [I] [core] [BaseModule-11] Set lastLevel to 0.4979246343700667 for Astronomy_37 (was 0.3453921034822776) [2016-02-25 07:14:05.291] [I] [core] [Astronomy] Calculate [2016-02-25 07:14:05.470] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"0.7 °"} [2016-02-25 07:14:06.544] [I] [core] [BaseModule-11] Set lastLevel to 0.6502575130783368 for Astronomy_37 (was 0.4979246343700667) [2016-02-25 07:15:00.575] [I] [core] --- histories polled [2016-02-25 07:15:05.289] [I] [core] [Astronomy] Calculate [2016-02-25 07:15:05.369] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"0.8 °"} [2016-02-25 07:15:06.412] [I] [core] [BaseModule-11] Set lastLevel to 0.8025058631537433 for Astronomy_37 (was 0.6502575130783368) [2016-02-25 07:16:05.313] [I] [core] [Astronomy] Calculate [2016-02-25 07:16:05.484] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"1.0 °"} [2016-02-25 07:16:06.611] [I] [core] [BaseModule-11] Set lastLevel to 0.9545536388561219 for Astronomy_37 (was 0.8025058631537433) [2016-02-25 07:17:05.293] [I] [core] [Astronomy] Calculate [2016-02-25 07:17:05.374] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"1.1 °"} [2016-02-25 07:17:06.417] [I] [core] [BaseModule-11] Set lastLevel to 1.1064599338075902 for Astronomy_37 (was 0.9545536388561219) [2016-02-25 07:18:05.327] [I] [core] [Astronomy] Calculate [2016-02-25 07:18:05.556] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"1.3 °"} [2016-02-25 07:18:06.632] [I] [core] [BaseModule-11] Set lastLevel to 1.2582353919159157 for Astronomy_37 (was 1.1064599338075902) [2016-02-25 07:19:05.304] [I] [core] [Astronomy] Calculate [2016-02-25 07:19:05.384] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"1.4 °"} [2016-02-25 07:19:06.420] [I] [core] [BaseModule-11] Set lastLevel to 1.40983025163582 for Astronomy_37 (was 1.2582353919159157) [2016-02-25 07:20:00.388] [I] [core] --- histories polled [2016-02-25 07:20:05.346] [I] [core] [Astronomy] Calculate [2016-02-25 07:20:05.502] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"1.6 °"} [2016-02-25 07:20:06.578] [I] [core] [BaseModule-11] Set lastLevel to 1.5613209998931266 for Astronomy_37 (was 1.40983025163582) [2016-02-25 07:21:05.340] [I] [core] [Astronomy] Calculate [2016-02-25 07:21:05.419] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"1.7 °"} [2016-02-25 07:21:06.497] [I] [core] [BaseModule-11] Set lastLevel to 1.7126424587499776 for Astronomy_37 (was 1.5613209998931266) [2016-02-25 07:22:05.370] [I] [core] [Astronomy] Calculate [2016-02-25 07:22:05.542] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"1.9 °"} [2016-02-25 07:22:06.618] [I] [core] [BaseModule-11] Set lastLevel to 1.863787844992562 for Astronomy_37 (was 1.7126424587499776) [2016-02-25 07:23:05.365] [I] [core] [Astronomy] Calculate [2016-02-25 07:23:05.444] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"2.0 °"} [2016-02-25 07:23:06.485] [I] [core] [BaseModule-11] Set lastLevel to 2.014798047281878 for Astronomy_37 (was 1.863787844992562) [2016-02-25 07:24:05.407] [I] [core] [Astronomy] Calculate [2016-02-25 07:24:05.586] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"2.2 °"} [2016-02-25 07:24:06.713] [I] [core] [BaseModule-11] Set lastLevel to 2.1656511066985686 for Astronomy_37 (was 2.014798047281878) [2016-02-25 07:25:00.275] [I] [core] --- histories polled [2016-02-25 07:25:05.395] [I] [core] [Astronomy] Calculate [2016-02-25 07:25:05.473] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"2.3 °"} [2016-02-25 07:25:06.512] [I] [core] [BaseModule-11] Set lastLevel to 2.3163150487247033 for Astronomy_37 (was 2.1656511066985686) [2016-02-25 07:25:15.092] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 84 07 70 ) [2016-02-25 07:25:15.101] [D] [zway] SENT ACK [2016-02-25 07:25:15.102] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000) [2016-02-25 07:25:15.104] [I] [zway] Node 2:0 CC Wakeup: Wakeup notification [2016-02-25 07:25:15.105] [D] [zway] SETDATA devices.2.instances.0.commandClasses.132.data.lastWakeup = 1456381515 (0x56ce9e4b) [2016-02-25 07:25:15.106] [D] [zway] SETDATA devices.2.data.isAwake = True [2016-02-25 07:25:15.108] [D] [zway] SETDATA devices.2.data.lastSend = 98680 (0x00018178) [2016-02-25 07:25:15.109] [D] [zway] SETDATA devices.2.data.lastNonceGet = 97680 (0x00017d90) [2016-02-25 07:25:15.111] [D] [zway] Running wakeup handler for node 2 [2016-02-25 07:25:15.112] [D] [zway] SETDATA devices.2.instances.0.commandClasses.132.data.lastSleep = 1456381515 (0x56ce9e4b) [2016-02-25 07:25:15.113] [I] [zway] Node 2:0 CC Wakeup: Send node to sleep [2016-02-25 07:25:15.114] [I] [zway] Adding job: Wakeup Sleep [2016-02-25 07:25:15.167] [D] [zway] SENDING (cb 0x01): ( 01 09 00 13 02 02 84 08 05 01 6D ) [2016-02-25 07:25:15.176] [D] [zway] RECEIVED ACK [2016-02-25 07:25:15.180] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2016-02-25 07:25:15.182] [D] [zway] SENT ACK [2016-02-25 07:25:15.183] [D] [zway] Delivered to Z-Wave stack [2016-02-25 07:25:15.205] [D] [zway] RECEIVED: ( 01 07 00 13 01 00 00 02 E8 ) [2016-02-25 07:25:15.211] [D] [zway] SENT ACK [2016-02-25 07:25:15.212] [I] [zway] Job 0x13 (Wakeup Sleep): Delivered [2016-02-25 07:25:15.214] [D] [zway] SETDATA devices.2.data.lastPacketInfo.delivered = True [2016-02-25 07:25:15.214] [D] [zway] SETDATA devices.2.data.lastPacketInfo.packetLength = 5 (0x00000005) [2016-02-25 07:25:15.216] [D] [zway] SETDATA devices.2.data.lastPacketInfo.deliveryTime = 29 (0x0000001d) [2016-02-25 07:25:15.216] [D] [zway] SETDATA devices.2.data.lastPacketInfo = ********** [2016-02-25 07:25:15.218] [D] [zway] SendData Response with callback 0x01 received: received by recipient [2016-02-25 07:25:15.219] [D] [zway] SETDATA devices.2.data.lastSend = 98690 (0x00018182) [2016-02-25 07:25:15.221] [D] [zway] Job 0x13 (Wakeup Sleep): success [2016-02-25 07:25:15.222] [D] [zway] SETDATA devices.2.data.isAwake = False [2016-02-25 07:25:15.222] [D] [zway] Sending the queue for node 2 into sleep [2016-02-25 07:25:15.224] [I] [zway] Removing job: Wakeup Sleep [2016-02-25 07:25:35.570] [D] [zway] Job 0x13: deleted from queue [2016-02-25 07:26:05.425] [I] [core] [Astronomy] Calculate [2016-02-25 07:26:05.603] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"2.5 °"} [2016-02-25 07:26:06.683] [I] [core] [BaseModule-11] Set lastLevel to 2.4668080476430596 for Astronomy_37 (was 2.3163150487247033) [2016-02-25 07:27:05.411] [I] [core] [Astronomy] Calculate [2016-02-25 07:27:05.493] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"2.6 °"} [2016-02-25 07:27:06.586] [I] [core] [BaseModule-11] Set lastLevel to 2.617133277223151 for Astronomy_37 (was 2.4668080476430596) [2016-02-25 07:28:05.436] [I] [core] [Astronomy] Calculate [2016-02-25 07:28:05.617] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"2.8 °"} [2016-02-25 07:28:06.691] [I] [core] [BaseModule-11] Set lastLevel to 2.7672737601554207 for Astronomy_37 (was 2.617133277223151) [2016-02-25 07:29:05.414] [I] [core] [Astronomy] Calculate [2016-02-25 07:29:05.493] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"2.9 °"} [2016-02-25 07:29:06.526] [I] [core] [BaseModule-11] Set lastLevel to 2.91724032391747 for Astronomy_37 (was 2.7672737601554207) [2016-02-25 07:30:00.255] [I] [core] --- histories polled [2016-02-25 07:30:05.458] [I] [core] [Astronomy] Calculate [2016-02-25 07:30:05.614] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"3.1 °"} [2016-02-25 07:30:06.740] [I] [core] [BaseModule-11] Set lastLevel to 3.067083384531437 for Astronomy_37 (was 2.91724032391747) [2016-02-25 07:30:37.096] [D] [zway] RECEIVED: ( 01 0C 00 04 00 04 06 31 05 01 22 00 B8 5A ) [2016-02-25 07:30:37.107] [D] [zway] SENT ACK [2016-02-25 07:30:37.107] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000) [2016-02-25 07:30:37.110] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.deviceScale = 0 (0x00000000) [2016-02-25 07:30:37.116] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.scaleString = "°C" [2016-02-25 07:30:37.120] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.val = 18.400000 [2016-02-25 07:30:37.124] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1 = Empty [2016-02-25 07:30:37.267] [I] [core] Notification: device-info (device-temperature): {"dev":"Fibar Group Temperature (4.0.49.1)","l":"18.4 °C"} [2016-02-25 07:30:37.361] [I] [core] [BaseModule-11] Set lastLevel to 18.4 for ZWayVDev_zway_4-0-49-1 (was 17.4) [2016-02-25 07:31:05.443] [I] [core] [Astronomy] Calculate [2016-02-25 07:31:05.515] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"3.2 °"} [2016-02-25 07:31:06.559] [I] [core] [BaseModule-11] Set lastLevel to 3.2167211572423953 for Astronomy_37 (was 3.067083384531437) [2016-02-25 07:32:05.494] [I] [core] [Astronomy] Calculate [2016-02-25 07:32:05.676] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"3.4 °"} [2016-02-25 07:32:06.751] [I] [core] [BaseModule-11] Set lastLevel to 3.3662264552071437 for Astronomy_37 (was 3.2167211572423953) [2016-02-25 07:33:05.484] [I] [core] [Astronomy] Calculate [2016-02-25 07:33:05.564] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"3.5 °"} [2016-02-25 07:33:06.647] [I] [core] [BaseModule-11] Set lastLevel to 3.5155152886410055 for Astronomy_37 (was 3.3662264552071437) [2016-02-25 07:34:05.516] [I] [core] [Astronomy] Calculate [2016-02-25 07:34:05.696] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"3.7 °"} [2016-02-25 07:34:06.772] [I] [core] [BaseModule-11] Set lastLevel to 3.6646179495770292 for Astronomy_37 (was 3.5155152886410055) [2016-02-25 07:35:00.196] [I] [core] --- histories polled [2016-02-25 07:35:05.498] [I] [core] [Astronomy] Calculate [2016-02-25 07:35:05.577] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"3.8 °"} [2016-02-25 07:35:06.627] [I] [core] [BaseModule-11] Set lastLevel to 3.813525356277791 for Astronomy_37 (was 3.6646179495770292) [2016-02-25 07:35:20.334] [D] [zway] RECEIVED: ( 01 12 00 04 00 02 0C 60 0D 02 02 31 05 01 44 00 00 07 6B 97 ) [2016-02-25 07:35:20.346] [D] [zway] SENT ACK [2016-02-25 07:35:20.347] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000) [2016-02-25 07:35:20.348] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.deviceScale = 0 (0x00000000) [2016-02-25 07:35:20.354] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.scaleString = "°C" [2016-02-25 07:35:20.358] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.val = 18.990000 [2016-02-25 07:35:20.362] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1 = Empty [2016-02-25 07:35:20.494] [I] [core] Notification: device-info (device-temperature): {"dev":"Flood Temperature (2.2.49.1)","l":"19.0 °C"} [2016-02-25 07:35:20.580] [I] [core] [BaseModule-11] Set lastLevel to 18.99 for ZWayVDev_zway_2-2-49-1 (was 18.7099984) [2016-02-25 07:36:05.503] [I] [core] [Astronomy] Calculate [2016-02-25 07:36:05.573] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"4.0 °"} [2016-02-25 07:36:06.649] [I] [core] [BaseModule-11] Set lastLevel to 3.9622626408394184 for Astronomy_37 (was 3.813525356277791) [2016-02-25 07:37:05.548] [I] [core] [Astronomy] Calculate [2016-02-25 07:37:05.739] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"4.1 °"} [2016-02-25 07:37:06.808] [I] [core] [BaseModule-11] Set lastLevel to 4.110828020174481 for Astronomy_37 (was 3.9622626408394184) [2016-02-25 07:38:05.536] [I] [core] [Astronomy] Calculate [2016-02-25 07:38:05.629] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"4.3 °"} [2016-02-25 07:38:06.697] [I] [core] [BaseModule-11] Set lastLevel to 4.25918730018769 for Astronomy_37 (was 4.110828020174481) [2016-02-25 07:39:05.584] [I] [core] [Astronomy] Calculate [2016-02-25 07:39:05.776] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"4.4 °"} [2016-02-25 07:39:06.894] [I] [core] [BaseModule-11] Set lastLevel to 4.407395297179331 for Astronomy_37 (was 4.25918730018769) [2016-02-25 07:40:00.196] [I] [core] --- histories polled [2016-02-25 07:40:05.573] [I] [core] [Astronomy] Calculate [2016-02-25 07:40:05.653] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"4.6 °"} [2016-02-25 07:40:06.684] [I] [core] [BaseModule-11] Set lastLevel to 4.5553735747267945 for Astronomy_37 (was 4.407395297179331) [2016-02-25 07:40:20.912] [D] [zway] RECEIVED: ( 01 0C 00 04 00 04 06 31 05 01 22 00 C2 20 ) [2016-02-25 07:40:20.921] [D] [zway] SENT ACK [2016-02-25 07:40:20.923] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000) [2016-02-25 07:40:20.924] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.deviceScale = 0 (0x00000000) [2016-02-25 07:40:20.930] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.scaleString = "°C" [2016-02-25 07:40:20.934] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.val = 19.400000 [2016-02-25 07:40:20.938] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1 = Empty [2016-02-25 07:40:21.150] [I] [core] Notification: device-info (device-temperature): {"dev":"Fibar Group Temperature (4.0.49.1)","l":"19.4 °C"} [2016-02-25 07:40:21.297] [I] [core] [BaseModule-11] Set lastLevel to 19.4 for ZWayVDev_zway_4-0-49-1 (was 18.4) [2016-02-25 07:41:05.614] [I] [core] [Astronomy] Calculate [2016-02-25 07:41:05.717] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"4.7 °"} [2016-02-25 07:41:06.804] [I] [core] [BaseModule-11] Set lastLevel to 4.703174590201295 for Astronomy_37 (was 4.5553735747267945) [2016-02-25 07:42:05.598] [I] [core] [Astronomy] Calculate [2016-02-25 07:42:05.666] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"4.9 °"} [2016-02-25 07:42:06.688] [I] [core] [BaseModule-11] Set lastLevel to 4.850754398660393 for Astronomy_37 (was 4.703174590201295) [2016-02-25 07:43:05.634] [I] [core] [Astronomy] Calculate [2016-02-25 07:43:05.824] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"5.0 °"} [2016-02-25 07:43:06.894] [I] [core] [BaseModule-11] Set lastLevel to 4.998147974798047 for Astronomy_37 (was 4.850754398660393) [2016-02-25 07:43:52.269] [D] [zway] RECEIVED: ( 01 08 00 04 00 04 02 98 40 2D ) [2016-02-25 07:43:52.278] [D] [zway] SENT ACK [2016-02-25 07:43:52.279] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000) [2016-02-25 07:43:52.280] [I] [zway] Node 4:0 CC Security: sending Nonce Report [2016-02-25 07:43:52.282] [I] [zway] Adding job: Nonce Report [2016-02-25 07:43:52.284] [D] [zway] SENDING (cb 0x02): ( 01 11 00 13 04 0A 98 80 B3 63 54 67 4F 5F 56 EF 05 02 A6 ) [2016-02-25 07:43:52.292] [D] [zway] RECEIVED ACK [2016-02-25 07:43:52.296] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2016-02-25 07:43:52.297] [D] [zway] SENT ACK [2016-02-25 07:43:52.299] [D] [zway] Delivered to Z-Wave stack [2016-02-25 07:43:52.320] [D] [zway] RECEIVED: ( 01 07 00 13 02 00 00 02 EB ) [2016-02-25 07:43:52.324] [D] [zway] SENT ACK [2016-02-25 07:43:52.326] [I] [zway] Job 0x13 (Nonce Report): Delivered [2016-02-25 07:43:52.328] [D] [zway] SETDATA devices.4.data.lastPacketInfo.delivered = True [2016-02-25 07:43:52.329] [D] [zway] SETDATA devices.4.data.lastPacketInfo.packetLength = 13 (0x0000000d) [2016-02-25 07:43:52.331] [D] [zway] SETDATA devices.4.data.lastPacketInfo.deliveryTime = 27 (0x0000001b) [2016-02-25 07:43:52.332] [D] [zway] SETDATA devices.4.data.lastPacketInfo = ********** [2016-02-25 07:43:52.333] [D] [zway] SendData Response with callback 0x02 received: received by recipient [2016-02-25 07:43:52.335] [D] [zway] SETDATA devices.4.data.lastSend = 207169 (0x00032941) [2016-02-25 07:43:52.336] [D] [zway] Job 0x13 (Nonce Report): success [2016-02-25 07:43:52.339] [I] [zway] Removing job: Nonce Report [2016-02-25 07:43:52.346] [D] [zway] RECEIVED: ( 01 1C 00 04 00 04 16 98 81 17 A6 C2 CC A9 51 E6 00 62 1E 3B B3 8A 38 72 F7 ED C5 15 47 F4 ) [2016-02-25 07:43:52.358] [D] [zway] SENT ACK [2016-02-25 07:43:52.358] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000) [2016-02-25 07:43:52.359] [I] [zway] Node 4:0 CC Security: Received a secure message [2016-02-25 07:43:52.360] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.firstPart = ********** [2016-02-25 07:43:52.361] [I] [zway] Node 4:0 CC Security: passing decrypted packet to application level: [ 84 07 ] [2016-02-25 07:43:52.362] [I] [zway] Node 4:0 CC Wakeup: Wakeup notification [2016-02-25 07:43:52.364] [D] [zway] SETDATA devices.4.instances.0.commandClasses.132.data.lastWakeup = 1456382632 (0x56cea2a8) [2016-02-25 07:43:52.364] [D] [zway] SETDATA devices.4.data.lastSend = 207171 (0x00032943) [2016-02-25 07:43:52.367] [D] [zway] SETDATA devices.4.data.lastNonceGet = 206171 (0x0003255b) [2016-02-25 07:43:52.367] [D] [zway] Running wakeup handler for node 4 [2016-02-25 07:43:52.369] [D] [zway] SETDATA devices.4.instances.0.commandClasses.132.data.lastSleep = 1456382632 (0x56cea2a8) [2016-02-25 07:43:52.370] [I] [zway] Node 4:0 CC Wakeup: Send node to sleep [2016-02-25 07:43:52.371] [I] [zway] Adding job: Wakeup Sleep [2016-02-25 07:43:52.383] [I] [zway] Node 4:0 CC Security: sending Nonce Get [2016-02-25 07:43:52.384] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.rNonceAckWait = ********** [2016-02-25 07:43:52.386] [I] [zway] Job 0x13 (Wakeup Sleep): Cancelling job: Wakeup Sleep [2016-02-25 07:43:52.388] [I] [zway] Removing job: Wakeup Sleep [2016-02-25 07:43:52.388] [I] [zway] Adding job: Nonce Get [2016-02-25 07:43:52.390] [D] [zway] SETDATA devices.4.instances.0.commandClasses.132.data.lastSleep = 1456382632 (0x56cea2a8) [2016-02-25 07:43:52.391] [I] [zway] Node 4:0 CC Wakeup: Send node to sleep [2016-02-25 07:43:52.392] [I] [zway] Adding job: Wakeup Sleep [2016-02-25 07:43:52.393] [D] [zway] SETDATA devices.4.data.lastNonceGet = 207173 (0x00032945) [2016-02-25 07:43:52.426] [D] [zway] SENDING (cb 0x03): ( 01 09 00 13 04 02 98 40 05 03 3D ) [2016-02-25 07:43:52.435] [D] [zway] RECEIVED ACK [2016-02-25 07:43:52.439] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2016-02-25 07:43:52.440] [D] [zway] SENT ACK [2016-02-25 07:43:52.442] [D] [zway] Delivered to Z-Wave stack [2016-02-25 07:43:52.453] [D] [zway] RECEIVED: ( 01 07 00 13 03 00 00 02 EA ) [2016-02-25 07:43:52.457] [D] [zway] SENT ACK [2016-02-25 07:43:52.458] [I] [zway] Job 0x13 (Nonce Get): Delivered [2016-02-25 07:43:52.460] [D] [zway] SETDATA devices.4.data.lastPacketInfo.delivered = True [2016-02-25 07:43:52.461] [D] [zway] SETDATA devices.4.data.lastPacketInfo.packetLength = 5 (0x00000005) [2016-02-25 07:43:52.462] [D] [zway] SETDATA devices.4.data.lastPacketInfo.deliveryTime = 17 (0x00000011) [2016-02-25 07:43:52.463] [D] [zway] SETDATA devices.4.data.lastPacketInfo = ********** [2016-02-25 07:43:52.465] [D] [zway] SendData Response with callback 0x03 received: received by recipient [2016-02-25 07:43:52.466] [D] [zway] SETDATA devices.4.data.lastSend = 207180 (0x0003294c) [2016-02-25 07:43:52.468] [D] [zway] Job 0x13 (Nonce Get): success [2016-02-25 07:43:52.469] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.rNonceAckWait = ********** [2016-02-25 07:43:52.469] [I] [zway] Removing job: Nonce Get [2016-02-25 07:43:52.476] [D] [zway] RECEIVED: ( 01 10 00 04 00 04 0A 98 80 CF B4 CD 4A 8A 6C 01 75 93 ) [2016-02-25 07:43:52.490] [D] [zway] SENT ACK [2016-02-25 07:43:52.491] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000) [2016-02-25 07:43:52.492] [I] [zway] Node 4:0 CC Security: received Nonce Report [2016-02-25 07:43:52.493] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.rNonce = ********** [2016-02-25 07:43:52.493] [I] [zway] Removing job: Wakeup Sleep [2016-02-25 07:43:52.495] [I] [zway] Node 4:0 CC Security: Sending a secure message [2016-02-25 07:43:52.496] [I] [zway] Node 4:0 CC Security: sending encrypted packet: Wakeup Sleep [2016-02-25 07:43:52.498] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.toFollow = False [2016-02-25 07:43:52.498] [D] [zway] SETDATA devices.4.data.lastNonceGet = Empty [2016-02-25 07:43:52.500] [I] [zway] Adding job: Security, Wakeup Sleep [2016-02-25 07:43:52.532] [D] [zway] SENDING (cb 0x04): ( 01 1D 00 13 04 16 98 81 16 94 DB 34 15 D1 C6 D1 2D 34 CC CF 16 98 F2 A8 29 3B 1D 07 05 04 83 ) [2016-02-25 07:43:52.545] [D] [zway] RECEIVED ACK [2016-02-25 07:43:52.549] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 ) [2016-02-25 07:43:52.550] [D] [zway] SENT ACK [2016-02-25 07:43:52.550] [I] [zway] Job 0x13 (Security, Wakeup Sleep): Response received - transfered to encapsulated jobs [2016-02-25 07:43:52.551] [D] [zway] Delivered to Z-Wave stack [2016-02-25 07:43:52.552] [D] [zway] Delivered to Z-Wave stack [2016-02-25 07:43:52.573] [D] [zway] RECEIVED: ( 01 07 00 13 04 00 00 02 ED ) [2016-02-25 07:43:52.577] [D] [zway] SENT ACK [2016-02-25 07:43:52.577] [I] [zway] Job 0x13 (Security, Wakeup Sleep): Callback received - transfered to encapsulated jobs [2016-02-25 07:43:52.578] [I] [zway] Job 0x13 (Wakeup Sleep): Delivered [2016-02-25 07:43:52.579] [D] [zway] SendData Response with callback 0x04 received: received by recipient [2016-02-25 07:43:52.580] [I] [zway] Job 0x13 (Security, Wakeup Sleep): Delivered [2016-02-25 07:43:52.581] [D] [zway] SETDATA devices.4.data.lastPacketInfo.delivered = True [2016-02-25 07:43:52.583] [D] [zway] SETDATA devices.4.data.lastPacketInfo.packetLength = 25 (0x00000019) [2016-02-25 07:43:52.584] [D] [zway] SETDATA devices.4.data.lastPacketInfo.deliveryTime = 28 (0x0000001c) [2016-02-25 07:43:52.586] [D] [zway] SETDATA devices.4.data.lastPacketInfo = ********** [2016-02-25 07:43:52.587] [D] [zway] SendData Response with callback 0x04 received: received by recipient [2016-02-25 07:43:52.589] [D] [zway] SETDATA devices.4.data.lastSend = 207190 (0x00032956) [2016-02-25 07:43:52.591] [D] [zway] Job 0x13 (Security, Wakeup Sleep): success [2016-02-25 07:43:52.592] [I] [zway] Removing job: Security, Wakeup Sleep [2016-02-25 07:44:05.629] [I] [core] [Astronomy] Calculate [2016-02-25 07:44:05.709] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"5.1 °"} [2016-02-25 07:44:06.798] [I] [core] [BaseModule-11] Set lastLevel to 5.145350941111396 for Astronomy_37 (was 4.998147974798047) [2016-02-25 07:44:12.737] [D] [zway] Job 0x13: deleted from queue [2016-02-25 07:44:12.803] [D] [zway] Job 0x13: deleted from queue [2016-02-25 07:44:12.858] [D] [zway] Job 0x13: deleted from queue [2016-02-25 07:44:12.964] [D] [zway] Job 0x13: deleted from queue [2016-02-25 07:44:12.978] [D] [zway] Job 0x13: deleted from queue [2016-02-25 07:45:01.171] [I] [core] --- histories polled [2016-02-25 07:45:05.653] [I] [core] [Astronomy] Calculate [2016-02-25 07:45:05.811] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"5.3 °"} [2016-02-25 07:45:06.880] [I] [core] [BaseModule-11] Set lastLevel to 5.292312248525851 for Astronomy_37 (was 5.145350941111396) [2016-02-25 07:45:25.893] [D] [zway] RECEIVED: ( 01 12 00 04 00 02 0C 60 0D 02 02 31 05 01 44 00 00 07 B1 4D ) [2016-02-25 07:45:25.904] [D] [zway] SENT ACK [2016-02-25 07:45:25.906] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000) [2016-02-25 07:45:25.907] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.deviceScale = 0 (0x00000000) [2016-02-25 07:45:25.914] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.scaleString = "°C" [2016-02-25 07:45:25.918] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.val = 19.690001 [2016-02-25 07:45:25.922] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1 = Empty [2016-02-25 07:45:26.064] [I] [core] Notification: device-info (device-temperature): {"dev":"Flood Temperature (2.2.49.1)","l":"19.7 °C"} [2016-02-25 07:45:26.157] [I] [core] [BaseModule-11] Set lastLevel to 19.69 for ZWayVDev_zway_2-2-49-1 (was 18.99) [2016-02-25 07:46:05.640] [I] [core] [Astronomy] Calculate [2016-02-25 07:46:05.708] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"5.4 °"} [2016-02-25 07:46:06.732] [I] [core] [BaseModule-11] Set lastLevel to 5.439093614662993 for Astronomy_37 (was 5.292312248525851) [2016-02-25 07:47:05.686] [I] [core] [Astronomy] Calculate [2016-02-25 07:47:05.875] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"5.6 °"} [2016-02-25 07:47:06.996] [I] [core] [BaseModule-11] Set lastLevel to 5.585697966930855 for Astronomy_37 (was 5.439093614662993) [2016-02-25 07:48:05.674] [I] [core] [Astronomy] Calculate [2016-02-25 07:48:05.754] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"5.7 °"} [2016-02-25 07:48:06.794] [I] [core] [BaseModule-11] Set lastLevel to 5.732062107309549 for Astronomy_37 (was 5.585697966930855) [2016-02-25 07:49:05.688] [I] [core] [Astronomy] Calculate [2016-02-25 07:49:05.756] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"5.9 °"} [2016-02-25 07:49:06.775] [I] [core] [BaseModule-11] Set lastLevel to 5.878237939120346 for Astronomy_37 (was 5.732062107309549) [2016-02-25 07:50:01.036] [I] [core] --- histories polled [2016-02-25 07:50:05.747] [I] [core] [Astronomy] Calculate [2016-02-25 07:50:06.033] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"6.0 °"} [2016-02-25 07:50:06.669] [I] [core] [Astronomy] Event goldenHourEnd [2016-02-25 07:50:07.349] [I] [core] [BaseModule-11] Set lastLevel to 6.024232855460571 for Astronomy_37 (was 5.878237939120346) [2016-02-25 07:51:05.740] [I] [core] [Astronomy] Calculate [2016-02-25 07:51:05.819] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"6.2 °"} [2016-02-25 07:51:06.846] [I] [core] [BaseModule-11] Set lastLevel to 6.169972080542198 for Astronomy_37 (was 6.024232855460571) [2016-02-25 07:52:05.788] [I] [core] [Astronomy] Calculate [2016-02-25 07:52:05.980] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"6.3 °"} [2016-02-25 07:52:07.050] [I] [core] [BaseModule-11] Set lastLevel to 6.315521542373375 for Astronomy_37 (was 6.169972080542198) [2016-02-25 07:53:05.778] [I] [core] [Astronomy] Calculate [2016-02-25 07:53:05.858] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"6.5 °"} [2016-02-25 07:53:06.949] [I] [core] [BaseModule-11] Set lastLevel to 6.4608187601588 for Astronomy_37 (was 6.315521542373375) [2016-02-25 07:54:05.784] [I] [core] [Astronomy] Calculate [2016-02-25 07:54:05.852] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"6.6 °"} [2016-02-25 07:54:06.871] [I] [core] [BaseModule-11] Set lastLevel to 6.605893066951716 for Astronomy_37 (was 6.4608187601588) [2016-02-25 07:55:00.982] [I] [core] --- histories polled [2016-02-25 07:55:05.818] [I] [core] [Astronomy] Calculate [2016-02-25 07:55:05.976] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"6.8 °"} [2016-02-25 07:55:07.049] [I] [core] [BaseModule-11] Set lastLevel to 6.750733039648398 for Astronomy_37 (was 6.605893066951716) [2016-02-25 07:55:30.909] [D] [zway] RECEIVED: ( 01 12 00 04 00 02 0C 60 0D 02 02 31 05 01 44 00 00 07 EB 17 ) [2016-02-25 07:55:30.919] [D] [zway] SENT ACK [2016-02-25 07:55:30.920] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000) [2016-02-25 07:55:30.921] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.deviceScale = 0 (0x00000000) [2016-02-25 07:55:30.927] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.scaleString = "°C" [2016-02-25 07:55:30.931] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.val = 20.270000 [2016-02-25 07:55:30.935] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1 = Empty [2016-02-25 07:55:31.076] [I] [core] Notification: device-info (device-temperature): {"dev":"Flood Temperature (2.2.49.1)","l":"20.3 °C"} [2016-02-25 07:55:31.166] [I] [core] [BaseModule-11] Set lastLevel to 20.27 for ZWayVDev_zway_2-2-49-1 (was 19.69) [2016-02-25 07:56:05.796] [I] [core] [Astronomy] Calculate [2016-02-25 07:56:05.863] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"6.9 °"} [2016-02-25 07:56:06.936] [I] [core] [BaseModule-11] Set lastLevel to 6.8953364141681766 for Astronomy_37 (was 6.750733039648398) [2016-02-25 07:56:25.719] [I] [core] --- Sonos_Device_Play_192.168.10.205_29 performCommand processing: {"0":"on","1":{}} [New Thread 0xb19ff450 (LWP 26144)] [Thread 0xb19ff450 (LWP 26144) exited] [2016-02-25 07:56:27.723] [I] [core] Notification: device-info (device-OnOff): {"dev":"Sonos Play 192.168.10.205 29","l":"on"} [2016-02-25 07:56:27.978] [I] [core] [BaseModule-11] Set lastLevel to on for Sonos_Device_Play_192.168.10.205_29 (was off) [2016-02-25 07:57:05.829] [I] [core] [Astronomy] Calculate [2016-02-25 07:57:05.910] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"7.0 °"} [2016-02-25 07:57:06.944] [I] [core] [BaseModule-11] Set lastLevel to 7.039737586718822 for Astronomy_37 (was 6.8953364141681766) [2016-02-25 07:58:05.831] [I] [core] [Astronomy] Calculate [2016-02-25 07:58:05.926] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"7.2 °"} [2016-02-25 07:58:06.982] [I] [core] [BaseModule-11] Set lastLevel to 7.183888623239038 for Astronomy_37 (was 7.039737586718822) [2016-02-25 07:59:05.807] [I] [core] [Astronomy] Calculate [2016-02-25 07:59:05.884] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"7.3 °"} [2016-02-25 07:59:06.973] [I] [core] [BaseModule-11] Set lastLevel to 7.327799538495848 for Astronomy_37 (was 7.183888623239038) [2016-02-25 08:00:00.998] [I] [core] --- histories polled [2016-02-25 08:00:05.844] [I] [core] [Astronomy] Calculate [2016-02-25 08:00:06.003] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"7.5 °"} [2016-02-25 08:00:07.069] [I] [core] [BaseModule-11] Set lastLevel to 7.471516269178418 for Astronomy_37 (was 7.327799538495848) [2016-02-25 08:01:05.829] [I] [core] [Astronomy] Calculate [2016-02-25 08:01:05.907] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"7.6 °"} [2016-02-25 08:01:06.933] [I] [core] [BaseModule-11] Set lastLevel to 7.614983997593528 for Astronomy_37 (was 7.471516269178418) [2016-02-25 08:02:05.877] [I] [core] [Astronomy] Calculate [2016-02-25 08:02:06.058] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"7.8 °"} [2016-02-25 08:02:07.178] [I] [core] [BaseModule-11] Set lastLevel to 7.758257916854876 for Astronomy_37 (was 7.614983997593528) [2016-02-25 08:02:49.048] [D] [zway] RECEIVED: ( 01 0C 00 04 00 04 06 31 05 01 22 00 CC 2E ) [2016-02-25 08:02:49.057] [D] [zway] SENT ACK [2016-02-25 08:02:49.059] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000) [2016-02-25 08:02:49.061] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.deviceScale = 0 (0x00000000) [2016-02-25 08:02:49.068] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.scaleString = "°C" [2016-02-25 08:02:49.072] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.val = 20.400000 [2016-02-25 08:02:49.075] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1 = Empty [2016-02-25 08:02:49.215] [I] [core] Notification: device-info (device-temperature): {"dev":"Fibar Group Temperature (4.0.49.1)","l":"20.4 °C"} [2016-02-25 08:02:49.307] [I] [core] [BaseModule-11] Set lastLevel to 20.4 for ZWayVDev_zway_4-0-49-1 (was 19.4) [2016-02-25 08:03:05.856] [I] [core] [Astronomy] Calculate [2016-02-25 08:03:05.925] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"7.9 °"} [2016-02-25 08:03:06.966] [I] [core] [BaseModule-11] Set lastLevel to 7.9012334367257155 for Astronomy_37 (was 7.758257916854876) [2016-02-25 08:04:05.912] [I] [core] [Astronomy] Calculate [2016-02-25 08:04:06.004] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"8.0 °"} [2016-02-25 08:04:07.057] [I] [core] [BaseModule-11] Set lastLevel to 8.044046676582356 for Astronomy_37 (was 7.9012334367257155) [2016-02-25 08:05:00.924] [I] [core] --- histories polled [2016-02-25 08:05:05.896] [I] [core] [Astronomy] Calculate [2016-02-25 08:05:05.972] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"8.2 °"} [2016-02-25 08:05:07.058] [I] [core] [BaseModule-11] Set lastLevel to 8.186547849864098 for Astronomy_37 (was 8.044046676582356) [2016-02-25 08:05:35.597] [D] [zway] RECEIVED: ( 01 12 00 04 00 02 0C 60 0D 02 02 31 05 01 44 00 00 08 1E ED ) [2016-02-25 08:05:35.607] [D] [zway] SENT ACK [2016-02-25 08:05:35.607] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000) [2016-02-25 08:05:35.609] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.deviceScale = 0 (0x00000000) [2016-02-25 08:05:35.614] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.scaleString = "°C" [2016-02-25 08:05:35.618] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1.val = 20.780001 [2016-02-25 08:05:35.622] [D] [zway] SETDATA devices.2.instances.2.commandClasses.49.data.1 = Empty [2016-02-25 08:05:35.777] [I] [core] Notification: device-info (device-temperature): {"dev":"Flood Temperature (2.2.49.1)","l":"20.8 °C"} [2016-02-25 08:05:35.873] [I] [core] [BaseModule-11] Set lastLevel to 20.78 for ZWayVDev_zway_2-2-49-1 (was 20.27) [2016-02-25 08:06:05.933] [I] [core] [Astronomy] Calculate [2016-02-25 08:06:06.015] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"8.3 °"} [2016-02-25 08:06:07.052] [I] [core] [BaseModule-11] Set lastLevel to 8.328830140554516 for Astronomy_37 (was 8.186547849864098) [2016-02-25 08:07:05.945] [I] [core] [Astronomy] Calculate [2016-02-25 08:07:06.176] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"8.5 °"} [2016-02-25 08:07:07.244] [I] [core] [BaseModule-11] Set lastLevel to 8.470853357949624 for Astronomy_37 (was 8.328830140554516) [2016-02-25 08:08:05.918] [I] [core] [Astronomy] Calculate [2016-02-25 08:08:05.999] [I] [core] Notification: device-info (device-astronomy): {"dev":"Astronomy Module","l":"8.6 °"} [2016-02-25 08:08:07.029] [I] [core] [BaseModule-11] Set lastLevel to 8.612592006859904 for Astronomy_37 (was 8.470853357949624) Program received signal SIGSEGV, Segmentation fault. [Switching to Thread 0xb2bff450 (LWP 25498)] 0xb5798460 in zwjs::SocketConnection::IsConfigured() const () from ./modules/modsockets.so (gdb) info thread Id Target Id Frame 9 Thread 0xb23ff450 (LWP 25499) "zway/core" 0xb642f514 in select () at ../sysdeps/unix/syscall-template.S:81 * 8 Thread 0xb2bff450 (LWP 25498) "zway/sockets" 0xb5798460 in zwjs::SocketConnection::IsConfigured() const () from ./modules/modsockets.so 7 Thread 0xb35ff450 (LWP 25497) "zway/timers" 0xb6403d90 in nanosleep () at ../sysdeps/unix/syscall-template.S:81 6 Thread 0xb3dff450 (LWP 25496) "zway/core" 0xb642f514 in select () at ../sysdeps/unix/syscall-template.S:81 5 Thread 0xb4723450 (LWP 25495) "zway/core" 0xb69834b0 in __pthread_mutex_unlock_usercnt (mutex=0x1, decr=1) at pthread_mutex_unlock.c:57 4 Thread 0xb4f23450 (LWP 25494) "v8:MsgDispHelpr" 0xb6986a40 in do_futex_wait (isem=isem@entry=0x5dc44) at ../nptl/sysdeps/unix/sysv/linux/sem_wait.c:48 3 Thread 0xb4f33450 (LWP 25493) "v8:SweeperThrea" 0xb6986a40 in do_futex_wait (isem=isem@entry=0x5db44) at ../nptl/sysdeps/unix/sysv/linux/sem_wait.c:48 2 Thread 0xb5733450 (LWP 25492) "zway" 0xb6987f60 in accept () at ../sysdeps/unix/syscall-template.S:81 1 Thread 0xb634f000 (LWP 25489) "z-way-server" 0xb6403d90 in nanosleep () at ../sysdeps/unix/syscall-template.S:81 (gdb) bt #0 0xb5798460 in zwjs::SocketConnection::IsConfigured() const () from ./modules/modsockets.so #1 0xb579a760 in zwjs::SocketsContext::Poll(int) () from ./modules/modsockets.so #2 0xb5799960 in zwjs::SocketsContext::ServerFunc(ZRefCountedPointer) () from ./modules/modsockets.so #3 0xb6f87264 in zwjs::Thread::ThreadFuncNative(void*) () from ./libs/libzwayjs.so #4 0xb697fe90 in start_thread (arg=0xb2bff450) at pthread_create.c:311 #5 0xb6436128 in ?? () at ../ports/sysdeps/unix/sysv/linux/arm/nptl/../clone.S:92 from /lib/arm-linux-gnueabihf/libc.so.6 Backtrace stopped: previous frame identical to this frame (corrupt stack?) (gdb)