FHEM Forum

FHEM - Hausautomations-Systeme => Homematic => Thema gestartet von: kaihs am 23 Mai 2016, 20:24:38

Titel: Firmware-Update via CUL schlägt fehl
Beitrag von: kaihs am 23 Mai 2016, 20:24:38
Das Update der FW schlägt bei mir reproduzierbar fehl, getestet mit zwei HM-LC-Bl1PBU-FM


2016.05.23 20:04:58 2: CUL_HM fwUpdate started for wz_Rollo_Fenster
2016.05.23 20:04:59 3: CUL_HM set wz_Rollo_Fenster fwUpdate /opt/fhem/hmfw/HM-LC-Bl1PBU-FM_update_V2_8_2_150713/HM-LC-Bl1PBU-FM_update_V2_8_2_150713.eq3
2016.05.23 20:04:59 2: CUL_HM fwUpdate wz_Rollo_Fenster entered mode. IO-speed: fast
2016.05.23 20:05:05 1: PERL WARNING: Use of uninitialized value $mNo in sprintf at ./FHEM/10_CUL_HM.pm line 6527.
2016.05.23 20:05:05 3: stacktrace:
2016.05.23 20:05:05 3:     main::__ANON__                      called by ./FHEM/10_CUL_HM.pm (6527)
2016.05.23 20:05:05 3:     main::CUL_HM_FWupdateSteps          called by ./FHEM/10_CUL_HM.pm (6596)
2016.05.23 20:05:05 3:     main::CUL_HM_FWupdateBTo            called by fhem.pl (2781)
2016.05.23 20:05:05 3:     main::HandleTimeout                 called by fhem.pl (591)
2016.05.23 20:05:10 1: PERL WARNING: Use of uninitialized value $mNo in sprintf at ./FHEM/10_CUL_HM.pm line 6527.
2016.05.23 20:05:10 3: stacktrace:
2016.05.23 20:05:10 3:     main::__ANON__                      called by ./FHEM/10_CUL_HM.pm (6527)
2016.05.23 20:05:10 3:     main::CUL_HM_FWupdateSteps          called by ./FHEM/10_CUL_HM.pm (6596)
2016.05.23 20:05:10 3:     main::CUL_HM_FWupdateBTo            called by fhem.pl (2781)
2016.05.23 20:05:10 3:     main::HandleTimeout                 called by fhem.pl (591)
2016.05.23 20:05:15 1: PERL WARNING: Use of uninitialized value $mNo in sprintf at ./FHEM/10_CUL_HM.pm line 6527.
2016.05.23 20:05:15 3: stacktrace:
2016.05.23 20:05:15 3:     main::__ANON__                      called by ./FHEM/10_CUL_HM.pm (6527)
2016.05.23 20:05:15 3:     main::CUL_HM_FWupdateSteps          called by ./FHEM/10_CUL_HM.pm (6596)
2016.05.23 20:05:15 3:     main::CUL_HM_FWupdateBTo            called by fhem.pl (2781)
2016.05.23 20:05:15 3:     main::HandleTimeout                 called by fhem.pl (591)
2016.05.23 20:05:20 1: PERL WARNING: Use of uninitialized value $mNo in sprintf at ./FHEM/10_CUL_HM.pm line 6527.
2016.05.23 20:05:20 3: stacktrace:
2016.05.23 20:05:20 3:     main::__ANON__                      called by ./FHEM/10_CUL_HM.pm (6527)
2016.05.23 20:05:20 3:     main::CUL_HM_FWupdateSteps          called by ./FHEM/10_CUL_HM.pm (6596)
2016.05.23 20:05:20 3:     main::CUL_HM_FWupdateBTo            called by fhem.pl (2781)
2016.05.23 20:05:20 3:     main::HandleTimeout                 called by fhem.pl (591)
2016.05.23 20:05:25 1: PERL WARNING: Use of uninitialized value $mNo in sprintf at ./FHEM/10_CUL_HM.pm line 6527.
2016.05.23 20:05:25 3: stacktrace:
2016.05.23 20:05:25 3:     main::__ANON__                      called by ./FHEM/10_CUL_HM.pm (6527)
2016.05.23 20:05:25 3:     main::CUL_HM_FWupdateSteps          called by ./FHEM/10_CUL_HM.pm (6596)
2016.05.23 20:05:25 3:     main::CUL_HM_FWupdateBTo            called by fhem.pl (2781)
2016.05.23 20:05:25 3:     main::HandleTimeout                 called by fhem.pl (591)
2016.05.23 20:05:31 2: CUL_HM fwUpdate wz_Rollo_Fenster end. IO-speed: normal


Firmware des CULs ist 1.61, CUL_HM ist aktuell

10_CUL_HM.pm 11499 2016-05-22 17:42:23Z martinp876


Ich nehme an die Warnung oben ist nur ein Folgefehler des fehlgeschlagenen Updates.

Empfangsprobleme sind unwahrscheinlich:


rssi_CUL_0 min:-55 max:-52 lst:-52 avg:-52.66 cnt:6
rssi_at_CUL_0 avg:-51.43 cnt:8 max:-49.5 lst:-53 min:-53


Der Vorgang nochmal mit höherem Loglevel

2016.05.23 20:19:45 5: CUL_HM wz_Rollo_Fenster protEvent:CMDs_FWupdate
2016.05.23 20:19:45 2: CUL_HM fwUpdate started for wz_Rollo_Fenster
2016.05.23 20:19:45 5: CUL_0 sending As0A0A3011F1103452A536CA
2016.05.23 20:19:45 4: CUL_send:  CUL_0As 0A 0A 3011 F11034 52A536 CA
2016.05.23 20:19:45 3: CUL_HM set wz_Rollo_Fenster fwUpdate /opt/fhem/hmfw/HM-LC-Bl1PBU-FM_update_V2_8_2_150713/HM-LC-Bl1PBU-FM_update_V2_8_2_150713.eq3
2016.05.23 20:19:46 5: CUL/RAW: /A0E0A800252A536F110340101C8003628

2016.05.23 20:19:46 4: CUL_Parse: CUL_0 A 0E 0A 8002 52A536 F11034 0101C8003628 -54
2016.05.23 20:19:46 5: CUL_0 dispatch A0E0A800252A536F110340101C80036::-54:CUL_0
2016.05.23 20:19:46 5: CUL/RAW: /A1400001052A536000000004C4551303037303633362A

2016.05.23 20:19:46 4: CUL_Parse: CUL_0 A 14 00 0010 52A536 000000 004C4551303037303633362A -53
2016.05.23 20:19:46 5: CUL_0 dispatch A1400001052A536000000004C455130303730363336::-53:CUL_0
2016.05.23 20:19:46 2: CUL_HM fwUpdate wz_Rollo_Fenster entered mode. IO-speed: fast
2016.05.23 20:19:46 5: CUL_0 sending As0F0B00CBF1103452A536105B11F81547
2016.05.23 20:19:46 5: CUL 52A536 dly:95ms
2016.05.23 20:19:46 4: CUL_send:  CUL_0As 0F 0B 00CB F11034 52A536 105B11F81547
2016.05.23 20:19:46 5: CUL_0 sending AR
2016.05.23 20:19:46 4: CUL_send:  CUL_0AR     
2016.05.23 20:19:46 5: CUL_HM fwUpdate write block 1 of 246: 9 messages
2016.05.23 20:19:46 5: CUL_0 sending As270D00CAF1103452A5360102267D269AD1E5142CB43C72463102BAB8929AE803F64FA478AED06803
2016.05.23 20:19:46 4: CUL_send:  CUL_0As 27 0D 00CA F11034 52A536 0102267D269AD1E5142CB43C72463102BAB8929AE803F64FA478AED06803
2016.05.23 20:19:46 5: CUL_0 sending As270E00CAF1103452A536FE1428F217A70D7DE87B9E3D18C60EC20188E38881084DAC714E17603B12
2016.05.23 20:19:46 4: CUL_send:  CUL_0As 27 0E 00CA F11034 52A536 FE1428F217A70D7DE87B9E3D18C60EC20188E38881084DAC714E17603B12
2016.05.23 20:19:46 5: CUL_0 sending As270F00CAF1103452A5369EC22BC17F7AEE125D1D4626042B4D9B32EA88FFCC2F45B6519977EEE466
2016.05.23 20:19:46 4: CUL_send:  CUL_0As 27 0F 00CA F11034 52A536 9EC22BC17F7AEE125D1D4626042B4D9B32EA88FFCC2F45B6519977EEE466
2016.05.23 20:19:46 5: CUL_0 sending As271000CAF1103452A536A7B167F1717EFDB337409C6D9DB3E64A143AD9673FAD75FA40C5B2972F78
2016.05.23 20:19:46 4: CUL_send:  CUL_0As 27 10 00CA F11034 52A536 A7B167F1717EFDB337409C6D9DB3E64A143AD9673FAD75FA40C5B2972F78
2016.05.23 20:19:46 5: CUL_0 sending As271100CAF1103452A536866221DEC1E9198EC6DFEE1E20BA681A4B4C1E4EE9CFD5DC6FD2DFB0BE4C
2016.05.23 20:19:46 4: CUL_send:  CUL_0As 27 11 00CA F11034 52A536 866221DEC1E9198EC6DFEE1E20BA681A4B4C1E4EE9CFD5DC6FD2DFB0BE4C
2016.05.23 20:19:46 5: CUL_0 sending As271200CAF1103452A536B1357BE087D93F238BAD77ED5AB1935A2820E2D3BD8AB42F222A22EEEB67
2016.05.23 20:19:46 4: CUL_send:  CUL_0As 27 12 00CA F11034 52A536 B1357BE087D93F238BAD77ED5AB1935A2820E2D3BD8AB42F222A22EEEB67
2016.05.23 20:19:46 5: CUL_0 sending As271300CAF1103452A53699481C7778D7A6BD5C6492F32D5E19182EA03B4200269E2E6AB24470F1BB
2016.05.23 20:19:46 4: CUL_send:  CUL_0As 27 13 00CA F11034 52A536 99481C7778D7A6BD5C6492F32D5E19182EA03B4200269E2E6AB24470F1BB
2016.05.23 20:19:46 5: CUL_0 sending As271400CAF1103452A536182E7F78763A58A73127FA51A9583DAF0C5EF2FAEABDDBA158CAE4BC1D44
2016.05.23 20:19:46 4: CUL_send:  CUL_0As 27 14 00CA F11034 52A536 182E7F78763A58A73127FA51A9583DAF0C5EF2FAEABDDBA158CAE4BC1D44
2016.05.23 20:19:46 5: CUL_0 sending As1D1520CAF1103452A5366976DB1FDF2ED3086CAEA5D06C42EEB60D6B4985
2016.05.23 20:19:46 4: CUL_send:  CUL_0As 1D 15 20CA F11034 52A536 6976DB1FDF2ED3086CAEA5D06C42EEB60D6B4985
2016.05.23 20:19:51 5: CUL_0 sending Ar
2016.05.23 20:19:51 4: CUL_send:  CUL_0Ar     
2016.05.23 20:19:51 5: CUL_HM wz_Rollo_Fenster protEvent:CMDs_done_FWupdate
2016.05.23 20:19:51 2: CUL_HM fwUpdate wz_Rollo_Fenster end. IO-speed: normal


Die Warnung taucht nicht mehr auf, sieht für mich eigentlich okay aus. Aber auch nach einem
set getVersion
wird noch die alte Version 2.3 gemeldet.

Woran könnte es noch liegen?
Titel: Antw:Firmware-Update via CUL schlägt fehl
Beitrag von: Jo am 23 Mai 2016, 21:39:45
Drück mal die Anlern Taste, ich hatte einen HM-LC-Bl1PBU-FM v2.3 bei dem auch die alte Version angezeigt wurde, das  Gerät drüber mit der selben Firmware und S/N nur eins höher hatte die neue FW-Version nach get Version angezeigt...
Gruß
Johannes
Titel: Antw:Firmware-Update via CUL schlägt fehl
Beitrag von: frank am 23 Mai 2016, 21:45:13
Zitatsieht für mich eigentlich okay aus.
es wird aber nur 1 block von 246 übertragen.

2016.05.23 20:19:46 5: CUL_HM fwUpdate write block 1 of 246: 9 messages
dann ist ende, wahrscheinlich weil das device kein ack sendet.

allerdings ist diese meldung dann seltsam
2016.05.23 20:19:51 5: CUL_HM wz_Rollo_Fenster protEvent:CMDs_done_FWupdate

vielleicht klappt das umschalten beim cul in den 100k mode nicht, sodass das device den cul dann nicht hören kann. ist der 100k mode (set cul AR) in der fw freigeschaltet?
Titel: Antw:Firmware-Update via CUL schlägt fehl
Beitrag von: kaihs am 24 Mai 2016, 20:42:18
Zitat von: frank am 23 Mai 2016, 21:45:13
ist der 100k mode (set cul AR) in der fw freigeschaltet?

Danke für den Denkanstoß. Ich war der festen Überzeugung, dass es in der culfw aktiviert sei.
Ein Blick in den Quellcode belehrt mich gerade eines Besseren.

Ich werde es mal aktivieren, flashen und nochmal versuchen.