debmatic Update 3.71.12

[deimos]

Hi,

ich habe grade das Update auf 3.71.12 im testing APT Repository bereitgestellt. Den Fix für den Known Bug habe ich eingebaut, daher werde ich wie gewohnt das Update in ca. 2 Wochen auch im stable APT Repository bereitstellen (sofern keine gravierenden Probleme in der Firmware auftauchen)

Viele Grüße
Alex

[deimos]

Hi,

ich habe die Version grade auch im stable APT Repository bereitgestellt.

Viele Grüße
Alex

[FKuypers]

Hi Alex,

ich habe eben das Update gemacht - bis jetzt ist Debmatic nicht wieder hoch gekommen - es wirkt, als wäre es total langsam. Laut Log hat es jetzt 8 Minuten gedauert, bis einmal hmserver gestartet wurde - Laufe auf einem Hyper-V Server in einer Ubuntu 20.04 VM.

Code: Alles auswählen

Aug 26 12:05:16 debmatic systemd[1247]: Startup finished in 371ms.
Aug 26 12:05:22 debmatic systemd-timesyncd[640]: Initial synchronization to time server 91.189.91.157:123 (ntp.ubuntu.com).
Aug 26 12:05:28 debmatic kernel: [   57.589481] hv_balloon: Max. dynamic memory size: 1048576 MB
Aug 26 12:05:30 debmatic systemd[1]: systemd-timedated.service: Succeeded.
Aug 26 12:05:40 debmatic systemd[1]: Started Session 4 of user frank.
Aug 26 12:05:54 debmatic kernel: [   83.455296] hv_balloon: Balloon request will be partially fulfilled. Balloon floor reached.
Aug 26 12:05:54 debmatic kernel: [   83.482829] hv_balloon: Balloon request will be partially fulfilled. Balloon floor reached.
Aug 26 12:05:54 debmatic kernel: [   83.483112] hv_balloon: Balloon request will be partially fulfilled. Balloon floor reached.
Aug 26 12:05:54 debmatic kernel: [   83.483395] hv_balloon: Balloon request will be partially fulfilled. Balloon floor reached.
Aug 26 12:07:05 debmatic cuxd[1181]: INIT 'xmlrpc_bin://192.168.2.141:8701' 'iobroker-hyperv:hm-rpc.2:5d2a83ac2e19dcb5c74b7364a0ae7abd'
Aug 26 12:09:59 debmatic dbus-daemon[735]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.13' (uid=0 pid=754 comm="/usr/lib/snapd/snapd " label="unconfined")
Aug 26 12:10:00 debmatic systemd[1]: debmatic-updatelgwfirmware.service: start operation timed out. Terminating.
Aug 26 12:10:00 debmatic systemd[1]: Starting Time & Date Service...
Aug 26 12:10:00 debmatic systemd[1]: debmatic-updatelgwfirmware.service: Main process exited, code=killed, status=15/TERM
Aug 26 12:10:00 debmatic systemd[1]: debmatic-updatelgwfirmware.service: Failed with result 'timeout'.
Aug 26 12:10:00 debmatic systemd[1]: Failed to start debmatic update lgw firmware.
Aug 26 12:10:00 debmatic systemd[1]: Starting debmatic update lgw key...
Aug 26 12:10:00 debmatic dbus-daemon[735]: [system] Successfully activated service 'org.freedesktop.timedate1'
Aug 26 12:10:00 debmatic systemd[1]: Started Time & Date Service.
Aug 26 12:10:01 debmatic snapd[754]: storehelpers.go:773: cannot refresh: snap has no updates available: "core18", "core20", "lxd", "snapd"
Aug 26 12:10:02 debmatic setlgwkey: Could not find gateway by unicast with ip 192.168.2.142. Trying search by serial number.
Aug 26 12:10:10 debmatic setlgwkey: Could not find Lan Gateway using ip and serial number
Aug 26 12:10:10 debmatic setlgwkey: Cannot determine IP address of gateway with serial CCU2GW0001
Aug 26 12:10:10 debmatic systemd[1]: Finished debmatic update lgw key.
Aug 26 12:10:10 debmatic systemd[1]: Starting debmatic rfd...
Aug 26 12:10:10 debmatic kernel: [  339.180861] bpfilter: Loaded bpfilter_umh pid 1646
Aug 26 12:10:10 debmatic kernel: [  339.181177] Started bpfilter
Aug 26 12:10:30 debmatic systemd[1]: systemd-timedated.service: Succeeded.
Aug 26 12:10:54 debmatic kernel: [  383.307787] hv_balloon: Balloon request will be partially fulfilled. Balloon floor reached.
Aug 26 12:13:10 debmatic systemd[1]: debmatic-rfd.service: start operation timed out. Terminating.
Aug 26 12:13:10 debmatic systemd[1]: debmatic-rfd.service: Failed with result 'timeout'.
Aug 26 12:13:10 debmatic systemd[1]: Failed to start debmatic rfd.
Aug 26 12:13:10 debmatic systemd[1]: Starting debmatic hmserver...
Aug 26 12:13:12 debmatic start_hmserver.sh[1786]: WARNING: sun.reflect.Reflection.getCallerClass is not supported. This will impact performance.
Aug 26 12:13:15 debmatic start_hmserver.sh[1786]: Init Hardware Info
Aug 26 12:13:15 debmatic systemd[1]: debmatic-rfd.service: Scheduled restart job, restart counter is at 1.
Aug 26 12:13:15 debmatic systemd[1]: Stopped debmatic rfd.
Aug 26 12:13:15 debmatic systemd[1]: Starting debmatic update lgw firmware...
Aug 26 12:13:15 debmatic update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Aug 26 12:13:24 debmatic kernel: [  533.394063] eq3loop: eq3loop_open_slave() mmd_hmip
Aug 26 12:13:24 debmatic kernel: [  533.394113] eq3loop: eq3loop_close_slave() mmd_hmip
Aug 26 12:13:24 debmatic kernel: [  533.394635] eq3loop: eq3loop_open_slave() mmd_hmip
Aug 26 12:13:24 debmatic kernel: [  533.394667] eq3loop: eq3loop_close_slave() mmd_hmip
Aug 26 12:13:24 debmatic kernel: [  533.394831] eq3loop: eq3loop_open_slave() mmd_hmip
Aug 26 12:13:24 debmatic kernel: [  533.394860] eq3loop: eq3loop_close_slave() mmd_hmip
Aug 26 12:13:24 debmatic kernel: [  533.396157] eq3loop: eq3loop_open_slave() mmd_hmip
Aug 26 12:13:34 debmatic cuxd[1181]: process_rpc_request(127.0.0.1) - illegal XMLRPC(listDevices) request
Aug 26 12:13:36 debmatic cuxd[1181]: process_rpc_request(127.0.0.1) - illegal XMLRPC(init) request
Aug 26 12:13:38 debmatic systemd[1]: Started debmatic hmserver.

hmserver.log zeigt jetzt:

Code: Alles auswählen

2023-08-26 12:19:26,062 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher ERROR [Timer-3] Could not register BidCos-RF_java 
2023-08-26 12:19:31,061 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher ERROR [Timer-3] Could not register BidCos-RF_java 
2023-08-26 12:19:36,061 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher ERROR [Timer-3] Could not register BidCos-RF_java 

Jetzt ist die Webseite hochgekommen - allerdings wird direkt gemeldet, daß eine Komponente nicht funktioniert...

Irgendwelche Tipps, wo ich suchen kann?

Danke,
Frank

[Baxxy]

Sieht irgendwie nach der gleichen Problematik wie bei piVCCU aus... zumindest anhand der Meldungen im hmserver.log.
viewtopic.php?f=69&t=79648#p775459

[deimos]

Hi,

die baloon Meldungen machen mich stutzig, hat die VM genug RAM?

Viele Grüße
Alex

[FKuypers]

Ich habe den Speicher dynamischi in Hyper-V zugewiesen.

Ich sehe vor allen Dingen eine hohe CPU Last im Hyper-V Manager zwischen 15% und 23%. Top sagt folgendes.

Code: Alles auswählen

top - 15:25:20 up  2:32,  1 user,  load average: 2.01, 2.02, 2.00
Tasks: 127 total,   3 running, 124 sleeping,   0 stopped,   0 zombie
%Cpu(s):100.0 us,  0.0 sy,  0.0 ni,  0.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
MiB Mem :   1848.1 total,    138.3 free,   1317.6 used,    392.2 buff/cache
MiB Swap:   2048.0 total,   2046.7 free,      1.3 used.    364.2 avail Mem

    PID USER      PR  NI    VIRT    RES    SHR S  %CPU  %MEM     TIME+ COMMAND
   4760 root      20   0    7668   1740   1552 R  99.7   0.1   1:02.89 rfd
   1091 root      20   0    6056   1160   1020 R  99.3   0.1 151:10.21 hss_led
   3655 root      20   0       0      0      0 I   0.3   0.0   0:02.24 kworker+
   4793 root      20   0    8032   3616   3108 R   0.3   0.2   0:00.03 top
      1 root      20   0  102844  11664   8428 S   0.0   0.6   0:03.37 systemd
      2 root      20   0       0      0      0 S   0.0   0.0   0:00.00 kthreadd
      3 root       0 -20       0      0      0 I   0.0   0.0   0:00.00 rcu_gp
      4 root       0 -20       0      0      0 I   0.0   0.0   0:00.00 rcu_par+
      6 root       0 -20       0      0      0 I   0.0   0.0   0:00.00 kworker+
      8 root       0 -20       0      0      0 I   0.0   0.0   0:00.00 mm_perc+
      9 root      20   0       0      0      0 S   0.0   0.0   0:00.12 ksoftir+
     10 root      20   0       0      0      0 I   0.0   0.0   0:00.90 rcu_sch+
     11 root      rt   0       0      0      0 S   0.0   0.0   0:00.03 migrati+
     12 root     -51   0       0      0      0 S   0.0   0.0   0:00.00 idle_in+
     14 root      20   0       0      0      0 S   0.0   0.0   0:00.00 cpuhp/0
     15 root      20   0       0      0      0 S   0.0   0.0   0:00.00 cpuhp/1
     16 root     -51   0       0      0      0 S   0.0   0.0   0:00.00 idle_in+
     
     

Ich habe übrigens wieder auf die letzte Version downgegraded, was leider auch keine Abhilfe schaft....

Frank

[FKuypers]

Ich habe jetzt Debmatic neu in einer frischen VM installiert und das Backup eingespielt - jetzt geht es alles.

So eine Desaster Recovery Übung ist ja manchmal ganz gut.

Die alte VM existiert noch, falls ich einen Fix testen soll..

Danke,
Frank

[gnom]

ich hatte beim Update ein Problem(chen) auf meinen beiden Systemen, welches ich auch schon bei vorherigen updates gehabt habe.

Nach Installation und anschließenden Neustart(s) wurde das Funkmodul nicht erkannt.

Das Problem ließ sich jedes mal mit sudo dpkg-reconfigure pivccu-modules-dkms beheben, soweit alles gut.

Frage ist nur, warum das mehrfach bei den letzten Updates passiert ist und ob das vermeidbar wäre.

Meine beiden Systeme laufen auf Laptops unter Debian 11