über 100 Warnmeldungen am Tag im Log normal ?

Begonnen von Deckoffizier, 20 April 2019, 20:59:10

Vorheriges Thema - Nächstes Thema
Drucken
Nach unten Seiten1
Benutzer-Aktionen

Deckoffizier

20 April 2019, 20:59:10
Hallo,
kann die Stunden und Versuche schon nicht mehr zählen um dies Problem in den Griff zu bekommen.

Auffällig als letztes includierter Fensterkontakt sendet jede Stunde eine Warnmeldung wie
ZWDongle_1 transmit NO_ACK for CB 4b, target DG_WZ_ZW_FK_L.

Habe aber nirgendwo ein at oder DOIF welches stündlich auslöst.

Mal ein kurzer Auszug aus dem Log

Code Auswählen
2019.04.20 17:00:07 2: ZWave: No ACK from Thermostat_OG_WZ after 5s for sentset:130c03750100258a
2019.04.20 17:00:38 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 17:13:03 2: ZWave: No ACK from KUECHE_ZW_THERMOSTAT after 5s for sentset:131a0326010025a7
2019.04.20 17:16:05 2: ZWDongle_1 transmit NO_ACK for CB ac, target DG_WZ_ZW_FK_L
2019.04.20 17:30:37 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 17:40:37 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 17:50:37 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 18:16:08 2: ZWDongle_1 transmit NO_ACK for CB e3, target DG_WZ_ZW_FK_L
2019.04.20 18:20:37 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 18:22:01 2: ZWave: No ACK from Thermostat_OG_WZ after 5s for sentset:130c0326010025e6
2019.04.20 18:40:37 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 19:12:00 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a0013020326010025fb1e
2019.04.20 19:16:12 2: ZWDongle_1 transmit NO_ACK for CB 02, target DG_WZ_ZW_FK_L
2019.04.20 19:20:38 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 19:32:02 2: ERROR: cannot SEND_DATA to WOH_ZW_THERMOSTAT_OST: transmit queue overflow
2019.04.20 19:32:05 2: ZWave: No ACK from Thermostat1_UG_WZ after 5s for sentset:130b0340011f2525
2019.04.20 19:32:05 2: ERROR: cannot SEND_DATA to Thermostat1_UG_WZ: transmit queue overflow
2019.04.20 19:32:05 2: ZWave: No ACK from WOH_ZW_THERMOSTAT_OST after 5s for set:13100340011f2526
2019.04.20 19:38:15 2: ZWave: No ACK from Thermostat1_UG_WZ after 5s for sentset:130b0340011f252e
2019.04.20 19:43:15 2: ERROR: cannot SEND_DATA to KUECHE_ZW_THERMOSTAT: transmit queue overflow
2019.04.20 19:43:15 2: ZWave: No ACK from Thermostat1_UG_WZ after 5s for sentset:130b0340011f2534
2019.04.20 19:43:15 2: ZWave: No ACK from WOH_ZW_THERMOSTAT_OST after 5s for sentset:13100340011f2535
2019.04.20 19:43:16 2: ZWave: No ACK from KUECHE_ZW_THERMOSTAT after 5s for sentset:131a032601002538
2019.04.20 19:48:16 2: ZWave: No ACK from Thermostat1_UG_WZ after 5s for sentset:130b0340011f253a
2019.04.20 19:50:38 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 19:53:16 2: ZWave: No ACK from Thermostat1_UG_WZ after 5s for sentset:130b0340011f2540
2019.04.20 20:00:38 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 20:10:38 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 20:16:19 2: ZWDongle_1 transmit NO_ACK for CB 4b, target DG_WZ_ZW_FK_L
2019.04.20 20:20:38 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 20:23:19 2: ZWave: No ACK from Thermostat1_UG_WZ after 5s for sentset:130b0340011f254f
2019.04.20 20:30:38 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 20:40:38 1: Steckd_POPP: Error, no send_nonce to decrypt message available

Auch ein List vom USB Dongle

Code Auswählen

Save config
Floorplans
 
   Heizraum
Alarm/Sicherheit
Wohnung
 
   Verkehrsinfo
OG_Wohnzi
Keller
Garten
Wetter
Abfall
Multimedia
TV
AutoShuttersControl
Entwicklung
FRITZBOX
Log_Dateien
OWX
Plots
   

   Server
Steuerung
Unsorted
ZWave
icoEverything Everything
Logfile
Commandref
Remote doc
Edit files
Select style
Event monitor
FHEM_Neustart

Internals:
   CallbackNr 0
   Clients    :ZWave:
   DEF        /dev/serial/by-id/usb-0658_0200-if00@115200
   DeviceName /dev/serial/by-id/usb-0658_0200-if00@115200
   FD         20
   FUUID      5ca34c4e-f33f-cca1-c233-927d5f33c9589e14
   MaxSendRetries 3
   NAME       ZWDongle_1
   NR         341
   PARTIAL   
   RAWMSG     000400140a3202a14a0000012d0000
   ReadTime   1555786095.41315
   STATE      Initialized
   SendRetries 0
   SendTime   1555785796.11013
   TYPE       ZWDongle
   WaitForAck 0
   ZWDongle_1_MSGCNT 5913
   ZWDongle_1_TIME 2019-04-20 20:48:15
   homeId     ceb400ca
   nodeIdHex  01
   nrNAck     0
   showSetInState 1
   MatchList:
     1:ZWave    .*
   READINGS:
     2019-04-19 23:10:26   caps            Vers:1 Rev:1 ManufID:0086 ProductType:0001 ProductID:005a SERIAL_API_GET_INIT_DATA SERIAL_API_APPL_NODE_INFORMATION APPLICATION_COMMAND_HANDLER ZW_GET_CONTROLLER_CAPABILITIES SERIAL_API_SET_TIMEOUTS SERIAL_API_GET_CAPABILITIES SERIAL_API_SOFT_RESET UNKNOWN_09 ZW_SET_R_F_RECEIVE_MODE ZW_SET_SLEEP_MODE ZW_SEND_NODE_INFORMATION ZW_SEND_DATA ZW_SEND_DATA_MULTI ZW_GET_VERSION ZW_SEND_DATA_ABORT ZW_R_F_POWER_LEVEL_SET ZW_SEND_DATA_META ZW_GET_RANDOM MEMORY_GET_ID MEMORY_GET_BYTE MEMORY_PUT_BYTE MEMORY_GET_BUFFER MEMORY_PUT_BUFFER FLASH_AUTO_PROG_SET NVM_GET_ID NVM_EXT_READ_LONG_BUFFER NVM_EXT_WRITE_LONG_BUFFER NVM_EXT_READ_LONG_BYTE NVM_EXT_WRITE_LONG_BYTE ZW_GET_NODE_PROTOCOL_INFO ZW_SET_DEFAULT ZW_REPLICATION_COMMAND_COMPLETE ZW_REPLICATION_SEND_DATA ZW_ASSIGN_RETURN_ROUTE ZW_DELETE_RETURN_ROUTE ZW_REQUEST_NODE_NEIGHBOR_UPDATE ZW_APPLICATION_UPDATE ZW_ADD_NODE_TO_NETWORK ZW_REMOVE_NODE_FROM_NETWORK ZW_CREATE_NEW_PRIMARY ZW_CONTROLLER_CHANGE ZW_SET_LEARN_MODE ZW_ASSIGN_SUC_RETURN_ROUTE ZW_REQUEST_NETWORK_UPDATE ZW_SET_SUC_NODE_ID ZW_DELETE_SUC_RETURN_ROUTE ZW_GET_SUC_NODE_ID ZW_SEND_SUC_ID ZW_EXPLORE_REQUEST_INCLUSION ZW_REQUEST_NODE_INFO ZW_REMOVE_FAILED_NODE_ID ZW_IS_FAILED_NODE ZW_REPLACE_FAILED_NODE UNKNOWN_66 UNKNOWN_67 GET_ROUTING_TABLE_LINE LOCK_ROUTE_RESPONSE ZW_GET_PRIORITY_ROUTE ZW_SET_PRIORITY_ROUTE UNKNOWN_98 ZW_SET_WUT_TIMEOUT ZW_WATCHDOG_ENABLE ZW_WATCHDOG_DISABLE ZW_WATCHDOG_CHECK ZW_SET_EXT_INT_LEVEL ZW_RF_POWERLEVEL_GET ZW_TYPE_LIBRARY ZW_SEND_TEST_FRAME ZW_GET_PROTOCOL_STATUS WATCHDOG_START WATCHDOG_STOP ZW_SET_ROUTING_MAX UNKNOWN_ee UNKNOWN_ef
     2019-04-19 23:10:26   ctrlCaps        PRIMARY SUC
     2019-04-19 23:10:26   homeId          HomeId:ceb400ca CtrlNodeIdHex:01
     2019-04-04 08:22:17   isFailedNode_1  no
     2019-04-08 21:51:11   isFailedNode_14 yes
     2019-04-05 19:16:06   isFailedNode_21 no
     2019-04-05 19:15:47   isFailedNode_23 no
     2019-04-05 19:53:25   isFailedNode_26 no
     2019-04-08 21:37:17   isFailedNode_UNKNOWN_14 no
     2019-04-11 22:08:37   neighborList_1  Thermostat_Buero fk_ost_buero Steckd_POPP Thermostat1_UG_WZ MotionSensor_UG_WZ ZWave_SWITCH_BINARY_15 WOH_ZW_THERMOSTAT_OST SZ_DECKE_ZW_DIM BUE_ZW_STECKDOSE1 ZWave_SWITCH_BINARY_22 KUECHE_ZW_THERMOSTAT WZ_ZW_STECKDOSE1 BUERO_ZW_FK_WEST
     2019-04-05 19:17:41   nodeInfo_1      ProtocolVers:SDK4.5x+6.0x listening routing maxBaud:40kbps Controller SpecificDev BeamCap SpeedExt:100kbps RoleType:N/A BasicDevClass:STATIC_CONTROLLER GenericDevClass:STATIC_CONTROLLER SpecificDevClass:01
     2019-04-08 21:38:20   nodeInfo_14     ProtocolVers:SDK4.5x+6.0x listening routing maxBaud:40kbps SpecificDev RoutingSlave BeamCap OptFunc SpeedExt:100kbps RoleType:N/A BasicDevClass:ROUTING_SLAVE GenericDevClass:SWITCH_BINARY SpecificDevClass:01
     2019-04-03 18:19:54   nodeInfo_23     ProtocolVers:SDK4.5x+6.0x listening routing maxBaud:40kbps SpecificDev RoutingSlave BeamCap OptFunc SpeedExt:100kbps RoleType:N/A BasicDevClass:ROUTING_SLAVE GenericDevClass:SWITCH_BINARY SpecificDevClass:01
     2019-04-03 18:19:40   nodeInfo_24     ProtocolVers:SDK4.5x+6.0x sleeping routing maxBaud:40kbps SpecificDev RoutingSlave BeamCap OptFunc SpeedExt:100kbps RoleType:N/A BasicDevClass:ROUTING_SLAVE GenericDevClass:SENSOR_NOTIFICATION SpecificDevClass:01
     2019-04-03 18:19:23   nodeInfo_25     ProtocolVers:SDK4.5x+6.0x sleeping routing maxBaud:40kbps SpecificDev RoutingSlave BeamCap FrequentListen1000ms OptFunc SpeedExt:100kbps RoleType:N/A BasicDevClass:ROUTING_SLAVE GenericDevClass:THERMOSTAT SpecificDevClass:06
     2019-04-18 21:24:54   nodeList        ZWDongle_1 Thermostat_Buero fk_ost_buero Thermostat_Bad fk_bad Steckd_POPP fk_ug_wz Thermostat1_UG_WZ Thermostat_OG_WZ MotionSensor_UG_WZ WANDSCH_ZW_SWITCH WOH_ZW_THERMOSTAT_OST SZ_DECKE_ZW_DIM ZWave_GENERIC_CONTROLLER_19 BUE_ZW_STECKDOSE1 FLUR_ZW_STECKD ZWave_SWITCH_BINARY_22 BAD_ZW_MOTIONSENSOR KUECHE_ZW_THERMOSTAT WZ_ZW_STECKDOSE1 BUERO_ZW_FK_WEST DG_WZ_ZW_FK_L
     2019-04-19 23:10:26   random          a78cac009c41c5e8f89f4d121e9ed3cd47f691b59f6fe291ac2dadc887f04bb6
     2019-04-18 22:25:19   raw_f2f20100    01f200
     2019-04-18 22:24:41   raw_f3f2        01f303f201ff
     2019-04-18 22:15:46   raw_f3f3        01f312f31029782e2d42deb6b98dbd824c561f88bc
     2019-04-10 21:21:59   routeFor_26     last WZ_ZW_STECKDOSE1 ZWave_SWITCH_BINARY_22 at 40kbps
     2019-04-19 23:11:58   state           Initialized
     2019-04-19 23:10:26   sucNodeId       1
     2019-04-02 16:33:20   version         Z-Wave 4.54 STATIC_CONTROLLER
   SendStack:
Attributes:
   DbLogExclude .*
   group      USB Gerät
   homeId     ceb400ca
   icon       cul_cul
   model      ZWDongle
   networkKey 12345678123456781234567812345678
   room       Steuerung->Interfaces,ZWave
   showSetInState 1
   verbose    3



Habe jetzt leider irgendwie keine Ideen mehr außer im Bios vom Server nachzusehen ob irgendwas
mit USB (xhci) ?? nicht in Ordnung ist.
Nur etwas umständlich jedesmal VGA Monitor anzustöpseln +Tastatur.

USB Verlängerung wurde getauscht (verschiedene)
Anderer USB Dongle Aeotec wird jetzt benutzt
Direkter Betrieb an USB Hub hat nichts genutzt.
Direkter Betrieb an USB Port hat nichts genutzt.
Lageänderung hat nichts genutzt.
In jedem Raum ein bis zwei NetzGeräte sind vorhanden( wie Dimmer,Switch und Steckdosen).

Ein Tip wo ich noch suchen könnte wäre schön.

Ein frohes sorgloses Osterfest wünscht

Hans-Jürgen
FHEM 5.8 auf "yakkaroo Emu A1FL.1" mit CUL 868MHz, SIGNALduino,2 1Wire USB Busmaster, diverse 1 Wire Sensoren,Landroid,Aeotec USB Dongle Z-Wave Plus

Wolfgang Hochweller

#1
21 April 2019, 09:25:32
Diese Meldungen

Code Auswählen

2019.04.20 17:30:37 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 17:40:37 1: Steckd_POPP: Error, no send_nonce to decrypt message available
2019.04.20 17:50:37 1: Steckd_POPP: Error, no send_nonce to decrypt message available

habe ich bekommen, nachdem ich ein Device mit Security inkludiert hatte. ( mit einem Aeotec-Stick )
Die anderen Meldungen ( NO_ACK, etc. ) waren eine Folge davon.

Ich habe das Device dann nochmals ohne Security eingebunden, danach war alles gut.

Vorher habe ich noch probiert, selbiges Geraet mit einem UZB1-Stick und OZW zu inkludieren ( mit und ohne Security kein Problem)

rudolfkoenig

#2
21 April 2019, 09:43:34
Die "ZWDongle" Zeilen im Log sind entweder USB-Problemen, oder einem verwirrten USB-Controller geschuldet.
Die "ZWave" Zeilen sind entweder dem verwirrten Controller, oder Uebertragungsproblemen (Funk) geschuldet.
Meiner Beobachtung nach kann man den Controller mit vielen Auftraegen, die er per Funk nicht los wird (d.h. Bestaetigung der Gegenseite fehlt), verwirren.

Wolfgang Hochweller

#3
21 April 2019, 11:19:16 Letzte Bearbeitung: 22 April 2019, 17:48:42 von howi42
Das denke ich auch.
Allerdings ist da noch mehr, was irgendwie nicht stimmt :
Code Auswählen

2019.04.21 10:20:05 3: ZWave set WolfgangWarn on
2019.04.21 10:20:06 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a001345032601FF251f42
2019.04.21 10:20:08 3: ZWave set WolfgangWarn off
2019.04.21 10:20:36 3: ZWave set WolfgangWarn on
2019.04.21 10:20:37 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a001345032601FF25217c
2019.04.21 10:20:39 3: ZWave set WolfgangWarn off
2019.04.21 10:26:02 3: ZWave set WolfgangWarn on
2019.04.21 10:26:03 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a001345032601FF25237e
2019.04.21 10:26:05 3: ZWave set WolfgangWarn off
2019.04.21 10:28:57 3: ZWave set WolfgangWarn on
2019.04.21 10:28:58 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a001345032601FF252578
2019.04.21 10:29:00 3: ZWave set WolfgangWarn off



und davon jede Menge.
Ausgeloest von diesem Notify :

Code Auswählen

Internals:
   DEF        FlurObenSensor:alarm:.*
{ if ( ReadingsVal("FlurObenSensor","alarm","")  eq  "HomeSecurity: Motion Detection - Unknown Location" )
{ fhem ("set WolfgangWarn on-for-timer 3")};;
}
   FUUID      5c67f5f1-f33f-775d-9003-55a3f79dfff7a1d3
   NAME       n_FlurOben
   NOTIFYDEV  FlurObenSensor
   NR         241
   NTFY_ORDER 50-n_FlurOben
   REGEXP     FlurObenSensor:alarm:.*
   STATE      2019-04-21 11:10:28
   TRIGGERTIME 1555837828.35938
   TYPE       notify
   READINGS:
     2019-04-20 09:21:59   state           active
Attributes:


Der Trigger fuer diese Notify ist ein ZWave-Bewegungsensor.

Es funktioniert zwar alles, aber etwas muss ja nicht ok sein.

Schalte ich diese Lampe in FHEM, ist alles gut.

Erwaehnen kann ich noch, dass dieses Verhalten unabhaengig vom Zielgeraet des Notify ist; inwieweit der Ausloeser verantwortlich ist, muss ich noch testen.

Wolfgang Hochweller

#4
24 April 2019, 12:25:09 Letzte Bearbeitung: 24 April 2019, 12:41:17 von howi42
Hier noch ein Log mit Verbose 5 :

Zweimal wird dieselbe Lampe getriggert,
beim ersten Mal durch einen Schalter ( TKBHome Steckdose ),
beim zweiten Mal durch einen Bewegungssensor ( NeoCoolCam Pir , Beispiel im Thread )

Ersteres geht immer gut,  letzteres triggert immer ein Resend.

Code Auswählen


2019.04.24 12:17:18 4: ZWDongle_Read ZWAVE2: rcvd 00040050063105012200d2 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:17:18 5: SW: 06
2019.04.24 12:17:18 5: ZWAVE2: dispatch 00040050063105012200d2
2019.04.24 12:17:18 4: CMD:APPLICATION_COMMAND_HANDLER ID:50 ARG:063105012200d2 CB:00
2019.04.24 12:17:29 4: ZWDongle_Read ZWAVE2: rcvd 0004004e0a7105000000ff07000108 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:17:29 5: SW: 06
2019.04.24 12:17:29 5: ZWAVE2: dispatch 0004004e0a7105000000ff07000108
2019.04.24 12:17:29 4: CMD:APPLICATION_COMMAND_HANDLER ID:4e ARG:0a7105000000ff07000108 CB:00
2019.04.24 12:17:29 4: ZWDongle_Read ZWAVE2: rcvd 0004004e043003000c (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:17:29 5: SW: 06
2019.04.24 12:17:29 5: ZWAVE2: dispatch 0004004e043003000c
2019.04.24 12:17:29 4: CMD:APPLICATION_COMMAND_HANDLER ID:4e ARG:043003000c CB:00
2019.04.24 12:17:35 4: ZWDongle_Read ZWAVE2: rcvd 0004004d12600d0401320221240000058b000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:17:35 5: SW: 06
2019.04.24 12:17:35 5: ZWAVE2: dispatch 0004004d12600d0401320221240000058b000000000000
2019.04.24 12:17:35 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:12600d0401320221240000058b000000000000 CB:00
2019.04.24 12:17:35 4: ZWDongle_Read ZWAVE2: rcvd 0004004d12600d0401320221240000058b000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:17:35 5: SW: 06
2019.04.24 12:17:35 5: ZWAVE2: dispatch 0004004d12600d0401320221240000058b000000000000
2019.04.24 12:17:35 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:12600d0401320221240000058b000000000000 CB:00
2019.04.24 12:17:35 4: ZWDongle_Read ZWAVE2: rcvd 0004004d12600d0401320221240000058b000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:17:35 5: SW: 06
2019.04.24 12:17:35 5: ZWAVE2: dispatch 0004004d12600d0401320221240000058b000000000000
2019.04.24 12:17:35 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:12600d0401320221240000058b000000000000 CB:00
2019.04.24 12:17:35 4: ZWDongle_Read ZWAVE2: rcvd 0004004d12600d0401320221240000058b000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:17:35 5: SW: 06
2019.04.24 12:17:35 5: ZWAVE2: dispatch 0004004d12600d0401320221240000058b000000000000
2019.04.24 12:17:35 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:12600d0401320221240000058b000000000000 CB:00
2019.04.24 12:17:35 4: ZWDongle_Read ZWAVE2: rcvd 0004004d12600d04013202215400000001000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:17:35 5: SW: 06
2019.04.24 12:17:35 5: ZWAVE2: dispatch 0004004d12600d04013202215400000001000000000000
2019.04.24 12:17:35 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:12600d04013202215400000001000000000000 CB:00
2019.04.24 12:17:35 4: ZWDongle_Read ZWAVE2: rcvd 0004004d0e600d04013202a122098500000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:17:35 5: SW: 06
2019.04.24 12:17:35 5: ZWAVE2: dispatch 0004004d0e600d04013202a122098500000000
2019.04.24 12:17:35 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:0e600d04013202a122098500000000 CB:00
2019.04.24 12:18:12 4: ZWDongle_Read ZWAVE2: rcvd 0004005412600d04013202212400000b3f000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:12 5: SW: 06
2019.04.24 12:18:12 5: ZWAVE2: dispatch 0004005412600d04013202212400000b3f000000000000
2019.04.24 12:18:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:54 ARG:12600d04013202212400000b3f000000000000 CB:00
2019.04.24 12:18:12 4: ZWDongle_Read ZWAVE2: rcvd 0004005412600d04013202215400000001000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:12 5: SW: 06
2019.04.24 12:18:12 5: ZWAVE2: dispatch 0004005412600d04013202215400000001000000000000
2019.04.24 12:18:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:54 ARG:12600d04013202215400000001000000000000 CB:00
2019.04.24 12:18:12 4: ZWDongle_Read ZWAVE2: rcvd 000400540e600d04013202a122098300000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:12 5: SW: 06
2019.04.24 12:18:12 5: ZWAVE2: dispatch 000400540e600d04013202a122098300000000
2019.04.24 12:18:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:54 ARG:0e600d04013202a122098300000000 CB:00
2019.04.24 12:18:12 4: ZWDongle_Read ZWAVE2: rcvd 000400540a600d0201310501220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:12 5: SW: 06
2019.04.24 12:18:12 5: ZWAVE2: dispatch 000400540a600d0201310501220000
2019.04.24 12:18:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:54 ARG:0a600d0201310501220000 CB:00
2019.04.24 12:18:12 4: ZWDongle_Read ZWAVE2: rcvd 000400540a600d0201310501220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:12 5: SW: 06
2019.04.24 12:18:12 5: ZWAVE2: dispatch 000400540a600d0201310501220000
2019.04.24 12:18:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:54 ARG:0a600d0201310501220000 CB:00
2019.04.24 12:18:12 4: ZWDongle_Read ZWAVE2: rcvd 000400540a600d03013105012200e0 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:12 5: SW: 06
2019.04.24 12:18:12 5: ZWAVE2: dispatch 000400540a600d03013105012200e0
2019.04.24 12:18:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:54 ARG:0a600d03013105012200e0 CB:00
2019.04.24 12:18:23 4: ZWDongle_Read ZWAVE2: rcvd 00040050032003ff (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:23 5: SW: 06
2019.04.24 12:18:23 5: ZWAVE2: dispatch 00040050032003ff
2019.04.24 12:18:23 4: CMD:APPLICATION_COMMAND_HANDLER ID:50 ARG:032003ff CB:00
2019.04.24 12:18:24 4: ZWDongle_Read ZWAVE2: rcvd 0004003b123202a1640003b54500000000000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:24 5: SW: 06
2019.04.24 12:18:24 5: ZWAVE2: dispatch 0004003b123202a1640003b54500000000000000000000
2019.04.24 12:18:24 4: CMD:APPLICATION_COMMAND_HANDLER ID:3b ARG:123202a1640003b54500000000000000000000 CB:00
2019.04.24 12:18:24 4: ZWDongle_Read ZWAVE2: rcvd 0004003b123202a16c0000007000000000000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:24 5: SW: 06
2019.04.24 12:18:24 5: ZWAVE2: dispatch 0004003b123202a16c0000007000000000000000000000
2019.04.24 12:18:24 4: CMD:APPLICATION_COMMAND_HANDLER ID:3b ARG:123202a16c0000007000000000000000000000 CB:00
2019.04.24 12:18:27 3: ZWave set ZWEisenbahn on
2019.04.24 12:18:27 5: ZWDongle_Write 001308032501FF2548 (cfba8db6)
2019.04.24 12:18:27 5: SW: 010a001308032501FF25485b
2019.04.24 12:18:27 3: ZWave set WolfgangWarn on
2019.04.24 12:18:27 5: ZWDongle_Write 001345032601FF2549 (cfba8db6)
2019.04.24 12:18:27 5: ACK received, WaitForAck=>2 for 010a001308032501FF25485b
2019.04.24 12:18:27 4: ZWDongle_Read ZWAVE2: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2019.04.24 12:18:27 5: SW: 06
2019.04.24 12:18:27 5: ZWAVE2: dispatch 011301
2019.04.24 12:18:27 4: ZWDongle_Read ZWAVE2: rcvd 001348000003 (request ZW_SEND_DATA), sending ACK
2019.04.24 12:18:27 5: SW: 06
2019.04.24 12:18:27 5: device ack reveived, removing 010a001308032501FF25485b from dongle sendstack
2019.04.24 12:18:27 5: ZWAVE2: dispatch 001348000003
2019.04.24 12:18:27 4: CMD:ZW_SEND_DATA ID:00 ARG:0003 CB:48
2019.04.24 12:18:27 4: ZWAVE2 transmit OK for CB 48, target ZWEisenbahn
2019.04.24 12:18:27 5: SW: 010a001345032601FF254914
2019.04.24 12:18:27 5: ACK received, WaitForAck=>2 for 010a001345032601FF254914
2019.04.24 12:18:27 4: ZWDongle_Read ZWAVE2: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2019.04.24 12:18:27 5: SW: 06
2019.04.24 12:18:27 5: ZWAVE2: dispatch 011301
2019.04.24 12:18:27 4: ZWDongle_Read ZWAVE2: rcvd 001349000002 (request ZW_SEND_DATA), sending ACK
2019.04.24 12:18:27 5: SW: 06
2019.04.24 12:18:27 5: device ack reveived, removing 010a001345032601FF254914 from dongle sendstack
2019.04.24 12:18:27 5: ZWAVE2: dispatch 001349000002
2019.04.24 12:18:27 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:49
2019.04.24 12:18:27 4: ZWAVE2 transmit OK for CB 49, target WolfgangWarn
2019.04.24 12:18:32 4: ZWDongle_Read ZWAVE2: rcvd 0004004d12600d0401320221240000058b000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:32 5: SW: 06
2019.04.24 12:18:32 5: ZWAVE2: dispatch 0004004d12600d0401320221240000058b000000000000
2019.04.24 12:18:32 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:12600d0401320221240000058b000000000000 CB:00
2019.04.24 12:18:32 4: ZWDongle_Read ZWAVE2: rcvd 0004004d12600d04013202215400000008000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:32 5: SW: 06
2019.04.24 12:18:32 5: ZWAVE2: dispatch 0004004d12600d04013202215400000008000000000000
2019.04.24 12:18:32 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:12600d04013202215400000008000000000000 CB:00
2019.04.24 12:18:32 4: ZWDongle_Read ZWAVE2: rcvd 0004004d0e600d04013202a122098700000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:32 5: SW: 06
2019.04.24 12:18:32 5: ZWAVE2: dispatch 0004004d0e600d04013202a122098700000000
2019.04.24 12:18:32 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:0e600d04013202a122098700000000 CB:00
2019.04.24 12:18:32 4: ZWDongle_Read ZWAVE2: rcvd 0004004d0a600d0201310501220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:32 5: SW: 06
2019.04.24 12:18:32 5: ZWAVE2: dispatch 0004004d0a600d0201310501220000
2019.04.24 12:18:32 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:0a600d0201310501220000 CB:00
2019.04.24 12:18:32 4: ZWDongle_Read ZWAVE2: rcvd 0004004d0a600d0201310501220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:32 5: SW: 06
2019.04.24 12:18:32 5: ZWAVE2: dispatch 0004004d0a600d0201310501220000
2019.04.24 12:18:32 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:0a600d0201310501220000 CB:00
2019.04.24 12:18:32 4: ZWDongle_Read ZWAVE2: rcvd 0004004d0a600d03013105012200d4 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:32 5: SW: 06
2019.04.24 12:18:32 5: ZWAVE2: dispatch 0004004d0a600d03013105012200d4
2019.04.24 12:18:32 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:0a600d03013105012200d4 CB:00
2019.04.24 12:18:32 4: ZWDongle_Read ZWAVE2: rcvd 0004004d0a600d03013105012200d4 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:32 5: SW: 06
2019.04.24 12:18:32 5: ZWAVE2: dispatch 0004004d0a600d03013105012200d4
2019.04.24 12:18:32 4: CMD:APPLICATION_COMMAND_HANDLER ID:4d ARG:0a600d03013105012200d4 CB:00
2019.04.24 12:18:32 3: ZWave set WolfgangWarn off
2019.04.24 12:18:32 5: ZWDongle_Write 00134503260100254a (cfba8db6)
2019.04.24 12:18:32 5: SW: 010a00134503260100254ae8
2019.04.24 12:18:32 5: ACK received, WaitForAck=>2 for 010a00134503260100254ae8
2019.04.24 12:18:32 4: ZWDongle_Read ZWAVE2: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2019.04.24 12:18:32 5: SW: 06
2019.04.24 12:18:32 5: ZWAVE2: dispatch 011301
2019.04.24 12:18:32 4: ZWDongle_Read ZWAVE2: rcvd 00134a000002 (request ZW_SEND_DATA), sending ACK
2019.04.24 12:18:32 5: SW: 06
2019.04.24 12:18:32 5: device ack reveived, removing 010a00134503260100254ae8 from dongle sendstack
2019.04.24 12:18:32 5: ZWAVE2: dispatch 00134a000002
2019.04.24 12:18:32 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:4a
2019.04.24 12:18:32 4: ZWAVE2 transmit OK for CB 4a, target WolfgangWarn
2019.04.24 12:18:57 4: ZWDongle_Read ZWAVE2: rcvd 0004004e097105000000ff070800 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:57 5: SW: 06
2019.04.24 12:18:57 5: ZWAVE2: dispatch 0004004e097105000000ff070800
2019.04.24 12:18:57 4: CMD:APPLICATION_COMMAND_HANDLER ID:4e ARG:097105000000ff070800 CB:00
2019.04.24 12:18:57 3: ZWave set WolfgangWarn on
2019.04.24 12:18:57 5: ZWDongle_Write 001345032601FF254b (cfba8db6)
2019.04.24 12:18:57 5: SW: 010a001345032601FF254b16
2019.04.24 12:18:57 4: ZWDongle_Read ZWAVE2: rcvd 0004004e043003ff0c (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:18:57 5: SW: 06
2019.04.24 12:18:57 5: ZWAVE2: dispatch 0004004e043003ff0c
2019.04.24 12:18:57 4: CMD:APPLICATION_COMMAND_HANDLER ID:4e ARG:043003ff0c CB:00
2019.04.24 12:18:57 4: ZWDongle_Read ZWAVE2: CAN received
2019.04.24 12:18:58 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a001345032601FF254b16
2019.04.24 12:18:58 5: SW: 010a001345032601FF254b16
2019.04.24 12:18:58 5: ACK received, WaitForAck=>2 for 010a001345032601FF254b16
2019.04.24 12:18:58 4: ZWDongle_Read ZWAVE2: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2019.04.24 12:18:58 5: SW: 06
2019.04.24 12:18:58 5: ZWAVE2: dispatch 011301
2019.04.24 12:18:58 4: ZWDongle_Read ZWAVE2: rcvd 00134b000002 (request ZW_SEND_DATA), sending ACK
2019.04.24 12:18:58 5: SW: 06
2019.04.24 12:18:58 5: device ack reveived, removing 010a001345032601FF254b16 from dongle sendstack
2019.04.24 12:18:58 5: ZWAVE2: dispatch 00134b000002
2019.04.24 12:18:58 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:4b
2019.04.24 12:18:58 4: ZWAVE2 transmit OK for CB 4b, target WolfgangWarn
2019.04.24 12:19:00 3: ZWave set WolfgangWarn off
2019.04.24 12:19:00 5: ZWDongle_Write 00134503260100254c (cfba8db6)
2019.04.24 12:19:00 5: SW: 010a00134503260100254cee
2019.04.24 12:19:00 5: ACK received, WaitForAck=>2 for 010a00134503260100254cee
2019.04.24 12:19:00 4: ZWDongle_Read ZWAVE2: rcvd 011301 (answer ZW_SEND_DATA), sending ACK
2019.04.24 12:19:00 5: SW: 06
2019.04.24 12:19:00 5: ZWAVE2: dispatch 011301
2019.04.24 12:19:00 4: ZWDongle_Read ZWAVE2: rcvd 00134c000002 (request ZW_SEND_DATA), sending ACK
2019.04.24 12:19:00 5: SW: 06
2019.04.24 12:19:00 5: device ack reveived, removing 010a00134503260100254cee from dongle sendstack
2019.04.24 12:19:00 5: ZWAVE2: dispatch 00134c000002
2019.04.24 12:19:00 4: CMD:ZW_SEND_DATA ID:00 ARG:0002 CB:4c
2019.04.24 12:19:00 4: ZWAVE2 transmit OK for CB 4c, target WolfgangWarn
2019.04.24 12:19:12 4: ZWDongle_Read ZWAVE2: rcvd 0004005412600d04013202212400000b3f000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:19:12 5: SW: 06
2019.04.24 12:19:12 5: ZWAVE2: dispatch 0004005412600d04013202212400000b3f000000000000
2019.04.24 12:19:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:54 ARG:12600d04013202212400000b3f000000000000 CB:00
2019.04.24 12:19:12 4: ZWDongle_Read ZWAVE2: rcvd 0004005412600d04013202215400000000000000000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:19:12 5: SW: 06
2019.04.24 12:19:12 5: ZWAVE2: dispatch 0004005412600d04013202215400000000000000000000
2019.04.24 12:19:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:54 ARG:12600d04013202215400000000000000000000 CB:00
2019.04.24 12:19:12 4: ZWDongle_Read ZWAVE2: rcvd 000400540e600d04013202a122098400000000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:19:12 5: SW: 06
2019.04.24 12:19:12 5: ZWAVE2: dispatch 000400540e600d04013202a122098400000000
2019.04.24 12:19:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:54 ARG:0e600d04013202a122098400000000 CB:00
2019.04.24 12:19:12 4: ZWDongle_Read ZWAVE2: rcvd 000400540a600d0201310501220000 (request APPLICATION_COMMAND_HANDLER), sending ACK
2019.04.24 12:19:12 5: SW: 06
2019.04.24 12:19:12 5: ZWAVE2: dispatch 000400540a600d0201310501220000
2019.04.24 12:19:12 4: CMD:APPLICATION_COMMAND_HANDLER ID:54 ARG:0a600d0201310501220000 CB:00



Was sagt mir das hier :

Code Auswählen

2019.04.24 12:18:57 4: ZWDongle_Read ZWAVE2: CAN received

krikan

#5
24 April 2019, 15:49:47
CAN = Nachricht/Befehl konnte vom Dongle nicht angenommen werden, da andere Nachricht sich vorgedrängelt hat. Eine der diversen PIR Nachrichten kommt gleichzeitig mit set-Befehl. Nachrichten des Pirs reduzieren, in notify minimale Verzögerung einbauen oder erst auf letzte Nachricht des Pir notify triggern oder akzeptieren oder...

Gruß, Christian

Wolfgang Hochweller

#6
24 April 2019, 17:25:19
Danke.
Verzoegerung macht es nicht besser, eher schlimmer.
Manchmal ist das Resend jetzt weg, manchmal wird der 'on' Befehl zweimal gesendet,
manchmal braucht jetzt auch das 'off' vom Timer ein Resend.

Wobei des mehrfache Senden eines Befehls nichts Neues ist, das kommt auch sonst immer wieder mal vor.
Alle involvierten Devices liegen in Sichtweite des Dongles.


Muss ich was anderes versuchen.

krikan

#7
24 April 2019, 19:53:43 Letzte Bearbeitung: 24 April 2019, 21:24:37 von krikan
Zitat von: howi42 am 24 April 2019, 17:25:19
Wobei des mehrfache Senden eines Befehls nichts Neues ist, das kommt auch sonst immer wieder mal vor.
Mehrfaches Senden ist überhaupt nicht tragisch, solange keine Verluste auftreten.

ZitatAlle involvierten Devices liegen in Sichtweite des Dongles.
Halte ich für irrelevant.

Ein nicht so "salopper" Erklaerungsversuch der CAN-Thematik:
Der ZWave-Controller (Dongle) kann grundsaetzlich zu einem Zeitpunkt nur eine Nachricht und nicht mehrere gleichzeitig verarbeiten. Nachrichten können von 2 Seiten kommen: Einmal per Funk aus dem ZWave-Netz an den Controller oder von der Software (FHEM,..) an dem Controller. Wenn der Controller mit einer empfangenen Nachricht (hier PIR) beschaeftigt ist, dann werden Befehle von FHEM nicht angenommmen; dies wird per CAN(cel) mitgeteilt. FHEM ist damit informiert, dass es die betreffende Nachricht nocheinmal an den Controller schicken muss.

Wenn ein ZWave-Geraete, wie der PIR, viele verschiedene Nachrichten an den Controller schickt, dann ist die Gefahr eines CAN groß, wenn ein Befehl von FHEM durch ein notify mit trigger PIR-Event ohne Verzögerung verschickt wird. Man kann diese CANs dann ignorieren, wenn man mit Wiederholungen leben kann und der  Befehl im Fazit verschick wird. Ansonsten eben analysieren, wie/wann man den Befehl abschicken muss, damit CANs (moeglichst) vermieden werden.

Wolfgang Hochweller

#8
24 April 2019, 23:07:15
Danke, das ist ok.
Das mehrfache Senden ist sicher nicht direkt tragisch, kann aber durchaus ungewuenschte Folgen habe, etwa wiederholtes triggern, etc.
Was mich mehr stoert, ist, dass es offenbar keinen sichtbaren Grund dafuer gibt, der Befehl ist beim ersten Mal schon angekommen.

Das zweite verunsichert mich aber noch viel mehr ....
Ich habe doch letztlich gar keinen Einfluss darauf, wann wieviele Meldungen verschickt werden; ich sehe auch nur wenig Erfolgsaussichten, wuerde ich ernsthaft versuchen, auf das Timing Einfluss zu nehmen.
Bei der naechsten Aenderung des Netzes kann ich dann gleich von vorne anfangen.
Ich denke, da nehme ich lieber die Logeintraege in Kauf; es scheint ja am Ende alles anzukommen :-)

krikan

#9
25 April 2019, 08:58:49
Zitat von: howi42 am 24 April 2019, 23:07:15
Danke, das ist ok.
Das mehrfache Senden ist sicher nicht direkt tragisch, kann aber durchaus ungewuenschte Folgen habe, etwa wiederholtes triggern, etc.
Was mich mehr stoert, ist, dass es offenbar keinen sichtbaren Grund dafuer gibt, der Befehl ist beim ersten Mal schon angekommen.
Bei CAN(cel) dürfte der Befehl beim ersten Mal nicht verschickt worden sein. Er wurde schließlich vom Controller abgelehnt. Triggern dürfte auch nichts mehrfach. Etwas anderes sehe ich in Deinem Logauszug nicht und würde mich wundern.

ZitatDas zweite verunsichert mich aber noch viel mehr ....
Ich habe doch letztlich gar keinen Einfluss darauf, wann wieviele Meldungen verschickt werden; ich sehe auch nur wenig Erfolgsaussichten, wuerde ich ernsthaft versuchen, auf das Timing Einfluss zu nehmen.
Bei der naechsten Aenderung des Netzes kann ich dann gleich von vorne anfangen.
Ich denke, da nehme ich lieber die Logeintraege in Kauf; es scheint ja am Ende alles anzukommen :-)
Ja, es könnte ein Wettlauf werden. Darum eben nicht zu viel Energie hineinstecken, wenn es im Fazit mit CAN/resending funktioniert.
Wiederholung sind nicht grundsätzlich schlecht.

Die ZWDongle-Logeinträge von @Deckoffizier sind hingegen anders gelagert; Hinweise zur Ursache sieht man sinnvoll erst im verbose 5-Log.
Code Auswählen
2019.04.20 20:16:19 2: ZWDongle_1 transmit NO_ACK for CB 4b, target DG_WZ_ZW_FK_L
Getippt: hier wurde eine Nachricht an DG_WZ_ZW_FK_L vom Controller bereits erfolgreich verschickt. DG_WZ_ZW_FK_L hat den Erhalt der Nachricht aber nicht bestätigt bzw. Controller hat Bestätigung nicht erhalten. Wenn es keine selbstausgelöste Nachricht ist, könnte es sich um die automatisch von FHEM abgesetzte Nachricht wakeupNoMoreInformation nach einer wakeUpNotification handeln. Folgewirkungen: ?. Könnte, könnte, ...  :)

Code Auswählen
2019.04.20 19:43:15 2: ERROR: cannot SEND_DATA to KUECHE_ZW_THERMOSTAT: transmit queue overflow
Das ist eine der "folgenreichsten" Meldungen. Der Controller ist verwirrt und es kommt sicherlich zu Telegrammverlusten. Typischerweise zu viel gleichzeitige Befehle an Controller.

Gruß, Christian

Deckoffizier

#10
25 April 2019, 19:09:42
Hallo,

klinke mich noch mal ein bei der unendlichen Geschichte mit den Logeinträgen......

Habe von Ostersonntag Mittag bis Ostermontag Morgen um halb 3 alle Geräte excludiert den Controller (AoetecDongle resettet) und alles
neu includiert. Sowie überall die neue HomeID nachgetragen wo nötig.
Irgendwo wird meine Schmerzgrenze auch langsam erreicht.

Läuft zwar schon etwas besser aber heute um ca.15.35 wird es auch wieder heftig.

anbei mal das Log von Heute

Code Auswählen
2019.04.25 00:00:01 2: Deleting fhem-2019-04-24.log
2019.04.25 01:00:00 1: SQL Datenbank geleert
2019.04.25 03:11:34 1: OWXTHERM_BinValues:  Abgassensor: invalid CRC,  -0.0625  0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff
2019.04.25 03:40:31 2: ZWave: No ACK from WOH_ZW_THERMOSTAT_OST after 5s for sentset:13070340011f2578
2019.04.25 07:00:04 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a0013070340011f25d64f
2019.04.25 07:00:05 2: ZWave: No ACK from WOH_ZW_THERMOSTAT_OST after 5s for sentset:13070340011f25d6
2019.04.25 07:41:41 2: ZWave: No ACK from KUECHE_ZW_THERMOSTAT after 5s for sentset:13120326010025e6
2019.04.25 15:35:57 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00131003250100258377
2019.04.25 15:39:09 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130f03750102259029
2019.04.25 16:08:10 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c03700510259c35
2019.04.25 16:08:11 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c03700528259e0f
2019.04.25 16:08:12 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370050625a01f
2019.04.25 16:08:12 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370050225a11a
2019.04.25 16:08:13 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370050925a212
2019.04.25 16:08:14 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370050125a51d
2019.04.25 16:08:15 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370051c25a702
2019.04.25 16:08:16 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370051625a906
2019.04.25 16:08:18 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370051825ab0a
2019.04.25 16:08:19 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370053e25ad2a
2019.04.25 16:08:20 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370054025af56
2019.04.25 16:08:21 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370055125b159
2019.04.25 16:08:21 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370055325b359
2019.04.25 16:08:23 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370055025b45d
2019.04.25 16:08:25 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370055625b659
2019.04.25 16:08:26 2: ZWDongle_ProcessSendStack: no ACK, resending message 010900130c02840825baf8
2019.04.25 17:36:55 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a0013050375010025f948
2019.04.25 17:37:05 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130203750100250bbd
2019.04.25 17:37:29 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a001310032501002516e2


Setze auch schon auf den Kommissar Zufall, das einzig Gute es funktioniert erstmal soweit.
Thema Timing... habe in den DOIFs für die Heizungssteuerung schon extra jede menge wait eingebaut um Pausen zwischen den Befehlen an den Spirit Thermostaten zu haben,
Ebenso in Heating Control ein Zeitversatz für die Auslösung also alle nicht gleichzeitig um z.B. 7 Uhr auslösen lassen.
Achso, habe alles mit addNode onNw includiert, ca. 25 Geräte mit in jedem Raum ein oder 2 Netzgeräte.

Bin weiterhin gespannt und hartleibig  ;D

Gruß
Hans-Jürgen
FHEM 5.8 auf "yakkaroo Emu A1FL.1" mit CUL 868MHz, SIGNALduino,2 1Wire USB Busmaster, diverse 1 Wire Sensoren,Landroid,Aeotec USB Dongle Z-Wave Plus

krikan

#11
26 April 2019, 07:46:04
Code Auswählen
2019.04.25 00:00:01 2: Deleting fhem-2019-04-24.log
2019.04.25 01:00:00 1: SQL Datenbank geleert
2019.04.25 03:11:34 1: OWXTHERM_BinValues:  Abgassensor: invalid CRC,  -0.0625  0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff
2019.04.25 03:40:31 2: ZWave: No ACK from WOH_ZW_THERMOSTAT_OST after 5s for sentset:13070340011f2578
2019.04.25 07:00:04 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a0013070340011f25d64f
2019.04.25 07:00:05 2: ZWave: No ACK from WOH_ZW_THERMOSTAT_OST after 5s for sentset:13070340011f25d6
2019.04.25 07:41:41 2: ZWave: No ACK from KUECHE_ZW_THERMOSTAT after 5s for sentset:13120326010025e6
2019.04.25 15:35:57 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00131003250100258377
2019.04.25 15:39:09 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130f03750102259029
2019.04.25 16:08:10 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c03700510259c35
2019.04.25 16:08:11 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c03700528259e0f
2019.04.25 16:08:12 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370050625a01f
2019.04.25 16:08:12 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370050225a11a
2019.04.25 16:08:13 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370050925a212
2019.04.25 16:08:14 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370050125a51d
2019.04.25 16:08:15 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370051c25a702
2019.04.25 16:08:16 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370051625a906
2019.04.25 16:08:18 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370051825ab0a
2019.04.25 16:08:19 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370053e25ad2a
2019.04.25 16:08:20 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370054025af56
2019.04.25 16:08:21 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370055125b159
2019.04.25 16:08:21 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370055325b359
2019.04.25 16:08:23 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370055025b45d
2019.04.25 16:08:25 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130c0370055625b659
2019.04.25 16:08:26 2: ZWDongle_ProcessSendStack: no ACK, resending message 010900130c02840825baf8
2019.04.25 17:36:55 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a0013050375010025f948
2019.04.25 17:37:05 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a00130203750100250bbd
2019.04.25 17:37:29 2: ZWDongle_ProcessSendStack: no ACK, resending message 010a001310032501002516e2

Bitte schaue nach von welchem Node/Gerät diese Meldungen kommen; Info zur Geräteermittlung: https://wiki.fhem.de/wiki/Z-Wave#Wie_ist_der_Aufbau_der_Z-Wave_Messages_bzw._wie_finde_ich_bei_einem_Sendefehler_die_NodeId_des_Empf.C3.A4ngers_heraus.3F .
Für eine Analyse möglicher Ursachen brauchst Du aber verbose 5 Logs. Diese verbose 3 Logs sind relativ nichtssagend.
Analyse bringt aber nur dann etwas, wenn der Controller eine stabile USB-Verbindung hat; insbesondere bei Raspi besser keine Hubs, stabiles Netzteil,..

Exklusion, Controllerreset und Inklusion hätte ich aus Faulheit und mangels Überzeugung nicht gemacht; schadet aber nicht. Der im Thread angeführte "verwirrter Controller" lässt sich mEn durch bessere Logik, Ab- und Anstecken oder Neustart von FHEM "entwirren".

Gruß, Christian

Wolfgang Hochweller

#12
26 April 2019, 10:31:02
Das mit dem Triggern bei doppelten Kommandos war eher akademisch, obwohl es mir schon untergekommen ist.
Mein Garagentor etwa wird ueber einen Pushbutton betaetigt, also einmal druecken, Tor geht auf, nochmal druecken, Tor bleibt stehen, wieder druecken, Tor geht wieder zu, usw.
Dem Tor selbst ist das egal, aber in FHEM musste ich mir dann was ueberlegen.

Deckoffizier

#13
26 April 2019, 15:29:12

Hallo Christian,

Danke für Deine Hilfestellung, Dein Link aus dem Forum hatte ich schon bei meiner
ständigen Suche gefunden und soweit interpretiert hinter der 13 steht die Node(Geräte)
Nummer.
Hatte dabei an den Geräten nichts auffälliges finden können.

Habe das Log vom Dongle auf verbose 5 umgestellt, Frage:

wie soll man bei den    1000   Einträgen in 2 bis 3 Stunden den relevanten Eintrag raus fischen den man
unter verbose 3 gleich sieht?

Also drehe mich leider immer noch im Kreis.
Zum Bios nachsehen ob eventuell Einstellung auf USB2.0 möglich und was
bringt bin ich leider noch nicht gekommen.
Irgendwie gefühlt scheinen die Spirit am meisten beteiligt zu sein.


Gruß
Hans-Jürgen

FHEM 5.8 auf "yakkaroo Emu A1FL.1" mit CUL 868MHz, SIGNALduino,2 1Wire USB Busmaster, diverse 1 Wire Sensoren,Landroid,Aeotec USB Dongle Z-Wave Plus

krikan

#14
26 April 2019, 15:56:46
Zitatwie soll man bei den    1000   Einträgen in 2 bis 3 Stunden den relevanten Eintrag raus fischen den man
unter verbose 3 gleich sieht?
Bspw. Logfile mit Editor Deines Vertrauens (bei mir unter Win Notepad++) oder per Kommandozeilenwerkzeug nach den Fehlertexten "no ACK" oder "resending" usw. durchsuchen und dann näher schauen. Habe auch kein Problem damit, wenn Du das hier im Thread gezippt anhängst. Wenn ich Zeit habe, schaue ich rein und gebe Hypothesen ab.  :)

ZitatIrgendwie gefühlt scheinen die Spirit am meisten beteiligt zu sein.
Gut möglich, da Telegrammlaufzeiten bei FLIRS eben sehr lang. Damit ist der Controller relativ lange "blockiert".

Setze Dir bitte (außer aus sportlichem Antrieb) nicht das Ziel alle Logmeldungen, die auf Störungen hinweisen zu beseitigen. Das ist vermutlich nicht leistbar. Nur den dauernden Problemmeldungen und Telegrammverlusten, die zu Komfortproblemen führen, würde ich nachgehen. Funk ist unvollkommen und nicht jede Logmeldung, die sich nach Fehler liest ist ein wirkliches Problem. Die resending-Versuche sind aber so geballt "seltsam".

Gruß, Christian
Drucken
Nach oben Seiten1
Benutzer-Aktionen