Sehr lange Antwortzeit bei "set update", Gerätekommunikation gestört HMCCU 5.0

Begonnen von Rewe2000, 22 Dezember 2021, 16:51:14

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

Rewe2000

22 Dezember 2021, 16:51:14
Hallo,

mir ist noch etwas aufgefallen und wollte hier mal nachfragen wo ihr die Ursache vermutet.
Wenn ich z.B. bei einem Fensterkontakt HMIP-SWDO "set <Device> clear" und dann "get <Device> update" ausführe, gefriert Fhem für ca. 50-60 Sekunden ein.
Das entsprechende Device wird dann als "activity = dead" angezeigt, so lange bis dieses wieder sendet.
Mein Fhem auf Raspi3 läuft soweit ohne Probleme, auch alle HmIP und HM Geräte arbeiten so wie erwartet. Das geschilderte Verhalten war definitiv bei mir unter HMCCU 4.3 nicht zu beobachten.
Das HMCCU Update von 4.3 auf 5.0 habe ich durch Löschen aller Device und Neuanlegen über "createDev" durchgeführt.
Meine Homematic Geräte werden über eine "CCU3 - Rapberrymatic" verwaltet.

Im Log finde ich mit "verbose 2" folgende Meldungen.
Code Auswählen
2021.12.22 16:09:32 1: HMCCURPCPROC [d_rpc050032HmIP_RF] Error in request getParamset 0000D3C995FFDC SERVICE:
2021.12.22 16:09:32 2: HMCCUCHN [EG_FKE1_Arbeitszimmer] Can't get parameterset SERVICE for address 0000D3C995FFDC
2021.12.22 16:09:52 1: HMCCURPCPROC [d_rpc050032HmIP_RF] Error in request getParamset 0000D3C995FFDC:0 SERVICE: Generic error (TRANSACTION_DISCARDED_FOR_UNREACHABLE_DEVICE)
2021.12.22 16:09:52 2: HMCCUCHN [EG_FKE1_Arbeitszimmer] Can't get parameterset SERVICE for address 0000D3C995FFDC:0
2021.12.22 16:10:12 1: HMCCURPCPROC [d_rpc050032HmIP_RF] Error in request getParamset 0000D3C995FFDC:1 SERVICE:
2021.12.22 16:10:12 2: HMCCUCHN [EG_FKE1_Arbeitszimmer] Can't get parameterset SERVICE for address 0000D3C995FFDC:1
2021.12.22 16:10:12 1: Perfmon: possible freeze starting at 16:09:13, delay is 59.943

Das list vom Fensterkontakt:
Code Auswählen
Internals:
   DEF        0000D3C995FFDC:1
   FUUID      61b488ff-f33f-7df9-9420-78895b3e2881f85b
   IODev      CCU2
   NAME       EG_FKE1_Arbeitszimmer
   NR         397
   STATE      closed
   TYPE       HMCCUCHN
   ccuaddr    0000D3C995FFDC:1
   ccudevstate active
   ccuif      HmIP-RF
   ccuname    EG_FKE1_Arbeitszimmer:1
   ccurolestate SHUTTER_CONTACT
   ccusubtype SWD
   ccutype    HMIP-SWDO
   firmware   1.16.8
   readonly   no
   OLDREADINGS:
   READINGS:
     2021-12-22 16:10:32   Batteriezustand 100
     2021-12-22 16:10:32   Devicename      EG Arbeitszimmer - Fensterkontakt Einbruch
     2021-12-22 16:10:12   STATE           closed
     2021-12-22 16:10:32   activity        dead
     2021-12-22 16:10:32   battery         ok
     2021-12-22 16:10:32   devstate        ok
     2021-12-22 16:10:32   hmstate         unreachable
     2021-12-22 16:10:32   rssidevice      -81
     2021-12-22 16:10:32   sabotage        false
     2021-12-22 16:10:12   state           closed
     2021-12-22 16:10:32   voltage         1.4
   hmccu:
     channels   1
     detect     1
     devspec    0000D3C995FFDC:1
     nodefaults 1
     role       1:SHUTTER_CONTACT
     setDefaults 0
     cmdlist:
       get       
       set       
     control:
     dp:
       0.ARR_TIMEOUT:
         MASTER:
           NVAL       10
           ONVAL      10
           OSVAL      10
           OVAL       10
           SVAL       10
           VAL        10
         VALUES:
       0.CONFIG_PENDING:
         VALUES:
           NVAL       0
           ONVAL      0
           OSVAL      false
           OVAL       0
           SVAL       false
           VAL        0
       0.CYCLIC_INFO_MSG:
         MASTER:
           NVAL       1
           ONVAL      1
           OSVAL      1
           OVAL       1
           SVAL       1
           VAL        1
         VALUES:
       0.CYCLIC_INFO_MSG_DIS:
         MASTER:
           NVAL       20
           ONVAL      20
           OSVAL      20
           OVAL       20
           SVAL       20
           VAL        20
         VALUES:
       0.CYCLIC_INFO_MSG_DIS_UNCHANGED:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       0.CYCLIC_INFO_MSG_OVERDUE_THRESHOLD:
         MASTER:
           NVAL       2
           ONVAL      2
           OSVAL      2
           OVAL       2
           SVAL       2
           VAL        2
         VALUES:
       0.DISABLE_MSG_TO_AC:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       0.DUTYCYCLE_LIMIT:
         MASTER:
           NVAL       180
           ONVAL      180
           OSVAL      180
           OVAL       180
           SVAL       180
           VAL        180
         VALUES:
       0.DUTY_CYCLE:
         VALUES:
           NVAL       0
           ONVAL      0
           OSVAL      false
           OVAL       0
           SVAL       false
           VAL        0
       0.ENABLE_ROUTING:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       0.ERROR_CODE:
         VALUES:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
       0.LOCAL_RESET_DISABLED:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       0.LOW_BAT:
         VALUES:
           NVAL       0
           ONVAL      0
           OSVAL      ok
           OVAL       0
           SVAL       ok
           VAL        0
       0.LOW_BAT_LIMIT:
         MASTER:
           NVAL       1.1
           ONVAL      1.1
           OSVAL      1.1
           OVAL       1.1
           SVAL       1.1
           VAL        1.1
         VALUES:
       0.OPERATING_VOLTAGE:
         VALUES:
           NVAL       1.4
           ONVAL      1.4
           OSVAL      1.4
           OVAL       1.4
           SVAL       1.4
           VAL        1.4
       0.OPERATING_VOLTAGE_STATUS:
         VALUES:
           NVAL       0
           ONVAL      0
           OSVAL      NORMAL
           OVAL       0
           SVAL       NORMAL
           VAL        0
       0.RSSI_DEVICE:
         VALUES:
           NVAL       -81
           ONVAL      -81
           OSVAL      -81
           OVAL       -81
           SVAL       -81
           VAL        -81
       0.SABOTAGE:
         VALUES:
           NVAL       0
           ONVAL      0
           OSVAL      false
           OVAL       0
           SVAL       false
           VAL        0
       0.UNREACH:
         VALUES:
           NVAL       1
           ONVAL      1
           OSVAL      dead
           OVAL       1
           SVAL       dead
           VAL        1
       0.UPDATE_PENDING:
         VALUES:
           NVAL       0
           ONVAL      0
           OSVAL      false
           OVAL       0
           SVAL       false
           VAL        0
       1.ALARM_MODE_TYPE:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       1.ALARM_MODE_ZONE_1:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       1.ALARM_MODE_ZONE_2:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       1.ALARM_MODE_ZONE_3:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       1.ALARM_MODE_ZONE_4:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       1.ALARM_MODE_ZONE_5:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       1.ALARM_MODE_ZONE_6:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       1.ALARM_MODE_ZONE_7:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       1.EVENT_DELAY_UNIT:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       1.EVENT_DELAY_VALUE:
         MASTER:
           NVAL       0
           ONVAL      0
           OSVAL      0
           OVAL       0
           SVAL       0
           VAL        0
         VALUES:
       1.MSG_FOR_POS_A:
         MASTER:
           NVAL       2
           ONVAL      2
           OSVAL      2
           OVAL       2
           SVAL       2
           VAL        2
         VALUES:
       1.MSG_FOR_POS_B:
         MASTER:
           NVAL       1
           ONVAL      1
           OSVAL      1
           OVAL       1
           SVAL       1
           VAL        1
         VALUES:
       1.SAMPLE_INTERVAL:
         MASTER:
           NVAL       0.5
           ONVAL      0.5
           OSVAL      0.5
           OVAL       0.5
           SVAL       0.5
           VAL        0.5
         VALUES:
       1.STATE:
         VALUES:
           NVAL       0
           ONVAL      0
           OSVAL      closed
           OVAL       0
           SVAL       closed
           VAL        0
     roleCmds:
       get:
       set:
     state:
       chn        1
       dpt        STATE
Attributes:
   DbLogExclude .*
   devStateIcon closed:fts_window_1w open:fts_window_1w_tilt
   event-on-change-reading state
   group      Batterieanzeige_Spannung,HmIP-Device,Sicherheit
   icon       fts_window_1w_tilt
   room       EG_Arbeitszimmer,Einbruchschutz,Homematic
   userReadings Batteriezustand {
return 00 if(ReadingsNum($name,"voltage",0) < 1.1 );
return 25 if(ReadingsNum($name,"voltage",0) < 1.2 );
return 50 if(ReadingsNum($name,"voltage",0) < 1.3 );
return 75 if(ReadingsNum($name,"voltage",0) < 1.4 );
return 100 },
Devicename {return 'EG Arbeitszimmer - Fensterkontakt Einbruch'}

Das Device HMCCU als RAW definition:
Code Auswählen
defmod CCU2 HMCCU 192.168.50.32 waitforccu=120
attr CCU2 DbLogExclude .*
attr CCU2 ccuReqTimeout 8
attr CCU2 ccuaggregate name:HmIP_battery_,filter:group=HmIP-Device,read:(battery),if:any=low,else:ok,prefix=HmIP_battery_,coll:alias;;\
name:HM_battery_,filter:group=HM-Device,read:(battery),if:any=low,else:ok,prefix=HM_battery_,coll:alias;;\
name:DutyCycle_,filter:group=HmIP-Device,read:(DUTY_CYCLE),if:any=(1|true),else:(0|false),prefix=DutyCycle_,coll:alias\
name:HmIP_Unreach_,filter:group=HmIP-Device,read:(activity),if:any=(1|true|dead),else:(0|false|alive),prefix=HmIP_Unreach_,coll:alias\
name:HM_Unreach_,filter:group=HM-Device,read:(activity),if:any=(1|true|dead),else:(0|false|alive),prefix=HM_Unreach_,coll:alias
attr CCU2 ccuflags procrpc,noInitialUpdate,nonBlocking,reconnect
attr CCU2 cmdIcon on:general_an off:general_aus
attr CCU2 group Hardware
attr CCU2 icon hm_ccu
attr CCU2 room Homematic
attr CCU2 rpcinterfaces BidCos-RF,HmIP-RF,VirtualDevices
attr CCU2 rpcserver on
attr CCU2 stateFormat rpcstate/state
attr CCU2 verbose 2

setstate CCU2 running/OK
setstate CCU2 2021-12-22 16:36:10 DutyCycle_count 65
setstate CCU2 2021-12-22 16:36:10 DutyCycle_list no match
setstate CCU2 2021-12-22 16:36:10 DutyCycle_match 0
setstate CCU2 2021-12-22 16:36:10 DutyCycle_state (0|false)
setstate CCU2 2021-12-22 16:30:00 HM_Unreach_count 6
setstate CCU2 2021-12-22 16:30:00 HM_Unreach_list no match
setstate CCU2 2021-12-22 16:30:00 HM_Unreach_match 0
setstate CCU2 2021-12-22 16:30:00 HM_Unreach_state (0|false|alive)
setstate CCU2 2021-12-22 16:30:00 HM_battery_count 6
setstate CCU2 2021-12-22 16:30:00 HM_battery_list no match
setstate CCU2 2021-12-22 16:30:00 HM_battery_match 0
setstate CCU2 2021-12-22 16:30:00 HM_battery_state ok
setstate CCU2 2021-12-22 16:30:00 HmIP_Unreach_count 65
setstate CCU2 2021-12-22 16:30:00 HmIP_Unreach_list EG_FKE1_Arbeitszimmer
setstate CCU2 2021-12-22 16:30:00 HmIP_Unreach_match 1
setstate CCU2 2021-12-22 16:30:00 HmIP_Unreach_state (1|true|dead)
setstate CCU2 2021-12-22 16:30:00 HmIP_battery_count 65
setstate CCU2 2021-12-22 16:30:00 HmIP_battery_list no match
setstate CCU2 2021-12-22 16:30:00 HmIP_battery_match 0
setstate CCU2 2021-12-22 16:30:00 HmIP_battery_state ok
setstate CCU2 2021-12-22 16:35:37 Status_Watchdog 0
setstate CCU2 2021-12-21 17:26:32 count_channels 490
setstate CCU2 2021-12-21 17:26:32 count_devices 76
setstate CCU2 2021-12-21 17:26:32 count_groups 6
setstate CCU2 2021-12-21 17:26:32 count_interfaces 3
setstate CCU2 2021-12-21 17:26:32 count_programs 26
setstate CCU2 2021-12-22 16:31:38 iface_addr_1 PEQ1947473
setstate CCU2 2021-12-22 16:31:38 iface_addr_2 3014F711A0001F58A9A71F51
setstate CCU2 2021-12-22 16:31:38 iface_conn_1 1
setstate CCU2 2021-12-22 16:31:38 iface_conn_2 1
setstate CCU2 2021-12-22 16:31:38 iface_ducy_1 70
setstate CCU2 2021-12-22 16:31:38 iface_ducy_2 68
setstate CCU2 2021-12-22 16:31:38 iface_type_1 CCU2
setstate CCU2 2021-12-22 16:31:38 iface_type_2 HMIP_CCU2
setstate CCU2 2021-12-21 17:27:42 rpcstate running
setstate CCU2 2021-12-21 17:27:42 state OK



Wenn ich mehr Infos liefern soll, gerne.
Gruß Reinhard
Fhem 6.3 auf Raspberry Pi4 SSD mit Raspbian Bookworm, Homematic, Homematic IP, CCU3 mit RapberryMatic, WAGO 750-880, E3DC S10E Hauskraftwerk, E3DC Wallbox, my-PV AC ELWA-E Heizstab, Fritz!Box 7590, KIA Bluelinky

zap

#1
22 Dezember 2021, 18:51:41
Das liegt vermutlich daran, dass in der Parameterset-Definition in der CCU ein Parameterset "SERVICE" definiert ist, das in Wirklichkeit nicht existiert. Dann laufen die RPC-Requests in einen Timeout, daher die Verzögerung.

Versuche es mal mit "get values" und "get config". Die beiden Befehle lesen nur die Parametersets "VALUES" (Datenpunkte) und "MASTER" (Config-Parameter).

2xCCU3 mit ca. 100 Aktoren, Sensoren
Entwicklung: FHEM auf Proxmox Debian VM
Produktiv inzwischen auf Home Assistant gewechselt.
Maintainer: HMCCU, (Fully, AndroidDB)

Rewe2000

#2
22 Dezember 2021, 19:20:37
Hallo zap,

ein "get value" bringt die Rückmeldung umgehend ohne Probleme.
Das "get config" verursacht wieder die gleichen "Hänger" wie auch bei "get update".

Code Auswählen
2021.12.22 19:00:08 1: Perfmon: possible freeze starting at 19:00:07, delay is 1.45
2021.12.22 19:02:48 1: HMCCURPCPROC [d_rpc050032HmIP_RF] Error in request getParamset 0000D3C995FFDC SERVICE:
2021.12.22 19:02:48 2: HMCCUCHN [EG_FKE1_Arbeitszimmer] Can't get parameterset SERVICE for address 0000D3C995FFDC
2021.12.22 19:03:08 1: HMCCURPCPROC [d_rpc050032HmIP_RF] Error in request getParamset 0000D3C995FFDC:0 SERVICE: Generic error (TRANSACTION_DISCARDED_FOR_UNREACHABLE_DEVICE)
2021.12.22 19:03:08 2: HMCCUCHN [EG_FKE1_Arbeitszimmer] Can't get parameterset SERVICE for address 0000D3C995FFDC:0
2021.12.22 19:03:28 1: HMCCURPCPROC [d_rpc050032HmIP_RF] Error in request getParamset 0000D3C995FFDC:1 SERVICE:
2021.12.22 19:03:28 2: HMCCUCHN [EG_FKE1_Arbeitszimmer] Can't get parameterset SERVICE for address 0000D3C995FFDC:1
2021.12.22 19:03:28 1: Perfmon: possible freeze starting at 19:02:29, delay is 59.746

Tritt das "Problem" nur bei mir auf, kannst du das bei deiner Konfiguration nachstellen?

Kann das eventuell mit einen früheren Problem https://forum.fhem.de/index.php/topic,98287.msg916435.html#msg916435 bei mir zusammenhängen?
Seit dieser Zeit verwende ich das "ccuflags noInitialUpdate" und hatte bisher keinerlei Probleme beim Neustart.

Ich kann aktuell mit dieser Problematik noch einige Zeit leben, du kannst dich gerne vorher um dringendere Probleme kümmern.

Gruß Reinhard
Fhem 6.3 auf Raspberry Pi4 SSD mit Raspbian Bookworm, Homematic, Homematic IP, CCU3 mit RapberryMatic, WAGO 750-880, E3DC S10E Hauskraftwerk, E3DC Wallbox, my-PV AC ELWA-E Heizstab, Fritz!Box 7590, KIA Bluelinky

zap

#3
23 Dezember 2021, 18:08:12
Vermutlich liest "get config" auch das Parameterset "SERVICE". Ich glaube, ich werfe das komplett raus. Bringt sowieso keinen Mehrwert.
2xCCU3 mit ca. 100 Aktoren, Sensoren
Entwicklung: FHEM auf Proxmox Debian VM
Produktiv inzwischen auf Home Assistant gewechselt.
Maintainer: HMCCU, (Fully, AndroidDB)
Drucken
Nach oben Seiten1
Benutzer-Aktionen