ØysteinHJ Skrevet 8. november 2020 Skrevet 8. november 2020 Har så langt fått montert én Namron roterbar LED-dimmer i heimen. Har ikke villet montere noen flere før jeg har fått testet litt. Er ganske fornøyd med dimmeren, men det hender at dimmeren "resetter" seg (lyset slukker og tenner igjen) hvis jeg er intens med lysjusteringen og skrur raskt opp og ned gjentatte ganger. Men en ting som jeg synes er en svakhet med Namron-dimmeren, er at det ikke går an å justere lysnivå når dimmeren er avslått. Altså, hvis lyset har vært på et høyt nivå da jeg slo av lyset, må jeg også slå på dimmeren på dette nivået seinere og deretter justere ned lysnivået. Dette blir fort litt ubehagelig når man skal slå på lyset på et lavt nivå tidlig på morgenen. Hvordan er andre merker mhp. stabilitet i dimmingen og å kunne justere lysnivå ved avslått lys? Tenker på dimmerne fra Heatit, EcoDim og Elko i hvert fall. Siter
Moskus Skrevet 8. november 2020 Skrevet 8. november 2020 11 timer siden, ØysteinHJ skrev: Altså, hvis lyset har vært på et høyt nivå da jeg slo av lyset, må jeg også slå på dimmeren på dette nivået seinere og deretter justere ned lysnivået. Dette høres spydig ut, det er ikke meningen. Men likevel: Hvorfor slår du på lyset manuelt...? Dimmer2 har iallfall en parameter for å kunne sette "på-nivå". Det er muligandre skru-dimmere har det, men det er ikke 100% vanlig. Les manualen til Z-DIM. EDIT: Men skulle man sett: 1 Siter
ØysteinHJ Skrevet 8. november 2020 Forfatter Skrevet 8. november 2020 (endret) Det går fint. Jeg er alltid med på å revurdere rutinene mine. 🙂 Dimmeren det er snakk om er til taklampa på soverommet vårt. Og skal ikke lyset slås på manuelt der, så må det jo gjøres med en gradvis oppdimming på et predefinert klokkeslett. Jeg luftet denne ideen for madammen, men fikk ikke noe gehør der gitt.. 😄 Ideen om å sette et fast på-nivå var ikke så dum faktisk! Det var en grei workaround på problemet med Namron-dimmeren. Men vet du om dette er tilfellet med andre dyrere dimmere også, at man ikke kan justere dimmernivået når man er avslått? Dette er en funksjon jeg har brukt ganske mye på analoge dimmere, merker jeg nå. Så jeg savnet det på Namron-dimmeren. Endret 8. november 2020 av ØysteinHJ Siter
Moskus Skrevet 9. november 2020 Skrevet 9. november 2020 Ja, OK. Soverom er faktisk tilgitt. Men: 16 timer siden, ØysteinHJ skrev: Og skal ikke lyset slås på manuelt der, så må det jo gjøres med en gradvis oppdimming på et predefinert klokkeslett. Dette er noe man ikke klarer seg uten etter å ha brukt det en stund. Det må testes, så hvorfor i det hele tatt spørre? Siter
putrid Skrevet 22. november 2020 Skrevet 22. november 2020 RPi4 Openhabian openHAB 2.5.10-1 Samler spørsmålene i samme tråd. Nettopp inkludert Namron LED Dimmer z-wave 200w Det virker som Dimmeren rapporterer gammel verdi tilbake når jeg endrer lysstyrken via OpenHab. Openhab viser 100% Openhab dimmer til 50% Lyset dimmes Openhab viser den som 100% (Lyset er fortsatt 50%) Hvis jeg dimmer manuelt på hjulet, så blir det stående riktig verdi. Det er den verdien Openhab alltid går tilbake til. Siter
NilsOF Skrevet 22. november 2020 Skrevet 22. november 2020 (endret) Openhab faller tilbake til siste kjente verdi når det ikke er kontakt med dingsen. Men det stemmer ikke helt med loggen din. Du må nesten slå på debug-logging for å se alle detaljene. edit: hvordan er det med naboer til Namron-dimmeren? (du finner de i Habmin) Samtidig så er Paperui ikke helt å stole på når det gjelder faktisk status på dingsene. Det ordner seg som oftest med en reload i nettleseren. Endret 22. november 2020 av NilsOF Siter
putrid Skrevet 22. november 2020 Skrevet 22. november 2020 6 timer siden, NilsOF skrev: Openhab faller tilbake til siste kjente verdi når det ikke er kontakt med dingsen. Men det stemmer ikke helt med loggen din. Du må nesten slå på debug-logging for å se alle detaljene. edit: hvordan er det med naboer til Namron-dimmeren? (du finner de i Habmin) Samtidig så er Paperui ikke helt å stole på når det gjelder faktisk status på dingsene. Det ordner seg som oftest med en reload i nettleseren. Namron er i hvertfall nærme nok til å oppdatere Openhab i sanntid når jeg vrir på hjulet. Nå har Dimmeren 13 naboer, og får fortsatt ikke tilbake ny verdi fra dimmeren når jeg endrer via openhab. (har testet med både OpenHab-appen og Paperui) Jeg er litt usikker på hvordan jeg aktivere debugging her.. Duwi dimmerne mine gjør motsatt. De sender ingenting til openhab når jeg manuelt dimmer lyset. Men Openhab får beholde ny verdi, når jeg dimmer via OH. (Dette er et nytt system som er satt opp denne helgen) Siter
NilsOF Skrevet 23. november 2020 Skrevet 23. november 2020 (endret) @putrid Når en endring gjøres fra openhab så vil en "xxx predicted to become aaa" -linje komme i event.log (dvs, det sendes ut i meshet) Når verdi kommer inn fra dings til openhab logges "xxx changed from aaa to bbb". MEN, når en dings ikke er på nett kommer også denne linja opp i loggen og "bbb" er da siste motatte verdi fra dings. Jeg sjekket akkurat dette i men egen logg på en lampe som ikke har strøm. Z-wave må ha litt tid på å stabilisere seg. At systemet passerer "Heal time" hjelper når nye dingser legges til. Alternativt kan man kjøre "Syncronize network" manuelt på z-wave-stikka. Jeg kjører "Heal the device" manuelt via Habmin på ny (eller flyttet) dings, samt de nærmeste naboene. (I Habmin velg z-wave-dingsen og trykk "Tools" i høyre hjørne oppe, velg "Show advanced settigs" og trykk "Tools" en gang til. Man bør ikke ha for mange "Heals" i køen på en gang. Man kan lett overbelaste meshet på denne måten. Det kan ta et par timer før de er ferdige. Samme med "Syncronize network", som vel egentlig er en serialisering av "heals" på alle nodene. Jeg har to kjørbare filer. En for å slå på debug-logging, og en for å sette tilbake til info-logging: (bitfrost er navnet på Openhab maskina mi) nils@xps13:~/bin$ cat oh-debuglog-zwave #!/bin/sh ssh -p 8101 openhab@bitfrost "log:set debug org.openhab.binding.zwave" nils@xps13:~/bin$ nils@xps13:~/bin$ cat oh-infolog-zwave #!/bin/sh ssh -p 8101 openhab@bitfrost "log:set info org.openhab.binding.zwave" nils@xps13:~/bin$ I debug-modus vokser logg-fila ganske fort! Det kan være lurt å ta ett utsnitt av loggfila å laste utsnittet opp på https://www.cd-jackson.com/index.php/openhab/zwave-log-viewer Den "dekoder" muligens z-wave trafikken til noe forståelig. Noen ganger må man studere rå logg.. (i skrivende stund så er det noe rart med webserveren) Men la meshet få stabilisert seg først. Likevel, det henger ikke helt på grep dette. Noen feilmeldinger i openhab.log? Edit: Debug modus leses i openhab.log Endret 23. november 2020 av NilsOF Siter
putrid Skrevet 23. november 2020 Skrevet 23. november 2020 (endret) Sender med et utsnitt av DEBUG loggen Gammel verdi var 15%. Ny verdi skulle bli 62% (lyset dimmet til 62%) Status ble satt tilbake til 15% Endret 23. november 2020 av putrid log_bilde Siter
putrid Skrevet 23. november 2020 Skrevet 23. november 2020 Ble mye logging såklart 2020-11-23 19:57:38.974 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-23 19:57:49.691 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Command received zwave:device:5727c0c5:node12:switch_dimmer --> 62 [PercentType] 2020-11-23 19:57:49.696 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 12: Creating new message for command SWITCH_MULTILEVEL_SET 2020-11-23 19:57:49.700 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL 2020-11-23 19:57:49.704 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured 2020-11-23 19:57:49.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Adding to device queue 2020-11-23 19:57:49.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Added 835 to queue - size 3 2020-11-23 19:57:49.718 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-23 19:57:49.724 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 26 01 3E 25 B6 63 2020-11-23 19:57:49.729 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 26 01 3E 25 B6 63 2020-11-23 19:57:49.736 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-11-23 19:57:49.734 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-11-23 19:57:49.743 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-23 19:57:49.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-23 19:57:49.749 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-11-23 19:57:49.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-11-23 19:57:49.751 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 835: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 182 2020-11-23 19:57:49.756 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-11-23 19:57:49.756 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:49.763 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-23 19:57:49.767 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 B6 00 00 02 5F 2020-11-23 19:57:49.772 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Request[0], dest=0, callback=182, payload=B6 00 00 02 2020-11-23 19:57:49.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-23 19:57:49.784 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling initialised at 86400 seconds - start in 1500 milliseconds. 2020-11-23 19:57:49.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:49.796 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 0C 04 26 01 3E FF 1F 2020-11-23 19:57:49.798 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 835: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 182 2020-11-23 19:57:49.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-23 19:57:49.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 04 26 01 3E FF 2020-11-23 19:57:49.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 835: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 182 2020-11-23 19:57:49.829 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:49.838 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2020-11-23 19:57:49.843 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 835: Advanced to WAIT_REQUEST 2020-11-23 19:57:49.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: TID 835: Transaction not completed 2020-11-23 19:57:49.855 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Request[0], dest=0, callback=182, payload=B6 00 00 02 2020-11-23 19:57:49.861 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 835: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 182 2020-11-23 19:57:49.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-23 19:57:49.872 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 835: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 182 2020-11-23 19:57:49.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 835: (Callback 182) 2020-11-23 19:57:49.885 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-11-23 19:57:49.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 835: callback 182 2020-11-23 19:57:49.897 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=182, payload=B6 00 00 02 2020-11-23 19:57:49.901 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 182, Status = Transmission complete and ACK received(0) 2020-11-23 19:57:49.906 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false 2020-11-23 19:57:49.911 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 835: Transaction COMPLETED 2020-11-23 19:57:49.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Response processed after 164ms 2020-11-23 19:57:49.919 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: TID 835: Transaction completed 2020-11-23 19:57:49.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:835 DONE 2020-11-23 19:57:49.930 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-11-23 19:57:49.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 04 26 01 3E FF 2020-11-23 19:57:49.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-11-23 19:57:49.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:DONE) 2020-11-23 19:57:49.953 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false 2020-11-23 19:57:49.957 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 2020-11-23 19:57:49.962 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL 2020-11-23 19:57:49.966 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_SWITCH_MULTILEVEL V2 SWITCH_MULTILEVEL_SET 2020-11-23 19:57:49.970 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 12: Switch Multi Level set, value = 62 2020-11-23 19:57:49.974 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2020-11-23 19:57:49.978 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=62 2020-11-23 19:57:49.982 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:5727c0c5:node12:switch_dimmer to 62 [PercentType] 2020-11-23 19:57:49.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2020-11-23 19:57:49.991 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1d8291. 2020-11-23 19:57:49.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-23 19:57:49.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-23 19:57:50.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-23 19:57:50.005 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. ==> /var/log/openhab2/events.log <== 2020-11-23 19:57:49.650 [ome.event.ItemCommandEvent] - Item 'masterbedroom_dimmer' received command 62 2020-11-23 19:57:49.671 [nt.ItemStatePredictedEvent] - masterbedroom_dimmer predicted to become 62 2020-11-23 19:57:49.691 [vent.ItemStateChangedEvent] - masterbedroom_dimmer changed from 15 to 62 ==> /var/log/openhab2/openhab.log <== 2020-11-23 19:57:51.067 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 0C 0A 32 02 21 54 00 00 11 88 00 00 31 2020-11-23 19:57:51.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0A 32 02 21 54 00 00 11 88 00 00 2020-11-23 19:57:51.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0A 32 02 21 54 00 00 11 88 00 00 2020-11-23 19:57:51.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-11-23 19:57:51.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:DONE) 2020-11-23 19:57:51.097 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false 2020-11-23 19:57:51.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_METER, endpoint 0 2020-11-23 19:57:51.112 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_METER 2020-11-23 19:57:51.117 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_METER V3 METER_REPORT 2020-11-23 19:57:51.124 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 12: Meter: Type=Electric(1), Scale=W(2), Value=44.88 2020-11-23 19:57:51.130 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveMeterValueEvent 2020-11-23 19:57:51.135 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=44.88 2020-11-23 19:57:51.139 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:5727c0c5:node12:meter_watts to 44.88 [DecimalType] 2020-11-23 19:57:51.145 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2020-11-23 19:57:51.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1972f2b. 2020-11-23 19:57:51.159 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-23 19:57:51.171 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-23 19:57:51.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-23 19:57:51.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-23 19:57:51.261 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling... 2020-11-23 19:57:51.267 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling zwave:device:5727c0c5:node12:switch_dimmer 2020-11-23 19:57:51.274 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 12: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 2020-11-23 19:57:51.280 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 12: Creating new message for command SWITCH_MULTILEVEL_GET 2020-11-23 19:57:51.286 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL 2020-11-23 19:57:51.292 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured 2020-11-23 19:57:51.296 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling skipped for zwave:device:5727c0c5:node12:switch_dimmer on COMMAND_CLASS_BASIC 2020-11-23 19:57:51.301 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling zwave:device:5727c0c5:node12:meter_voltage 2020-11-23 19:57:51.305 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 12: Generating poll message for COMMAND_CLASS_METER, endpoint 0 2020-11-23 19:57:51.309 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 12: Generating poll message for COMMAND_CLASS_METER, endpoint 0 2020-11-23 19:57:51.313 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 12: Creating new message for application command METER_GET 2020-11-23 19:57:51.322 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_METER 2020-11-23 19:57:51.325 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Command Class COMMAND_CLASS_METER is NOT required to be secured ==> /var/log/openhab2/events.log <== 2020-11-23 19:57:51.153 [vent.ItemStateChangedEvent] - MasterBedroom_dimmer_ElectricMeterWatts changed from 7.9 to 44.88 ==> /var/log/openhab2/openhab.log <== 2020-11-23 19:57:51.335 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Polling zwave:device:5727c0c5:node12:meter_watts 2020-11-23 19:57:51.342 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 12: Generating poll message for COMMAND_CLASS_METER, endpoint 0 2020-11-23 19:57:51.346 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 12: Generating poll message for COMMAND_CLASS_METER, endpoint 0 2020-11-23 19:57:51.352 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 12: Creating new message for application command METER_GET 2020-11-23 19:57:51.360 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_METER 2020-11-23 19:57:51.368 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Command Class COMMAND_CLASS_METER is NOT required to be secured 2020-11-23 19:57:51.374 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Adding to device queue 2020-11-23 19:57:51.380 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Added 836 to queue - size 3 2020-11-23 19:57:51.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-23 19:57:51.394 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0C 02 26 02 25 B7 5D 2020-11-23 19:57:51.403 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 09 00 13 0C 02 26 02 25 B7 5D 2020-11-23 19:57:51.409 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-11-23 19:57:51.411 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-11-23 19:57:51.413 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 836: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 183 2020-11-23 19:57:51.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Adding to device queue 2020-11-23 19:57:51.424 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Added 837 to queue - size 3 2020-11-23 19:57:51.426 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-23 19:57:51.431 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-23 19:57:51.428 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-23 19:57:51.435 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-11-23 19:57:51.437 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 836: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 183 2020-11-23 19:57:51.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-11-23 19:57:51.447 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-23 19:57:51.445 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Adding to device queue 2020-11-23 19:57:51.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Added 838 to queue - size 4 2020-11-23 19:57:51.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-23 19:57:51.443 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:51.472 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 B7 00 00 03 5F 2020-11-23 19:57:51.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-23 19:57:51.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:51.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Request[0], dest=0, callback=183, payload=B7 00 00 03 2020-11-23 19:57:51.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 836: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 183 2020-11-23 19:57:51.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-23 19:57:51.509 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 0C 03 26 03 0F D7 2020-11-23 19:57:51.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 836: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 183 2020-11-23 19:57:51.516 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 03 26 03 0F 2020-11-23 19:57:51.519 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:51.525 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2020-11-23 19:57:51.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 836: Advanced to WAIT_REQUEST 2020-11-23 19:57:51.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: TID 836: Transaction not completed 2020-11-23 19:57:51.539 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Request[0], dest=0, callback=183, payload=B7 00 00 03 2020-11-23 19:57:51.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 836: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 183 2020-11-23 19:57:51.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-23 19:57:51.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 836: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 183 2020-11-23 19:57:51.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 836: (Callback 183) 2020-11-23 19:57:51.561 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-11-23 19:57:51.565 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 836: callback 183 2020-11-23 19:57:51.570 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=183, payload=B7 00 00 03 2020-11-23 19:57:51.574 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 183, Status = Transmission complete and ACK received(0) 2020-11-23 19:57:51.579 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false 2020-11-23 19:57:51.584 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 836: Advanced to WAIT_DATA 2020-11-23 19:57:51.588 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: TID 836: Transaction not completed 2020-11-23 19:57:51.598 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 03 26 03 0F 2020-11-23 19:57:51.605 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-11-23 19:57:51.611 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:DONE) 2020-11-23 19:57:51.618 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false 2020-11-23 19:57:51.624 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0 2020-11-23 19:57:51.632 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_SWITCH_MULTILEVEL 2020-11-23 19:57:51.636 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_SWITCH_MULTILEVEL V2 SWITCH_MULTILEVEL_REPORT 2020-11-23 19:57:51.646 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 12: Switch Multi Level report, value = 15 2020-11-23 19:57:51.653 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveCommandClassValueEvent 2020-11-23 19:57:51.659 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=15 2020-11-23 19:57:51.664 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:5727c0c5:node12:switch_dimmer to 15 [PercentType] 2020-11-23 19:57:51.670 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2020-11-23 19:57:51.673 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@460bad. 2020-11-23 19:57:51.681 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@460bad. 2020-11-23 19:57:51.687 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:836 DONE 2020-11-23 19:57:51.694 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-11-23 19:57:51.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-11-23 19:57:51.704 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-23 19:57:51.708 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-23 19:57:51.712 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-23 19:57:51.717 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 32 01 20 25 B8 67 2020-11-23 19:57:51.722 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 32 01 20 25 B8 67 2020-11-23 19:57:51.730 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-11-23 19:57:51.729 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-11-23 19:57:51.735 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-23 19:57:51.736 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 837: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 184 2020-11-23 19:57:51.747 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-23 19:57:51.751 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-11-23 19:57:51.756 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 837: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 184 2020-11-23 19:57:51.759 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:51.764 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-11-23 19:57:51.768 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 B8 00 00 03 50 2020-11-23 19:57:51.770 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:51.774 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=184, payload=B8 00 00 03 2020-11-23 19:57:51.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 837: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 184 2020-11-23 19:57:51.781 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-23 19:57:51.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 837: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 184 2020-11-23 19:57:51.790 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 0C 0A 32 02 A1 44 00 00 00 00 00 00 38 2020-11-23 19:57:51.794 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:51.799 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0A 32 02 A1 44 00 00 00 00 00 00 2020-11-23 19:57:51.800 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2020-11-23 19:57:51.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 837: Advanced to WAIT_REQUEST 2020-11-23 19:57:51.812 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: TID 837: Transaction not completed 2020-11-23 19:57:51.817 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Request[0], dest=0, callback=184, payload=B8 00 00 03 2020-11-23 19:57:51.823 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 837: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 184 2020-11-23 19:57:51.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-23 19:57:51.834 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 837: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 184 2020-11-23 19:57:51.839 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 837: (Callback 184) 2020-11-23 19:57:51.845 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-11-23 19:57:51.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 837: callback 184 2020-11-23 19:57:51.857 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=184, payload=B8 00 00 03 2020-11-23 19:57:51.862 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 184, Status = Transmission complete and ACK received(0) 2020-11-23 19:57:51.866 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false 2020-11-23 19:57:51.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 837: Advanced to WAIT_DATA 2020-11-23 19:57:51.874 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: TID 837: Transaction not completed 2020-11-23 19:57:51.880 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0A 32 02 A1 44 00 00 00 00 00 00 2020-11-23 19:57:51.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-11-23 19:57:51.888 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:DONE) 2020-11-23 19:57:51.892 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false 2020-11-23 19:57:51.896 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_METER, endpoint 0 2020-11-23 19:57:51.900 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_METER 2020-11-23 19:57:51.905 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_METER V3 METER_REPORT 2020-11-23 19:57:51.909 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 12: Meter: Type=Electric(1), Scale=V(4), Value=0E+1 2020-11-23 19:57:51.913 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveMeterValueEvent 2020-11-23 19:57:51.917 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1 2020-11-23 19:57:51.922 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:5727c0c5:node12:meter_voltage to 0 [DecimalType] 2020-11-23 19:57:51.927 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2020-11-23 19:57:51.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@15faaa. 2020-11-23 19:57:51.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@15faaa. 2020-11-23 19:57:51.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:837 DONE 2020-11-23 19:57:51.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-11-23 19:57:51.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-11-23 19:57:51.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-23 19:57:51.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-23 19:57:51.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-23 19:57:51.985 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 0C 03 32 01 10 25 B9 56 2020-11-23 19:57:51.991 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 0A 00 13 0C 03 32 01 10 25 B9 56 2020-11-23 19:57:51.996 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-11-23 19:57:51.998 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-11-23 19:57:52.003 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-23 19:57:52.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 838: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 185 2020-11-23 19:57:52.011 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-11-23 19:57:52.015 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:52.016 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-23 19:57:52.021 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 B9 00 00 02 50 2020-11-23 19:57:52.023 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 838: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 185 2020-11-23 19:57:52.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Request[0], dest=0, callback=185, payload=B9 00 00 02 2020-11-23 19:57:52.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-11-23 19:57:52.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:52.050 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 838: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 185 2020-11-23 19:57:52.056 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-23 19:57:52.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 838: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 185 2020-11-23 19:57:52.066 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-23 19:57:52.070 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 0C 0E 32 02 21 44 00 00 00 00 00 57 00 00 00 00 EF 2020-11-23 19:57:52.073 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: sentData successfully placed on stack. 2020-11-23 19:57:52.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 838: Advanced to WAIT_REQUEST 2020-11-23 19:57:52.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: TID 838: Transaction not completed 2020-11-23 19:57:52.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0E 32 02 21 44 00 00 00 00 00 57 00 00 00 00 2020-11-23 19:57:52.087 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=185, payload=B9 00 00 02 2020-11-23 19:57:52.091 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 838: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 185 2020-11-23 19:57:52.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-23 19:57:52.097 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 838: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 185 2020-11-23 19:57:52.100 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 838: (Callback 185) 2020-11-23 19:57:52.103 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-11-23 19:57:52.107 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 838: callback 185 2020-11-23 19:57:52.111 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=185, payload=B9 00 00 02 2020-11-23 19:57:52.114 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 185, Status = Transmission complete and ACK received(0) 2020-11-23 19:57:52.118 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false 2020-11-23 19:57:52.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 838: Advanced to WAIT_DATA 2020-11-23 19:57:52.126 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: TID 838: Transaction not completed 2020-11-23 19:57:52.130 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=12, callback=0, payload=00 0C 0E 32 02 21 44 00 00 00 00 00 57 00 00 00 00 2020-11-23 19:57:52.133 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-11-23 19:57:52.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Application Command Request (ALIVE:DONE) 2020-11-23 19:57:52.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: resetResendCount initComplete=true isDead=false 2020-11-23 19:57:52.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: Incoming command class COMMAND_CLASS_METER, endpoint 0 2020-11-23 19:57:52.148 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 12: SECURITY NOT required on COMMAND_CLASS_METER 2020-11-23 19:57:52.155 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 12: Received COMMAND_CLASS_METER V3 METER_REPORT 2020-11-23 19:57:52.158 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 12: Meter: Type=Electric(1), Scale=kWh(0), Value=0E+1 2020-11-23 19:57:52.162 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveMeterValueEvent 2020-11-23 19:57:52.165 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1 2020-11-23 19:57:52.169 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:5727c0c5:node12:meter_kwh to 0 [DecimalType] 2020-11-23 19:57:52.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Commands processed 1. 2020-11-23 19:57:52.193 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@80f5f3. 2020-11-23 19:57:52.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@80f5f3. 2020-11-23 19:57:52.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 12: notifyTransactionResponse TID:838 DONE 2020-11-23 19:57:52.210 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-11-23 19:57:52.214 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-11-23 19:57:52.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-23 19:57:52.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-23 19:57:52.228 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. ==> /var/log/openhab2/events.log <== 2020-11-23 19:57:51.678 [vent.ItemStateChangedEvent] - masterbedroom_dimmer changed from 62 to 15 Siter
NilsOF Skrevet 24. november 2020 Skrevet 24. november 2020 Jeg har ikke tid til å studere loggene dine i detalj nå. Du må nesten se om du klarer å finne noen mening eller rød tråd i dette selv med litt prøving og feiling 😉 Siter
putrid Skrevet 3. desember 2020 Skrevet 3. desember 2020 De lå en feil i databasen som satte Association Group 1, 2 og 3 til Controller. Det er nå rettet, men ikke rullet ut enda. Siter
Anbefalte innlegg
Bli med i samtalen
Du kan publisere innhold nå og registrere deg senere. Hvis du har en konto, logg inn nå for å poste med kontoen din.