Hallo Gemeinde, Hallo Martin,
ich habe noch eine Warning aus dem 10_CUL_HM erhalten:
2019.05.01 20:49:07 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/10_CUL_HM.pm line 3447.
2019.05.01 20:49:07 1: waiting for: , got:RegisterRead # await msgNo:, rec:3
2019.05.01 20:49:07 1: waiting for: , got:RegisterRead # await msgNo:, rec:3
Die Warning kommt sehr selten, die letzten Male jeweils:
2019.04.28 19:06:05 1: waiting for: , got:RegisterRead # await msgNo:, rec:117
2019.04.28 19:06:05 1: waiting for: , got:RegisterRead # await msgNo:, rec:119
2019.04.28 19:06:06 1: waiting for: , got:RegisterRead # await msgNo:, rec:121
2019.04.28 19:06:06 1: waiting for: , got:RegisterRead # await msgNo:, rec:117
2019.04.28 19:06:06 1: waiting for: , got:RegisterRead # await msgNo:, rec:119
2019.04.28 19:06:06 1: waiting for: , got:RegisterRead # await msgNo:, rec:121
und
2019.04.29 17:23:06 1: waiting for: PeerList, got:RegisterRead # await msgNo:4, rec:3
Vielleicht kann man dies noch beheben, oder es ist letztlich doch noch ein Fehler ????
VG
mcfly
Da stimmt mit dem msg sequencing etwas nicht. Wie viele ios hast du? Welches device ist es?
Das ist eine Nachricht zu getconfig. Könnte evtl. auch eine Nachricht zu Register Änderungen am device sein. Ei rt bspw.
Kannst du die passenden msg aufzeichnen?
Bei mir im LOG taucht ab und zu folgender Eintrag auf:
Zitat
2019.05.02 21:31:59 1: PERL WARNING: Use of uninitialized value in string eq at ./FHEM/10_CUL_HM.pm line 8674.
@Martin: hast Du eine Idee, wo das herkommt?
Gruß
Blueberry63
Zitat von: martinp876 am 02 Mai 2019, 21:05:37
Da stimmt mit dem msg sequencing etwas nicht. Wie viele ios hast du? Welches device ist es?
Das ist eine Nachricht zu getconfig. Könnte evtl. auch eine Nachricht zu Register Änderungen am device sein. Ei rt bspw.
Kannst du die passenden msg aufzeichnen?
Ich bin zwar nicht der Threadersteller, habe aber die gleiche Meldung..
Hier mit Stacktrace Ausgabe...
2019.05.02 21:02:51 1: waiting for: RegisterRead, got:RegisterRead # await msgNo:236, rec:234
2019.05.02 21:02:56 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/10_CUL_HM.pm line 3447.
2019.05.02 21:02:56 1: stacktrace:
2019.05.02 21:02:56 1: main::__ANON__ called by ./FHEM/10_CUL_HM.pm (3447)
2019.05.02 21:02:56 1: main::CUL_HM_parseCommon called by ./FHEM/10_CUL_HM.pm (1529)
2019.05.02 21:02:56 1: main::CUL_HM_Parse called by fhem.pl (3905)
2019.05.02 21:02:56 1: main::Dispatch called by ./FHEM/00_HMUARTLGW.pm (1463)
2019.05.02 21:02:56 1: main::HMUARTLGW_Parse called by ./FHEM/00_HMUARTLGW.pm (1566)
2019.05.02 21:02:56 1: main::HMUARTLGW_Read called by fhem.pl (3709)
2019.05.02 21:02:56 1: main::CallFn called by fhem.pl (745)
2019.05.02 21:02:56 1: waiting for: , got:RegisterRead # await msgNo:, rec:236
2019.05.02 21:03:00 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/10_CUL_HM.pm line 3447.
2019.05.02 21:03:00 1: stacktrace:
2019.05.02 21:03:00 1: main::__ANON__ called by ./FHEM/10_CUL_HM.pm (3447)
2019.05.02 21:03:00 1: main::CUL_HM_parseCommon called by ./FHEM/10_CUL_HM.pm (1529)
2019.05.02 21:03:00 1: main::CUL_HM_Parse called by fhem.pl (3905)
2019.05.02 21:03:00 1: main::Dispatch called by ./FHEM/00_HMUARTLGW.pm (1463)
2019.05.02 21:03:00 1: main::HMUARTLGW_Parse called by ./FHEM/00_HMUARTLGW.pm (1566)
2019.05.02 21:03:00 1: main::HMUARTLGW_Read called by fhem.pl (3709)
2019.05.02 21:03:00 1: main::CallFn called by fhem.pl (745)
2019.05.02 21:03:00 1: waiting for: , got:RegisterRead # await msgNo:, rec:238
2019.05.02 21:03:01 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/10_CUL_HM.pm line 3447.
2019.05.02 21:03:01 1: stacktrace:
2019.05.02 21:03:01 1: main::__ANON__ called by ./FHEM/10_CUL_HM.pm (3447)
2019.05.02 21:03:01 1: main::CUL_HM_parseCommon called by ./FHEM/10_CUL_HM.pm (1529)
2019.05.02 21:03:01 1: main::CUL_HM_Parse called by fhem.pl (3905)
2019.05.02 21:03:01 1: main::Dispatch called by ./FHEM/00_HMUARTLGW.pm (1463)
2019.05.02 21:03:01 1: main::HMUARTLGW_Parse called by ./FHEM/00_HMUARTLGW.pm (1566)
2019.05.02 21:03:01 1: main::HMUARTLGW_Read called by fhem.pl (3709)
2019.05.02 21:03:01 1: main::CallFn called by fhem.pl (745)
2019.05.02 21:03:01 1: waiting for: , got:RegisterRead # await msgNo:, rec:240Ich habe insgesamt 3 HMUART's, einen als Modul im RPI und 2 über ESP8266 angebunde HMAURT (serial ip)
VG Klaus
Edit #1:
Habe intiuitv das Device, welches bei getconfig die Meldung auslöst erfassen können. (HM-SEN-RD-O)
2019.05.02 23:09:28 3: CUL_HM set Regensensor_Controller getConfig
2019.05.02 23:09:30 2: CUL_HM Regensensor_Controller attack:0129A08341D1CE01040000000001,0129A08341D1CE0103:129A08341D1CE00040000000000
2019.05.02 23:09:30 1: waiting for: PeerList, got:RegisterRead # await msgNo:89, rec:87
2019.05.02 23:09:30 1: waiting for: PeerList, got:RegisterRead # await msgNo:89, rec:88
2019.05.02 23:09:30 1: waiting for: PeerList, got:RegisterRead # await msgNo:89, rec:88Und hier noch ein Listing:
ZitatInternals:
CHANGED
DEF 41D1CE
FUUID 5c489c1c-f33f-b6d9-0976-94de6a65bfb3da6e
HMUART1_MSGCNT 5
HMUART1_RAWMSG 0500015159A01041D1CE29A08301000000
HMUART1_RSSI -81
HMUART1_TIME 2019-05-02 23:09:30
HMUART2_MSGCNT 6
HMUART2_RAWMSG 0500015459A01041D1CE29A08301000000
HMUART2_RSSI -84
HMUART2_TIME 2019-05-02 23:09:30
HMUART3_MSGCNT 6
HMUART3_RAWMSG 0501004A59A01041D1CE29A08301000000
HMUART3_RSSI -74
HMUART3_TIME 2019-05-02 23:09:30
IODev HMUART3
LASTInputDev HMUART2
MSGCNT 17
NAME Regensensor_Controller
NOTIFYDEV global
NR 459
NTFY_ORDER 50-Regensensor_Controller
STATE CMDs_done
TYPE CUL_HM
channel_01 Regensensor_Regen
channel_02 Regensensor_Heizung
lastMsg No:59 - t:10 s:41D1CE d:29A083 01000000
protErrIoAttack 1 last_at:2019-05-02 23:09:30
protLastRcv 2019-05-02 23:09:30
protRcv 8 last_at:2019-05-02 23:09:30
protSnd 12 last_at:2019-05-02 23:09:30
protState CMDs_done
rssi_at_HMUART1 cnt:5 min:-93 max:-81 avg:-84 lst:-81
rssi_at_HMUART2 cnt:6 min:-86 max:-84 avg:-85.16 lst:-84
rssi_at_HMUART3 cnt:6 min:-75 max:-73 avg:-73.66 lst:-74
READINGS:
2019-03-15 07:04:11 Activity alive
2017-01-11 16:56:20 CommandAccepted yes
2016-12-16 23:02:03 D-firmware 1.4
2016-12-16 23:02:03 D-serialNr MEQ1120910
2019-05-02 23:09:28 PairedTo 0x29A083
2016-12-12 08:41:55 R-localResDis off
2016-12-12 08:41:55 R-pairCentral 0x29A083
2016-12-16 23:02:11 R-sign off
2016-12-12 08:41:55 R-transmDevTryMax 10
2019-05-02 23:09:28 RegL_00. 00:00 02:01 0A:29 0B:A0 0C:83 14:0A 18:00
2019-04-20 20:48:22 powerOn 2019-04-20 20:48:22
2019-05-02 23:09:30 sabotageAttack_ErrIoAttack cnt 1
2019-05-02 23:09:30 state CMDs_done
helper:
HM_CMDNR 89
cSnd 0129A08341D1CE01040000000001,0129A08341D1CE0103
mId 00A7
peerFriend
peerOpt -:sensRain
regLst 0
rxType 1
supp_Pair_Rep 0
ack:
expert:
def 1
det 1
raw 1
tpl 1
io:
newChn +41D1CE,00,01,00
nextSend 1556831370.49188
prefIO
rxt 0
vccu vccu
p:
41D1CE
00
01
00
mRssi:
mNo 59
io:
HMUART1:
-81
-81
HMUART2:
-84
-84
HMUART3:
-72
-72
prt:
bErr 0
sProc 0
rspWait:
q:
qReqConf
qReqStat
regCollect:
role:
dev 1
rpt:
IO HMUART1
flg A
ts 1556831370.28082
ack:
HASH(0x1e718d8)
59800229A08341D1CE00
rssi:
at_HMUART1:
avg -84
cnt 5
lst -81
max -81
min -93
at_HMUART2:
avg -85.1666666666667
cnt 6
lst -84
max -84
min -86
at_HMUART3:
avg -73.6666666666667
cnt 6
lst -74
max -73
min -75
shadowReg:
tmpl:
Attributes:
IODev HMUART1
IOgrp vccu
actCycle 048:00
actStatus alive
alias Regensensor Controller
autoReadReg 4_reqStatus
event-on-change-reading no
expert 251_anything
firmware 1.4
group Regensensor
icon sani_floor_heating_neutral
model HM-SEN-RD-O
room Garten
serialNr MEQ1120910
subType sensRain
webCmd getConfig:clear msgEvents
Edit #2:
ein
attr Regensensor_Controller IOgrp vccu:HMUART1
brachte Besserung
Hallo Martin,
Zitat von: martinp876 am 02 Mai 2019, 21:05:37
Da stimmt mit dem msg sequencing etwas nicht. Wie viele ios hast du? Welches device ist es?
das weiss ich garnicht so genau, aber es sind schon einige viele. Beim Starten kommt
Server started with 376 defined entities
aber da sind natürlich auch ein paar nicht Homematic defines dabei. Ich besitze aber fast nur ausschließlich HM Geräte.
Zitat von: martinp876 am 02 Mai 2019, 21:05:37
Das ist eine Nachricht zu getconfig. Könnte evtl. auch eine Nachricht zu Register Änderungen am device sein. Ei rt bspw.
Kannst du die passenden msg aufzeichnen?
Welches device den getconfig gemacht hat, kann ich nicht ersehen. Ich mache jetzt mal einzeln getconfigs und schaue mal,
ob irgendwann die Meldung kommt, dann sage ich bescheid....
VG
mcfly
Irgendwie strange, die Meldung kommt nicht immer, aber immer öfter:
Zitat019.05.04 21:05:52 1: waiting for: PeerList, got:RegisterRead # await msgNo:87, rec:84
Servus alle zusammen,
ich habe nun einzeln einige devices per getconfig abgefragt, aber noch nicht den fehler reproduzieren können.
@rample: bekommst du das per Hand hin?
vg
mcfly
Zitat von: mcfly71 am 06 Mai 2019, 07:38:04
@rampler: bekommst du das per Hand hin?
vg
mcfly
Hallo mcfly71,
wie bereits weiter oben beschrieben konnte ich den Fehler mit diesem Command:
set Regensensor_Controller getConfigreproduzieren.
Nachdem ich für meinen Regensensor_Controller das IO-Device mit:
attr Regensensor_Controller IOgrp vccu:HMUART1 geändert habe, war zumindest für das spezielle Device die Fehlermeldung weg. (vorher war nur vccu als IOgrp definiert)
Leider ist das aber nicht die Lösung, habe mitlerweile immer mal wieder solche Meldungen, und keine Ahnung woher die kommen.
z.B.
2019.05.05 21:07:21 1: waiting for: PeerList, got:RegisterRead # await msgNo:88, rec:81
2019.05.06 06:00:08 1: waiting for: PeerList, got:RegisterRead # await msgNo:191, rec:190Diese Meldungen kamen bei mir jedenfalls erst mit einer der letzten updates..
Vielleicht äußert sich Martin noch ...
VG Klaus
Hallo Martin,
ich kriege durch ein statusinfo oder ein getconfig die fehler nicht reproduziert, aber in Abständen tauchen sie immer mal wieder auf.
Wie kann ich dem Fehler auf die Schliche kommen ? Hast du eine Idee, oder soagr eine Idee zur Fehlerbeseitigung ???
Die letzten Fehler waren folgende, aber die Devices in der Nähe mit getconfig o.ä. haben nicht den Fehler reproduziert...
Zitat
2019.05.07 08:25:02 3: CUL_HM set LICHT_BAR on
2019.05.07 08:25:02 3: CUL_HM set BUEGELSTECKDOSEN_WASCHKUECHE on
2019.05.07 08:25:11 1: waiting for: RegisterRead, got:RegisterRead # await msgNo:63, rec:61
2019.05.07 08:25:11 1: waiting for: RegisterRead, got:RegisterRead # await msgNo:63, rec:61
2019.05.07 08:25:11 3: CUL_HM set ROLLO2_WOHNZIMMER_ on
2019.05.07 08:25:13 1: waiting for: RegisterRead, got:RegisterRead # await msgNo:67, rec:65
2019.05.07 08:25:13 1: waiting for: PeerList, got:RegisterRead # await msgNo:68, rec:65
2019.05.07 08:25:13 1: waiting for: PeerList, got:RegisterRead # await msgNo:68, rec:67
2019.05.07 08:25:51 1: StarteRollade: ROLLO2_WOHNZIMMER_ Richtung: hoch Gruppe: 0 in 0[sek]
:
:
2019.05.07 17:17:57 3: CUL_HM set LICHT_BAR on
2019.05.07 17:17:57 3: CUL_HM set BUEGELSTECKDOSEN_WASCHKUECHE on
2019.05.07 17:18:07 3: CUL_HM set ROLLO2_WOHNZIMMER_ on
2019.05.07 17:18:12 1: waiting for: PeerList, got:RegisterRead # await msgNo:180, rec:177
2019.05.07 17:18:12 1: waiting for: PeerList, got:RegisterRead # await msgNo:180, rec:179
vg
mcfly
Ist schon ein paar Tage her...
Die Meldungen kommen trotz updates immer noch, gibt es da was neues ?
VG Klaus
Ich habe auch nichts geändert. Es kann vorkommen, dass die msg-sequenze nicht in der richtigen Reihenfolge empfangen wird. das ist - wie schon angemerkt - eine Warnung.
Zum debuggen bräuchte ich ein log (siehe sniffen) und ich brauche die Konfiguration : welche IOs sind vorhanden und in betrieb, wie sind diese angebunden...
Es hat etwas mit verzögerungen und wiederholungen zu tun.
so lange keine Protokoll-fehler auftreten hat die Kommunikation sowie die wiederholmechanismen gegriffen..
Zitat von: martinp876 am 01 Juni 2020, 16:43:15
Es kann vorkommen, dass die msg-sequenze nicht in der richtigen Reihenfolge empfangen wird. das ist - wie schon angemerkt - eine Warnung.
Ok, verstehe ich, zumal ich ja auch keine Fehlfunktion mit diesen Meldungen auftreten. Ich dachte es wäre irgendwie wichtig, da ja der Meldung ein LogLvel von 1 zugeordnet ist. Evtl. könnte man die Medlung mit 4 versehen. Ein sniffen ist irgendwie schwierig, da die Meldung nicht jeden Tag kommt.