[gelöst] Probleme mit WeekdayTimer

JWRu · 01 Oktober 2019, 23:16:25

Mein WeekdayTimer, der monatelang einwandfrei funktioniert hat, macht plötzlich (nach einem Update vor einigen Tagen ??) Probleme.

Hier ein List (um 22:10 Uhr gezogen):

Code Auswählen

Internals:
   COMMAND    { if (Value("Status_Fussbodenheizung") eq "on") { my $Value = substr($EVENT,0,2); fhem("set $NAME setpointHeating $Value") } }
   CONDITION  
   DEF        ZWave_THERMOSTAT_2 06:15|28 08:45|20 21:45|28 22:55|20 { if (Value("Status_Fussbodenheizung") eq "on") { my $Value = substr($EVENT,0,2); fhem("set $NAME setpointHeating $Value") } }
   DEVICE     ZWave_THERMOSTAT_2
   FUUID      5c447ba1-f33f-c1fa-ad2b-49e10c535265c659
   GlobalDaylistSpec 
   LANGUAGE   de
   NAME       Timer_Fussbodenheizung
   NR         215
   Profil 0: Sonntag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 1: Montag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 2: Dienstag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 3: Mittwoch 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 4: Donnerstag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 5: Freitag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 6: Samstag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   STATE      28
   STILLDONETIME 0
   TYPE       WeekdayTimer
   Helper:
     DBLOG:
       currValue:
         myDbLog:
           TIME       1569959100.03821
           VALUE      28
       disabled:
         myDbLog:
           TIME       1569574064.55683
           VALUE      0
       nextUpdate:
         myDbLog:
           TIME       1569959100.03821
           VALUE      2019-10-01 22:55:00
       nextValue:
         myDbLog:
           TIME       1569959100.03821
           VALUE      20
       state:
         myDbLog:
           TIME       1569959100.03821
           VALUE      28
   READINGS:
     2019-10-01 21:45:00   currValue       28
     2019-09-27 10:47:44   disabled        0
     2019-10-01 21:45:00   nextUpdate      2019-10-01 22:55:00
     2019-10-01 21:45:00   nextValue       20
     2019-10-01 21:45:00   state           28
   SWITCHINGTIMES:
     06:15|28
     08:45|20
     21:45|28
     22:55|20
   TIMER:
     Timer_Fussbodenheizung_1:
       HASH       Timer_Fussbodenheizung
       MODIFIER   1
       NAME       Timer_Fussbodenheizung_1
     Timer_Fussbodenheizung_2:
       HASH       Timer_Fussbodenheizung
       MODIFIER   2
       NAME       Timer_Fussbodenheizung_2
     Timer_Fussbodenheizung_3:
       HASH       Timer_Fussbodenheizung
       MODIFIER   3
       NAME       Timer_Fussbodenheizung_3
     Timer_Fussbodenheizung_4:
       HASH       Timer_Fussbodenheizung
       MODIFIER   4
       NAME       Timer_Fussbodenheizung_4
       immerSchalten 1
     Timer_Fussbodenheizung_SetTimerOfDay:
       HASH       Timer_Fussbodenheizung
       MODIFIER   SetTimerOfDay
       NAME       Timer_Fussbodenheizung_SetTimerOfDay
       SETTIMERATMIDNIGHT 1
     Timer_Fussbodenheizung_delayed:
       HASH       Timer_Fussbodenheizung
       MODIFIER   delayed
       NAME       Timer_Fussbodenheizung_delayed
   dayNumber:
     !$we       8
     $we        7
     di         2
     do         4
     fr         5
     mi         3
     mo         1
     sa         6
     so         0
   helper:
     daysRegExp (so|mo|di|mi|do|fr|sa|\$we|\!\$we)
     daysRegExpMessage (so|mo|di|mi|do|fr|sa|$we|!$we)
     SWITCHINGTIME:
       0:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       1:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       2:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       3:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       4:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       5:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       6:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
   longDays:
     de:
       Sonntag
       Montag
       Dienstag
       Mittwoch
       Donnerstag
       Freitag
       Samstag
       Wochenende
       Werktags
     en:
       Sunday
       Monday
       Tuesday
       Wednesday
       Thursday
       Friday
       Saturday
       weekend
       weekdays
     fr:
       Dimanche
       Lundi
       Mardi
       Mercredi
       Jeudi
       Vendredi
       Samedi
       weekend
       jours de la semaine
   profil:
     1:
       EPOCH      1569903300
       PARA       28
       TIME       06:15
       TAGE:
         0
         1
         2
         3
         4
         5
         6
     2:
       EPOCH      1569912300
       PARA       20
       TIME       08:45
       TAGE:
         0
         1
         2
         3
         4
         5
         6
     3:
       EPOCH      1569959100
       PARA       28
       TIME       21:45
       TAGE:
         0
         1
         2
         3
         4
         5
         6
     4:
       EPOCH      1569963300
       PARA       20
       TIME       22:55
       TAGE:
         0
         1
         2
         3
         4
         5
         6
   profile_IDX:
     0:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     1:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     2:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     3:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     4:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     5:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     6:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
   shortDays:
     de:
       so
       mo
       di
       mi
       do
       fr
       sa
       $we
       !$we
     en:
       su
       mo
       tu
       we
       th
       fr
       sa
       $we
       !$we
     fr:
       di
       lu
       ma
       me
       je
       ve
       sa
       $we
       !$we
Attributes:
   commandTemplate set $NAME desired-temp $EVENT
   disable    0
   room       Badezimmer
   verbose    5

Die ersten drei Schaltungen um 6:15, 8:45 und 21:45 Uhr werden einwandfrei ausgeführt, die von 22:55 Uhr aber nicht. Trotz verbose 5 taucht im Log zu dieser Zeit nichts auf.
Im Reading nextUpdate steht danach immer noch 2019-10-01 22:55:00.

Hier ist ein List, das um 23:10 Uhr gezogen wurde:

Code Auswählen

Internals:
   COMMAND    { if (Value("Status_Fussbodenheizung") eq "on") { my $Value = substr($EVENT,0,2); fhem("set $NAME setpointHeating $Value") } }
   CONDITION  
   DEF        ZWave_THERMOSTAT_2 06:15|28 08:45|20 21:45|28 22:55|20 { if (Value("Status_Fussbodenheizung") eq "on") { my $Value = substr($EVENT,0,2); fhem("set $NAME setpointHeating $Value") } }
   DEVICE     ZWave_THERMOSTAT_2
   FUUID      5c447ba1-f33f-c1fa-ad2b-49e10c535265c659
   GlobalDaylistSpec 
   LANGUAGE   de
   NAME       Timer_Fussbodenheizung
   NR         215
   Profil 0: Sonntag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 1: Montag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 2: Dienstag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 3: Mittwoch 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 4: Donnerstag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 5: Freitag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   Profil 6: Samstag 06:15:00 28, 08:45:00 20, 21:45:00 28, 22:55:00 20
   STATE      28
   STILLDONETIME 0
   TYPE       WeekdayTimer
   Helper:
     DBLOG:
       currValue:
         myDbLog:
           TIME       1569959100.03821
           VALUE      28
       disabled:
         myDbLog:
           TIME       1569574064.55683
           VALUE      0
       nextUpdate:
         myDbLog:
           TIME       1569959100.03821
           VALUE      2019-10-01 22:55:00
       nextValue:
         myDbLog:
           TIME       1569959100.03821
           VALUE      20
       state:
         myDbLog:
           TIME       1569959100.03821
           VALUE      28
   READINGS:
     2019-10-01 21:45:00   currValue       28
     2019-09-27 10:47:44   disabled        0
     2019-10-01 21:45:00   nextUpdate      2019-10-01 22:55:00
     2019-10-01 21:45:00   nextValue       20
     2019-10-01 21:45:00   state           28
   SWITCHINGTIMES:
     06:15|28
     08:45|20
     21:45|28
     22:55|20
   TIMER:
     Timer_Fussbodenheizung_1:
       HASH       Timer_Fussbodenheizung
       MODIFIER   1
       NAME       Timer_Fussbodenheizung_1
     Timer_Fussbodenheizung_2:
       HASH       Timer_Fussbodenheizung
       MODIFIER   2
       NAME       Timer_Fussbodenheizung_2
     Timer_Fussbodenheizung_3:
       HASH       Timer_Fussbodenheizung
       MODIFIER   3
       NAME       Timer_Fussbodenheizung_3
     Timer_Fussbodenheizung_4:
       HASH       Timer_Fussbodenheizung
       MODIFIER   4
       NAME       Timer_Fussbodenheizung_4
       immerSchalten 1
     Timer_Fussbodenheizung_SetTimerOfDay:
       HASH       Timer_Fussbodenheizung
       MODIFIER   SetTimerOfDay
       NAME       Timer_Fussbodenheizung_SetTimerOfDay
       SETTIMERATMIDNIGHT 1
     Timer_Fussbodenheizung_delayed:
       HASH       Timer_Fussbodenheizung
       MODIFIER   delayed
       NAME       Timer_Fussbodenheizung_delayed
   dayNumber:
     !$we       8
     $we        7
     di         2
     do         4
     fr         5
     mi         3
     mo         1
     sa         6
     so         0
   helper:
     daysRegExp (so|mo|di|mi|do|fr|sa|\$we|\!\$we)
     daysRegExpMessage (so|mo|di|mi|do|fr|sa|$we|!$we)
     SWITCHINGTIME:
       0:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       1:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       2:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       3:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       4:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       5:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
       6:
         06:15:00   28
         08:45:00   20
         21:45:00   28
         22:55:00   20
   longDays:
     de:
       Sonntag
       Montag
       Dienstag
       Mittwoch
       Donnerstag
       Freitag
       Samstag
       Wochenende
       Werktags
     en:
       Sunday
       Monday
       Tuesday
       Wednesday
       Thursday
       Friday
       Saturday
       weekend
       weekdays
     fr:
       Dimanche
       Lundi
       Mardi
       Mercredi
       Jeudi
       Vendredi
       Samedi
       weekend
       jours de la semaine
   profil:
     1:
       EPOCH      1569903300
       PARA       28
       TIME       06:15
       TAGE:
         0
         1
         2
         3
         4
         5
         6
     2:
       EPOCH      1569912300
       PARA       20
       TIME       08:45
       TAGE:
         0
         1
         2
         3
         4
         5
         6
     3:
       EPOCH      1569959100
       PARA       28
       TIME       21:45
       TAGE:
         0
         1
         2
         3
         4
         5
         6
     4:
       EPOCH      1569963300
       PARA       20
       TIME       22:55
       TAGE:
         0
         1
         2
         3
         4
         5
         6
   profile_IDX:
     0:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     1:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     2:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     3:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     4:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     5:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
     6:
       06:15:00   1
       08:45:00   2
       21:45:00   3
       22:55:00   4
   shortDays:
     de:
       so
       mo
       di
       mi
       do
       fr
       sa
       $we
       !$we
     en:
       su
       mo
       tu
       we
       th
       fr
       sa
       $we
       !$we
     fr:
       di
       lu
       ma
       me
       je
       ve
       sa
       $we
       !$we
Attributes:
   commandTemplate set $NAME desired-temp $EVENT
   disable    0
   room       Badezimmer
   verbose    5

Die Systemzeit stimmt übrigens genau.

Beta-User · 02 Oktober 2019, 10:34:13

Welche Version ist das?

Ich habe eben (@StrawberryPerl) einen Test mit folgenden Parametern gemacht, wie du an den Zeitstempeln sehen kannst, lief der auch am Ende sauber durch:

Code Auswählen

defmod testWDT WeekdayTimer sz_deckenlicht 10:05|on 10:06|off 10:07|on 10:08|off  10:09|on 10:10|off
attr testWDT commandTemplate set $NAME  $EVENT

setstate testWDT off
setstate testWDT 2019-10-02 10:10:00 currValue off
setstate testWDT 2019-10-02 10:10:00 nextUpdate 2019-10-03 10:05:00
setstate testWDT 2019-10-02 10:10:00 nextValue on
setstate testWDT 2019-10-02 10:10:00 state off

So einen richtigen Anpack sehe ich nicht, version lieferte Rev. 19806. Auch sonst gab es bisher keine Beschwerden in diese Richtung (und ich bin mir auch recht sicher, nichts geändert zu haben, was solche Auswirkungen haben könnte).

JWRu · 02 Oktober 2019, 12:09:57

Code Auswählen

98_WeekdayTimer.pm 19806 2019-07-09 16:52:45Z Beta-User

Beta-User · 02 Oktober 2019, 12:27:16

Hmm, dann sollte das passen. Was ist denn der Status von Value("Status_Fussbodenheizung")?
Bei mir kommt in dem list nichts, was nach "delayed" klingt, vielleicht macht das den Unterschied. Das müßte dann aber aus dem Perl-Code kommen, was dann aber im Log stehten müßte. (Du sprichst doch vom FHEM-Log oder ist das Logfile des Devices gemeint?)

Sonst bin ich im Moment ratlos und würde das mal bei Gelegenheit auf einer Linux-Kiste nachstellen wollen (auch mit "if")...

JWRu · 02 Oktober 2019, 13:54:01

Es handelt sich um den FHEM-Log.
Status_Fussbodenheizung ist ein Dummy, der um 6:00 Uhr über ein at abhängig von Außentemperatur gesetzt wird. Der Status war am entsprechenden Tag "on".
Selbst wenn er "off" gewesen wäre, hätte doch irgendetwas im Log auftauchen müssen.
So sieht ein erfolgreicher Schaltvorgang im Log aus (verbose 5):

Code Auswählen

2019.10.02 08:45:00 4: [Timer_Fussbodenheizung] time=08:45/1569998700 delay=0, nextDelay=60, nextRetry=1569998760
2019.10.02 08:45:00 4: [Timer_Fussbodenheizung] delayedExecutionCond:0
2019.10.02 08:45:00 4: [Timer_Fussbodenheizung] result of delayedExecutionCond: 0
2019.10.02 08:45:00 4: [Timer_Fussbodenheizung] list of window sensors found: 'Timer_Fussbodenheizung'
2019.10.02 08:45:00 4: [Timer_Fussbodenheizung] condition: - Tage:0,1,2,3,4,5,6
2019.10.02 08:45:00 5: [Timer_Fussbodenheizung] condition: {my $days={};;map{$days->{$_}=1}(0,1,2,3,4,5,6);;;;( 1 && (defined $days->{$wday}))}
2019.10.02 08:45:00 5: [Timer_Fussbodenheizung] result of condition:1
2019.10.02 08:45:00 4: [Timer_Fussbodenheizung] Update   - timer seems to be active today: 0123456|08:45|20
2019.10.02 08:45:00 4: [Timer_Fussbodenheizung] device type heating recognized, setModifier:desired-temp
2019.10.02 08:45:00 4: [Timer_Fussbodenheizung] aktParam: newParam:20.0 - is  not disabled
2019.10.02 08:45:00 4: [Timer_Fussbodenheizung] command: '{ if (Value("Status_Fussbodenheizung") eq "on") { my $Value = substr($EVENT,0,2);; fhem("set $NAME setpointHeating $Value") } }' executed with %EVENT=>20.0,%NAME=>ZWave_THERMOSTAT_2
2019.10.02 08:45:00 3: ZWave set ZWave_THERMOSTAT_2 setpointHeating 20

Ich habe jetzt mal die Vorversion von 98_WeekdayTimer.pm eingespielt (19567) und schaue mal, was heute Abend passiert.

Beta-User · 02 Oktober 2019, 14:06:00

Puh, das ist vermutlich nicht weit genug zurück. Wann war denn davor das letzte update?

Würde sagen, du müßtest vor/wenigstens auf 19043 (eher noch weiter) zurück - das war im März nach der Änderung rund um $we... Dazwischen gab es nichts, was irgendwie mit der Timererstellung/Verarbeitung zu tun gehabt hätte.

JWRu · 02 Oktober 2019, 14:20:07

Ok. Die Fußbodenheizung lief den Sommer über nicht, deshalb habe ich wohl nichts bemerkt.
Ich habe mal in ../restoreDir/update.. nachgeschaut: Ich habe 19567, 19043, 18591, 16005 ...
Mit 18591 ist es sicher noch gelaufen - das war im Februar.

JWRu · 02 Oktober 2019, 22:58:35

Mit 19567 hat's geklappt:

Code Auswählen

2019.10.02 22:55:00 4: [Timer_Fussbodenheizung] time=22:55/1570049700 delay=0, nextDelay=60, nextRetry=1570049760
2019.10.02 22:55:00 4: [Timer_Fussbodenheizung] delayedExecutionCond:0
2019.10.02 22:55:00 4: [Timer_Fussbodenheizung] result of delayedExecutionCond:0
2019.10.02 22:55:00 4: [Timer_Fussbodenheizung] list of window sensors found: 'Timer_Fussbodenheizung'
2019.10.02 22:55:00 4: [Timer_Fussbodenheizung] condition: - Tage:0,1,2,3,4,5,6
2019.10.02 22:55:00 5: [Timer_Fussbodenheizung] condition: {my $days={};;map{$days->{$_}=1}(0,1,2,3,4,5,6);;;;( 1 && (defined $days->{$wday}))}
2019.10.02 22:55:00 5: [Timer_Fussbodenheizung] result of condition:1
2019.10.02 22:55:00 4: [Timer_Fussbodenheizung] Update   - timer seems to be active today: 0123456|22:55|20
2019.10.02 22:55:00 4: [Timer_Fussbodenheizung] device type heating recognized, setModifier:desired-temp
2019.10.02 22:55:00 4: [Timer_Fussbodenheizung] aktParam: newParam:20.0 - is  not disabled
2019.10.02 22:55:00 4: [Timer_Fussbodenheizung] command: '{ if (Value("Status_Fussbodenheizung") eq "on") { my $Value = substr($EVENT,0,2);; fhem("set $NAME setpointHeating $Value") } }' executed with %NAME=>ZWave_THERMOSTAT_2,%EVENT=>20.0
2019.10.02 22:55:00 3: ZWave set ZWave_THERMOSTAT_2 setpointHeating 20

JWRu · 05 Oktober 2019, 10:49:31

Seit dem Rückgang auf Version 19567 läuft bei mir wieder alles ohne Probleme. Das Problem hängt anscheinend an Version 19806.

Beta-User · 06 Oktober 2019, 08:10:04

Hmm,

sieht mir nach einem Problem mit dem letzten Wert aus, da scheint die Auswertung aus irgendeinem Grund nicht zu klappen, hier wurde was ähnliches berichtet: https://forum.fhem.de/index.php/topic,104272.0.html.
Kannst du die aktuelle Version nochmal nehmen und als workaround mal einen "gedoppelten" Schaltzeitpunkt einfügen, nämlich 23:59 Uhr - 20°C?

JWRu · 06 Oktober 2019, 11:31:42

Läuft - weitere Infos morgen.

JWRu · 07 Oktober 2019, 08:00:56

Mit 19806 hat jetzt alles geklappt, auch die letzte Schaltung um 23:59 Uhr. Ich versteh's auch nicht mehr.

Beta-User · 07 Oktober 2019, 08:21:04

Hmm, kannst du dann einfach nochmal den letzten Schaltzeitpunkt löschen?

(Wie geschrieben: mit meinem "normalen" Testdevice ging das auch reibungslos durch, dieses Problem konnte ich nicht so einfach nachstellen).

JWRu · 07 Oktober 2019, 09:21:25

Mach ich.

JWRu · 07 Oktober 2019, 22:59:11

Hat geklappt.