Jalousien fährt wiederholt in Shading obwohl ASC_blockAscDrivesAfterManual aktiv

[FunkOdyssey2001]

Hallo,

meine Jalousien fahren wiederholt in die Shading-Position.
Und dies obwohl manuell die Jalousie nach der ersten Beschattung wieder hochgefahren wurde.
Im ASC_ShuttersLastDrive-Reading steht auch manual und das Attribut ist passend auf ASC_blockAscDrivesAfterManual gesetzt.

Beim zweiten Shading-Herunterfahren wurde sehr zügig wieder manuell hochgefahren. ASC kam nicht dazu, das Reading ASC_ShuttersLastDrive auf Shading In zu setzen. Die Beschattungsfahrt wurde unterbrochen. Doch dann blieb die Jalousie auch oben. In ASC_ShuttersLastDrive wurde vermutlich nicht erneut manual protokolliert, weil ein event-on-change-reading: .* aktiv ist.

Mein Jalousie-Device:

attr jal_wohnen ASC_BrightnessSensor lightsensor:lux 250:150
attr jal_wohnen ASC_Down brightness
attr jal_wohnen ASC_DriveUpMaxDuration 50
attr jal_wohnen ASC_Drive_Delay 0
attr jal_wohnen ASC_Drive_DelayStart 30
attr jal_wohnen ASC_Mode_Down always
attr jal_wohnen ASC_Mode_Up always
attr jal_wohnen ASC_Partymode on
attr jal_wohnen ASC_Pos_Reading pct
attr jal_wohnen ASC_PrivacyDown_Pos 60
attr jal_wohnen ASC_Shading_InOutAzimuth 65:250
attr jal_wohnen ASC_Shading_MinMax_Elevation 10:100
attr jal_wohnen ASC_Shading_Min_OutsideTemperature 22
attr jal_wohnen ASC_Shading_Mode always
attr jal_wohnen ASC_Shading_Pos 30
attr jal_wohnen ASC_Shading_StateChange_SunnyCloudy 95000:10000
attr jal_wohnen ASC_Shutter_IdleDetection motor:stop.*
attr jal_wohnen ASC_Time_Down_Early 16:00
attr jal_wohnen ASC_Time_Down_Late 22:00
attr jal_wohnen ASC_Time_Up_Early 08:00
attr jal_wohnen ASC_Time_Up_Late 08:30
attr jal_wohnen ASC_Time_Up_WE_Holiday 08:15
attr jal_wohnen ASC_Up brightness
attr jal_wohnen ASC_WiggleValue 15

Mein ASC-Device
attr Rolladensteuerung ASC_blockAscDrivesAfterManual 1

OK - Jalousie öffnet morgens

2020-07-21_08:00:51 jal_wohnen commState: CMDs_pending
2020-07-21_08:00:51 jal_wohnen level: set_100
2020-07-21_08:00:51 jal_wohnen set_100
2020-07-21_08:00:51 jal_wohnen commState: CMDs_processing...
2020-07-21_08:00:51 jal_wohnen commState: CMDs_done
2020-07-21_08:00:51 jal_wohnen level: 0
2020-07-21_08:00:51 jal_wohnen motor: up:off
2020-07-21_08:00:51 jal_wohnen off
2020-07-21_08:01:21 jal_wohnen deviceMsg: on (to VCCU)
2020-07-21_08:01:21 jal_wohnen level: 100
2020-07-21_08:01:21 jal_wohnen motor: stop:on
2020-07-21_08:01:21 jal_wohnen pct: 100
2020-07-21_08:01:21 jal_wohnen on
2020-07-21_08:01:22 jal_wohnen ASC_ShuttersLastDrive: maximum brightness threshold exceeded

OK - Jalousie fährt erstmals in die Beschattung

2020-07-21_11:06:38 jal_wohnen ASC_ShadingMessage: INFO: current shading status is 'in reserved' - next check in 10m
2020-07-21_11:16:53 jal_wohnen ASC_ShadingMessage: INFO: current shading status is 'in' - next check in 20m
2020-07-21_11:17:23 jal_wohnen commState: CMDs_pending
2020-07-21_11:17:23 jal_wohnen level: set_30
2020-07-21_11:17:23 jal_wohnen set_30
2020-07-21_11:17:23 jal_wohnen commState: CMDs_processing...
2020-07-21_11:17:23 jal_wohnen commState: CMDs_done
2020-07-21_11:17:23 jal_wohnen level: 100
2020-07-21_11:17:23 jal_wohnen motor: down:on
2020-07-21_11:17:23 jal_wohnen on
2020-07-21_11:17:44 jal_wohnen deviceMsg: 30 (to VCCU)
2020-07-21_11:17:44 jal_wohnen level: 30
2020-07-21_11:17:44 jal_wohnen motor: stop:30
2020-07-21_11:17:44 jal_wohnen pct: 30
2020-07-21_11:17:44 jal_wohnen 30
2020-07-21_11:17:44 jal_wohnen ASC_ShuttersLastDrive: shading in

OK - Manueller Eingriff - Jalousie soll wieder hochfahren und kommt auch auf Level100 an

2020-07-21_12:10:54 jal_wohnen deviceMsg: 41.5 (to VCCU)
2020-07-21_12:10:54 jal_wohnen level: 41.5
2020-07-21_12:10:54 jal_wohnen motor: up:41.5
2020-07-21_12:10:54 jal_wohnen pct: 41.5
2020-07-21_12:10:54 jal_wohnen 41.5
2020-07-21_12:11:14 jal_wohnen deviceMsg: on (to VCCU)
2020-07-21_12:11:14 jal_wohnen level: 100
2020-07-21_12:11:14 jal_wohnen motor: stop:on
2020-07-21_12:11:14 jal_wohnen pct: 100
2020-07-21_12:11:14 jal_wohnen on
2020-07-21_12:11:14 jal_wohnen ASC_ShuttersLastDrive: manual

FEHLER - Jalousie bekommt erneut von ASC den Fahrbefehl von Pos100 auf Pos30 zu fahren

2020-07-21_12:11:26 jal_wohnen commState: CMDs_pending
2020-07-21_12:11:26 jal_wohnen level: set_30
2020-07-21_12:11:26 jal_wohnen set_30
2020-07-21_12:11:26 jal_wohnen commState: CMDs_processing...
2020-07-21_12:11:27 jal_wohnen commState: CMDs_done
2020-07-21_12:11:27 jal_wohnen level: 100
2020-07-21_12:11:27 jal_wohnen motor: down:on
2020-07-21_12:11:27 jal_wohnen on
2020-07-21_12:11:40 jal_wohnen deviceMsg: 74.5 (to VCCU)
2020-07-21_12:11:40 jal_wohnen level: 74.5
2020-07-21_12:11:40 jal_wohnen motor: up:74.5
2020-07-21_12:11:40 jal_wohnen pct: 74.5
2020-07-21_12:11:40 jal_wohnen 74.5

Jalousie wurde erneut manuell geöffnet.

2020-07-21_12:11:51 jal_wohnen deviceMsg: on (to VCCU)
2020-07-21_12:11:51 jal_wohnen level: 100
2020-07-21_12:11:51 jal_wohnen motor: stop:on
2020-07-21_12:11:51 jal_wohnen pct: 100
2020-07-21_12:11:51 jal_wohnen on

[FunkOdyssey2001]

Der Fehler ärgert mich enorm. :-) Ich habe das bei allen Jalousien, die beschattet werden.

Mir fällt auf, dass ich die Rollos zweimal fahren muss und dann ist Ruhe.

• Shading In - runter
• manual - Hoch
• Erneut shading - runter
• Wieder manual - Hoch
  Und dann bleibt das so.

Aufgefallen ist mir auch, dass bei der zweiten Beschattungsfahrt runter das Reading ASC_ShuttersLastDrive nicht mit "Shading In" beschrieben wird. Es bleibt die vorherige manuelle Fahrt dort stehen. Und das obwohl die Erkennung bei mir ansonsten gut funktioniert.

[CoolTuxNet]

Ich kann den Fehler beim besten willen nicht nachvollziehen. Bei mir verbleibt das Rollo so lange in der manual Position bis einmal komplett entschattet wurde und fährt erst bei einer neuen Beschattung

[FunkOdyssey2001]

Wie kann ich bei der Suche danach unterstützen? Ich helfe gerne mit und ich baue auch gerne ein paar Debug-Zeilen ein.

[CoolTuxNet]

Hast Du bereits die aktuelle 0.10.6er Version?

[FunkOdyssey2001]

Aktuell ja, aber zum Zeitpunkt des Tickets natürlich noch nicht.
Ich versuche das Verhalten in den nächsten Tagen zu reproduzieren.

[CoolTuxNet]

Danke Dir.

[FunkOdyssey2001]

Selber :-)

[FunkOdyssey2001]

Das Problem ist leider immer noch vorhanden.
Version ist aktuell.
Debug-Log leider nicht vorhanden.

[FunkOdyssey2001]

Ich habe Probleme, über mehrere Tage die volle Protokollierung mitlaufen zu lassen. Die CPU-Last steigt dann zum Tagesende enorm an und FHEM wird sehr träge. Die Logs wachsen nämlich auf mehrere hundert Megabyte an.

Aktuell kann ich nur folgendes liefern:

ASC Device Log

2020-08-19_17:45:21 Rolladensteuerung jal_wohnen_lastPosValue: 100
2020-08-19_17:45:26 Rolladensteuerung jal_wohnen_lastPosValue: 100
2020-08-19_17:45:36 Rolladensteuerung jal_wohnen_lastPosValue: 100
2020-08-19_17:45:37 Rolladensteuerung jal_wohnen_PosValue: 100
2020-08-19_17:45:37 Rolladensteuerung manual
2020-08-19_17:46:03 Rolladensteuerung jal_wohnen_PosValue: 99
2020-08-19_17:46:03 Rolladensteuerung manual
2020-08-19_17:46:09 Rolladensteuerung jal_wohnen_PosValue: 100
2020-08-19_17:46:09 Rolladensteuerung manual
2020-08-19_17:46:28 Rolladensteuerung jal_wohnen_PosValue: 50
2020-08-19_17:46:28 Rolladensteuerung manual
2020-08-19_17:47:09 Rolladensteuerung jal_wohnen_PosValue: 100
2020-08-19_17:47:09 Rolladensteuerung manual

Rollo-Log

2020-08-19_12:14:01 jal_wohnen ASC_ShuttersLastDrive: shading in
2020-08-19_17:45:20 jal_wohnen deviceMsg: 60.5 (to VCCU)
2020-08-19_17:45:20 jal_wohnen level: 60.5
2020-08-19_17:45:20 jal_wohnen motor: up:60.5
2020-08-19_17:45:20 jal_wohnen pct: 60.5
2020-08-19_17:45:20 jal_wohnen 60.5
2020-08-19_17:45:37 jal_wohnen deviceMsg: on (to VCCU)
2020-08-19_17:45:37 jal_wohnen level: 100
2020-08-19_17:45:37 jal_wohnen motor: stop:on
2020-08-19_17:45:37 jal_wohnen pct: 100
2020-08-19_17:45:37 jal_wohnen on
2020-08-19_17:45:37 jal_wohnen ASC_ShuttersLastDrive: manual
2020-08-19_17:45:56 jal_wohnen commState: CMDs_pending
2020-08-19_17:45:56 jal_wohnen level: set_50
2020-08-19_17:45:56 jal_wohnen set_50
2020-08-19_17:45:56 jal_wohnen commState: CMDs_processing...
2020-08-19_17:45:56 jal_wohnen commState: CMDs_done
2020-08-19_17:45:56 jal_wohnen level: 100
2020-08-19_17:45:56 jal_wohnen motor: down:on
2020-08-19_17:45:56 jal_wohnen on
2020-08-19_17:46:03 jal_wohnen deviceMsg: 99 (to VCCU)
2020-08-19_17:46:03 jal_wohnen level: 99
2020-08-19_17:46:03 jal_wohnen motor: up:99
2020-08-19_17:46:03 jal_wohnen pct: 99
2020-08-19_17:46:03 jal_wohnen 99
2020-08-19_17:46:09 jal_wohnen deviceMsg: on (to VCCU)
2020-08-19_17:46:09 jal_wohnen level: 100
2020-08-19_17:46:09 jal_wohnen motor: stop:on
2020-08-19_17:46:09 jal_wohnen pct: 100
2020-08-19_17:46:09 jal_wohnen on
2020-08-19_17:46:11 jal_wohnen commState: CMDs_pending
2020-08-19_17:46:11 jal_wohnen level: set_50
2020-08-19_17:46:11 jal_wohnen set_50
2020-08-19_17:46:11 jal_wohnen commState: CMDs_processing...
2020-08-19_17:46:11 jal_wohnen commState: CMDs_done
2020-08-19_17:46:11 jal_wohnen level: 100
2020-08-19_17:46:11 jal_wohnen motor: down:on
2020-08-19_17:46:11 jal_wohnen on
2020-08-19_17:46:28 jal_wohnen deviceMsg: 50 (to VCCU)
2020-08-19_17:46:28 jal_wohnen level: 50
2020-08-19_17:46:28 jal_wohnen motor: stop:50
2020-08-19_17:46:28 jal_wohnen pct: 50
2020-08-19_17:46:28 jal_wohnen 50
2020-08-19_17:46:52 jal_wohnen deviceMsg: 60.5 (to VCCU)
2020-08-19_17:46:52 jal_wohnen level: 60.5
2020-08-19_17:46:52 jal_wohnen motor: up:60.5
2020-08-19_17:46:52 jal_wohnen pct: 60.5
2020-08-19_17:46:52 jal_wohnen 60.5
2020-08-19_17:47:09 jal_wohnen deviceMsg: on (to VCCU)
2020-08-19_17:47:09 jal_wohnen level: 100
2020-08-19_17:47:09 jal_wohnen motor: stop:on
2020-08-19_17:47:09 jal_wohnen pct: 100
2020-08-19_17:47:09 jal_wohnen on

Sehr merkwürdig ist auch folgender Sachverhalt. Das Rollo sollte ja eigentlich um 17:45 Uhr manuell hochgefahren werden. Und wie oben beschrieben wurde immer wieder runtergefahren.
Aber auch um ca. 20:45 Uhr wurde wieder gefahren. Der Brightness-Wert war unterschritten.
Das finde ich eigentlich ganz gut. Doch hätte beim aktivierten Blocking die Fahrt runter nicht auch ausfallen müssen?

[CoolTuxNet]

Wird eine manuelle Fahrt denn immer korrekt bei Dir erkannt?

[CoolTuxNet]

Es gibt morgen ein Update. Eigentlich berührt es dieses Problem nicht wirklich, wäre super wenn Du dennoch mal testen könntest. Danke

[FunkOdyssey2001]

Leider eine schlechte Nachricht.

Die Rollos sind wieder hochgefahren. Scheinbar zeitgleich mit einem Events des Lichtsensors. Es wird jedesmal in der gleichen Sekunde heruntergefahren.
Und es ist definitiv so, dass man scheinbar zweimal das Rollo wieder hochfahren muss und dann ist Ruhe. ASC scheint einmal korrigieren zu wollen.

• Manuell hoch
• ASC fährt runter (set 50)
• Manuell wieder hoch
• Bleibt oben

Zu diesem Zeitpunkt ist heute der Lichtwert zwischen den Werten im Attribut StateChange_SunnyCloudy gewesen. Also nicht größer als der Sunny-Wert.

ASC_Shutter_IdleDetection habe ich mittlerweile schon gelöscht, da ich den Fehler ursprünglich hier vermutet hatte.

Leider habe ich nicht rechtzeitig das Debug-Log aktiviert. Erst bei der zweiten manuell Fahrt auf Pos100 war das Debug-Log aktiv. Aber das hilft uns nicht weiter, da ASC hier nicht mehr eingegriffen hat.

---

Und jetzt wird es merkwürdig. Ich war selbst nicht daheim und ich kann mir nur die Logs dazu anschauen.
Aber ich habe direkt daneben ein Rollo, welches fast die gleiche Konfiguration hat. Nur Unterschiede in ASC_Shading_InOutAzimuth und ASC_Shading_Pos. Dieses Rollo hatte heute keine Probleme und ist ausnahmsweise nach manueller Fahrt oben gelieben. Ich werde meine Frau mal fragen. Denn das Rollo hatte damals (siehe Ausgangspost) mitsamt aller anderen Rollos auch die gleichen Probleme.
Vielleicht ignorierst du das erst einmal. :-)

---

Gerade habe ich das noch bei einer anderen Jalousie ausprobiert. Diese hat kaum Ähnlichkeiten mit den ursprünglichen, hier bisher erwähnten Rollos. Da trat das Problem heute nicht auf.

Ich schaue mir das weiterhin an.

[FunkOdyssey2001]

Wird eine manuelle Fahrt denn immer korrekt bei Dir erkannt?

Ich habe event-on-change-reading .* nun deaktiviert, da ich dann etwas mehr sehe.
Aber ja, wie du oben im Ausgangsbeitrag sehen kann, wurde die manuelle Fahrt erkannt.
Die falsche Fahrt von ASC wurde in ASC_ShuttersLastDrive aber nicht gesetzt. Es bleibt also "manual" im Reading stehen. Auch bei der zweiten manuellen Fahrt, da das event-on-Attribut gesetzt war.

[FunkOdyssey2001]

Hallo, gestern hatte ich endlich Verbose=5 und das Debug-Log aktiv während der Fehler auftrat.
Ich sende dir alle meine Logs per E-Mails zu. Diesmal vollständig ungekürzt und die möchte ich nicht auf GitHub hochladen.

Am Rande: Ich habe event-on-change nicht gesetzt. So sieht man auch möglich Zwischenschritte.

Rollo

• fuhr 11:39:03 in Shading in (50%)
• sollte um 11:50:43 wieder manuell hoch auf 100%
• ASC fuhr um 11:51:18 wieder auf 50% runter. Merkwürdig: ASC_ShuttersLastDrive wurde auf "manual" gesetzt.
• um 11:56:34 haben wir wieder manuell hochgefahren
• danach war wieder alles ruhig

Auch komisch: Um 12:59:10 wurde erstmals wieder in Shading In gefahren. ABER: Ich hatte FHEM nach der obigen Aktion neu gestartet. Vermutlich ist das der Grund für 12:59:10 gewesen.

[CoolTuxNet]

Habe bis jetzt noch keine Mail bekommen.