fhempy: Tuya Cloud

Begonnen von dominik, 30 Juli 2021, 21:36:48

Vorheriges Thema - Nächstes Thema

thburkhart

Zitat von: dominik am 04 Februar 2023, 00:08:05
Ich werde morgen den Timeout auf 3 Minuten hochdrehen, damit auch ein blockiertes Fhem 3 Minuten Zeit für die Antwort hat.

Generell sollte FHEM aber nie blockieren, da sollte man nachgehen.

mache ich bzgl. MAX morgen mit Wzut
und stelle Max im verbose höher

Gute Nacht und vielen Dank!

Thomas
1 RASPI4B, 1 RASPI3B, 2 CUL, 2 Jeelink, 60 Tuya-Devices (Schalter, Dimmer, Sensoren, Cameras), 30 HUE-Lampen, 5 MAX! WTs, 16 MAX! HTs, 12 MAX! FKs, 1 Bresser 5in1, 1 OilFox, 8 ALEXA Echos und Dots, FHEM, 5 Tasmota-Devices, SonOff -Bridge, PowerFox, Buderus KM200

thburkhart

#841
Zitat von: dominik am 04 Februar 2023, 00:08:05
Ich werde morgen den Timeout auf 3 Minuten hochdrehen, damit auch ein blockiertes Fhem 3 Minuten Zeit für die Antwort hat.

Generell sollte FHEM aber nie blockieren, da sollte man nachgehen.

ich habe kurz nach Mitternacht Apptime gestartet; aktuelle Werte:
active-timers: 83; max-active timers: 85; max-timer-load: 36  min-tmrHandlingTm: 0.0ms; max-tmrHandlingTm: 11512.2ms; totAvgDly: 22.1ms

name                                     function                               max    count      total  average   maxDly   avgDly TS Max call     param Max call
tmr-at_Exec                              HASH(0x4050738)                       3310        1    3310.78  3310.78     0.74     0.74 04.02. 08:06:09 HASH(Kueche_Rollo_3_bei_Sonnenaufgang_hoch_AT)
Kueche_Rollo_3                           dummy_Set                             3259        2    3259.19  1629.59     0.00     0.00 04.02. 08:06:09 HASH(Kueche_Rollo_3); Kueche_Rollo_3; up
Kueche_Rollo_3_up_Notify                 notify_Exec                           3093        1    3093.26  3093.26     0.00     0.00 04.02. 08:06:09 HASH(Kueche_Rollo_3_up_Notify); HASH(Kueche_Rollo_3)
tmr-at_Exec                              HASH(0x4049348)                       2512        1    2512.38  2512.38     1.00     1.00 04.02. 07:46:08 HASH(Untergeschoss_Rollos_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x401c400)                       2494        1    2494.51  2494.51     1.12     1.12 04.02. 08:30:02 HASH(Wohnzimmer_Rollos_bei_Sonnenaufgang_hoch_AT)
Wohnzimmer_Rollo_Alle                    dummy_Set                             2492        2    2492.09  1246.05     0.00     0.00 04.02. 08:30:02 HASH(Wohnzimmer_Rollo_Alle); Wohnzimmer_Rollo_Alle; up
Untergeschoss_Rollo_Alle                 dummy_Set                             2459        2    2459.80  1229.90     0.00     0.00 04.02. 07:46:08 HASH(Untergeschoss_Rollo_Alle); Untergeschoss_Rollo_Alle; up
Wohnzimmer_Rollo_Alle_up_Notify          notify_Exec                           2384        1    2384.53  2384.53     0.00     0.00 04.02. 08:30:02 HASH(Wohnzimmer_Rollo_Alle_up_Notify); HASH(Wohnzimmer_Rollo_Alle)
Untergeschoss_Rollo_Alle_up_Notify       notify_Exec                           2351        1    2351.91  2351.91     0.00     0.00 04.02. 07:46:08 HASH(Untergeschoss_Rollo_Alle_up_Notify); HASH(Untergeschoss_Rollo_Alle)
tmr-at_Exec                              HASH(0x4055088)                       2289        1    2289.99  2289.99  9223.02  9223.02 04.02. 07:46:17 HASH(Wohnzimmer_Rollo_3_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x4052ff0)                       2240        1    2240.61  2240.61  4748.97  4748.97 04.02. 07:46:12 HASH(Wohnzimmer_Rollo_1_bei_Sonnenaufgang_hoch_AT)
Wohnzimmer_Rollo_3                       dummy_Set                             2238        4    2337.02   584.25     0.00     0.00 04.02. 07:46:17 HASH(Wohnzimmer_Rollo_3); Wohnzimmer_Rollo_3; up
tmr-at_Exec                              HASH(0x4049a50)                       2235        1    2235.40  2235.40  2513.47  2513.47 04.02. 07:46:10 HASH(Untergeschoss_Rollo_2_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x4053860)                       2233        1    2233.21  2233.21  6989.71  6989.71 04.02. 07:46:15 HASH(Wohnzimmer_Rollo_2_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x4049258)                       2199        1    2199.03  2199.03    72.11    72.11 04.02. 07:49:28 HASH(Untergeschoss_Rollo_1_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x404c708)                       2190        1    2190.38  2190.38    35.88    35.88 04.02. 09:30:02 HASH(Obergeschoss_Rollo_1_bei_Sonnenaufgang_hoch_AT)
Obergeschoss_Rollo_1                     dummy_Set                             2188        2    2188.40  1094.20     0.00     0.00 04.02. 09:30:02 HASH(Obergeschoss_Rollo_1); Obergeschoss_Rollo_1; up
Wohnzimmer_Rollo_1                       dummy_Set                             2188        4    2348.09   587.02     0.00     0.00 04.02. 07:46:12 HASH(Wohnzimmer_Rollo_1); Wohnzimmer_Rollo_1; up
Untergeschoss_Rollo_2                    dummy_Set                             2183        4    2283.91   570.98     0.00     0.00 04.02. 07:46:10 HASH(Untergeschoss_Rollo_2); Untergeschoss_Rollo_2; up
Wohnzimmer_Rollo_2                       dummy_Set                             2180        4    2278.67   569.67     0.00     0.00 04.02. 07:46:15 HASH(Wohnzimmer_Rollo_2); Wohnzimmer_Rollo_2; up
Untergeschoss_Rollo_1                    dummy_Set                             2147        4    2273.58   568.39     0.00     0.00 04.02. 07:49:28 HASH(Untergeschoss_Rollo_1); Untergeschoss_Rollo_1; up
tmr-at_Exec                              HASH(0x404be98)                       2146        1    2146.51  2146.51  2495.73  2495.73 04.02. 08:30:04 HASH(Untergeschoss_Rollo_3_bei_Sonnenaufgang_hoch_AT)
Untergeschoss_Rollo_3                    dummy_Set                             2144        4    2244.05   561.01     0.00     0.00 04.02. 08:30:04 HASH(Untergeschoss_Rollo_3); Untergeschoss_Rollo_3; up
Wohnzimmer_Rollo_3_up_Notify             notify_Exec                           2079        2    2079.67  1039.83     0.00     0.00 04.02. 07:46:17 HASH(Wohnzimmer_Rollo_3_up_Notify); HASH(Wohnzimmer_Rollo_3)
Obergeschoss_Rollo_1_up_Notify           notify_Exec                           2051        1    2051.12  2051.12     0.00     0.00 04.02. 09:30:02 HASH(Obergeschoss_Rollo_1_up_Notify); HASH(Obergeschoss_Rollo_1)
Wohnzimmer_Rollo_1_up_Notify             notify_Exec                           2028        2    2028.71  1014.36     0.00     0.00 04.02. 07:46:12 HASH(Wohnzimmer_Rollo_1_up_Notify); HASH(Wohnzimmer_Rollo_1)
Untergeschoss_Rollo_2_up_Notify          notify_Exec                           2023        2    2023.74  1011.87     0.00     0.00 04.02. 07:46:10 HASH(Untergeschoss_Rollo_2_up_Notify); HASH(Untergeschoss_Rollo_2)
Untergeschoss_Rollo_3_up_Notify          notify_Exec                           2023        2    2023.27  1011.64     0.00     0.00 04.02. 08:30:04 HASH(Untergeschoss_Rollo_3_up_Notify); HASH(Untergeschoss_Rollo_3)
Wohnzimmer_Rollo_2_up_Notify             notify_Exec                           2022        2    2022.89  1011.45     0.00     0.00 04.02. 07:46:15 HASH(Wohnzimmer_Rollo_2_up_Notify); HASH(Wohnzimmer_Rollo_2)
Untergeschoss_Rollo_1_up_Notify          notify_Exec                           2020        2    2020.68  1010.34     0.00     0.00 04.02. 07:49:28 HASH(Untergeschoss_Rollo_1_up_Notify); HASH(Untergeschoss_Rollo_1)
CUL_0                                    CUL_Read                              1322     4160  938172.37   225.52     0.00     0.00 04.02. 12:22:11 HASH(CUL_0)
dblog_THB                                DbLog_Log                              893    97273 6635585.25    68.22     0.00     0.00 04.02. 11:29:45 HASH(dblog_THB); HASH(WU_IPLIEZ_3)
local_pybinding                          CODE(0x2fc7ef0)                        867   490175 5527962.98    11.28     0.00     0.00 04.02. 07:46:18 HASH(local_pybinding)
WEB_127.0.0.1_42872                      FW_Notify                              801    97273  400898.83     4.12     0.00     0.00 04.02. 07:46:18 HASH(WEB_127.0.0.1_42872); HASH(TUYA_JL27)
TUYA_JL27                                fhempy_Set                             799     1058    7777.51     7.35     0.00     0.00 04.02. 07:46:18 HASH(TUYA_JL27); ARRAY(0x4e64b00); HASH(0x43eb128)
TUYA_JL06                                fhempy_Set                             738     1826    9038.39     4.95     0.00     0.00 04.02. 07:46:18 HASH(TUYA_JL06); ARRAY(0x4eb4d98); HASH(0x514e3e8)
TUYA_JL15                                fhempy_Set                             679     5048   25678.02     5.09     0.00     0.00 04.02. 07:46:18 HASH(TUYA_JL15); ARRAY(0x508b360); HASH(0x5043300)
TUYA_JL31                                fhempy_Set                             617     5073   23350.83     4.60     0.00     0.00 04.02. 07:46:18 HASH(TUYA_JL31); ARRAY(0x4e600d8); HASH(0x4e5ccb8)
TUYA_JL13                                fhempy_Set                             553     2877   15014.83     5.22     0.00     0.00 04.02. 07:46:18 HASH(TUYA_JL13); ARRAY(0x460a018); HASH(0x513b260)
TUYA_JL29                                fhempy_Set                             463     5130   26481.28     5.16     0.00     0.00 04.02. 07:46:18 HASH(TUYA_JL29); ARRAY(0x4f3e4e8); HASH(0x4f8a9c8)
tmr-HUEBridge_GetUpdate                  HASH(0x2b45038)                        444      721  190054.52   263.60   557.76    11.25 04.02. 12:19:37 HASH(hueBridge1)


dblog ist gut dabei; das hat ach heute Nacht automatische dumps und dblogreduces gefahren
fhempy hat sehr hohe Werte entsprechend log:
2023-02-04 12:53:32,572 - WARNING  - asyncio: socket.send() raised exception.
2023-02-04 12:53:34,077 - WARNING  - asyncio: socket.send() raised exception.
2023-02-04 12:53:34,079 - WARNING  - asyncio: socket.send() raised exception.
2023-02-04 12:53:38,563 - WARNING  - asyncio: socket.send() raised exception.
2023-02-04 12:53:39,094 - WARNING  - asyncio: socket.send() raised exception.
2023-02-04 12:53:39,096 - WARNING  - asyncio: socket.send() raised exception.




WEB_127.0.0.1_42872                      FW_Notify                              801    97273  400898.83     4.12     0.00     0.00 04.02. 07:46:18 HASH(WEB_127.0.0.1_42872); HASH(TUYA_JL27) ist auffällig.
JL27 ist eine Steckdose direkt an meinem Schreibtisch und ist an.

ist da noch was Bedenkliches?


die fhemlog ist unauffällig

1 RASPI4B, 1 RASPI3B, 2 CUL, 2 Jeelink, 60 Tuya-Devices (Schalter, Dimmer, Sensoren, Cameras), 30 HUE-Lampen, 5 MAX! WTs, 16 MAX! HTs, 12 MAX! FKs, 1 Bresser 5in1, 1 OilFox, 8 ALEXA Echos und Dots, FHEM, 5 Tasmota-Devices, SonOff -Bridge, PowerFox, Buderus KM200

dominik

ehm...deine apptime Ausgaben sind ziemlich schlecht. Überall Laufzeiten (avg!) von über 1s!

Wenn ich apptime richtig verstehe, sollte in avg nie ein hoher Wert drin stehen. Ich bin dort im Normalfall überall im ms Bereich, du hast 3310.78 stehen, das sind 3,3s! Freezemon meldet sich da garnicht zu Wort?
fhempy -  https://github.com/fhempy/fhempy: GoogleCast, Tuya, UPnP, Ring, EQ3BT, Nespresso, Xiaomi, Spotify, Object Detection, ...
Kaffeespende: https://paypal.me/todominik

thburkhart

#843
Zitat von: dominik am 04 Februar 2023, 14:47:11
ehm...deine apptime Ausgaben sind ziemlich schlecht. Überall Laufzeiten (avg!) von über 1s!

Wenn ich apptime richtig verstehe, sollte in avg nie ein hoher Wert drin stehen. Ich bin dort im Normalfall überall im ms Bereich, du hast 3310.78 stehen, das sind 3,3s! Freezemon meldet sich da garnicht zu Wort?

active-timers: 80; max-active timers: 80; max-timer-load: 1  min-tmrHandlingTm: 0.2ms; max-tmrHandlingTm: 60.1ms; totAvgDly: 35.4ms

name                                     function                               max    count      total  average   maxDly   avgDly TS Max call     param Max call
CUL_0                                    CUL_Read                               341        5     798.55   159.71     0.00     0.00 04.02. 14:54:03 HASH(CUL_0)
local_pybinding                          CODE(0x42ed668)                        287      305    3383.83    11.09     0.00     0.00 04.02. 14:53:59 HASH(local_pybinding)
dblog_THB                                DbLog_Log                              277       38    2954.70    77.76     0.00     0.00 04.02. 14:54:03 HASH(dblog_THB); HASH(MAX_THOMAS_HT2)
WEB_127.0.0.1_36872                      FW_Notify                              125       38     281.31     7.40     0.00     0.00 04.02. 14:53:59 HASH(WEB_127.0.0.1_36872); HASH(TUYA_JL04)
TUYA_JL04                                fhempy_Set                             124       14     209.24    14.95     0.00     0.00 04.02. 14:53:59 HASH(TUYA_JL04); ARRAY(0x6038b50); HASH(0x6166088)
tmr-DbLog_execMemCacheAsync              HASH(0x1ae2620)                         60        1      60.01    60.01    70.50    70.50 04.02. 14:53:59 HASH(dblog_THB)
JLLaCR                                   JeeLink_Read                            52       32     143.27     4.48     0.00     0.00 04.02. 14:54:03 HASH(JLLaCR)
WEB_192.168.9.103_1111                   FW_Notify                               50       38     180.19     4.74     0.00     0.00 04.02. 14:53:59 HASH(WEB_192.168.9.103_1111); HASH(TUYA_JL04)
TUYA_JL31                                fhempy_Set                              10        4      25.30     6.32     0.00     0.00 04.02. 14:53:58 HASH(TUYA_JL31); ARRAY(0x60a67e8); HASH(0x5d4e578)
WEB_192.168.9.103_1070                   FW_Read                                  6        1       6.30     6.30     0.00     0.00 04.02. 14:53:48 HASH(WEB_192.168.9.103_1070)
TUYA_JL30                                fhempy_Set                               6        6      26.67     4.45     0.00     0.00 04.02. 14:53:50 HASH(TUYA_JL30); ARRAY(0x5c9cd70); HASH(0x6170c88)
TUYA_JL26                                fhempy_Set                               5        2      10.26     5.13     0.00     0.00 04.02. 14:53:54 HASH(TUYA_JL26); ARRAY(0x5fb6918); HASH(0x6233988)
MAX_THOMAS_HT2                           CODE(0x506de60)                          5        1       5.36     5.36     0.00     0.00 04.02. 14:54:03 HASH(MAX_THOMAS_HT2); MAX_THOMAS_HT2; ?
MAX_PETRA_HT1                            CODE(0x506de60)                          5        1       5.32     5.32     0.00     0.00 04.02. 14:54:01 HASH(MAX_PETRA_HT1); MAX_PETRA_HT1; ?
MAX_PETRA_WT                             CODE(0x506de60)                          5        1       5.29     5.29     0.00     0.00 04.02. 14:54:01 HASH(MAX_PETRA_WT); MAX_PETRA_WT; ?
TUYA_SP15                                fhempy_Set                               4        2       9.18     4.59     0.00     0.00 04.02. 14:53:59 HASH(TUYA_SP15); ARRAY(0x616a1f8); HASH(0x5d26e80)
TUYA_JL33                                fhempy_Set                               4        6      18.38     3.06     0.00     0.00 04.02. 14:54:02 HASH(TUYA_JL33); ARRAY(0x573b6b8); HASH(0x62cb388)
TUYA_JL29                                fhempy_Set                               3        6      16.31     2.72     0.00     0.00 04.02. 14:53:58 HASH(TUYA_JL29); ARRAY(0x5d34d80); HASH(0x5ce3748)
TUYA_JL06                                fhempy_Set                               3        2       6.46     3.23     0.00     0.00 04.02. 14:54:03 HASH(TUYA_JL06); ARRAY(0x5fc87d8); HASH(0x60b8db0)
TUYA_JL27                                fhempy_Set                               2        2       4.89     2.45     0.00     0.00 04.02. 14:54:02 HASH(TUYA_JL27); ARRAY(0x393b018); HASH(0x6011d20)
tuya_cloud_453005615002911dfe06          fhempy_Set                               2        1       2.43     2.43     0.00     0.00 04.02. 14:54:02 HASH(tuya_cloud_453005615002911dfe06); ARRAY(0x5341fd0); HASH(0x5d12bf8)
TUYA_JL13                                fhempy_Set                               2        2       4.75     2.38     0.00     0.00 04.02. 14:53:56 HASH(TUYA_JL13); ARRAY(0x6157bb8); HASH(0x5cf2780)
TUYA_JL15                                fhempy_Set                               2        4       9.35     2.34     0.00     0.00 04.02. 14:54:01 HASH(TUYA_JL15); ARRAY(0x5ccfae8); HASH(0x62c29a8)
TUYA_JL24                                fhempy_Set                               2        2       4.69     2.34     0.00     0.00 04.02. 14:54:02 HASH(TUYA_JL24); ARRAY(0x5746c98); HASH(0x61db738)
dblog_THB                                DbLog_Set                                1        1       1.27     1.27     0.00     0.00 04.02. 14:53:59 HASH(dblog_THB); dblog_THB; ?
Pythonbinding_15733                      fhempyServer_Read                        1        6       3.19     0.53     0.00     0.00 04.02. 14:54:03 HASH(Pythonbinding_15733)
WEB                                      FW_Read                                  0        1       0.67     0.67     0.00     0.00 04.02. 14:53:48 HASH(WEB)
dblog_THB                                DbLog_SBP_Read                           0        1       0.42     0.42     0.00     0.00 04.02. 14:53:59 HASH(dblog_THB)
EcoTaster_2_notify_auto                  notify_Exec                              0       38       4.15     0.11     0.00     0.00 04.02. 14:53:54 HASH(EcoTaster_2_notify_auto); HASH(TUYA_JL26)
EcoTaster_1_notify_auto                  notify_Exec                              0       38       6.23     0.16     0.00     0.00 04.02. 14:53:58 HASH(EcoTaster_1_notify_auto); HASH(TUYA_JL29)
EcoTaster_5_notify_auto                  notify_Exec                              0       38       3.89     0.10     0.00     0.00 04.02. 14:53:50 HASH(EcoTaster_5_notify_auto); HASH(TUYA_JL30)
alexa                                    alexa_Read                               0        1       0.26     0.26     0.00     0.00 04.02. 14:54:01 HASH(alexa)
EcoTaster_3_notify_eco                   notify_Exec                              0       38       3.75     0.10     0.00     0.00 04.02. 14:53:56 HASH(EcoTaster_3_notify_eco); HASH(TUYA_JL04)
n_defined                                notify_Exec                              0       38       5.45     0.14     0.00     0.00 04.02. 14:53:54 HASH(n_defined); HASH(TUYA_JL26)
EcoTaster_1_notify_eco                   notify_Exec                              0       38       4.08     0.11     0.00     0.00 04.02. 14:53:56 HASH(EcoTaster_1_notify_eco); HASH(TUYA_JL04)
EcoTaster_4_notify_auto                  notify_Exec                              0       38       3.72     0.10     0.00     0.00 04.02. 14:53:56 HASH(EcoTaster_4_notify_auto); HASH(TUYA_JL04)
EcoTaster_3_notify_auto                  notify_Exec                              0       38       3.72     0.10     0.00     0.00 04.02. 14:53:56 HASH(EcoTaster_3_notify_auto); HASH(TUYA_JL04)
EcoTaster_4_notify_eco                   notify_Exec                              0       38       3.60     0.09     0.00     0.00 04.02. 14:53:56 HASH(EcoTaster_4_notify_eco); HASH(TUYA_JL04)
EcoTaster_2_notify_eco                   notify_Exec                              0       38       3.74     0.10     0.00     0.00 04.02. 14:53:56 HASH(EcoTaster_2_notify_eco); HASH(TUYA_JL04)
EcoTaster_5_notify_eco                   notify_Exec                              0       38       3.58     0.09     0.00     0.00 04.02. 14:53:56 HASH(EcoTaster_5_notify_eco); HASH(TUYA_JL04)
EcoTaster_6_notify_eco                   notify_Exec                              0       38       3.48     0.09     0.00     0.00 04.02. 14:53:50 HASH(EcoTaster_6_notify_eco); HASH(TUYA_JL30)


ich hatte FHEM neu gestartet; also neues bild:
local_pybinding                          CODE(0x42ed668)                        287      305    3383.83    11.09     0.00     0.00 04.02. 14:53:59 HASH(local_pybinding)
hat den höchsten average Wert.

es wurden 3 doppelte "erkannt"

fhem-log:
2023.02.04 13:05:28 1: stacktrace:
2023.02.04 13:05:28 1:     main::readingsBulkUpdate            called by (eval 6528) (1)
2023.02.04 13:05:28 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:28 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:28 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:28 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:28 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (103)
2023.02.04 13:05:28 1:     main::fhempy_Attr                   called by fhem.pl (3976)
2023.02.04 13:05:28 1:     main::CallFn                        called by fhem.pl (3198)
2023.02.04 13:05:28 1:     main::CommandAttr                   called by (eval 6524) (1)
2023.02.04 13:05:28 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:28 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:28 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:28 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:28 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (103)
2023.02.04 13:05:28 1:     main::fhempy_Attr                   called by fhem.pl (3976)
2023.02.04 13:05:28 1:     main::CallFn                        called by fhem.pl (3198)
2023.02.04 13:05:28 1:     main::CommandAttr                   called by (eval 6520) (1)
2023.02.04 13:05:28 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:28 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:28 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:28 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:28 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:28 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:28 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:28 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:28 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:28 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:28 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:28 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:28 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:28 1:     main::DoTrigger                     called by fhem.pl (4995)
2023.02.04 13:05:28 1:     main::readingsEndUpdate             called by (eval 6518) (1)
2023.02.04 13:05:28 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:28 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:28 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (301)
2023.02.04 13:05:28 1:     main::BindingsIo_Read               called by fhem.pl (3976)
2023.02.04 13:05:28 1:     main::CallFn                        called by fhem.pl (784)
2023.02.04 13:05:28 1: readingsUpdate(TUYA_Temp02,va_temperature,19.9) missed to call readingsBeginUpdate first.
2023.02.04 13:05:28 1: stacktrace:
2023.02.04 13:05:28 1:     main::readingsBulkUpdate            called by (eval 6529) (1)
2023.02.04 13:05:28 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:28 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:28 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:28 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:28 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (103)
2023.02.04 13:05:28 1:     main::fhempy_Attr                   called by fhem.pl (3976)
2023.02.04 13:05:28 1:     main::CallFn                        called by fhem.pl (3198)
2023.02.04 13:05:28 1:     main::CommandAttr                   called by (eval 6524) (1)
2023.02.04 13:05:28 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:28 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:28 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:28 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:28 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (103)
2023.02.04 13:05:28 1:     main::fhempy_Attr                   called by fhem.pl (3976)
2023.02.04 13:05:28 1:     main::CallFn                        called by fhem.pl (3198)
2023.02.04 13:05:28 1:     main::CommandAttr                   called by (eval 6520) (1)
2023.02.04 13:05:28 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:28 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:28 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:28 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:28 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:28 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:28 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:28 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:28 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:28 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:28 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:28 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:28 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:28 1:     main::DoTrigger                     called by fhem.pl (4995)
2023.02.04 13:05:28 1:     main::readingsEndUpdate             called by (eval 6518) (1)
2023.02.04 13:05:28 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:28 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:28 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (301)
2023.02.04 13:05:28 1:     main::BindingsIo_Read               called by fhem.pl (3976)
2023.02.04 13:05:28 1:     main::CallFn                        called by fhem.pl (784)
2023.02.04 13:05:29 1: readingsUpdate(TUYA_Temp07,va_humidity,34.9) missed to call readingsBeginUpdate first.
2023.02.04 13:05:29 1: stacktrace:
2023.02.04 13:05:29 1:     main::readingsBulkUpdate            called by (eval 6543) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (3222)
2023.02.04 13:05:29 1:     main::CommandAttr                   called by (eval 6524) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (103)
2023.02.04 13:05:29 1:     main::fhempy_Attr                   called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3198)
2023.02.04 13:05:29 1:     main::CommandAttr                   called by (eval 6520) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (4995)
2023.02.04 13:05:29 1:     main::readingsEndUpdate             called by (eval 6518) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (301)
2023.02.04 13:05:29 1:     main::BindingsIo_Read               called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (784)
2023.02.04 13:05:29 1: readingsUpdate(TUYA_Temp02,humidity_value,42.0) missed to call readingsBeginUpdate first.
2023.02.04 13:05:29 1: stacktrace:
2023.02.04 13:05:29 1:     main::readingsBulkUpdate            called by (eval 6546) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (3222)
2023.02.04 13:05:29 1:     main::CommandAttr                   called by (eval 6524) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (103)
2023.02.04 13:05:29 1:     main::fhempy_Attr                   called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3198)
2023.02.04 13:05:29 1:     main::CommandAttr                   called by (eval 6520) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (4995)
2023.02.04 13:05:29 1:     main::readingsEndUpdate             called by (eval 6518) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (301)
2023.02.04 13:05:29 1:     main::BindingsIo_Read               called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (784)
2023.02.04 13:05:29 1: readingsUpdate(TUYA_SP13,cur_current,934.0) missed to call readingsBeginUpdate first.
2023.02.04 13:05:29 1: stacktrace:
2023.02.04 13:05:29 1:     main::readingsBulkUpdate            called by fhem.pl (5012)
2023.02.04 13:05:29 1:     main::readingsBulkUpdateIfChanged   called by (eval 6554) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (3222)
2023.02.04 13:05:29 1:     main::CommandAttr                   called by (eval 6520) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (4995)
2023.02.04 13:05:29 1:     main::readingsEndUpdate             called by (eval 6518) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (301)
2023.02.04 13:05:29 1:     main::BindingsIo_Read               called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (784)
2023.02.04 13:05:29 1: readingsUpdate(TUYA_Temp07,battery_percentage,34.0) missed to call readingsBeginUpdate first.
2023.02.04 13:05:29 1: stacktrace:
2023.02.04 13:05:29 1:     main::readingsBulkUpdate            called by (eval 6555) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (3222)
2023.02.04 13:05:29 1:     main::CommandAttr                   called by (eval 6520) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (4995)
2023.02.04 13:05:29 1:     main::readingsEndUpdate             called by (eval 6518) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (301)
2023.02.04 13:05:29 1:     main::BindingsIo_Read               called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (784)
2023.02.04 13:05:29 1: readingsUpdate(TUYA_Temp02,temp_unit_convert,c) missed to call readingsBeginUpdate first.
2023.02.04 13:05:29 1: stacktrace:
2023.02.04 13:05:29 1:     main::readingsBulkUpdate            called by (eval 6562) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (3222)
2023.02.04 13:05:29 1:     main::CommandAttr                   called by (eval 6520) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (4995)
2023.02.04 13:05:29 1:     main::readingsEndUpdate             called by (eval 6518) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (301)
2023.02.04 13:05:29 1:     main::BindingsIo_Read               called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (784)
2023.02.04 13:05:29 1: readingsUpdate(TUYA_SP13,cur_power,155.9) missed to call readingsBeginUpdate first.
2023.02.04 13:05:29 1: stacktrace:
2023.02.04 13:05:29 1:     main::readingsBulkUpdate            called by fhem.pl (5012)
2023.02.04 13:05:29 1:     main::readingsBulkUpdateIfChanged   called by (eval 6571) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (4995)
2023.02.04 13:05:29 1:     main::readingsEndUpdate             called by (eval 6564) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)
2023.02.04 13:05:29 1:     main::IOWrite                       called by ./FHEM/10_fhempy.pm (91)
2023.02.04 13:05:29 1:     main::fhempy_Set                    called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (1954)
2023.02.04 13:05:29 1:     main::DoSet                         called by fhem.pl (1996)
2023.02.04 13:05:29 1:     main::CommandSet                    called by fhem.pl (2904)
2023.02.04 13:05:29 1:     main::getAllSets                    called by ./FHEM/01_FHEMWEB.pm (3331)
2023.02.04 13:05:29 1:     main::FW_devState                   called by ./FHEM/01_FHEMWEB.pm (3223)
2023.02.04 13:05:29 1:     main::FW_Notify                     called by fhem.pl (3976)
2023.02.04 13:05:29 1:     main::CallFn                        called by fhem.pl (3888)
2023.02.04 13:05:29 1:     main::DoTrigger                     called by fhem.pl (3222)
2023.02.04 13:05:29 1:     main::CommandAttr                   called by (eval 6520) (1)
2023.02.04 13:05:29 1:     (eval)                              called by ./FHEM/10_BindingsIo.pm (576)
2023.02.04 13:05:29 1:     main::BindingsIo_processMessage     called by ./FHEM/10_BindingsIo.pm (686)
2023.02.04 13:05:29 1:     main::BindingsIo_readWebsocketMessage called by ./FHEM/10_BindingsIo.pm (430)
2023.02.04 13:05:29 1:     main::BindingsIo_Write              called by fhem.pl (1064)


[code]- asyn
1 RASPI4B, 1 RASPI3B, 2 CUL, 2 Jeelink, 60 Tuya-Devices (Schalter, Dimmer, Sensoren, Cameras), 30 HUE-Lampen, 5 MAX! WTs, 16 MAX! HTs, 12 MAX! FKs, 1 Bresser 5in1, 1 OilFox, 8 ALEXA Echos und Dots, FHEM, 5 Tasmota-Devices, SonOff -Bridge, PowerFox, Buderus KM200

thburkhart

wie kann ich denn die Ergebnisse von perfmon sehen?

wie kann ich fhempy temporär deaktivieren, um die Systenlast ohne fhempy zu ermitteln?
1 RASPI4B, 1 RASPI3B, 2 CUL, 2 Jeelink, 60 Tuya-Devices (Schalter, Dimmer, Sensoren, Cameras), 30 HUE-Lampen, 5 MAX! WTs, 16 MAX! HTs, 12 MAX! FKs, 1 Bresser 5in1, 1 OilFox, 8 ALEXA Echos und Dots, FHEM, 5 Tasmota-Devices, SonOff -Bridge, PowerFox, Buderus KM200

dominik

Nicht auf total schauen. Avg ist der Wert der wichtig ist. Bzw. max natürlich auch.

Lass mal paar Stunden laufen und mach dann wieder apptime. Und lass freezemon laufen.
fhempy -  https://github.com/fhempy/fhempy: GoogleCast, Tuya, UPnP, Ring, EQ3BT, Nespresso, Xiaomi, Spotify, Object Detection, ...
Kaffeespende: https://paypal.me/todominik

dominik

Ich denke irgendwas blockiert fhem beim Start.
fhempy -  https://github.com/fhempy/fhempy: GoogleCast, Tuya, UPnP, Ring, EQ3BT, Nespresso, Xiaomi, Spotify, Object Detection, ...
Kaffeespende: https://paypal.me/todominik

thburkhart

Zitat von: dominik am 04 Februar 2023, 16:00:41
Nicht auf total schauen. Avg ist der Wert der wichtig ist. Bzw. max natürlich auch.

Lass mal paar Stunden laufen und mach dann wieder apptime. Und lass freezemon laufen.

so nun kommt mein Morgen-Bericht:

fhempy-log

2023-02-05 04:21:42,123 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 04:21:44,220 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 04:21:49,285 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 04:21:52,213 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 04:21:52,906 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 04:21:54,482 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 06:01:31,837 - ERROR    - fhempy.lib.fhem: FHEM took 5290ms for readingsBulkUpdate($defs{'tuya_cloud_3367036770039f60ce2b'},'cur_power','64.0');;
2023-02-05 06:01:31,838 - ERROR    - fhempy.lib.fhem: FHEM took 5175ms for readingsBeginUpdate($defs{'TUYA_JL31'});;
2023-02-05 07:44:58,000 - ERROR    - fhempy.lib.fhem: FHEM took 11985ms for readingsBeginUpdate($defs{'TUYA_SP16'});;
2023-02-05 07:44:58,000 - ERROR    - fhempy.lib.fhem: FHEM took 11272ms for readingsBeginUpdate($defs{'TUYA_SP11'});;
2023-02-05 07:44:58,001 - ERROR    - fhempy.lib.fhem: FHEM took 11035ms for readingsBeginUpdate($defs{'TUYA_JL06'});;
2023-02-05 07:44:58,001 - ERROR    - fhempy.lib.fhem: FHEM took 10085ms for readingsBeginUpdate($defs{'TUYA_SP15'});;
2023-02-05 07:44:58,003 - ERROR    - fhempy.lib.fhem: FHEM took 9622ms for readingsBeginUpdate($defs{'TUYA_JL30'});;
2023-02-05 07:44:58,004 - ERROR    - fhempy.lib.fhem: FHEM took 9511ms for readingsBeginUpdate($defs{'TUYA_JL18'});;
2023-02-05 07:44:58,005 - ERROR    - fhempy.lib.fhem: FHEM took 9159ms for readingsBeginUpdate($defs{'tuya_cloud_3367036770039f60ce2b'});;
2023-02-05 07:44:58,006 - ERROR    - fhempy.lib.fhem: FHEM took 9036ms for readingsBeginUpdate($defs{'TUYA_JL29'});;
2023-02-05 07:44:58,007 - ERROR    - fhempy.lib.fhem: FHEM took 8925ms for readingsBeginUpdate($defs{'TUYA_JL27'});;
2023-02-05 07:44:58,009 - ERROR    - fhempy.lib.fhem: FHEM took 8699ms for readingsBeginUpdate($defs{'TUYA_SP13'});;
2023-02-05 07:44:58,010 - ERROR    - fhempy.lib.fhem: FHEM took 8699ms for readingsBeginUpdate($defs{'TUYA_SP14'});;
2023-02-05 07:44:58,011 - ERROR    - fhempy.lib.fhem: FHEM took 8347ms for readingsBeginUpdate($defs{'TUYA_JL04'});;
2023-02-05 07:44:58,012 - ERROR    - fhempy.lib.fhem: FHEM took 8348ms for readingsBeginUpdate($defs{'TUYA_JL33'});;
2023-02-05 07:44:58,014 - ERROR    - fhempy.lib.fhem: FHEM took 7541ms for readingsBeginUpdate($defs{'TUYA_JL31'});;
2023-02-05 07:44:58,115 - ERROR    - fhempy.lib.fhem: FHEM took 6094ms for readingsBeginUpdate($defs{'TUYA_JL15'});;
2023-02-05 07:44:58,117 - ERROR    - fhempy.lib.fhem: FHEM took 5983ms for readingsBeginUpdate($defs{'TUYA_JL07'});;
2023-02-05 08:30:05,156 - ERROR    - fhempy.lib.fhem: FHEM took 5121ms for readingsBeginUpdate($defs{'TUYA_JL30'});;


fhem.log
2023.02.05 00:00:31 3: alexa: using logfile: ./log/alexa-2023-02-05.log
2023.02.05 00:00:50 3: TelegramBot_Callback TelegramBotTHB: Digest: Number of poll failures on 2023-02-04 is :1:
2023.02.05 00:01:41 1: PERL WARNING: Subroutine HandleTimeout redefined at ./FHEM/98_apptime.pm line 45.
2023.02.05 00:01:41 1: PERL WARNING: Subroutine CallFn redefined at ./FHEM/98_apptime.pm line 122.
2023.02.05 00:04:41 1: [Freezemon] myFreezemon: possible freeze starting at 00:04:40, delay is 1.103 possibly caused by: tmr-MQTT2_SERVER_keepaliveChecker(MQTT2)
2023.02.05 00:07:34 1: [Freezemon] myFreezemon: possible freeze starting at 00:07:26, delay is 8.084 possibly caused by: no bad guy found :-(
2023.02.05 00:10:00 3: DbRep ReportDbLog_THB - ################################################################
2023.02.05 00:10:00 3: DbRep ReportDbLog_THB - ###             New database clientSide dump                 ###
2023.02.05 00:10:00 3: DbRep ReportDbLog_THB - ################################################################
2023.02.05 00:10:00 3: DbRep ReportDbLog_THB - Starting dump of database 'fhem'
2023.02.05 00:10:00 4: DbRep ReportDbLog_THB - Database connect - user: fhemuser, UTF-8 option set: yes
2023.02.05 00:10:00 4: DbRep ReportDbLog_THB - SQL execute: SELECT VERSION()
2023.02.05 00:10:00 4: DbRep ReportDbLog_THB - SQL execute: SET NAMES 'utf8'
2023.02.05 00:10:00 4: DbRep ReportDbLog_THB - SQL execute: SHOW VARIABLES LIKE 'character_set_connection'
2023.02.05 00:10:00 3: DbRep ReportDbLog_THB - Characterset of collection set to utf8.
2023.02.05 00:10:00 3: DbRep ReportDbLog_THB - Searching for tables inside database fhem....
2023.02.05 00:10:00 4: DbRep ReportDbLog_THB - SQL execute: SHOW TABLE STATUS FROM `fhem`
2023.02.05 00:10:00 4: DbRep ReportDbLog_THB - SQL execute: SELECT count(*) FROM `current`
2023.02.05 00:10:00 4: DbRep ReportDbLog_THB - SQL execute: SELECT count(*) FROM `history`
2023.02.05 00:10:04 3: DbRep ReportDbLog_THB - Found 2 tables with 1939011 records.
2023.02.05 00:10:04 3: DbRep ReportDbLog_THB - Dumping table current (Type InnoDB):
2023.02.05 00:10:04 4: DbRep ReportDbLog_THB - SQL execute: SHOW CREATE TABLE `current`
2023.02.05 00:10:04 4: DbRep ReportDbLog_THB - SQL execute: SHOW FIELDS FROM `current`
2023.02.05 00:10:04 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `current` LIMIT 0,10000;
2023.02.05 00:10:04 3: DbRep ReportDbLog_THB - 1146 records inserted (size of backupfile: 233.65 KB)
2023.02.05 00:10:04 3: DbRep ReportDbLog_THB - Dumping table history (Type InnoDB):
2023.02.05 00:10:04 4: DbRep ReportDbLog_THB - SQL execute: SHOW CREATE TABLE `history`
2023.02.05 00:10:04 4: DbRep ReportDbLog_THB - SQL execute: SHOW FIELDS FROM `history`
2023.02.05 00:10:04 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 0,10000;
2023.02.05 00:10:04 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 10000,10000;
2023.02.05 00:10:05 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 20000,10000;
2023.02.05 00:10:05 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 30000,10000;
2023.02.05 00:10:05 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 40000,10000;
2023.02.05 00:10:06 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 50000,10000;
2023.02.05 00:10:06 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 60000,10000;
2023.02.05 00:10:06 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 70000,10000;
2023.02.05 00:10:07 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 80000,10000;
2023.02.05 00:10:07 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 90000,10000;
2023.02.05 00:10:07 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 100000,10000;
2023.02.05 00:10:08 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 110000,10000;
2023.02.05 00:10:08 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 120000,10000;
2023.02.05 00:10:09 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 130000,10000;
2023.02.05 00:10:09 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 140000,10000;
2023.02.05 00:10:10 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 150000,10000;
2023.02.05 00:10:10 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 160000,10000;
2023.02.05 00:10:11 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 170000,10000;
2023.02.05 00:10:11 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 180000,10000;
2023.02.05 00:10:12 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 190000,10000;
2023.02.05 00:10:12 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 200000,10000;
2023.02.05 00:10:13 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 210000,10000;
2023.02.05 00:10:13 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 220000,10000;
2023.02.05 00:10:14 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 230000,10000;
2023.02.05 00:10:15 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 240000,10000;
2023.02.05 00:10:15 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 250000,10000;
2023.02.05 00:10:16 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 260000,10000;
2023.02.05 00:10:17 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 270000,10000;
2023.02.05 00:10:17 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 280000,10000;
2023.02.05 00:10:18 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 290000,10000;
2023.02.05 00:10:19 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 300000,10000;
2023.02.05 00:10:20 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 310000,10000;
2023.02.05 00:10:20 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 320000,10000;
2023.02.05 00:10:21 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 330000,10000;
2023.02.05 00:10:22 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 340000,10000;
2023.02.05 00:10:23 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 350000,10000;
2023.02.05 00:10:23 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 360000,10000;
2023.02.05 00:10:24 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 370000,10000;
2023.02.05 00:10:25 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 380000,10000;
2023.02.05 00:10:26 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 390000,10000;
2023.02.05 00:10:27 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 400000,10000;
2023.02.05 00:10:28 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 410000,10000;
2023.02.05 00:10:29 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 420000,10000;
2023.02.05 00:10:30 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 430000,10000;
2023.02.05 00:10:31 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 440000,10000;
2023.02.05 00:10:31 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 450000,10000;
2023.02.05 00:10:32 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 460000,10000;
2023.02.05 00:10:33 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 470000,10000;
2023.02.05 00:10:34 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 480000,10000;
2023.02.05 00:10:35 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 490000,10000;
2023.02.05 00:10:36 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 500000,10000;
2023.02.05 00:10:38 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 510000,10000;
2023.02.05 00:10:39 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 520000,10000;
2023.02.05 00:10:40 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 530000,10000;
2023.02.05 00:10:41 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 540000,10000;
2023.02.05 00:10:42 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 550000,10000;
2023.02.05 00:10:43 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 560000,10000;
2023.02.05 00:10:44 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 570000,10000;
2023.02.05 00:10:45 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 580000,10000;
2023.02.05 00:10:46 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 590000,10000;
2023.02.05 00:10:48 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 600000,10000;
2023.02.05 00:10:49 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 610000,10000;
2023.02.05 00:10:50 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 620000,10000;
2023.02.05 00:10:51 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 630000,10000;
2023.02.05 00:10:53 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 640000,10000;
2023.02.05 00:10:54 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 650000,10000;
2023.02.05 00:10:55 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 660000,10000;
2023.02.05 00:10:56 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 670000,10000;
2023.02.05 00:10:58 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 680000,10000;
2023.02.05 00:10:59 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 690000,10000;
2023.02.05 00:11:00 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 700000,10000;
2023.02.05 00:11:02 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 710000,10000;
2023.02.05 00:11:03 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 720000,10000;
2023.02.05 00:11:05 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 730000,10000;
2023.02.05 00:11:06 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 740000,10000;
2023.02.05 00:11:07 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 750000,10000;
2023.02.05 00:11:09 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 760000,10000;
2023.02.05 00:11:10 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 770000,10000;
2023.02.05 00:11:12 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 780000,10000;
2023.02.05 00:11:13 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 790000,10000;
2023.02.05 00:11:15 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 800000,10000;
2023.02.05 00:11:16 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 810000,10000;
2023.02.05 00:11:18 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 820000,10000;
2023.02.05 00:11:20 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 830000,10000;
2023.02.05 00:11:21 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 840000,10000;
2023.02.05 00:11:23 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 850000,10000;
2023.02.05 00:11:24 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 860000,10000;
2023.02.05 00:11:26 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 870000,10000;
2023.02.05 00:11:28 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 880000,10000;
2023.02.05 00:11:29 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 890000,10000;
2023.02.05 00:11:31 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 900000,10000;
2023.02.05 00:11:33 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 910000,10000;
2023.02.05 00:11:34 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 920000,10000;
2023.02.05 00:11:36 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 930000,10000;
2023.02.05 00:11:38 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 940000,10000;
2023.02.05 00:11:39 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 950000,10000;
2023.02.05 00:11:41 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 960000,10000;
2023.02.05 00:11:43 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 970000,10000;
2023.02.05 00:11:45 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 980000,10000;
2023.02.05 00:11:47 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 990000,10000;
2023.02.05 00:11:49 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1000000,10000;
2023.02.05 00:11:52 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1010000,10000;
2023.02.05 00:11:54 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1020000,10000;
2023.02.05 00:11:57 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1030000,10000;
2023.02.05 00:11:59 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1040000,10000;
2023.02.05 00:12:01 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1050000,10000;
2023.02.05 00:12:04 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1060000,10000;
2023.02.05 00:12:06 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1070000,10000;
2023.02.05 00:12:09 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1080000,10000;
2023.02.05 00:12:12 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1090000,10000;
2023.02.05 00:12:14 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1100000,10000;
2023.02.05 00:12:17 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1110000,10000;
2023.02.05 00:12:19 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1120000,10000;
2023.02.05 00:12:22 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1130000,10000;
2023.02.05 00:12:25 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1140000,10000;
2023.02.05 00:12:27 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1150000,10000;
2023.02.05 00:12:30 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1160000,10000;
2023.02.05 00:12:33 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1170000,10000;
2023.02.05 00:12:36 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1180000,10000;
2023.02.05 00:12:39 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1190000,10000;
2023.02.05 00:12:41 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1200000,10000;
2023.02.05 00:12:44 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1210000,10000;
2023.02.05 00:12:47 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1220000,10000;
2023.02.05 00:12:50 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1230000,10000;
2023.02.05 00:12:53 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1240000,10000;
2023.02.05 00:12:56 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1250000,10000;
2023.02.05 00:12:59 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1260000,10000;
2023.02.05 00:13:02 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1270000,10000;
2023.02.05 00:13:05 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1280000,10000;
2023.02.05 00:13:08 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1290000,10000;
2023.02.05 00:13:11 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1300000,10000;
2023.02.05 00:13:14 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1310000,10000;
2023.02.05 00:13:18 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1320000,10000;
2023.02.05 00:13:21 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1330000,10000;
2023.02.05 00:13:24 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1340000,10000;
2023.02.05 00:13:27 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1350000,10000;
2023.02.05 00:13:30 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1360000,10000;
2023.02.05 00:13:34 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1370000,10000;
2023.02.05 00:13:37 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1380000,10000;
2023.02.05 00:13:40 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1390000,10000;
2023.02.05 00:13:44 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1400000,10000;
2023.02.05 00:13:47 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1410000,10000;
2023.02.05 00:13:50 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1420000,10000;
2023.02.05 00:13:54 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1430000,10000;
2023.02.05 00:13:57 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1440000,10000;
2023.02.05 00:14:01 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1450000,10000;
2023.02.05 00:14:04 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1460000,10000;
2023.02.05 00:14:08 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1470000,10000;
2023.02.05 00:14:12 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1480000,10000;
2023.02.05 00:14:15 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1490000,10000;
2023.02.05 00:14:19 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1500000,10000;
2023.02.05 00:14:22 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1510000,10000;
2023.02.05 00:14:26 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1520000,10000;
2023.02.05 00:14:30 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1530000,10000;
2023.02.05 00:14:34 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1540000,10000;
2023.02.05 00:14:37 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1550000,10000;
2023.02.05 00:14:41 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1560000,10000;
2023.02.05 00:14:45 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1570000,10000;
2023.02.05 00:14:49 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1580000,10000;
2023.02.05 00:14:53 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1590000,10000;
2023.02.05 00:14:57 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1600000,10000;
2023.02.05 00:15:01 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1610000,10000;
2023.02.05 00:15:05 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1620000,10000;
2023.02.05 00:15:09 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1630000,10000;
2023.02.05 00:15:13 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1640000,10000;
2023.02.05 00:15:17 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1650000,10000;
2023.02.05 00:15:21 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1660000,10000;
2023.02.05 00:15:25 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1670000,10000;
2023.02.05 00:15:29 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1680000,10000;
2023.02.05 00:15:33 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1690000,10000;
2023.02.05 00:15:37 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1700000,10000;
2023.02.05 00:15:42 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1710000,10000;
2023.02.05 00:15:46 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1720000,10000;
2023.02.05 00:15:50 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1730000,10000;
2023.02.05 00:15:55 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1740000,10000;
2023.02.05 00:15:59 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1750000,10000;
2023.02.05 00:16:03 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1760000,10000;
2023.02.05 00:16:08 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1770000,10000;
2023.02.05 00:16:12 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1780000,10000;
2023.02.05 00:16:16 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1790000,10000;
2023.02.05 00:16:21 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1800000,10000;
2023.02.05 00:16:25 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1810000,10000;
2023.02.05 00:16:30 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1820000,10000;
2023.02.05 00:16:34 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1830000,10000;
2023.02.05 00:16:39 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1840000,10000;
2023.02.05 00:16:44 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1850000,10000;
2023.02.05 00:16:48 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1860000,10000;
2023.02.05 00:16:53 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1870000,10000;
2023.02.05 00:16:57 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1880000,10000;
2023.02.05 00:17:02 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1890000,10000;
2023.02.05 00:17:07 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1900000,10000;
2023.02.05 00:17:12 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1910000,10000;
2023.02.05 00:17:17 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1920000,10000;
2023.02.05 00:17:21 4: DbRep ReportDbLog_THB - SQL execute: SELECT * FROM `history` LIMIT 1930000,10000;
2023.02.05 00:17:26 3: DbRep ReportDbLog_THB - 1937865 records inserted (size of backupfile: 383.78 MB)
2023.02.05 00:17:26 3: DbRep ReportDbLog_THB - Deleting old dumpfile 'fhem_2023_02_02_00_10.sql'
2023.02.05 00:17:26 3: DbRep ReportDbLog_THB - Finished backup of database fhem - total time used (hh:mm:ss): 00:07:26
2023.02.05 00:17:27 3: DbRep ReportDbLog_THB - Database dump finished successfully.
2023.02.05 00:44:25 2: Buderus : ERROR: Service: /system/sensors/temperatures/supply_t1: No proper Communication with Gateway: read from http://192.168.9.114:80 timed out
2023.02.05 01:29:18 2: Buderus : ERROR: Service: /heatingCircuits/hc1/roomtemperature: No proper Communication with Gateway: read from http://192.168.9.114:80 timed out
2023.02.05 02:09:05 1: [Freezemon] myFreezemon: possible freeze starting at 02:09:04, delay is 1.031 possibly caused by: tmr-CODE(0x4bab278)(GetUpdate)
2023.02.05 02:20:53 3: MaxSystem, Send Queue missing ack from MAX_HCH_HT for TimeInformation, removing from queue
2023.02.05 03:00:00 2: DbLog dblog_THB - Connection closed until 03:00:00 (86400 seconds).
2023.02.05 03:00:00 3: DbLog dblog_THB - Database disconnected by request.
2023.02.05 03:01:07 3: DbLog dblog_THB - Reopen requested
2023.02.05 03:01:08 2: DbRep Reduce_Daily - command message after reduceLog: "Reopen executed."
2023.02.05 03:01:08 3: DbLog dblog_THB - Database disconnected by request.
2023.02.05 03:01:08 3: DbLog dblog_THB - SubProcess connected to fhem
2023.02.05 03:19:13 1: PERL WARNING: Use of uninitialized value in numeric ge (>=) at ./FHEM/12_OilFox.pm line 387.
2023.02.05 03:19:13 2: LOGIN TOKEN MISSING OR EXPIRED
2023.02.05 03:19:13 3: Reconnecting...
2023.02.05 03:19:13 2: {"token_type":"Bearer","access_token":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzUxMiJ9.eyJzdWIiOiJiNmU5ZDg5Ni1kMzMwLTRiMWYtODVhNS0wYjk4NGQyZWJiMDQiLCJhdWQiOiJVU0VSIiwic2NvcGUiOiJDVVNUT01FUl9BUEkiLCJpc3MiOiJvaWxmb3guaW8iLCJleHAiOjE2NzU1NjQ0NTMsImp0aSI6IjE2NTBkYjRmLTQ0MDQtNGE4Ny04YjMyLWE0MDEzNWQxYWQzMSIsImVtYWlsIjoiYmt0QGdteC5uZXQifQ.QLqU_qMrJrIbxJKI3Khimc2KdmsA2P9oBibc2R-1PZkn-m8UGhso1pHr9vG7zrCMBAtKng87C4ExxL2kt633Fg","refresh_token":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzUxMiJ9.eyJzY29wZSI6IkNVU1RPTUVSX0FQSSIsImlzcyI6Im9pbGZveC5pbyIsImV4cCI6MTY3Njg1OTU1MywianRpIjoiZWJiOTBjOWUtMmE0NC00OTBmLWE3M2YtY2I2MTlmODdkYjQxIn0.OunwjSz7ntnnJmBAb-QW587f-9BcRBF7GpL4YOscrcj9UC3baejq9Ht2IZDdseEsVdm5cP1ucbOVEBhAOEZBYA"}
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - ################################################################
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - ###                    new reduceLog run                     ###
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - ################################################################
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - get initial structure information of database "fhem", remaining attempts: 3
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - Connectiontest to database mysql:database=fhem;host=localhost;port=3306 with user fhemuser
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - Index Report_Idx exists. Check ok
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - Initial data information retrieved - total time used: 0.0124 seconds
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - Connectiontest to db mysql:database=fhem;host=localhost;port=3306 successful
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - execute command before reduceLog: 'set dblog_THB reopen 86400'
2023.02.05 04:00:00 2: DbLog dblog_THB - Connection closed until 04:00:00 (86400 seconds).
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - reduce data older than: 2023-01-29 23:59:59, newer than: 2023-01-27 00:00:00
2023.02.05 04:00:00 3: DbRep Reduce_Hourly - reduceLog requested with options:
average=day
INCLUDE -> Devs: % Readings: %
2023.02.05 04:00:00 3: DbLog dblog_THB - Database disconnected by request.
2023.02.05 04:00:03 3: DbRep Reduce_Hourly - reduceLog deleting 67327 records of day: 2023-01-29
2023.02.05 04:00:08 3: DbRep Reduce_Hourly - reduceLog deletion progress of day: 2023-01-29 is: 10000
2023.02.05 04:00:13 3: DbRep Reduce_Hourly - reduceLog deletion progress of day: 2023-01-29 is: 20000
2023.02.05 04:00:18 3: DbRep Reduce_Hourly - reduceLog deletion progress of day: 2023-01-29 is: 30000
2023.02.05 04:00:23 3: DbRep Reduce_Hourly - reduceLog deletion progress of day: 2023-01-29 is: 40000
2023.02.05 04:00:28 3: DbRep Reduce_Hourly - reduceLog deletion progress of day: 2023-01-29 is: 50000
2023.02.05 04:00:33 3: DbRep Reduce_Hourly - reduceLog deletion progress of day: 2023-01-29 is: 60000
2023.02.05 04:00:38 3: DbRep Reduce_Hourly - reduceLog (hourly-average) updating 2193 records of day: 2023-01-29
2023.02.05 04:00:39 3: DbRep Reduce_Hourly - reduceLog (hourly-average) updating progress of day: 2023-01-29 is: 1000
2023.02.05 04:00:40 3: DbRep Reduce_Hourly - reduceLog (hourly-average) updating progress of day: 2023-01-29 is: 2000
2023.02.05 04:00:40 3: DbRep Reduce_Hourly - reduceLog (daily-average) updating 236, deleting 3524 records of day: 2023-01-29
2023.02.05 04:00:41 3: DbRep Reduce_Hourly - reduceLog (daily-average) deleting progress of day: 2023-01-29 is: 1000
2023.02.05 04:00:41 3: DbRep Reduce_Hourly - reduceLog (daily-average) updating progress of day: 2023-01-29 is: 100
2023.02.05 04:00:42 3: DbRep Reduce_Hourly - reduceLog (daily-average) deleting progress of day: 2023-01-29 is: 2000
2023.02.05 04:00:42 3: DbRep Reduce_Hourly - reduceLog (daily-average) updating progress of day: 2023-01-29 is: 200
2023.02.05 04:00:42 3: DbRep Reduce_Hourly - reduceLog (daily-average) deleting progress of day: 2023-01-29 is: 3000
2023.02.05 04:00:43 3: DbRep Reduce_Hourly - reduceLog finished. Rows processed: 77661, deleted: 70851, updated: 2429
2023.02.05 04:00:43 3: DbLog dblog_THB - Reopen requested
2023.02.05 04:00:43 2: DbRep Reduce_Hourly - command message after reduceLog: "Reopen executed."
2023.02.05 04:00:43 3: DbLog dblog_THB - Database disconnected by request.
2023.02.05 04:00:43 3: DbLog dblog_THB - SubProcess connected to fhem
2023.02.05 05:00:00 2: DbLog dblog_THB - WARNING - "count" is outdated. Please consider use of DbRep "set <Name> countEntries" instead.
2023.02.05 06:00:00 3: DbLog dblog_THB - Reopen requested
2023.02.05 06:00:00 3: set dblog_THB reopen : Reopen executed.
2023.02.05 06:00:00 3: DbLog_reopen: Reopen executed.
2023.02.05 06:00:00 3: DbLog dblog_THB - Database disconnected by request.
2023.02.05 06:00:00 3: DbLog dblog_THB - SubProcess connected to fhem
2023.02.05 06:01:31 1: [Freezemon] myFreezemon: possible freeze starting at 06:01:27, delay is 4.516 possibly caused by: no bad guy found :-(
2023.02.05 06:31:51 2: Buderus : ERROR: Service: /heatSources/actualModulation: No proper Communication with Gateway: read from http://192.168.9.114:80 timed out
2023.02.05 06:44:07 1: [Freezemon] myFreezemon: possible freeze starting at 06:44:06, delay is 1.047 possibly caused by: no bad guy found :-(
2023.02.05 06:47:01 1: [Freezemon] myFreezemon: possible freeze starting at 06:47:00, delay is 1.003 possibly caused by: no bad guy found :-(
2023.02.05 07:04:11 1: [Freezemon] myFreezemon: possible freeze starting at 07:04:10, delay is 1.025 possibly caused by: no bad guy found :-(
2023.02.05 07:20:55 3: MaxSystem, Send Queue missing ack from MAX_Wohnzimmer_O for TimeInformation, removing from queue
2023.02.05 07:44:48 3: Untergeschoss_Rollo_Alle_up_Notify return value: OK
2023.02.05 07:44:50 3: Untergeschoss_Rollo_2_up_Notify return value: OK
2023.02.05 07:44:52 3: Wohnzimmer_Rollo_1_up_Notify return value: OK
2023.02.05 07:44:55 3: Wohnzimmer_Rollo_2_up_Notify return value: OK
2023.02.05 07:44:57 3: Wohnzimmer_Rollo_3_up_Notify return value: OK
2023.02.05 07:44:57 1: [Freezemon] myFreezemon: possible freeze starting at 07:44:46, delay is 11.456 possibly caused by: tmr-at_Exec(Untergeschoss_Rollos_bei_Sonnenaufgang_hoch_AT) tmr-at_Exec(Untergeschoss_Rollo_2_bei_Sonnenaufgang_hoch_AT) tmr-at_Exec(Wohnzimmer_Rollo_1_bei_Sonnenaufgang_hoch_AT) tmr-at_Exec(Wohnzimmer_Rollo_2_bei_Sonnenaufgang_hoch_AT) tmr-at_Exec(Wohnzimmer_Rollo_3_bei_Sonnenaufgang_hoch_AT)
2023.02.05 07:48:08 3: Untergeschoss_Rollo_1_up_Notify return value: OK
2023.02.05 07:48:08 1: [Freezemon] myFreezemon: possible freeze starting at 07:48:06, delay is 2.248 possibly caused by: tmr-at_Exec(Untergeschoss_Rollo_1_bei_Sonnenaufgang_hoch_AT)
2023.02.05 08:04:48 3: Kueche_Rollo_3_up_Notify return value: OK
2023.02.05 08:04:48 1: [Freezemon] myFreezemon: possible freeze starting at 08:04:46, delay is 2.352 possibly caused by: tmr-at_Exec(Kueche_Rollo_3_bei_Sonnenaufgang_hoch_AT)
2023.02.05 08:22:11 1: [Freezemon] myFreezemon: possible freeze starting at 08:22:10, delay is 1.394 possibly caused by: no bad guy found :-(
2023.02.05 08:27:19 1: [Freezemon] myFreezemon: possible freeze starting at 08:27:18, delay is 1.017 possibly caused by: tmr-HUEBridge_GetUpdate(hueBridge1)
2023.02.05 08:30:02 3: Wohnzimmer_Rollo_Alle_up_Notify return value: OK
2023.02.05 08:30:04 3: Untergeschoss_Rollo_3_up_Notify return value: OK
2023.02.05 08:30:04 1: [Freezemon] myFreezemon: possible freeze starting at 08:30:00, delay is 4.695 possibly caused by: tmr-at_Exec(Wohnzimmer_Rollos_bei_Sonnenaufgang_hoch_AT) tmr-at_Exec(Untergeschoss_Rollo_3_bei_Sonnenaufgang_hoch_AT)
2023.02.05 09:17:45 1: [Freezemon] myFreezemon: possible freeze starting at 09:17:39, delay is 6.668 possibly caused by: no bad guy found :-(
2023.02.05 09:18:56 1: [Freezemon] myFreezemon: possible freeze starting at 09:18:45, delay is 11.05 possibly caused by: no bad guy found :-(
2023.02.05 09:19:20 1: [Freezemon] myFreezemon: possible freeze starting at 09:19:19, delay is 1.004 possibly caused by: tmr-HUEBridge_GetUpdate(hueBridge1)
2023.02.05 09:30:02 3: Obergeschoss_Rollo_1_up_Notify return value: OK
2023.02.05 09:30:02 1: [Freezemon] myFreezemon: possible freeze starting at 09:30:00, delay is 2.188 possibly caused by: tmr-CUL_MAX_Alive(MaxSystem) tmr-MAX_Timer(MAX_BAD_WT) tmr-MAX_Timer(MAX_BAD_HT) tmr-MAX_Timer(MAX_DIELE_HT) tmr-MAX_Timer(MAX_HWR_HT) tmr-MAX_Timer(MAX_HCH_HT) tmr-MAX_Timer(MAX_PETRA_WT) tmr-MAX_Timer(MAX_PETRA_HT1) tmr-MAX_Timer(MAX_PETRA_HT2) tmr-MAX_Timer(MAX_PETRA_HT3) tmr-MAX_Timer(MAX_Schlafzimmer_WT) tmr-MAX_Timer(MAX_Schlafzimmer_HT) tmr-MAX_Timer(MAX_Treppenhaus_WT) tmr-MAX_Timer(MAX_Treppenhaus_HT) tmr-MAX_Timer(MAX_THOMAS_WT) tmr-MAX_Timer(MAX_THOMAS_HT1) tmr-MAX_Timer(MAX_THOMAS_HT2) tmr-MAX_Timer(MAX_TOILETTE_HT) tmr-MAX_Timer(MAX_Wohnzimmer_WT) tmr-MAX_Timer(MAX_Wohnzimmer_O) tmr-MAX_Timer(MAX_Wohnzimmer_SO) tmr-MAX_Timer(MAX_Wohnzimmer_SW) tmr-MAX_Timer(MAX_BAD_F1) tmr-MAX_Timer(MAX_HWR_F1) tmr-MAX_Timer(MAX_KCH_F1) tmr-MAX_Timer(MAX_PETRA_F2) tmr-MAX_Timer(MAX_Schlafzimmer_F1) tmr-MAX_Timer(MAX_THOMAS_F1) tmr-MAX_Timer(MAX_THOMAS_F3) tmr-MAX_Timer(MAX_TOILETTE_F1) tmr-MAX_Timer(MAX_Wohnzimmer_F1) tmr-at_Exec(Obergeschoss_Rollo_1_bei_Sonnenaufgang_hoch_AT)


um 09:15 hatte ich mich an den PC gesetzt und das WebFrontend von FHEM aufgerufen.
die tmr.MAX tauchen wohl nur alle mehrere Stunden mal auf.

AppTime:

active-timers: 83; max-active timers: 86; max-timer-load: 34  min-tmrHandlingTm: 0.0ms; max-tmrHandlingTm: 11701.7ms; totAvgDly: 39.8ms

name                                     function                               max    count      total  average   maxDly   avgDly TS Max call     param Max call
tmr-at_Exec                              HASH(0x5836000)                       2556        1    2556.71  2556.71     0.41     0.41 05.02. 07:44:48 HASH(Untergeschoss_Rollos_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x58086d0)                       2504        1    2504.82  2504.82     0.76     0.76 05.02. 08:30:02 HASH(Wohnzimmer_Rollos_bei_Sonnenaufgang_hoch_AT)
Wohnzimmer_Rollo_Alle                    dummy_Set                             2502        2    2502.47  1251.24     0.00     0.00 05.02. 08:30:02 HASH(Wohnzimmer_Rollo_Alle); Wohnzimmer_Rollo_Alle; up
Untergeschoss_Rollo_Alle                 dummy_Set                             2501        2    2501.29  1250.65     0.00     0.00 05.02. 07:44:48 HASH(Untergeschoss_Rollo_Alle); Untergeschoss_Rollo_Alle; up
Wohnzimmer_Rollo_Alle_up_Notify          notify_Exec                           2397        1    2397.67  2397.67     0.00     0.00 05.02. 08:30:02 HASH(Wohnzimmer_Rollo_Alle_up_Notify); HASH(Wohnzimmer_Rollo_Alle)
Untergeschoss_Rollo_Alle_up_Notify       notify_Exec                           2397        1    2397.35  2397.35     0.00     0.00 05.02. 07:44:48 HASH(Untergeschoss_Rollo_Alle_up_Notify); HASH(Untergeschoss_Rollo_Alle)
tmr-at_Exec                              HASH(0x583ef60)                       2352        1    2352.40  2352.40     0.29     0.29 05.02. 08:04:48 HASH(Kueche_Rollo_3_bei_Sonnenaufgang_hoch_AT)
Kueche_Rollo_3                           dummy_Set                             2298        2    2298.21  1149.11     0.00     0.00 05.02. 08:04:48 HASH(Kueche_Rollo_3); Kueche_Rollo_3; up
tmr-at_Exec                              HASH(0x583ff40)                       2240        1    2240.14  2240.14  4775.45  4775.45 05.02. 07:44:53 HASH(Wohnzimmer_Rollo_1_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x5842288)                       2225        1    2225.53  2225.53  7015.70  7015.70 05.02. 07:44:55 HASH(Wohnzimmer_Rollo_2_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x5835f10)                       2220        1    2220.09  2220.09    28.56    28.56 05.02. 07:48:08 HASH(Untergeschoss_Rollo_1_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x5836fe0)                       2218        1    2218.14  2218.14  2557.21  2557.21 05.02. 07:44:50 HASH(Untergeschoss_Rollo_2_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x5842ca8)                       2214        1    2214.86  2214.86  9241.32  9241.32 05.02. 07:44:57 HASH(Wohnzimmer_Rollo_3_bei_Sonnenaufgang_hoch_AT)
tmr-at_Exec                              HASH(0x5837850)                       2189        1    2189.67  2189.67  2505.69  2505.69 05.02. 08:30:04 HASH(Untergeschoss_Rollo_3_bei_Sonnenaufgang_hoch_AT)
Untergeschoss_Rollo_3                    dummy_Set                             2187        4    2292.84   573.21     0.00     0.00 05.02. 08:30:04 HASH(Untergeschoss_Rollo_3); Untergeschoss_Rollo_3; up
tmr-at_Exec                              HASH(0x583e6f0)                       2187        1    2187.38  2187.38     0.51     0.51 05.02. 09:30:02 HASH(Obergeschoss_Rollo_1_bei_Sonnenaufgang_hoch_AT)
Obergeschoss_Rollo_1                     dummy_Set                             2185        2    2185.05  1092.52     0.00     0.00 05.02. 09:30:02 HASH(Obergeschoss_Rollo_1); Obergeschoss_Rollo_1; up
Wohnzimmer_Rollo_1                       dummy_Set                             2184        4    2349.38   587.35     0.00     0.00 05.02. 07:44:52 HASH(Wohnzimmer_Rollo_1); Wohnzimmer_Rollo_1; up
Wohnzimmer_Rollo_2                       dummy_Set                             2171        4    2275.96   568.99     0.00     0.00 05.02. 07:44:55 HASH(Wohnzimmer_Rollo_2); Wohnzimmer_Rollo_2; up
Untergeschoss_Rollo_1                    dummy_Set                             2164        4    2325.08   581.27     0.00     0.00 05.02. 07:48:08 HASH(Untergeschoss_Rollo_1); Untergeschoss_Rollo_1; up
Untergeschoss_Rollo_2                    dummy_Set                             2164        4    2268.92   567.23     0.00     0.00 05.02. 07:44:50 HASH(Untergeschoss_Rollo_2); Untergeschoss_Rollo_2; up
Wohnzimmer_Rollo_3                       dummy_Set                             2160        4    2265.04   566.26     0.00     0.00 05.02. 07:44:57 HASH(Wohnzimmer_Rollo_3); Wohnzimmer_Rollo_3; up
Kueche_Rollo_3_up_Notify                 notify_Exec                           2156        1    2156.66  2156.66     0.00     0.00 05.02. 08:04:48 HASH(Kueche_Rollo_3_up_Notify); HASH(Kueche_Rollo_3)
Untergeschoss_Rollo_3_up_Notify          notify_Exec                           2037        2    2037.82  1018.91     0.00     0.00 05.02. 08:30:04 HASH(Untergeschoss_Rollo_3_up_Notify); HASH(Untergeschoss_Rollo_3)
Wohnzimmer_Rollo_3_up_Notify             notify_Exec                           2026        2    2026.59  1013.29     0.00     0.00 05.02. 07:44:57 HASH(Wohnzimmer_Rollo_3_up_Notify); HASH(Wohnzimmer_Rollo_3)
Wohnzimmer_Rollo_2_up_Notify             notify_Exec                           2026        2    2026.40  1013.20     0.00     0.00 05.02. 07:44:55 HASH(Wohnzimmer_Rollo_2_up_Notify); HASH(Wohnzimmer_Rollo_2)
Obergeschoss_Rollo_1_up_Notify           notify_Exec                           2023        1    2023.68  2023.68     0.00     0.00 05.02. 09:30:02 HASH(Obergeschoss_Rollo_1_up_Notify); HASH(Obergeschoss_Rollo_1)
Untergeschoss_Rollo_2_up_Notify          notify_Exec                           2022        2    2022.81  1011.41     0.00     0.00 05.02. 07:44:50 HASH(Untergeschoss_Rollo_2_up_Notify); HASH(Untergeschoss_Rollo_2)
Untergeschoss_Rollo_1_up_Notify          notify_Exec                           2022        2    2023.00  1011.50     0.00     0.00 05.02. 07:48:08 HASH(Untergeschoss_Rollo_1_up_Notify); HASH(Untergeschoss_Rollo_1)
Wohnzimmer_Rollo_1_up_Notify             notify_Exec                           2021        2    2022.50  1011.25     0.00     0.00 05.02. 07:44:52 HASH(Wohnzimmer_Rollo_1_up_Notify); HASH(Wohnzimmer_Rollo_1)
CUL_0                                    CUL_Read                              1460     3130  779899.50   249.17     0.00     0.00 05.02. 09:22:11 HASH(CUL_0)
local_pybinding                          CODE(0x47b7310)                       1230   376849 8850518.06    23.49     0.00     0.00 05.02. 07:44:59 HASH(local_pybinding)
WEB_127.0.0.1_49226                      FW_Notify                             1166   100638 3305708.51    32.85     0.00     0.00 05.02. 07:44:59 HASH(WEB_127.0.0.1_49226); HASH(TUYA_JL06)
TUYA_JL06                                fhempy_Set                            1164     1312   72362.01    55.15     0.00     0.00 05.02. 07:44:59 HASH(TUYA_JL06); ARRAY(0x5f540e0); HASH(0x79b5190)
TUYA_JL30                                fhempy_Set                            1050     4090  224349.78    54.85     0.00     0.00 05.02. 07:44:59 HASH(TUYA_JL30); ARRAY(0x6b2e880); HASH(0x7a4b9b8)
TUYA_JL18                                fhempy_Set                             940     1488   85734.12    57.62     0.00     0.00 05.02. 07:44:59 HASH(TUYA_JL18); ARRAY(0x78bbd20); HASH(0x85e72a0)
TUYA_JL31                                fhempy_Set                             871     3985  217384.02    54.55     0.00     0.00 05.02. 06:01:33 HASH(TUYA_JL31); ARRAY(0x79f42a0); HASH(0x5b91ce8)
TUYA_JL24                                fhempy_Set                             830     3722  189704.58    50.97     0.00     0.00 05.02. 00:44:38 HASH(TUYA_JL24); ARRAY(0x7a694a0); HASH(0x5a86cd8)
TUYA_JL29                                fhempy_Set                             808     3778  222937.95    59.01     0.00     0.00 05.02. 07:44:59 HASH(TUYA_JL29); ARRAY(0x75ba8d0); HASH(0x5d38ed0)
TUYA_JL15                                fhempy_Set                             779     3918  221111.62    56.43     0.00     0.00 05.02. 03:20:45 HASH(TUYA_JL15); ARRAY(0x888c5d0); HASH(0x703c738)
dblog_THB                                DbLog_Log                              775   100638 7611640.05    75.63     0.00     0.00 05.02. 03:20:48 HASH(dblog_THB); HASH(WU_IPLIEZ_1)


das sind die üblichen Vorkommnisse per Timer oder eben TUYA

Solange Fhemy wohl nicht suchen muss ist auch Ruhehe.
Gestern Abend hatte ich gegen 23:00 noch mehrfach  den room Fhempy aufgerufen (schnell) und ein paar Bezeichnungen und Plots angepasst. Da kam dann promt einiges forn FreezeMon...

hier das Log von FreezeMon der letzten Minuten:

1 RASPI4B, 1 RASPI3B, 2 CUL, 2 Jeelink, 60 Tuya-Devices (Schalter, Dimmer, Sensoren, Cameras), 30 HUE-Lampen, 5 MAX! WTs, 16 MAX! HTs, 12 MAX! FKs, 1 Bresser 5in1, 1 OilFox, 8 ALEXA Echos und Dots, FHEM, 5 Tasmota-Devices, SonOff -Bridge, PowerFox, Buderus KM200

thburkhart

#848
2023.02.05 09:19:19.372 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.382 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.382 5: BindingsIo (local_pybinding): QUEUE: start handling - 7
2023.02.05 09:19:19.382 5: processMessage: {"awaitId": 35196052, "NAME": "TUYA_SP03", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP03'},1);;"}
2023.02.05 09:19:19.383 5: processMessage: {"awaitId": 80839845, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_21','1');;"}
2023.02.05 09:19:19.383 5: processMessage: {"awaitId": 12653493, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_21','1');;"}
2023.02.05 09:19:19.383 5: processMessage: {"awaitId": 12158569, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_21','1');;"}
2023.02.05 09:19:19.383 5: processMessage: {"awaitId": 12653496, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_21','1');;"}
2023.02.05 09:19:19.383 5: processMessage: {"awaitId": 86201351, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'cur_power','758.0');;"}
2023.02.05 09:19:19.383 5: processMessage: {"awaitId": 42537394, "NAME": "TUYA_JL30", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL30'},'online','1');;"}
2023.02.05 09:19:19.384 5: BindingsIo (local_pybinding): QUEUE: finished handling - 7
2023.02.05 09:19:19.384 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.394 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.394 5: BindingsIo (local_pybinding): QUEUE: start handling - 7
2023.02.05 09:19:19.395 5: processMessage: {"awaitId": 35196052, "NAME": "TUYA_SP03", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP03'},1);;"}
2023.02.05 09:19:19.395 5: processMessage: {"awaitId": 80839845, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_21','1');;"}
2023.02.05 09:19:19.395 5: processMessage: {"awaitId": 12653493, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_21','1');;"}
2023.02.05 09:19:19.395 5: processMessage: {"awaitId": 12158569, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_21','1');;"}
2023.02.05 09:19:19.395 5: processMessage: {"awaitId": 12653496, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_21','1');;"}
2023.02.05 09:19:19.395 5: processMessage: {"awaitId": 86201351, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'cur_power','758.0');;"}
2023.02.05 09:19:19.396 5: processMessage: {"awaitId": 42537394, "NAME": "TUYA_JL30", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL30'},'online','1');;"}
2023.02.05 09:19:19.396 5: BindingsIo (local_pybinding): QUEUE: finished handling - 7
2023.02.05 09:19:19.396 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.401 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.401 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 92954678, "NAME": "TUYA_SP05", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP05'},1);;"}
2023.02.05 09:19:19.402 5: BindingsIo (local_pybinding): QUEUE: start handling - 8
2023.02.05 09:19:19.402 5: processMessage: {"awaitId": 35196052, "NAME": "TUYA_SP03", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP03'},1);;"}
2023.02.05 09:19:19.402 5: processMessage: {"awaitId": 80839845, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_21','1');;"}
2023.02.05 09:19:19.402 5: processMessage: {"awaitId": 12653493, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_21','1');;"}
2023.02.05 09:19:19.402 5: processMessage: {"awaitId": 12158569, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_21','1');;"}
2023.02.05 09:19:19.403 5: processMessage: {"awaitId": 12653496, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_21','1');;"}
2023.02.05 09:19:19.403 5: processMessage: {"awaitId": 86201351, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'cur_power','758.0');;"}
2023.02.05 09:19:19.403 5: processMessage: {"awaitId": 42537394, "NAME": "TUYA_JL30", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL30'},'online','1');;"}
2023.02.05 09:19:19.403 5: processMessage: {"awaitId": 92954678, "NAME": "TUYA_SP05", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP05'},1);;"}
2023.02.05 09:19:19.404 5: BindingsIo (local_pybinding): QUEUE: finished handling - 8
2023.02.05 09:19:19.404 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.404 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.404 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 12871118, "NAME": "TUYA_SP07", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP07'},1);;"}
2023.02.05 09:19:19.405 5: BindingsIo (local_pybinding): QUEUE: start handling - 9
2023.02.05 09:19:19.405 5: processMessage: {"awaitId": 35196052, "NAME": "TUYA_SP03", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP03'},1);;"}
2023.02.05 09:19:19.405 5: processMessage: {"awaitId": 80839845, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_21','1');;"}
2023.02.05 09:19:19.405 5: processMessage: {"awaitId": 12653493, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_21','1');;"}
2023.02.05 09:19:19.405 5: processMessage: {"awaitId": 12158569, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_21','1');;"}
2023.02.05 09:19:19.405 5: processMessage: {"awaitId": 12653496, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_21','1');;"}
2023.02.05 09:19:19.406 5: processMessage: {"awaitId": 86201351, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'cur_power','758.0');;"}
2023.02.05 09:19:19.406 5: processMessage: {"awaitId": 42537394, "NAME": "TUYA_JL30", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL30'},'online','1');;"}
2023.02.05 09:19:19.406 5: processMessage: {"awaitId": 92954678, "NAME": "TUYA_SP05", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP05'},1);;"}
2023.02.05 09:19:19.406 5: processMessage: {"awaitId": 12871118, "NAME": "TUYA_SP07", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP07'},1);;"}
2023.02.05 09:19:19.407 5: BindingsIo (local_pybinding): QUEUE: finished handling - 9
2023.02.05 09:19:19.407 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.407 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.407 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 69476713, "NAME": "TUYA_SP13", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_SP13'});;"}
2023.02.05 09:19:19.408 4: BindingsIo (local_pybinding): >>> WS: {"FHEMPYTYPE": "tuya", "id": 27039214, "defargsh": {}, "defargs": ["TUYA_SP01", "fhempy", "tuya", "IGzCi97RpN2Lf9cu", "260064612cf432ca55d8", "192.168.9.31", "52a00c366067a6f7", "3.3", "ea8453wdazquzfl08l0e", "8a4b27dd759d4c6a9456aa8155d2a0ea"], "msgtype": "function", "argsh": {}, "function": "Set", "PYTHONTYPE": "tuya", "NAME": "TUYA_SP01", "args": ["TUYA_SP01", "?"], "finished": 1, "returnval": "Unknown argument ?, choose one of on:noArg off:noArg countdown_1:slider,0.0,1.0,86400.0"}
2023.02.05 09:19:19.408 5: BindingsIo (local_pybinding): QUEUE: start handling - 11
2023.02.05 09:19:19.408 5: processMessage: {"awaitId": 35196052, "NAME": "TUYA_SP03", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP03'},1);;"}
2023.02.05 09:19:19.408 5: processMessage: {"awaitId": 80839845, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_21','1');;"}
2023.02.05 09:19:19.408 5: processMessage: {"awaitId": 12653493, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_21','1');;"}
2023.02.05 09:19:19.409 5: processMessage: {"awaitId": 12158569, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_21','1');;"}
2023.02.05 09:19:19.409 5: processMessage: {"awaitId": 12653496, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_21','1');;"}
2023.02.05 09:19:19.409 5: processMessage: {"awaitId": 86201351, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'cur_power','758.0');;"}
2023.02.05 09:19:19.409 5: processMessage: {"awaitId": 42537394, "NAME": "TUYA_JL30", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL30'},'online','1');;"}
2023.02.05 09:19:19.409 5: processMessage: {"awaitId": 92954678, "NAME": "TUYA_SP05", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP05'},1);;"}
2023.02.05 09:19:19.409 5: processMessage: {"awaitId": 12871118, "NAME": "TUYA_SP07", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP07'},1);;"}
2023.02.05 09:19:19.409 5: processMessage: {"awaitId": 69476713, "NAME": "TUYA_SP13", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_SP13'});;"}
2023.02.05 09:19:19.410 5: processMessage: {"FHEMPYTYPE": "tuya", "id": 27039214, "defargsh": {}, "defargs": ["TUYA_SP01", "fhempy", "tuya", "IGzCi97RpN2Lf9cu", "260064612cf432ca55d8", "192.168.9.31", "52a00c366067a6f7", "3.3", "ea8453wdazquzfl08l0e", "8a4b27dd759d4c6a9456aa8155d2a0ea"], "msgtype": "function", "argsh": {}, "function": "Set", "PYTHONTYPE": "tuya", "NAME": "TUYA_SP01", "args": ["TUYA_SP01", "?"], "finished": 1, "returnval": "Unknown argument ?, choose one of on:noArg off:noArg countdown_1:slider,0.0,1.0,86400.0"}
2023.02.05 09:19:19.410 5: BindingsIo (local_pybinding): QUEUE: finished handling - 10
2023.02.05 09:19:19.410 4: BindingsIo (local_pybinding): end fhempyFunction: TUYA_SP01 => Set (27039214) - result: Unknown argument ?, choose one of on:noArg off:noArg countdown_1:slider,0.0,1.0,86400.0
2023.02.05 09:19:19.410 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.411 5: BindingsIo (local_pybinding): DevIo_SimpleRead NoTimeout
2023.02.05 09:19:19.411 5: BindingsIo (local_pybinding): QUEUE: start handling - 10
2023.02.05 09:19:19.411 5: processMessage: {"awaitId": 35196052, "NAME": "TUYA_SP03", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP03'},1);;"}
2023.02.05 09:19:19.411 4: BindingsIo (local_pybinding): <<< WS: {"awaitId":35196052,"result":null,"error":0}
2023.02.05 09:19:19.411 5: DevIo_SimpleWrite local_pybinding: 7b2261776169744964223a33353139363035322c22726573756c74223a6e756c6c2c226572726f72223a307d
2023.02.05 09:19:19.413 5: processMessage: {"awaitId": 80839845, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_21','1');;"}
2023.02.05 09:19:19.413 4: BindingsIo (local_pybinding): <<< WS: {"awaitId":80839845,"result":null,"error":0}
2023.02.05 09:19:19.413 5: DevIo_SimpleWrite local_pybinding: 7b2261776169744964223a38303833393834352c22726573756c74223a6e756c6c2c226572726f72223a307d
2023.02.05 09:19:19.415 5: processMessage: {"awaitId": 12653493, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_21','1');;"}
2023.02.05 09:19:19.415 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"result":null,"awaitId":12653493}
2023.02.05 09:19:19.415 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c22726573756c74223a6e756c6c2c2261776169744964223a31323635333439337d
2023.02.05 09:19:19.416 5: processMessage: {"awaitId": 12158569, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_21','1');;"}
2023.02.05 09:19:19.417 4: BindingsIo (local_pybinding): <<< WS: {"awaitId":12158569,"result":null,"error":0}
2023.02.05 09:19:19.417 5: DevIo_SimpleWrite local_pybinding: 7b2261776169744964223a31323135383536392c22726573756c74223a6e756c6c2c226572726f72223a307d
2023.02.05 09:19:19.419 5: processMessage: {"awaitId": 12653496, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_21','1');;"}
2023.02.05 09:19:19.419 4: BindingsIo (local_pybinding): <<< WS: {"awaitId":12653496,"result":null,"error":0}
2023.02.05 09:19:19.419 5: DevIo_SimpleWrite local_pybinding: 7b2261776169744964223a31323635333439362c22726573756c74223a6e756c6c2c226572726f72223a307d
2023.02.05 09:19:19.421 5: processMessage: {"awaitId": 86201351, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'cur_power','758.0');;"}
2023.02.05 09:19:19.421 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"result":null,"awaitId":86201351}
2023.02.05 09:19:19.421 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c22726573756c74223a6e756c6c2c2261776169744964223a38363230313335317d
2023.02.05 09:19:19.422 5: processMessage: {"awaitId": 42537394, "NAME": "TUYA_JL30", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL30'},'online','1');;"}
2023.02.05 09:19:19.423 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"awaitId":42537394,"result":null}
2023.02.05 09:19:19.423 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c2261776169744964223a34323533373339342c22726573756c74223a6e756c6c7d
2023.02.05 09:19:19.424 5: processMessage: {"awaitId": 92954678, "NAME": "TUYA_SP05", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP05'},1);;"}
2023.02.05 09:19:19.425 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"result":null,"awaitId":92954678}
2023.02.05 09:19:19.425 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c22726573756c74223a6e756c6c2c2261776169744964223a39323935343637387d
2023.02.05 09:19:19.426 5: processMessage: {"awaitId": 12871118, "NAME": "TUYA_SP07", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_SP07'},1);;"}
2023.02.05 09:19:19.426 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"result":null,"awaitId":12871118}
2023.02.05 09:19:19.426 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c22726573756c74223a6e756c6c2c2261776169744964223a31323837313131387d
2023.02.05 09:19:19.428 5: processMessage: {"awaitId": 69476713, "NAME": "TUYA_SP13", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_SP13'});;"}
2023.02.05 09:19:19.428 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"awaitId":69476713,"result":"2023-02-05 09:19:19"}
2023.02.05 09:19:19.428 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c2261776169744964223a36393437363731332c22726573756c74223a22323032332d30322d30352030393a31393a3139227d
2023.02.05 09:19:19.430 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2023.02.05 09:19:19.432 5: End notify loop for TUYA_SP01
2023.02.05 09:19:19.432 4: BindingsIo (local_pybinding): <<< WS: {"awaitId":78651841,"result":null,"error":0}
2023.02.05 09:19:19.433 5: DevIo_SimpleWrite local_pybinding: 7b2261776169744964223a37383635313834312c22726573756c74223a6e756c6c2c226572726f72223a307d
2023.02.05 09:19:19.434 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2023.02.05 09:19:19.437 4: BindingsIo (local_pybinding): start fhempyFunction: TUYA_JL15 => Set (75457611)
2023.02.05 09:19:19.437 4: BindingsIo (local_pybinding): <<< WS: {"argsh":{},"msgtype":"function","function":"Set","PYTHONTYPE":"tuya","NAME":"TUYA_JL15","args":["TUYA_JL15","?"],"FHEMPYTYPE":"tuya","id":75457611,"defargsh":{},"defargs":["TUYA_JL15","fhempy","tuya","37mnhia3pojleqfh","10438000d8f15ba4ccd3","192.168.9.44","8ef2d52a326d2259","3.3","ea8453wdazquzfl08l0e","8a4b27dd759d4c6a9456aa8155d2a0ea"]}
2023.02.05 09:19:19.437 5: DevIo_SimpleWrite local_pybinding: 7b226172677368223a7b7d2c226d736774797065223a2266756e6374696f6e222c2266756e6374696f6e223a22536574222c22505954484f4e54595045223a2274757961222c224e414d45223a22545559415f4a4c3135222c2261726773223a5b22545559415f4a4c3135222c223f225d2c224648454d505954595045223a2274757961222c226964223a37353435373631312c226465666172677368223a7b7d2c2264656661726773223a5b22545559415f4a4c3135222c226668656d7079222c2274757961222c2233376d6e68696133706f6a6c65716668222c223130343338303030643866313562613463636433222c223139322e3136382e392e3434222c2238656632643532613332366432323539222c22332e33222c226561383435337764617a71757a666c30386c3065222c223861346232376464373539643463366139343536616138313535643261306561225d7d
2023.02.05 09:19:19.439 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.449 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.449 5: BindingsIo (local_pybinding): QUEUE: start handling - 0
2023.02.05 09:19:19.449 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2023.02.05 09:19:19.449 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.459 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.459 5: BindingsIo (local_pybinding): QUEUE: start handling - 0
2023.02.05 09:19:19.460 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2023.02.05 09:19:19.460 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.470 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.470 5: BindingsIo (local_pybinding): QUEUE: start handling - 0
2023.02.05 09:19:19.470 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2023.02.05 09:19:19.470 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.480 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.480 5: BindingsIo (local_pybinding): QUEUE: start handling - 0
2023.02.05 09:19:19.480 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2023.02.05 09:19:19.480 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.490 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.491 5: BindingsIo (local_pybinding): QUEUE: start handling - 0
2023.02.05 09:19:19.491 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2023.02.05 09:19:19.491 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.501 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.501 5: BindingsIo (local_pybinding): QUEUE: start handling - 0
2023.02.05 09:19:19.501 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2023.02.05 09:19:19.501 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.511 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.511 5: BindingsIo (local_pybinding): QUEUE: start handling - 0
2023.02.05 09:19:19.511 5: BindingsIo (local_pybinding): QUEUE: finished handling - 0
2023.02.05 09:19:19.512 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.514 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.514 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 85297474, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL24'});;"}
2023.02.05 09:19:19.514 5: BindingsIo (local_pybinding): QUEUE: start handling - 1
2023.02.05 09:19:19.514 5: processMessage: {"awaitId": 85297474, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL24'});;"}
2023.02.05 09:19:19.515 5: BindingsIo (local_pybinding): QUEUE: finished handling - 1
2023.02.05 09:19:19.515 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.516 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.516 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 14121363, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL26'});;"}
2023.02.05 09:19:19.517 5: BindingsIo (local_pybinding): QUEUE: start handling - 2
2023.02.05 09:19:19.517 5: processMessage: {"awaitId": 85297474, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL24'});;"}
2023.02.05 09:19:19.517 5: processMessage: {"awaitId": 14121363, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL26'});;"}
2023.02.05 09:19:19.517 5: BindingsIo (local_pybinding): QUEUE: finished handling - 2
2023.02.05 09:19:19.517 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.518 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.518 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 64709140, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_22','729');;"}
2023.02.05 09:19:19.518 5: BindingsIo (local_pybinding): QUEUE: start handling - 3
2023.02.05 09:19:19.518 5: processMessage: {"awaitId": 85297474, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL24'});;"}
2023.02.05 09:19:19.518 5: processMessage: {"awaitId": 14121363, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL26'});;"}
2023.02.05 09:19:19.519 5: processMessage: {"awaitId": 64709140, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_22','729');;"}
2023.02.05 09:19:19.519 5: BindingsIo (local_pybinding): QUEUE: finished handling - 3
2023.02.05 09:19:19.519 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.519 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.519 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 12748403, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_22','715');;"}
2023.02.05 09:19:19.520 5: BindingsIo (local_pybinding): QUEUE: start handling - 4
2023.02.05 09:19:19.520 5: processMessage: {"awaitId": 85297474, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL24'});;"}
2023.02.05 09:19:19.520 5: processMessage: {"awaitId": 14121363, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL26'});;"}
2023.02.05 09:19:19.520 5: processMessage: {"awaitId": 64709140, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_22','729');;"}
2023.02.05 09:19:19.520 5: processMessage: {"awaitId": 12748403, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_22','715');;"}
2023.02.05 09:19:19.521 5: BindingsIo (local_pybinding): QUEUE: finished handling - 4
2023.02.05 09:19:19.521 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.521 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.521 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 88310421, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_22','724');;"}
2023.02.05 09:19:19.521 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 52988890, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_22','721');;"}
2023.02.05 09:19:19.522 5: BindingsIo (local_pybinding): QUEUE: start handling - 6
2023.02.05 09:19:19.522 5: processMessage: {"awaitId": 85297474, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL24'});;"}
2023.02.05 09:19:19.522 5: processMessage: {"awaitId": 14121363, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL26'});;"}
2023.02.05 09:19:19.522 5: processMessage: {"awaitId": 64709140, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_22','729');;"}
2023.02.05 09:19:19.522 5: processMessage: {"awaitId": 12748403, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_22','715');;"}
2023.02.05 09:19:19.522 5: processMessage: {"awaitId": 88310421, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_22','724');;"}
2023.02.05 09:19:19.522 5: processMessage: {"awaitId": 52988890, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_22','721');;"}
2023.02.05 09:19:19.523 5: BindingsIo (local_pybinding): QUEUE: finished handling - 6
2023.02.05 09:19:19.523 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.523 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.524 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 51619211, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'cur_voltage','229.3');;"}
2023.02.05 09:19:19.524 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 63472181, "NAME": "TUYA_JL30", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_JL30'},1);;"}
2023.02.05 09:19:19.524 5: BindingsIo (local_pybinding): QUEUE: start handling - 8
2023.02.05 09:19:19.524 5: processMessage: {"awaitId": 85297474, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL24'});;"}
2023.02.05 09:19:19.525 5: processMessage: {"awaitId": 14121363, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL26'});;"}
2023.02.05 09:19:19.525 5: processMessage: {"awaitId": 64709140, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_22','729');;"}
2023.02.05 09:19:19.525 5: processMessage: {"awaitId": 12748403, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_22','715');;"}
2023.02.05 09:19:19.525 5: processMessage: {"awaitId": 88310421, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_22','724');;"}
2023.02.05 09:19:19.525 5: processMessage: {"awaitId": 52988890, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_22','721');;"}
2023.02.05 09:19:19.525 5: processMessage: {"awaitId": 51619211, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'cur_voltage','229.3');;"}
2023.02.05 09:19:19.525 5: processMessage: {"awaitId": 63472181, "NAME": "TUYA_JL30", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_JL30'},1);;"}
2023.02.05 09:19:19.526 5: BindingsIo (local_pybinding): QUEUE: finished handling - 8
2023.02.05 09:19:19.526 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.526 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.526 4: BindingsIo (local_pybinding): >>> WS: {"argsh": {}, "msgtype": "function", "function": "Set", "PYTHONTYPE": "tuya", "NAME": "TUYA_JL15", "args": ["TUYA_JL15", "?"], "FHEMPYTYPE": "tuya", "id": 75457611, "defargsh": {}, "defargs": ["TUYA_JL15", "fhempy", "tuya", "37mnhia3pojleqfh", "10438000d8f15ba4ccd3", "192.168.9.44", "8ef2d52a326d2259", "3.3", "ea8453wdazquzfl08l0e", "8a4b27dd759d4c6a9456aa8155d2a0ea"], "finished": 1, "returnval": "Unknown argument ?, choose one of on:noArg off:noArg countdown_1:slider,0.0,1.0,86400.0"}
2023.02.05 09:19:19.527 5: BindingsIo (local_pybinding): QUEUE: start handling - 9
2023.02.05 09:19:19.527 5: processMessage: {"awaitId": 85297474, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL24'});;"}
2023.02.05 09:19:19.527 5: processMessage: {"awaitId": 14121363, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL26'});;"}
2023.02.05 09:19:19.527 5: processMessage: {"awaitId": 64709140, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_22','729');;"}
2023.02.05 09:19:19.527 5: processMessage: {"awaitId": 12748403, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_22','715');;"}
2023.02.05 09:19:19.527 5: processMessage: {"awaitId": 88310421, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_22','724');;"}
2023.02.05 09:19:19.527 5: processMessage: {"awaitId": 52988890, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_22','721');;"}
2023.02.05 09:19:19.528 5: processMessage: {"awaitId": 51619211, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'cur_voltage','229.3');;"}
2023.02.05 09:19:19.528 5: processMessage: {"awaitId": 63472181, "NAME": "TUYA_JL30", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_JL30'},1);;"}
2023.02.05 09:19:19.528 5: processMessage: {"argsh": {}, "msgtype": "function", "function": "Set", "PYTHONTYPE": "tuya", "NAME": "TUYA_JL15", "args": ["TUYA_JL15", "?"], "FHEMPYTYPE": "tuya", "id": 75457611, "defargsh": {}, "defargs": ["TUYA_JL15", "fhempy", "tuya", "37mnhia3pojleqfh", "10438000d8f15ba4ccd3", "192.168.9.44", "8ef2d52a326d2259", "3.3", "ea8453wdazquzfl08l0e", "8a4b27dd759d4c6a9456aa8155d2a0ea"], "finished": 1, "returnval": "Unknown argument ?, choose one of on:noArg off:noArg countdown_1:slider,0.0,1.0,86400.0"}
2023.02.05 09:19:19.528 5: BindingsIo (local_pybinding): QUEUE: finished handling - 8
2023.02.05 09:19:19.528 4: BindingsIo (local_pybinding): end fhempyFunction: TUYA_JL15 => Set (75457611) - result: Unknown argument ?, choose one of on:noArg off:noArg countdown_1:slider,0.0,1.0,86400.0
2023.02.05 09:19:19.528 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.529 5: BindingsIo (local_pybinding): DevIo_SimpleRead NoTimeout
2023.02.05 09:19:19.529 5: BindingsIo (local_pybinding): QUEUE: start handling - 8
2023.02.05 09:19:19.529 5: processMessage: {"awaitId": 85297474, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL24'});;"}
2023.02.05 09:19:19.529 4: BindingsIo (local_pybinding): <<< WS: {"result":"2023-02-05 09:19:19","awaitId":85297474,"error":0}
2023.02.05 09:19:19.529 5: DevIo_SimpleWrite local_pybinding: 7b22726573756c74223a22323032332d30322d30352030393a31393a3139222c2261776169744964223a38353239373437342c226572726f72223a307d
2023.02.05 09:19:19.531 5: processMessage: {"awaitId": 14121363, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL26'});;"}
2023.02.05 09:19:19.531 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"result":"2023-02-05 09:19:19","awaitId":14121363}
2023.02.05 09:19:19.531 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c22726573756c74223a22323032332d30322d30352030393a31393a3139222c2261776169744964223a31343132313336337d
2023.02.05 09:19:19.533 5: processMessage: {"awaitId": 64709140, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_22','729');;"}
2023.02.05 09:19:19.534 4: BindingsIo (local_pybinding): <<< WS: {"result":null,"awaitId":64709140,"error":0}
2023.02.05 09:19:19.534 5: DevIo_SimpleWrite local_pybinding: 7b22726573756c74223a6e756c6c2c2261776169744964223a36343730393134302c226572726f72223a307d
2023.02.05 09:19:19.535 5: processMessage: {"awaitId": 12748403, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_22','715');;"}
2023.02.05 09:19:19.536 4: BindingsIo (local_pybinding): <<< WS: {"result":null,"awaitId":12748403,"error":0}
2023.02.05 09:19:19.536 5: DevIo_SimpleWrite local_pybinding: 7b22726573756c74223a6e756c6c2c2261776169744964223a31323734383430332c226572726f72223a307d
2023.02.05 09:19:19.537 5: processMessage: {"awaitId": 88310421, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_22','724');;"}
2023.02.05 09:19:19.537 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"result":null,"awaitId":88310421}
2023.02.05 09:19:19.538 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c22726573756c74223a6e756c6c2c2261776169744964223a38383331303432317d
2023.02.05 09:19:19.539 5: processMessage: {"awaitId": 52988890, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_22','721');;"}
2023.02.05 09:19:19.539 4: BindingsIo (local_pybinding): <<< WS: {"awaitId":52988890,"result":null,"error":0}
2023.02.05 09:19:19.540 5: DevIo_SimpleWrite local_pybinding: 7b2261776169744964223a35323938383839302c22726573756c74223a6e756c6c2c226572726f72223a307d
2023.02.05 09:19:19.541 5: processMessage: {"awaitId": 51619211, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'cur_voltage','229.3');;"}
2023.02.05 09:19:19.541 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"awaitId":51619211,"result":null}
2023.02.05 09:19:19.541 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c2261776169744964223a35313631393231312c22726573756c74223a6e756c6c7d
2023.02.05 09:19:19.543 5: processMessage: {"awaitId": 63472181, "NAME": "TUYA_JL30", "msgtype": "command", "command": "readingsEndUpdate($defs{'TUYA_JL30'},1);;"}
2023.02.05 09:19:19.550 4: DbLog dblog_THB - check Device: TUYA_JL30 , Event: cur_current: 1060.0
2023.02.05 09:19:19.610 1: BindingsIo (local_pybinding): devspec2array *: Unknown verb pattern '' in regex; marked by <-- HERE in m/^(*) <-- HERE $/ at fhem.pl line 1352.
=> COMMAND: readingsEndUpdate($defs{'TUYA_JL30'},1);;
2023.02.05 09:19:19.610 1: BindingsIo (local_pybinding): ^* matches null string many times in regex; marked by <-- HERE in m/^* <-- HERE :.*RSSI.*$/ at ./FHEM/93_DbLog.pm line 1310.
=> COMMAND: readingsEndUpdate($defs{'TUYA_JL30'},1);;
2023.02.05 09:19:19.610 5: DbLog dblog_THB - parsed Event: TUYA_JL30 , Event: cur_current: 1060.0
2023.02.05 09:19:19.610 5: DbLog dblog_THB - DbLogExclude of "TUYA_JL30": .*
2023.02.05 09:19:19.610 4: DbLog dblog_THB - check Device: TUYA_JL30 , Event: cur_power: 2467.0
2023.02.05 09:19:19.659 1: BindingsIo (local_pybinding): devspec2array *: Unknown verb pattern '' in regex; marked by <-- HERE in m/^(*) <-- HERE $/ at fhem.pl line 1352.
=> COMMAND: readingsEndUpdate($defs{'TUYA_JL30'},1);;
2023.02.05 09:19:19.659 1: BindingsIo (local_pybinding): ^* matches null string many times in regex; marked by <-- HERE in m/^* <-- HERE :.*RSSI.*$/ at ./FHEM/93_DbLog.pm line 1310.
=> COMMAND: readingsEndUpdate($defs{'TUYA_JL30'},1);;
2023.02.05 09:19:19.660 5: DbLog dblog_THB - parsed Event: TUYA_JL30 , Event: cur_power: 2467.0
2023.02.05 09:19:19.660 5: DbLog dblog_THB - DbLogExclude of "TUYA_JL30": .*
2023.02.05 09:19:19.660 4: DbLog dblog_THB - check Device: TUYA_JL30 , Event: cur_voltage: 232.7
2023.02.05 09:19:19.708 1: BindingsIo (local_pybinding): devspec2array *: Unknown verb pattern '' in regex; marked by <-- HERE in m/^(*) <-- HERE $/ at fhem.pl line 1352.
=> COMMAND: readingsEndUpdate($defs{'TUYA_JL30'},1);;
2023.02.05 09:19:19.708 1: BindingsIo (local_pybinding): ^* matches null string many times in regex; marked by <-- HERE in m/^* <-- HERE :.*RSSI.*$/ at ./FHEM/93_DbLog.pm line 1310.
=> COMMAND: readingsEndUpdate($defs{'TUYA_JL30'},1);;
2023.02.05 09:19:19.708 5: DbLog dblog_THB - parsed Event: TUYA_JL30 , Event: cur_voltage: 232.7
2023.02.05 09:19:19.708 5: DbLog dblog_THB - DbLogExclude of "TUYA_JL30": .*
2023.02.05 09:19:19.709 5: rgBatterieStatus: not on any display, ignoring notify
2023.02.05 09:19:19.709 5: rgTUYA: not on any display, ignoring notify
2023.02.05 09:19:19.709 4: BindingsIo (local_pybinding): start fhempyFunction: TUYA_JL30 => Set (14990372)
2023.02.05 09:19:19.709 4: BindingsIo (local_pybinding): <<< WS: {"defargs":["TUYA_JL30","fhempy","tuya","37mnhia3pojleqfh","20538630c44f33f7b2b6","192.168.9.69","b2b021b05d25903e","3.3","ea8453wdazquzfl08l0e","8a4b27dd759d4c6a9456aa8155d2a0ea"],"defargsh":{},"id":14990372,"FHEMPYTYPE":"tuya","args":["TUYA_JL30","?"],"NAME":"TUYA_JL30","PYTHONTYPE":"tuya","function":"Set","msgtype":"function","argsh":{}}
2023.02.05 09:19:19.709 5: DevIo_SimpleWrite local_pybinding: 7b2264656661726773223a5b22545559415f4a4c3330222c226668656d7079222c2274757961222c2233376d6e68696133706f6a6c65716668222c223230353338363330633434663333663762326236222c223139322e3136382e392e3639222c2262326230323162303564323539303365222c22332e33222c226561383435337764617a71757a666c30386c3065222c223861346232376464373539643463366139343536616138313535643261306561225d2c226465666172677368223a7b7d2c226964223a31343939303337322c224648454d505954595045223a2274757961222c2261726773223a5b22545559415f4a4c3330222c223f225d2c224e414d45223a22545559415f4a4c3330222c22505954484f4e54595045223a2274757961222c2266756e6374696f6e223a22536574222c226d736774797065223a2266756e6374696f6e222c226172677368223a7b7d7d
2023.02.05 09:19:19.711 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.711 5: BindingsIo (local_pybinding): DevIo_SimpleRead WithTimeout
2023.02.05 09:19:19.711 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 64561166, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL24'},'cur_voltage','229.3');;"}
2023.02.05 09:19:19.711 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 41685307, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL26'},'cur_voltage','228.0');;"}
2023.02.05 09:19:19.711 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 63848462, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_23','31250');;"}
2023.02.05 09:19:19.712 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 81452013, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_23','30264');;"}
2023.02.05 09:19:19.712 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 62496518, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_23','31060');;"}
2023.02.05 09:19:19.712 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 74312766, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_23','30787');;"}
2023.02.05 09:19:19.712 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 14947482, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'dp_07','0');;"}
2023.02.05 09:19:19.712 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 24107605, "NAME": "TUYA_SP15", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_SP15'});;"}
2023.02.05 09:19:19.712 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 84529117, "NAME": "TUYA_SP13", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP13'},'state','on');;"}
2023.02.05 09:19:19.712 4: BindingsIo (local_pybinding): >>> WS: {"awaitId": 16464170, "NAME": "TUYA_JL04", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL04'});;"}
2023.02.05 09:19:19.712 4: BindingsIo (local_pybinding): >>> WS: {"defargs": ["TUYA_JL30", "fhempy", "tuya", "37mnhia3pojleqfh", "20538630c44f33f7b2b6", "192.168.9.69", "b2b021b05d25903e", "3.3", "ea8453wdazquzfl08l0e", "8a4b27dd759d4c6a9456aa8155d2a0ea"], "defargsh": {}, "id": 14990372, "FHEMPYTYPE": "tuya", "args": ["TUYA_JL30", "?"], "NAME": "TUYA_JL30", "PYTHONTYPE": "tuya", "function": "Set", "msgtype": "function", "argsh": {}, "finished": 1, "returnval": "Unknown argument ?, choose one of on:noArg off:noArg countdown_1:slider,0.0,1.0,86400.0"}
2023.02.05 09:19:19.713 5: BindingsIo (local_pybinding): QUEUE: start handling - 11
2023.02.05 09:19:19.713 5: processMessage: {"awaitId": 64561166, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL24'},'cur_voltage','229.3');;"}
2023.02.05 09:19:19.713 5: processMessage: {"awaitId": 41685307, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL26'},'cur_voltage','228.0');;"}
2023.02.05 09:19:19.713 5: processMessage: {"awaitId": 63848462, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_23','31250');;"}
2023.02.05 09:19:19.713 5: processMessage: {"awaitId": 81452013, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_23','30264');;"}
2023.02.05 09:19:19.713 5: processMessage: {"awaitId": 62496518, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_23','31060');;"}
2023.02.05 09:19:19.713 5: processMessage: {"awaitId": 74312766, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_23','30787');;"}
2023.02.05 09:19:19.713 5: processMessage: {"awaitId": 14947482, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'dp_07','0');;"}
2023.02.05 09:19:19.713 5: processMessage: {"awaitId": 24107605, "NAME": "TUYA_SP15", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_SP15'});;"}
2023.02.05 09:19:19.713 5: processMessage: {"awaitId": 84529117, "NAME": "TUYA_SP13", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP13'},'state','on');;"}
2023.02.05 09:19:19.713 5: processMessage: {"awaitId": 16464170, "NAME": "TUYA_JL04", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL04'});;"}
2023.02.05 09:19:19.713 5: processMessage: {"defargs": ["TUYA_JL30", "fhempy", "tuya", "37mnhia3pojleqfh", "20538630c44f33f7b2b6", "192.168.9.69", "b2b021b05d25903e", "3.3", "ea8453wdazquzfl08l0e", "8a4b27dd759d4c6a9456aa8155d2a0ea"], "defargsh": {}, "id": 14990372, "FHEMPYTYPE": "tuya", "args": ["TUYA_JL30", "?"], "NAME": "TUYA_JL30", "PYTHONTYPE": "tuya", "function": "Set", "msgtype": "function", "argsh": {}, "finished": 1, "returnval": "Unknown argument ?, choose one of on:noArg off:noArg countdown_1:slider,0.0,1.0,86400.0"}
2023.02.05 09:19:19.714 5: BindingsIo (local_pybinding): QUEUE: finished handling - 10
2023.02.05 09:19:19.714 4: BindingsIo (local_pybinding): end fhempyFunction: TUYA_JL30 => Set (14990372) - result: Unknown argument ?, choose one of on:noArg off:noArg countdown_1:slider,0.0,1.0,86400.0
2023.02.05 09:19:19.714 5: BindingsIo (local_pybinding): DevIo_SimpleRead
2023.02.05 09:19:19.714 5: BindingsIo (local_pybinding): DevIo_SimpleRead NoTimeout
2023.02.05 09:19:19.714 5: BindingsIo (local_pybinding): QUEUE: start handling - 10
2023.02.05 09:19:19.714 5: processMessage: {"awaitId": 64561166, "NAME": "TUYA_JL24", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL24'},'cur_voltage','229.3');;"}
2023.02.05 09:19:19.714 4: BindingsIo (local_pybinding): <<< WS: {"result":"cur_voltage: 229.3","awaitId":64561166,"error":0}
2023.02.05 09:19:19.714 5: DevIo_SimpleWrite local_pybinding: 7b22726573756c74223a226375725f766f6c746167653a203232392e33222c2261776169744964223a36343536313136362c226572726f72223a307d
2023.02.05 09:19:19.716 5: processMessage: {"awaitId": 41685307, "NAME": "TUYA_JL26", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL26'},'cur_voltage','228.0');;"}
2023.02.05 09:19:19.716 4: BindingsIo (local_pybinding): <<< WS: {"result":"cur_voltage: 228.0","awaitId":41685307,"error":0}
2023.02.05 09:19:19.716 5: DevIo_SimpleWrite local_pybinding: 7b22726573756c74223a226375725f766f6c746167653a203232382e30222c2261776169744964223a34313638353330372c226572726f72223a307d
2023.02.05 09:19:19.718 5: processMessage: {"awaitId": 63848462, "NAME": "TUYA_SP09", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP09'},'dp_23','31250');;"}
2023.02.05 09:19:19.718 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"result":null,"awaitId":63848462}
2023.02.05 09:19:19.718 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c22726573756c74223a6e756c6c2c2261776169744964223a36333834383436327d
2023.02.05 09:19:19.719 5: processMessage: {"awaitId": 81452013, "NAME": "TUYA_SP11", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP11'},'dp_23','30264');;"}
2023.02.05 09:19:19.720 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"awaitId":81452013,"result":null}
2023.02.05 09:19:19.720 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c2261776169744964223a38313435323031332c22726573756c74223a6e756c6c7d
2023.02.05 09:19:19.721 5: processMessage: {"awaitId": 62496518, "NAME": "TUYA_SP08", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP08'},'dp_23','31060');;"}
2023.02.05 09:19:19.721 4: BindingsIo (local_pybinding): <<< WS: {"result":null,"awaitId":62496518,"error":0}
2023.02.05 09:19:19.721 5: DevIo_SimpleWrite local_pybinding: 7b22726573756c74223a6e756c6c2c2261776169744964223a36323439363531382c226572726f72223a307d
2023.02.05 09:19:19.723 5: processMessage: {"awaitId": 74312766, "NAME": "TUYA_SP12", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP12'},'dp_23','30787');;"}
2023.02.05 09:19:19.723 4: BindingsIo (local_pybinding): <<< WS: {"error":0,"result":null,"awaitId":74312766}
2023.02.05 09:19:19.723 5: DevIo_SimpleWrite local_pybinding: 7b226572726f72223a302c22726573756c74223a6e756c6c2c2261776169744964223a37343331323736367d
2023.02.05 09:19:19.724 5: processMessage: {"awaitId": 14947482, "NAME": "TUYA_JL33", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_JL33'},'dp_07','0');;"}
2023.02.05 09:19:19.725 4: BindingsIo (local_pybinding): <<< WS: {"result":null,"awaitId":14947482,"error":0}
2023.02.05 09:19:19.725 5: DevIo_SimpleWrite local_pybinding: 7b22726573756c74223a6e756c6c2c2261776169744964223a31343934373438322c226572726f72223a307d
2023.02.05 09:19:19.726 5: processMessage: {"awaitId": 24107605, "NAME": "TUYA_SP15", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_SP15'});;"}
2023.02.05 09:19:19.726 4: BindingsIo (local_pybinding): <<< WS: {"awaitId":24107605,"result":"2023-02-05 09:19:19","error":0}
2023.02.05 09:19:19.726 5: DevIo_SimpleWrite local_pybinding: 7b2261776169744964223a32343130373630352c22726573756c74223a22323032332d30322d30352030393a31393a3139222c226572726f72223a307d
2023.02.05 09:19:19.728 5: processMessage: {"awaitId": 84529117, "NAME": "TUYA_SP13", "msgtype": "command", "command": "readingsBulkUpdateIfChanged($defs{'TUYA_SP13'},'state','on');;"}
2023.02.05 09:19:19.728 4: BindingsIo (local_pybinding): <<< WS: {"awaitId":84529117,"result":null,"error":0}
2023.02.05 09:19:19.728 5: DevIo_SimpleWrite local_pybinding: 7b2261776169744964223a38343532393131372c22726573756c74223a6e756c6c2c226572726f72223a307d
2023.02.05 09:19:19.730 5: processMessage: {"awaitId": 16464170, "NAME": "TUYA_JL04", "msgtype": "command", "command": "readingsBeginUpdate($defs{'TUYA_JL04'});;"}
1 RASPI4B, 1 RASPI3B, 2 CUL, 2 Jeelink, 60 Tuya-Devices (Schalter, Dimmer, Sensoren, Cameras), 30 HUE-Lampen, 5 MAX! WTs, 16 MAX! HTs, 12 MAX! FKs, 1 Bresser 5in1, 1 OilFox, 8 ALEXA Echos und Dots, FHEM, 5 Tasmota-Devices, SonOff -Bridge, PowerFox, Buderus KM200

thburkhart

#849
ich konnte bislang nicht nachvollziehen, warum fhempy neue devices fälschlich als doppelte anlegt, wenn sie nicht rechtzeitig antworten.


ich habe mal testweise autocreate für TYPE = fhempy ausgeschlossen.

in Apptime:

active-timers: 84; max-active timers: 162; max-timer-load: 82  min-tmrHandlingTm: 0.0ms; max-tmrHandlingTm: 11701.7ms; totAvgDly: 56.1ms

name                                     function                               max    count      total  average   maxDly   avgDly TS Max call     param Max call
Buderus                                  km200_Attr                           15015       10   15016.31  1501.63     0.00     0.00 05.02. 10:50:46 set; Buderus; DoNotPoll; /dhwCircuits/dhw1/switchPrograms/
WEB_192.168.9.103_30328                  FW_Read                               8184        8    8189.53  1023.69     0.00     0.00 05.02. 10:55:50 HASH(WEB_192.168.9.103_30328)
JLLaCR                                   JeeLink_Read                          1206      878   18444.88    21.01     0.00     0.00 05.02. 10:50:52 HASH(JLLaCR)
JLLaCR                                   JeeLink_Define                        1025        1    1025.74  1025.74     0.00     0.00 05.02. 10:50:31 HASH(JLLaCR); JLLaCR JeeLink /dev/ttyUSB0@57600
SamsungSmartThingsConnector              SST_Notify                             567      659     577.33     0.88     0.00     0.00 05.02. 10:50:50 HASH(SamsungSmartThingsConnector); HASH(global)
dblog_THB                                DbLog_Log                              456      660   44661.13    67.67     0.00     0.00 05.02. 10:51:17 HASH(dblog_THB); HASH(WU_IPLIEZ_1)
eventTypes                               eventTypes_Define                      413        1     413.81   413.81     0.00     0.00 05.02. 10:50:46 HASH(eventTypes); eventTypes eventTypes ./log/eventTypes.txt
hueBridge1                               HUEBridge_Notify                       402        2     402.93   201.46     0.00     0.00 05.02. 10:50:50 HASH(hueBridge1); HASH(global)
tmr-HUEBridge_GetUpdate                  HASH(0x6b0efb8)                        291        5    1366.06   273.21   415.92    84.02 05.02. 10:51:50 HASH(hueBridge1)
tmr-freezemon_ProcessTimer               HASH(0x77a1220)                        259      344     917.78     2.67  7612.83    42.08 05.02. 10:55:54 HASH(myFreezemon)
tmr-SYSSTAT_GetUpdate                    HASH(0x532c228)                        215        3     570.14   190.05   195.42    65.91 05.02. 10:54:55 HASH(BBxM)
TelegramBotTHB                           TelegramBot_Define                     168        1     168.23   168.23     0.00     0.00 05.02. 10:50:30 HASH(TelegramBotTHB); TelegramBotTHB TelegramBot
TUYA_SP01                                fhempy_Set                             159        2     160.53    80.27     0.00     0.00 05.02. 10:55:50 HASH(TUYA_SP01); ARRAY(0x80ded00); HASH(0x7c79a88)
WEB_192.168.9.103_30338                  FW_Read                                123        6     127.50    21.25     0.00     0.00 05.02. 10:56:22 HASH(WEB_192.168.9.103_30338)
tmr-CUL_MAX_SQH                          HASH(0x88688e8)                        121       63    4867.08    77.26  4374.59    76.83 05.02. 10:56:05 HASH(MaxSystem)
CUL_0                                    CUL_Get                                120       64    4885.57    76.34     0.00     0.00 05.02. 10:56:05 HASH(CUL_0); CUL_0; credit10ms
tmr-DbLog_execMemCacheAsync              HASH(0x6ce6850)                        118       11     935.24    85.02   365.20    35.54 05.02. 10:53:21 HASH(dblog_THB)
tmr-CUL_MAX_BroadcastTime                HASH(0x88688e8)                        117        1     117.07   117.07    33.81    33.81 05.02. 10:51:21 HASH(MaxSystem)
TUYA_JL30                                fhempy_Set                              65        3     123.92    41.31     0.00     0.00 05.02. 10:55:51 HASH(TUYA_JL30); ARRAY(0x88b4ac0); HASH(0x7fa4ee0)
TUYA_JL18                                fhempy_Set                              60        2     116.94    58.47     0.00     0.00 05.02. 10:55:45 HASH(TUYA_JL18); ARRAY(0x82cf680); HASH(0x833d010)
TUYA_JL22                                fhempy_Set                              60        4     172.04    43.01     0.00     0.00 05.02. 10:55:44 HASH(TUYA_JL22); ARRAY(0x7d5bc30); HASH(0x7c94df0)
TUYA_JL33                                fhempy_Set                              59        3     115.94    38.65     0.00     0.00 05.02. 10:55:49 HASH(TUYA_JL33); ARRAY(0x7f92518); HASH(0x8048418)
TUYA_JL02                                fhempy_Set                              59        4     172.52    43.13     0.00     0.00 05.02. 10:55:43 HASH(TUYA_JL02); ARRAY(0x7c9dbc0); HASH(0x8267b28)
TUYA_EMYLO4                              fhempy_Set                              59        4     169.81    42.45     0.00     0.00 05.02. 10:55:43 HASH(TUYA_EMYLO4); ARRAY(0x7cc9aa0); HASH(0x7050330)
TUYA_EMYLO3                              fhempy_Set                              59        4     170.66    42.67     0.00     0.00 05.02. 10:55:43 HASH(TUYA_EMYLO3); ARRAY(0x81cd380); HASH(0x718db98)
TUYAC_Sir01                              fhempy_Set                              59        3      59.46    19.82     0.00     0.00 05.02. 10:55:48 HASH(TUYAC_Sir01); ARRAY(0x7d9d4f8); HASH(0x8266ff8)
TUYA_JL31                                fhempy_Set                              58        3     115.10    38.37     0.00     0.00 05.02. 10:55:49 HASH(TUYA_JL31); ARRAY(0x8122cd8); HASH(0x7c37bf0)
TUYA_JL21                                fhempy_Set                              58        4     169.85    42.46     0.00     0.00 05.02. 10:55:44 HASH(TUYA_JL21); ARRAY(0x88f3238); HASH(0x7fdf7f8)
TUYAC_SensorSmoke01                      fhempy_Set                              58        4     171.75    42.94     0.00     0.00 05.02. 10:55:50 HASH(TUYAC_SensorSmoke01); ARRAY(0x88ef6f0); HASH(0x80ba9d0)
tuya_system                              fhempy_Set                              58        3     114.86    38.29     0.00     0.00 05.02. 10:55:50 HASH(tuya_system); ARRAY(0x88fee00); HASH(0x81583f0)
TUYAC_Sir02                              fhempy_Set                              58        3      58.69    19.56     0.00     0.00 05.02. 10:55:48 HASH(TUYAC_Sir02); ARRAY(0x88df7d0); HASH(0x81d9408)
TUYA_JL32                                fhempy_Set                              57        3     114.15    38.05     0.00     0.00 05.02. 10:55:49 HASH(TUYA_JL32); ARRAY(0x82d8cd8); HASH(0x88ef9d8)
TUYA_JL23                                fhempy_Set                              57        2     113.76    56.88     0.00     0.00 05.02. 10:55:44 HASH(TUYA_JL23); ARRAY(0x81fdef0); HASH(0x588ef58)
TUYAC_SensorW03                          fhempy_Set                              57        3      58.04    19.35     0.00     0.00 05.02. 10:55:48 HASH(TUYAC_SensorW03); ARRAY(0x828aef8); HASH(0x88e07c8)
TUYA_Temp07                              fhempy_Set                              57        3      58.01    19.34     0.00     0.00 05.02. 10:55:48 HASH(TUYA_Temp07); ARRAY(0x7fc7200); HASH(0x8222e80)
TUYAC_Relais01                           fhempy_Set                              57        3      57.89    19.30     0.00     0.00 05.02. 10:55:46 HASH(TUYAC_Relais01); ARRAY(0x826bf58); HASH(0x78d3c60)
TUYAC_SensorT01                          fhempy_Set                              57        3      57.95    19.32     0.00     0.00 05.02. 10:55:47 HASH(TUYAC_SensorT01); ARRAY(0x7daa290); HASH(0x64768e8)
TUYA_Temp02                              fhempy_Set                              57        3      57.95    19.32     0.00     0.00 05.02. 10:55:48 HASH(TUYA_Temp02); ARRAY(0x8290140); HASH(0x8351168)
TUYAC_SensorW04                          fhempy_Set                              57        3      57.86    19.29     0.00     0.00 05.02. 10:55:48 HASH(TUYAC_SensorW04); ARRAY(0x8163ee0); HASH(0x8295400)
TUYA_PS3                                 fhempy_Set                              57        3     113.66    37.89     0.00     0.00 05.02. 10:55:49 HASH(TUYA_PS3); ARRAY(0x78d49c8); HASH(0x7cb0580)
TUYA_Temp03                              fhempy_Set                              57        3      57.77    19.26     0.00     0.00 05.02. 10:55:48 HASH(TUYA_Temp03); ARRAY(0x88f44b8); HASH(0x80bf198)


ich habe keine doppelten Devices mehr

in tuya_local erzeuge ich doch auch durch eine "SET createdevices" Bentzeraktion neue und somit nur gewünschte devices ;
tuya_cloud könnte ja das doch auch so ermöglichen
1 RASPI4B, 1 RASPI3B, 2 CUL, 2 Jeelink, 60 Tuya-Devices (Schalter, Dimmer, Sensoren, Cameras), 30 HUE-Lampen, 5 MAX! WTs, 16 MAX! HTs, 12 MAX! FKs, 1 Bresser 5in1, 1 OilFox, 8 ALEXA Echos und Dots, FHEM, 5 Tasmota-Devices, SonOff -Bridge, PowerFox, Buderus KM200

thburkhart

sodele nach update auf fhempy 0.1.593

Phantom_devices aus den fhem.cfg:


define tuya_cloud_bf16f1788a2fd6edd6pr1d fhempy tuya_cloud TUYA bf16f1788a2fd6edd6pr1d
setuuid tuya_cloud_bf16f1788a2fd6edd6pr1d 63dfba2f-f33f-fd5f-8f45-4e400ef1f46ce1eb
attr tuya_cloud_bf16f1788a2fd6edd6pr1d DbLogExclude .*
attr tuya_cloud_bf16f1788a2fd6edd6pr1d alias Balkonstern BULB03
attr tuya_cloud_bf16f1788a2fd6edd6pr1d event-on-change-reading .*
attr tuya_cloud_bf16f1788a2fd6edd6pr1d group tuya_cloud
attr tuya_cloud_bf16f1788a2fd6edd6pr1d room fhempy
>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>bereits Local vorhanden

define tuya_cloud_3367036770039f60ce2b fhempy tuya_cloud TUYA 3367036770039f60ce2b
setuuid tuya_cloud_3367036770039f60ce2b 63dfba55-f33f-fd5f-8d22-0c88c4ccfb4329b8
attr tuya_cloud_3367036770039f60ce2b DbLogExclude .*
attr tuya_cloud_3367036770039f60ce2b alias USB Lader2 Thomas
attr tuya_cloud_3367036770039f60ce2b event-on-change-reading .*
attr tuya_cloud_3367036770039f60ce2b group tuya_cloud
attr tuya_cloud_3367036770039f60ce2b room fhempy
>>>> aktiv >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>bereits Local vorhanden

define tuya_cloud_453005615002911dfe06 fhempy tuya_cloud TUYA 453005615002911dfe06
setuuid tuya_cloud_453005615002911dfe06 63dfbaa7-f33f-fd5f-77e2-78d57c6e3f7d09db
attr tuya_cloud_453005615002911dfe06 DbLogExclude .*
attr tuya_cloud_453005615002911dfe06 alias Heizkissen 2
attr tuya_cloud_453005615002911dfe06 event-on-change-reading .*
attr tuya_cloud_453005615002911dfe06 group tuya_cloud
attr tuya_cloud_453005615002911dfe06 room fhempy
>>>>> aktiv >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>bereits Local vorhanden

define tuya_cloud_720570712462ab27e625 fhempy tuya_cloud TUYA 720570712462ab27e625
setuuid tuya_cloud_720570712462ab27e625 63dfbab0-f33f-fd5f-39f8-d7bb6d72c54a3b42
attr tuya_cloud_720570712462ab27e625 DbLogExclude .*
attr tuya_cloud_720570712462ab27e625 alias Steckdose 4
attr tuya_cloud_720570712462ab27e625 event-on-change-reading .*
attr tuya_cloud_720570712462ab27e625 group tuya_cloud
attr tuya_cloud_720570712462ab27e625 room fhempy

>>>>nicht online  und auch nicht mehr in TUYA_cloud >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>bereits Local vorhanden
[/code]

letzteres garnicht nicht mehr in der Cloud.

fhempY-Log:

2023-02-05 18:29:01,670 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:03,407 - ERROR    - fhempy.lib.fhem: FHEM took 5006ms for readingsEndUpdate($defs{'TUYA_JL04'},1);;
2023-02-05 18:29:06,822 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:07,179 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:11,873 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:16,922 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:17,270 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:21,946 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:26,987 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:27,364 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:32,081 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:37,093 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:37,466 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:42,172 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:47,194 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:47,561 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:52,233 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:54,254 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:57,246 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:29:57,574 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:30:02,258 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:30:07,269 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:30:07,586 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:30:12,372 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:30:17,413 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:30:17,669 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:30:22,454 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:30:27,505 - WARNING  - asyncio: socket.send() raised exception.
2023-02-05 18:30:27,761 - WARNING  - asyncio: socket.send() raised exception.

JL04 ist online

fhem.log

2023.02.05 17:30:14 3: Kueche_Rollo_3_down_Notify return value: OK
2023.02.05 17:30:14 1: [Freezemon] myFreezemon: possible freeze starting at 17:30:12, delay is 2.229 possibly caused by: tmr-at_Exec(Kueche_Rollo_3_bei_Sonnenuntergang_runter_AT)
2023.02.05 17:43:59 1: [Freezemon] myFreezemon: possible freeze starting at 17:43:49, delay is 10.224 possibly caused by: no bad guy found :-(
2023.02.05 17:45:01 2: CM_Parse, unhandled message type 45 from MAX_002ad5 to MAX_26850e - ignoring !
2023.02.05 17:45:14 3: Untergeschoss_Rollo_1_down_Notify return value: OK
2023.02.05 17:45:16 3: Obergeschoss_Rollo_1_down_Notify return value: OK
2023.02.05 17:45:18 3: Wohnzimmer_Rollo_1_down_Notify return value: OK
2023.02.05 17:45:21 3: Wohnzimmer_Rollo_3_down_Notify return value: OK
2023.02.05 17:45:21 1: [Freezemon] myFreezemon: possible freeze starting at 17:45:12, delay is 9.415 possibly caused by: tmr-at_Exec(Untergeschoss_Rollo_1_bei_Sonnenuntergang_runter_AT) tmr-at_Exec(Obergeschoss_Rollo_1_bei_Sonnenuntergang_runter_AT) tmr-at_Exec(Wohnzimmer_Rollo_1_bei_Sonnenuntergang_runter_AT) tmr-at_Exec(Wohnzimmer_Rollo_3_bei_Sonnenuntergang_runter_AT)
2023.02.05 17:45:24 1: [Freezemon] myFreezemon: possible freeze starting at 17:45:23, delay is 1.88 possibly caused by: no bad guy found :-(
2023.02.05 17:45:27 2: CM_Parse, unhandled message type C0 from MAX_0018c9 to MAX_048989 - ignoring !
2023.02.05 17:45:27 2: CM_Parse, unhandled message type 07 from MAX_898a00 to MAX_015800 - ignoring !
2023.02.05 17:45:47 2: CM_Parse, unhandled message type 88 from MAX_0caa04 to MAX_420ccd - ignoring !
2023.02.05 17:45:47 3: CM_Parse, unhandled message PairPong from MAX_58232a to MAX_328a8a, groupid : 1 , payload : 880C7E04420CCD8716EDC5002AD308860E7E02 - ignoring !
2023.02.05 17:45:55 2: CM_Parse, unhandled message type CD from MAX_870158 to MAX_182a21 - ignoring !
2023.02.05 17:45:55 3: MaxSystem, device MAX_015823 want to be re-paired to MAX_2a328a, not to us [123456] - ignoring !
2023.02.05 17:46:35 1: [Freezemon] myFreezemon: possible freeze starting at 17:46:23, delay is 12.166 possibly caused by: tmr-MAX_Timer(MAX_PETRA_F3) tmr-DbLog_execMemCacheAsync(dblog_THB)
2023.02.05 17:48:21 1: [Freezemon] myFreezemon: possible freeze starting at 17:48:10, delay is 11.142 possibly caused by: no bad guy found :-(
2023.02.05 17:50:46 1: [Freezemon] myFreezemon: possible freeze starting at 17:50:45, delay is 1.739 possibly caused by: no bad guy found :-(
2023.02.05 17:50:52 1: [Freezemon] myFreezemon: possible freeze starting at 17:50:51, delay is 1.394 possibly caused by: no bad guy found :-(
2023.02.05 17:50:57 1: [Freezemon] myFreezemon: possible freeze starting at 17:50:55, delay is 2.455 possibly caused by: no bad guy found :-(
2023.02.05 17:51:23 1: [Freezemon] myFreezemon: possible freeze starting at 17:51:22, delay is 1.134 possibly caused by: no bad guy found :-(
2023.02.05 18:05:45 1: [Freezemon] myFreezemon: possible freeze starting at 18:05:31, delay is 14.187 possibly caused by: no bad guy found :-(
2023.02.05 18:05:56 1: [Freezemon] myFreezemon: possible freeze starting at 18:05:55, delay is 1.458 possibly caused by: no bad guy found :-(
2023.02.05 18:06:56 1: [Freezemon] myFreezemon: possible freeze starting at 18:06:54, delay is 2.229 possibly caused by: no bad guy found :-(
2023.02.05 18:07:56 1: [Freezemon] myFreezemon: possible freeze starting at 18:07:55, delay is 1.032 possibly caused by: no bad guy found :-(
2023.02.05 18:08:56 1: [Freezemon] myFreezemon: possible freeze starting at 18:08:55, delay is 1.136 possibly caused by: no bad guy found :-(
2023.02.05 18:12:17 1: [Freezemon] myFreezemon: possible freeze starting at 18:12:16, delay is 1.592 possibly caused by: no bad guy found :-(
2023.02.05 18:15:34 1: [Freezemon] myFreezemon: possible freeze starting at 18:15:33, delay is 1.055 possibly caused by: no bad guy found :-(
2023.02.05 18:15:36 1: [Freezemon] myFreezemon: possible freeze starting at 18:15:35, delay is 1.523 possibly caused by: no bad guy found :-(
2023.02.05 18:15:57 1: [Freezemon] myFreezemon: possible freeze starting at 18:15:56, delay is 1.698 possibly caused by: no bad guy found :-(
2023.02.05 18:16:22 1: [Freezemon] myFreezemon: possible freeze starting at 18:16:21, delay is 1.317 possibly caused by: tmr-MAX_Timer(MAX_THOMAS_F2)
2023.02.05 18:17:08 1: [Freezemon] myFreezemon: possible freeze starting at 18:17:06, delay is 2.049 possibly caused by: no bad guy found :-(
2023.02.05 18:17:15 1: [Freezemon] myFreezemon: possible freeze starting at 18:17:14, delay is 1.057 possibly caused by: no bad guy found :-(
2023.02.05 18:17:37 1: [Freezemon] myFreezemon: possible freeze starting at 18:17:36, delay is 1.392 possibly caused by: no bad guy found :-(
2023.02.05 18:17:57 1: [Freezemon] myFreezemon: possible freeze starting at 18:17:56, delay is 1.452 possibly caused by: no bad guy found :-(
2023.02.05 18:18:08 1: [Freezemon] myFreezemon: possible freeze starting at 18:18:06, delay is 2.767 possibly caused by: no bad guy found :-(
2023.02.05 18:18:12 1: [Freezemon] myFreezemon: possible freeze starting at 18:18:11, delay is 1.285 possibly caused by: no bad guy found :-(
2023.02.05 18:18:53 1: [Freezemon] myFreezemon: possible freeze starting at 18:18:52, delay is 1.871 possibly caused by: no bad guy found :-(
2023.02.05 18:19:37 3: Watchdog WDT_HWR_F2 triggered
2023.02.05 18:19:59 1: [Freezemon] myFreezemon: possible freeze starting at 18:19:58, delay is 1.254 possibly caused by: no bad guy found :-(
2023.02.05 18:20:01 1: [Freezemon] myFreezemon: possible freeze starting at 18:20:00, delay is 1.096 possibly caused by: tmr-DbLog_execMemCacheAsync(dblog_THB)
2023.02.05 18:20:09 1: [Freezemon] myFreezemon: possible freeze starting at 18:20:08, delay is 1.335 possibly caused by: no bad guy found :-(
2023.02.05 18:20:11 1: [Freezemon] myFreezemon: possible freeze starting at 18:20:10, delay is 1.381 possibly caused by: no bad guy found :-(
2023.02.05 18:20:13 1: [Freezemon] myFreezemon: possible freeze starting at 18:20:12, delay is 1.408 possibly caused by: no bad guy found :-(
2023.02.05 18:21:58 1: [Freezemon] myFreezemon: possible freeze starting at 18:21:57, delay is 1.021 possibly caused by: no bad guy found :-(
2023.02.05 18:22:01 1: [Freezemon] myFreezemon: possible freeze starting at 18:22:00, delay is 1.8 possibly caused by: tmr-DbLog_execMemCacheAsync(dblog_THB)
2023.02.05 18:22:12 1: [Freezemon] myFreezemon: possible freeze starting at 18:22:11, delay is 1.948 possibly caused by: no bad guy found :-(
2023.02.05 18:22:14 1: [Freezemon] myFreezemon: possible freeze starting at 18:22:13, delay is 1.505 possibly caused by: no bad guy found :-(
2023.02.05 18:25:10 1: [Freezemon] myFreezemon: possible freeze starting at 18:25:09, delay is 1.299 possibly caused by: no bad guy found :-(
2023.02.05 18:25:32 1: [Freezemon] myFreezemon: possible freeze starting at 18:25:31, delay is 1.171 possibly caused by: no bad guy found :-(
2023.02.05 18:25:56 1: [Freezemon] myFreezemon: possible freeze starting at 18:25:54, delay is 2.577 possibly caused by: no bad guy found :-(
2023.02.05 18:26:01 1: [Freezemon] myFreezemon: possible freeze starting at 18:25:59, delay is 2.153 possibly caused by: no bad guy found :-(
2023.02.05 18:27:41 1: [Freezemon] myFreezemon: possible freeze starting at 18:27:40, delay is 1.385 possibly caused by: no bad guy found :-(
2023.02.05 18:27:51 1: [Freezemon] myFreezemon: possible freeze starting at 18:27:50, delay is 1.126 possibly caused by: no bad guy found :-(
2023.02.05 18:28:21 1: [Freezemon] myFreezemon: possible freeze starting at 18:28:20, delay is 1.374 possibly caused by: no bad guy found :-(
2023.02.05 18:28:58 1: [Freezemon] myFreezemon: possible freeze starting at 18:28:57, delay is 1.406 possibly caused by: no bad guy found :-(
2023.02.05 18:29:03 1: [Freezemon] myFreezemon: possible freeze starting at 18:28:59, delay is 4.408 possibly caused by: no bad guy found :-(
2023.02.05 18:29:52 1: [Freezemon] myFreezemon: possible freeze starting at 18:29:51, delay is 1.639 possibly caused by: no bad guy found :-(
2023.02.05 18:30:36 1: [Freezemon] myFreezemon: possible freeze starting at 18:30:35, delay is 1.042 possibly caused by: no bad guy found :-(
2023.02.05 18:30:38 1: [Freezemon] myFreezemon: possible freeze starting at 18:30:37, delay is 1.161 possibly caused by: no bad guy found :-(
2023.02.05 18:30:47 1: [Freezemon] myFreezemon: possible freeze starting at 18:30:46, delay is 1.015 possibly caused by: no bad guy found :-(
2023.02.05 18:31:21 1: [Freezemon] myFreezemon: possible freeze starting at 18:31:20, delay is 1.12 possibly caused by: no bad guy found :-(
2023.02.05 18:32:16 1: [Freezemon] myFreezemon: possible freeze starting at 18:32:15, delay is 1.193 possibly caused by: no bad guy found :-(
2023.02.05 18:32:37 1: [Freezemon] myFreezemon: possible freeze starting at 18:32:35, delay is 2.565 possibly caused by: tmr-DbLog_execMemCacheAsync(dblog_THB)


ja da sind einige delays..

aver warum muss dann FHEMPY ein neues Device anlegen??

Die Daten kommen doch an.

Ich vermute, dass fhempy bei Schaltern Schaltvorgänge innerhalb einer Sekunde mitkriegen soll; deshalb wird häufig abgefragt.
Statt ein neues Device anzulegen, könnte fhempy sich fhempy doch merken, dass das Gerät aus welchem Grund auch immer nicht alive ist und es nach xxx Sekunden erst wieder versuchen.

Kann es nicht doch sein, dass fhempy selbst zu  hohe Last erzeugt  bei eben über 100 devices?








1 RASPI4B, 1 RASPI3B, 2 CUL, 2 Jeelink, 60 Tuya-Devices (Schalter, Dimmer, Sensoren, Cameras), 30 HUE-Lampen, 5 MAX! WTs, 16 MAX! HTs, 12 MAX! FKs, 1 Bresser 5in1, 1 OilFox, 8 ALEXA Echos und Dots, FHEM, 5 Tasmota-Devices, SonOff -Bridge, PowerFox, Buderus KM200

dominik

Von 0.1.589 auf 0.1.593 gab es keine Änderung, da ich alle Änderungen aus den Versionen dazwischen wieder entfernt hatte. 589 ist also gleich wie 593.

Nochmals zur Erklärung was passiert und warum Devices manchmal doppelt erstellt werden:
- fhempy schickt einen Befehl an FHEM um zu prüfen ob ein Device existiert
- wenn in der Zeit FHEM für längere Zeit blockiert, dann erhält FHEM diesen Befehl nicht und es kommt das WARNING asyncio.send() im fhempy Log
- Für fhempy bedeutet das, dass kein Device gefunden wurde und somit ein neues angelegt wird. Da ich in fhempy leider noch keine Möglichkeit gefunden habe zu erkennen wann ein Befehl in FHEM nicht ankommt, kann ich das noch nicht anders handhaben.

Dieses Verhalten kann bei ALLEN Befehlen auftreten. Mal ist es die Prüfung ob ein Device existiert, mal ist es ein Update der Readings und mal was ganz anderes. Das Verhalten ist also jedes mal anders.

Die Ursache liegt jedoch in FHEM bei dir, dass für lange Zeit blockiert. Du siehst es auch beim freezemon, welches in der Zeit um 18:29 herum, wo auch die Warnings in fhempy kommen, jede Menge Freezes hat.

Zu deiner Vermutung, dass fhempy die Devices häufig abfragt:
fhempy muss für die "Überwachung" der Devices wegen Schaltzustand nix machen außer eine Verbindung aufrecht halten. Das kostet also keine CPU. Wie schon öfter erwähnt, fhempy fragt nicht häufig ab, sondern erhält einen Event vom Device sobald geschalten wird.

Fazit:
- Ich schau in fhempy wie ich solche Fehler handhaben kann
- Du solltest in FHEM schauen wie du deine Freezes wegbekommst

Für mich sind deine Freezes natürlich sehr hilfreich um fhempy zu verbessern ;)
fhempy -  https://github.com/fhempy/fhempy: GoogleCast, Tuya, UPnP, Ring, EQ3BT, Nespresso, Xiaomi, Spotify, Object Detection, ...
Kaffeespende: https://paypal.me/todominik

thburkhart

hi Dominik,
perfektes Fazit !! :-)

witzigerweise habe ich nach einem Stromausfall am Raspi zwar eine lange Wartezeit, bis room fhempy wieder flüssig anzeigt. Es gibt aktuell keine Doppelten mehr :-)
Im Vorfeld hatte ich die kritischen Devices in local neu scanen lassen.

Es würde mich riesig freuen, wenn Du das Thema "Kumulation Verbrauchswerte" bald mit auf Deine Agenda nimmst.
Ich teste dann auch fleißig ;-)

viele Grüße und Dank
von Thomas
1 RASPI4B, 1 RASPI3B, 2 CUL, 2 Jeelink, 60 Tuya-Devices (Schalter, Dimmer, Sensoren, Cameras), 30 HUE-Lampen, 5 MAX! WTs, 16 MAX! HTs, 12 MAX! FKs, 1 Bresser 5in1, 1 OilFox, 8 ALEXA Echos und Dots, FHEM, 5 Tasmota-Devices, SonOff -Bridge, PowerFox, Buderus KM200

dominik

Verbrauchswerte kommen erst wenn die Probleme hier behoben sind. Es macht keinen Sinn eine 2. Baustelle aufzumachen wenn die Basis nicht zuverlässig funktioniert.
fhempy -  https://github.com/fhempy/fhempy: GoogleCast, Tuya, UPnP, Ring, EQ3BT, Nespresso, Xiaomi, Spotify, Object Detection, ...
Kaffeespende: https://paypal.me/todominik

McShire

Zitat von: McShire am 30 Januar 2023, 00:50:22
Jetzt funktioniert es, zumindest schon der Server und das BindingIo.
Das device zur Steuerung von Tuya (Ölradiator von Kesser) trage ich später ein.

Hallo Dominik,
leider funktioniert es doch nicht. Die Icons von fhempy_local und fhempyserver sind zwar grün,
aber fhem_local steht dauerhaft auf installing fhempy mit previous error Verbindungsaufbau abgelehnt
und der server ist running.

Ich habe auf dem Raspi3b+ ein Upgrade auf Bullseye durchgeführt und Python 3.9.16 installiert.
Aber leider immer das gleich Verhalten, sowohl bei Buster mit Python 3.9.2 und 3.9.16
als auch bei Bullseye mit 3.9.2 oder 3.9.16.

Hier die beiden lists:

fhempy_local:

Internals:
   BindingType fhempy
   DEF        fhempy
   DeviceName ws:localhost:15733
   FUUID      63dfe147-f33f-f21b-158d-2320d4208add6a9c
   IP         127.0.0.1
   NAME       fhempy_local
   NEXT_OPEN  1675620943.39065
   NR         1015
   NTFY_ORDER 50-fhempy_local
   PARTIAL   
   PORT       15733
   STATE      Installing fhempy (15min)...
   TYPE       BindingsIo
   binary     1
   devioLoglevel 0
   eventCount 4681
   installing 0
   localBinding 1
   nextOpenDelay 10
   prev_error localhost: Verbindungsaufbau abgelehnt (111)
   READINGS:
     2023-02-05 21:52:58   state           Installing fhempy (15min)...
   args:
     fhempy_local
     BindingsIo
     fhempy
Attributes:
   devStateIcon {      my $attr_ver = "1.1.0";;      my $status_img = "10px-kreis-gruen";;      my $status_txt = "connected";;      my $ver = ReadingsVal($name, "version", "-");;      my $ver_available = ReadingsVal($name, "version_available", $ver);;      my $update_icon = "";;      my $refresh_img = "refresh";;      my $refresh_txt = "Update fhempy";;      if ($ver_available ne $ver) {        $refresh_img = "refresh\@orange";;        $refresh_txt = "Version ".$ver_available." available for update";;      }      if (ReadingsVal($name, "state", "disconnected") eq "disconnected") {        $status_img = "10px-kreis-rot";;        $status_txt = "disconnected";;      }      $update_icon = "<a  href=\"/fhem?cmd.dummy=set $name update&XHR=1\" title=\"Start ".$ver_available." update\">".FW_makeImage($refresh_img, $refresh_txt)."</a>";;      my $restart_icon = "<a  href=\"/fhem?cmd.dummy=set $name restart&XHR=1\" title=\"Restart fhempy\">".FW_makeImage("control_reboot")."</a>";;      "<div><a>".FW_makeImage($status_img, $status_txt)."</a><a> ".$ver." </a>".$update_icon.$restart_icon."</div>"    }
   group      fhempy
   icon       file_json-ld2
   room       fhempy

fhempyserver:

Internals:
   DEF        0
   FD         4
   FUUID      63dfe147-f33f-f21b-5561-05bc61eeadb59101
   LAST_START 2023-02-05 21:54:21
   LAST_STOP  2023-02-05 21:54:21
   NAME       fhempyserver_15733
   NR         1016
   NTFY_ORDER 50-fhempyserver_15733
   PID        2345
   STARTS     66
   STATE      ???
   TYPE       fhempyServer
   currentlogfile ./log/fhempy-2023-02-05.log
   eventCount 132
   logfile    ./log/fhempy-%Y-%m-%d.log
   CoProcess:
     cmdFn      fhempyServer_getCmd
     name       fhempy
     state      running FHEM/bindings/python/bin/fhempy
   READINGS:
     2023-02-05 21:54:21   fhempy          running FHEM/bindings/python/bin/fhempy
     2023-02-05 19:15:16   python          v3.9.16
Attributes:
   devStateIcon {      my $status_img = "10px-kreis-gruen";;      my $status_txt = "running";;      if (substr(ReadingsVal($name, "fhempy", "running"),0,7) ne "running") {        $status_img = "10px-kreis-rot";;        $status_txt = "stopped";;      }      "<div><a>".FW_makeImage($status_img, $status_txt)."</a><a  href=\"/fhem?cmd.dummy=set $name restart&XHR=1\" title=\"Kill and restart\">".FW_makeImage("audio_repeat")."</a></div>"      }
   group      fhempy
   icon       python
   logfile    ./log/fhempy-%Y-%m-%d.log
   nrarchive  10
   room       fhempy


Hast Du einen Lösungsansatz?

Viele Grüße
Werner