HmIP-RFUSB unter RaspberryMatic

[ByterTom]

hier das log-File:
hoffe das hilft weiter, sind ja ein paar Fehler aufgeführt.
die Synology läuft normal, USB2.0 wird durchgereicht, denke da stimmt einiges mit dem Stick nicht.
Was kann ich tun?
vielen Dank für die Hilfe...

Code: Alles auswählen

Mar 14 22:02:22 homematic-raspi syslog.info syslogd started: BusyBox v1.34.1
Mar 14 22:02:22 homematic-raspi kern.notice kernel: klogd started: BusyBox v1.34.1 (2022-02-26 11:42:45 UTC)
Mar 14 22:02:22 homematic-raspi daemon.info udhcpc[378]: started, v1.34.1
Mar 14 22:02:23 homematic-raspi daemon.info udhcpc[378]: broadcasting discover
Mar 14 22:02:23 homematic-raspi daemon.info udhcpc[378]: broadcasting select for 192.168.1.109, server 192.168.1.1
Mar 14 22:02:23 homematic-raspi daemon.info udhcpc[378]: lease of 192.168.1.109 obtained from 192.168.1.1, lease time 864000
Mar 14 22:02:23 homematic-raspi kern.info kernel: [   11.598352] NET: Registered protocol family 10
Mar 14 22:02:23 homematic-raspi kern.info kernel: [   11.603560] Segment Routing with IPv6
Mar 14 22:02:23 homematic-raspi user.info firewall: configuration set
Mar 14 22:02:23 homematic-raspi daemon.info ifplugd(eth0)[477]: ifplugd 0.28 initializing.
Mar 14 22:02:23 homematic-raspi daemon.info ifplugd(eth0)[477]: Using interface eth0/02:11:32:2B:2A:80 with driver <virtio_net> (version: 1.0.0)
Mar 14 22:02:23 homematic-raspi daemon.info ifplugd(eth0)[477]: Using detection mode: SIOCETHTOOL
Mar 14 22:02:23 homematic-raspi daemon.info ifplugd(eth0)[477]: Initialization complete, link beat detected.
Mar 14 22:02:24 homematic-raspi daemon.info ifplugd(eth0)[477]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Mar 14 22:02:24 homematic-raspi daemon.warn ifplugd(eth0)[477]: client: ifup: interface eth0 already configured
Mar 14 22:02:24 homematic-raspi daemon.info ifplugd(eth0)[477]: Program executed successfully.
Mar 14 22:02:35 homematic-raspi daemon.notice ntpdate[483]: step time server 31.209.85.242 offset +1.429424 sec
Mar 14 22:02:35 homematic-raspi daemon.info chronyd[486]: chronyd version 4.0 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH +IPV6 -DEBUG)
Mar 14 22:02:35 homematic-raspi kern.info kernel: [   22.495026] raw-uart raw-uart: Reset radio module
Mar 14 22:02:39 homematic-raspi daemon.err xinetd[581]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Mar 14 22:02:39 homematic-raspi daemon.crit xinetd[581]: 581 {init_services} no services. Exiting...
Mar 14 22:02:39 homematic-raspi auth.info sshd[613]: Server listening on 0.0.0.0 port 22.
Mar 14 22:02:39 homematic-raspi auth.info sshd[613]: Server listening on :: port 22.
Mar 14 22:02:39 homematic-raspi user.info root: Updating RF Lan Gateway Coprocessor Firmware
Mar 14 22:02:39 homematic-raspi user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Mar 14 22:02:39 homematic-raspi user.info root: Updating RF Lan Gateway Firmware
Mar 14 22:02:39 homematic-raspi user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Mar 14 22:02:39 homematic-raspi kern.info kernel: [   26.629258] eq3loop: created slave mmd_hmip
Mar 14 22:02:39 homematic-raspi kern.info kernel: [   26.629864] eq3loop: created slave mmd_bidcos
Mar 14 22:02:41 homematic-raspi kern.info kernel: [   28.716037] eq3loop: eq3loop_open_slave() mmd_bidcos
Mar 14 22:02:49 homematic-raspi kern.info kernel: [   36.656188] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 14 22:02:49 homematic-raspi kern.info kernel: [   36.657498] eq3loop: eq3loop_close_slave() mmd_hmip
Mar 14 22:02:49 homematic-raspi kern.info kernel: [   36.663137] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 14 22:02:49 homematic-raspi kern.info kernel: [   36.663272] eq3loop: eq3loop_close_slave() mmd_hmip
Mar 14 22:02:49 homematic-raspi kern.info kernel: [   36.665454] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 14 22:02:49 homematic-raspi kern.info kernel: [   36.665563] eq3loop: eq3loop_close_slave() mmd_hmip
Mar 14 22:02:49 homematic-raspi kern.info kernel: [   36.674766] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 14 22:03:43 homematic-raspi daemon.info chronyd[486]: Selected source 192.168.1.1
Mar 14 21:06:08 homematic-raspi daemon.info : starting pid 989, tty '': '/bin/mv /tmp/boot.log /var/log/boot.log'
Mar 14 21:06:08 homematic-raspi daemon.info : starting pid 990, tty '/dev/null': '/usr/bin/monit -Ic /etc/monitrc'
Mar 14 21:06:08 homematic-raspi daemon.info : starting pid 991, tty '/dev/tty2': '/sbin/getty -L tty2 0 vt100'
Mar 14 22:06:08 homematic-raspi user.info monit[990]: Starting Monit 5.30.0 daemon with http interface at /var/run/monit.sock
Mar 14 22:06:08 homematic-raspi user.info monit[990]: 'homematic-raspi' Monit 5.30.0 started
Mar 14 22:06:11 homematic-raspi local0.err ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Mar 14 22:06:11 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'init': rpcClient.execute() failed (url: xmlrpc://127.0.0.1:39292/groups, params: {"xmlrpc_bin://127.0.0.1:31999","1008"}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Mar 14 22:06:11 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'init' call failed (interface: 1008, params: {"xmlrpc_bin://127.0.0.1:31999","1008"}) [CallInit():iseXmlRpc.cpp:1204]
Mar 14 22:06:11 homematic-raspi local0.err ReGaHss: ERROR: failed CallInit() for interface=VirtualDevices [ThreadFunction():iseRTHss.cpp:163]
Mar 14 22:06:11 homematic-raspi local0.err ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Mar 14 22:06:11 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'init': rpcClient.execute() failed (url: xmlrpc://127.0.0.1:32010, params: {"xmlrpc_bin://127.0.0.1:31999","1009"}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Mar 14 22:06:11 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'init' call failed (interface: 1009, params: {"xmlrpc_bin://127.0.0.1:31999","1009"}) [CallInit():iseXmlRpc.cpp:1204]
Mar 14 22:06:11 homematic-raspi local0.err ReGaHss: ERROR: failed CallInit() for interface=HmIP-RF [ThreadFunction():iseRTHss.cpp:163]
Mar 14 22:06:18 homematic-raspi auth.info login[991]: root login on 'tty2'
Mar 14 22:06:25 homematic-raspi user.err monit[990]: 'hw-watchdogEnabled' status failed (1) -- no output
Mar 14 22:06:25 homematic-raspi user.err monit[990]: 'wlan0Exists' status failed (1) -- no output
Mar 14 22:06:28 homematic-raspi user.err monit[990]: 'hs485dEnabled' status failed (2) -- grep: /var/etc/hs485d.conf: No such file or directory
Mar 14 22:06:28 homematic-raspi user.err monit[990]: 'hmlangwEnabled' status failed (1) -- no output
Mar 14 22:06:28 homematic-raspi user.warn monit[990]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection refused
Mar 14 22:06:28 homematic-raspi user.warn monit[990]: 'hasUSB' status failed (1) -- no output
Mar 14 22:06:28 homematic-raspi user.err monit[990]: 'voltageCheckEnabled' status failed (1) -- no output
Mar 14 22:06:28 homematic-raspi user.err monit[990]: 'hb_rf_eth-CheckEnabled' status failed (1) -- no output
Mar 14 22:06:28 homematic-raspi user.warn monit[990]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Mar 14 22:06:28 homematic-raspi user.warn monit[990]: 'rpi4usb3Check' status failed (1) -- no output
Mar 14 22:06:28 homematic-raspi user.err monit[990]: 'tailscaleEnabled' status failed (1) -- no output
Mar 14 22:06:28 homematic-raspi user.err monit[990]: Lookup for '/media/usb1' filesystem failed  -- not found in /proc/self/mounts
Mar 14 22:06:28 homematic-raspi user.err monit[990]: Filesystem '/media/usb1' not mounted
Mar 14 22:06:28 homematic-raspi user.err monit[990]: 'usb1' unable to read filesystem '/media/usb1' state
Mar 14 22:06:28 homematic-raspi user.info monit[990]: 'usb1' trying to restart
Mar 14 22:06:45 homematic-raspi user.warn monit[990]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection refused
Mar 14 22:06:46 homematic-raspi user.warn monit[990]: 'hasUSB' status failed (1) -- no output
Mar 14 22:06:46 homematic-raspi user.warn monit[990]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Mar 14 22:06:46 homematic-raspi user.warn monit[990]: 'rpi4usb3Check' status failed (1) -- no output
Mar 14 22:06:46 homematic-raspi user.err monit[990]: Filesystem '/media/usb1' not mounted
Mar 14 22:06:46 homematic-raspi user.err monit[990]: 'usb1' unable to read filesystem '/media/usb1' state
Mar 14 22:06:46 homematic-raspi user.info monit[990]: 'usb1' trying to restart
Mar 14 22:07:00 homematic-raspi local0.err ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Mar 14 22:07:00 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.execute() failed (url: xmlrpc://127.0.0.1:32010, params: {"00041D89B553C8:0","CARRIER_SENSE_LEVEL"}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Mar 14 22:07:00 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00041D89B553C8:0","CARRIER_SENSE_LEVEL"}) [CallGetValue():iseXmlRpc.cpp:1435]
Mar 14 22:07:00 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Mar 14 22:07:03 homematic-raspi user.warn monit[990]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection refused
Mar 14 22:07:04 homematic-raspi user.warn monit[990]: 'hasUSB' status failed (1) -- no output
Mar 14 22:07:04 homematic-raspi user.err monit[990]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Mar 14 22:07:04 homematic-raspi user.err monit[990]: 'rpi4usb3Check' status failed (1) -- no output
Mar 14 22:07:04 homematic-raspi user.err monit[990]: Filesystem '/media/usb1' not mounted
Mar 14 22:07:04 homematic-raspi user.err monit[990]: 'usb1' unable to read filesystem '/media/usb1' state
Mar 14 22:07:04 homematic-raspi user.info monit[990]: 'usb1' trying to restart
Mar 14 22:07:21 homematic-raspi user.warn monit[990]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection refused
Mar 14 22:07:22 homematic-raspi user.warn monit[990]: 'hasUSB' status failed (1) -- no output
Mar 14 22:07:22 homematic-raspi user.err monit[990]: Filesystem '/media/usb1' not mounted
Mar 14 22:07:22 homematic-raspi user.err monit[990]: 'usb1' unable to read filesystem '/media/usb1' state
Mar 14 22:07:22 homematic-raspi user.info monit[990]: 'usb1' trying to restart
Mar 14 22:07:39 homematic-raspi user.err monit[990]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection refused
Mar 14 22:07:39 homematic-raspi user.info monit[990]: 'HMIPServer' trying to restart
Mar 14 22:07:39 homematic-raspi user.info monit[990]: 'HMIPServer' restart: '/etc/init.d/S62HMServer restart'
Mar 14 22:07:39 homematic-raspi kern.info kernel: [  326.490932] eq3loop: eq3loop_close_slave() mmd_hmip
Mar 14 22:07:43 homematic-raspi kern.info kernel: [  330.944628] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 14 22:07:43 homematic-raspi kern.info kernel: [  330.944979] eq3loop: eq3loop_close_slave() mmd_hmip
Mar 14 22:07:43 homematic-raspi kern.info kernel: [  330.946323] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 14 22:07:43 homematic-raspi kern.info kernel: [  330.946414] eq3loop: eq3loop_close_slave() mmd_hmip
Mar 14 22:07:43 homematic-raspi kern.info kernel: [  330.947151] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 14 22:07:43 homematic-raspi kern.info kernel: [  330.947236] eq3loop: eq3loop_close_slave() mmd_hmip
Mar 14 22:07:43 homematic-raspi kern.info kernel: [  330.950647] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 14 22:07:54 homematic-raspi user.err monit[990]: 'hasUSB' status failed (1) -- no output
Mar 14 22:08:11 homematic-raspi user.err monit[990]: 'HMIPServer' service restarted 1 times within 1 cycles(s) - exec
Mar 14 22:08:11 homematic-raspi user.info monit[990]: 'HMIPServer' exec: '/bin/triggerAlarm.tcl HMIPServer restarted WatchDog: hmipserver-restart true'
Mar 14 22:08:11 homematic-raspi user.info monit[990]: 'HMIPServer' process is running after previous restart timeout (manually recovered?)
Mar 14 22:08:29 homematic-raspi user.info monit[990]: 'HMIPServer' connection succeeded to [localhost]:39292 [TCP/IP]
Mar 15 00:30:12 homematic-raspi kern.warn kernel: [ 8879.095080] hrtimer: interrupt took 2507740 ns

***** hmserver.log *****

Code: Alles auswählen

Mar 14 22:02:48 de.eq3.cbcs.server.core.vertx.KeyServerWorker ERROR [vert.x-worker-thread-0] Missing key server configuration parameter (Network.Key) for  mode: KEYSERVER_LOCAL 
Mar 14 22:07:42 de.eq3.cbcs.server.core.vertx.KeyServerWorker ERROR [vert.x-worker-thread-2] Missing key server configuration parameter (Network.Key) for  mode: KEYSERVER_LOCAL

[ByterTom]

Habge gerade in der Fritzbox die "Kindersicherung", also kein Internet für Raspberrymatic, aktiviert.
Anlernen mit KEY+SGTIN scheitern, kein Gerät erkannt, nichts in der Postbox.
Kann es ggf. an der neuesten Version der EQ3-HmIP RFUSB liegen? umtauschen ?

[Blackpoison]

Also ich habe auch schon den zweiten Stick und da hat sich nichts getan.
Müsst dann schon sehr viel Pech sein, wenn ich 2 kaputte Sticks nacheinander bekommen hätte.

Ich würde es an deiner Stelle aber aufjedenfall mal probieren. Eq3 war hier sehr kulant.

Vg

[ByterTom]

Schau auch mal in Dein Log-File. Bei mir sind es schone viele Probleme, die da gemeldet werden.
Es ist auf keinen Fall ein Carrier-Sense oder Reset-Problem.
Mein Stick bekam bei ersten Boot auch eine aktuellere Softwareversion verpasst, hat dann aber nie den Betrieb aufgenommen. Wird auch beim ersten Programmstart in die Liste aufgenommen. Warten wir noch ein wenig auf die Profis.
Da per USB bei mir der Softwarestand ausgelesen wird, scheidet wohl auch ein Versorgungsspannungsproblen aus.
Bin derzeit direkt an der DS220+ eingesteckt.

[ByterTom]

nach einem Neustart/Reboot gibt es bei mir die Fehlermeldung:
mutimacd ERROR
rfd ERROR
HMIPServer ERROR
Wie soll ich weiter vorgehen ?

[Baxxy]

Wie soll ich weiter vorgehen ?

Das ganze sieht ziemlich übel aus.
Wenn da die einzelnen Dienste beim booten nicht sauber gestartet werden brauchst du auch gar nicht groß weiter rumexperimentieren.
Das hat dann auch wenig bis nichts mit dem HmIP-RFUSB zu tun, denn der wird laut Log sauber erkannt.

Wenn du noch einen Pi2B/3B/3B+ rumfliegen hast würde ich erstmal dort eine RaspberryMatic aufsetzen. Das sollte schnell, einfach und problemlos funktionieren.

Dann kannst du in Ruhe die Probleme mit deine VM auf der Syno angehen, und wenn das mal fehlerfrei läuft immer noch dorthin migrieren.

[ByterTom]

Danke, möchte keinen PI.
Habe extra auf DS220+ aufgerüstet um darauf die Raspberrymatic in der VM zu installieren.
Derzeit läuft bei mir ja Homematic AP Cloud stabil seit einem Jahr, davor über 10 Jahre Max!
Stören sich ggf AP und Synology im selben IP-Netz?
Oder Ist das aktuelle .ova nicht mit der neuesten Synology-Software kompatibel? Dann stelle ich es bei GitHub ein.
Ist ja eine komplette Neuinstallation, und eigentlich nicht so schwierig, die VM einzurichten, aber es läuft nicht ohne Fehlermeldung und erkennt keine Geräte.

[ByterTom]

nach gefühlt 10x ReBoot auch mal RecoveryModus probiert, da ging es ein Device anzulernen. Dann ReBooot „normal“, Device ist noch da.

[Roland M.]

Hallo!

Jetzt hat es auch mich "erwischt"...
Ich hab mir 2 HmIP-RFUSB geholt und wollte einen am Proxmox-Server laufen lassen. Nach einigen Problemen und herumprobieren hab ich die Reißleine gezogen und das ganze einmal auf einem Raspi 3 installiert (mit den exakt gleichen Problemen wir am Proxmox-Server!). Um es kurz zu machen, es sieht alles vernünftig aus, es werden aber keine Geräte, egal ob HM oder HmIP angelernt!

Dass zwei USB-Sticks defekt sind, kann ich mir aber auch nicht vorstellen...

Also der Reihe nach:

Raspi 3B mit frisch gezogener aktueller Raspberrymatic 3.61.7.20220226 auf neuer Speicherkarte,
HmIP-RFUSB mit mitgeliefertem Verlängerungskabel angesteckt,
Monitor und Tastatur angesteckt.

Zur Sicherheit noch nach jedem Wechsel des Sticks das System zurückgesetzt,
Nur Grundinstallation durchgeführt, HmIP-RFUSB aus dem Postfach übernommen,
RM über WebUI ausgeschaltet (!) und alles neu gestartet.
Internetzugang über ssh erfolgreich überprüft.
USB-Stick wird auch auf der Konsole richtig erkannt, FW 4.4.16
Unter RM wird auf der Hilfe-Seite alles richtig angezeigt

Geräte anlernen funktioniert aber nicht, weder HM, noch HmIP (beide Möglichkeiten werden in der WebUI richtig angezeigt). Zeit läuft einfach ab.
Beide Geräte aber an anderen CCUs erfolgreich getestet.

Das danach gezogene Systemlog sieht aber auch recht vernünftig aus:

Code: Alles auswählen

***** messages *****
Jan  1 01:00:10 localhost syslog.info syslogd started: BusyBox v1.34.1
Jan  1 01:00:10 localhost kern.notice kernel: klogd started: BusyBox v1.34.1 (2022-02-26 11:31:39 UTC)
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
Jan  1 01:00:10 localhost kern.notice kernel: [    0.000000] Linux version 5.10.92 (builder@cd835bddfe43) (aarch64-buildroot-linux-gnu-gcc.br_real (Buildroot -gf01de4c) 10.3.0, GNU ld (GNU Binutils) 2.36.1) #1 SMP PREEMPT Sat Feb 26 12:05:03 UTC 2022
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] efi: UEFI not found.
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Reserved memory: created CMA memory pool at 0x000000003a000000, size 64 MiB
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Zone ranges:
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000]   DMA      [mem 0x0000000000000000-0x000000003dffffff]
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000]   DMA32    empty
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000]   Normal   empty
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Movable zone start for each node
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Early memory node ranges
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x000000003dffffff]
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003dffffff]
Jan  1 01:00:10 localhost kern.debug kernel: [    0.000000] On node 0 totalpages: 253952
Jan  1 01:00:10 localhost kern.debug kernel: [    0.000000]   DMA zone: 3968 pages used for memmap
Jan  1 01:00:10 localhost kern.debug kernel: [    0.000000]   DMA zone: 0 pages reserved
Jan  1 01:00:10 localhost kern.debug kernel: [    0.000000]   DMA zone: 253952 pages, LIFO batch:63
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] percpu: Embedded 30 pages/cpu s83224 r8192 d31464 u122880
Jan  1 01:00:10 localhost kern.debug kernel: [    0.000000] pcpu-alloc: s83224 r8192 d31464 u122880 alloc=30*4096
Jan  1 01:00:10 localhost kern.debug kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Detected VIPT I-cache on CPU0
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] CPU features: detected: ARM erratum 845719
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] CPU features: detected: ARM erratum 843419
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 249984
Jan  1 01:00:10 localhost kern.notice kernel: [    0.000000] Kernel command line: dwc_otg.lpm_enable=0 sdhci_bcm2708.enable_llm=0 console=tty2 kgdboc=tty2 scandelay=5 root=PARTUUID=deedbeef-02 ro rootfstype=ext4 elevator=deadline fsck.repair=yes lapic rootwait rootdelay=5 consoleblank=120 logo.nologo quiet loglevel=0 init_on_alloc=1 init_on_free=1 slab_nomerge iomem=relaxed pti=on net.ifnames=0 usb-storage.quirks=  coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1080 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  empty
Jan  1 01:00:10 localhost kern.warn kernel: [    0.000000] Kernel parameter elevator= does not have any effect anymore.
Jan  1 01:00:10 localhost kern.warn kernel: [    0.000000] Please use sysfs to set IO scheduler for individual devices.
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] mem auto-init: stack:off, heap alloc:on, heap free:on
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] mem auto-init: clearing system memory may take some time...
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] Memory: 909784K/1015808K available (10176K kernel code, 1688K rwdata, 3428K rodata, 3264K init, 1233K bss, 40488K reserved, 65536K cma-reserved)
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] ftrace: allocating 31627 entries in 124 pages
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] ftrace: allocated 124 pages with 5 groups
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] rcu: Preemptible hierarchical RCU implementation.
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] rcu: 	RCU event tracing is enabled.
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] 	Trampoline variant of Tasks RCU enabled.
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] 	Rude variant of Tasks RCU enabled.
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
Jan  1 01:00:10 localhost kern.notice kernel: [    0.000000] random: get_random_bytes called from start_kernel+0x3b8/0x57c with crng_init=0
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Jan  1 01:00:10 localhost kern.info kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Jan  1 01:00:10 localhost kern.info kernel: [    0.000009] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Jan  1 01:00:10 localhost kern.info kernel: [    0.000329] Console: colour dummy device 80x25
Jan  1 01:00:10 localhost kern.info kernel: [    0.000396] printk: console [tty2] enabled
Jan  1 01:00:10 localhost kern.info kernel: [    0.000463] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800)
Jan  1 01:00:10 localhost kern.info kernel: [    0.000499] pid_max: default: 32768 minimum: 301
Jan  1 01:00:10 localhost kern.info kernel: [    0.000719] LSM: Security Framework initializing
Jan  1 01:00:10 localhost kern.info kernel: [    0.000755] Yama: becoming mindful.
Jan  1 01:00:10 localhost kern.info kernel: [    0.001050] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
Jan  1 01:00:10 localhost kern.info kernel: [    0.001074] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
Jan  1 01:00:10 localhost kern.info kernel: [    0.002737] cgroup: Disabling memory control group subsystem
Jan  1 01:00:10 localhost kern.info kernel: [    0.005686] rcu: Hierarchical SRCU implementation.
Jan  1 01:00:10 localhost kern.info kernel: [    0.006951] EFI services will not be available.
Jan  1 01:00:10 localhost kern.info kernel: [    0.007633] smp: Bringing up secondary CPUs ...
Jan  1 01:00:10 localhost kern.info kernel: [    0.009046] Detected VIPT I-cache on CPU1
Jan  1 01:00:10 localhost kern.info kernel: [    0.009148] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
Jan  1 01:00:10 localhost kern.info kernel: [    0.010729] Detected VIPT I-cache on CPU2
Jan  1 01:00:10 localhost kern.info kernel: [    0.010790] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
Jan  1 01:00:10 localhost kern.info kernel: [    0.012346] Detected VIPT I-cache on CPU3
Jan  1 01:00:10 localhost kern.info kernel: [    0.012400] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
Jan  1 01:00:10 localhost kern.info kernel: [    0.012620] smp: Brought up 1 node, 4 CPUs
Jan  1 01:00:10 localhost kern.info kernel: [    0.012645] SMP: Total of 4 processors activated.
Jan  1 01:00:10 localhost kern.info kernel: [    0.012664] CPU features: detected: 32-bit EL0 Support
Jan  1 01:00:10 localhost kern.info kernel: [    0.012682] CPU features: detected: CRC32 instructions
Jan  1 01:00:10 localhost kern.info kernel: [    0.048663] CPU: All CPU(s) started at EL2
Jan  1 01:00:10 localhost kern.info kernel: [    0.048762] alternatives: patching kernel code
Jan  1 01:00:10 localhost kern.info kernel: [    0.050724] devtmpfs: initialized
Jan  1 01:00:10 localhost kern.notice kernel: [    0.073196] Enabled cp15_barrier support
Jan  1 01:00:10 localhost kern.notice kernel: [    0.073249] Enabled setend support
Jan  1 01:00:10 localhost kern.info kernel: [    0.073552] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
Jan  1 01:00:10 localhost kern.info kernel: [    0.073592] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Jan  1 01:00:10 localhost kern.info kernel: [    0.148107] pinctrl core: initialized pinctrl subsystem
Jan  1 01:00:10 localhost kern.info kernel: [    0.149466] DMI not present or invalid.
Jan  1 01:00:10 localhost kern.info kernel: [    0.150036] NET: Registered protocol family 16
Jan  1 01:00:10 localhost kern.info kernel: [    0.163697] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
Jan  1 01:00:10 localhost kern.info kernel: [    0.164652] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
Jan  1 01:00:10 localhost kern.info kernel: [    0.167113] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
Jan  1 01:00:10 localhost kern.info kernel: [    0.167997] thermal_sys: Registered thermal governor 'step_wise'
Jan  1 01:00:10 localhost kern.info kernel: [    0.168356] cpuidle: using governor ladder
Jan  1 01:00:10 localhost kern.info kernel: [    0.168439] cpuidle: using governor menu
Jan  1 01:00:10 localhost kern.info kernel: [    0.168916] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
Jan  1 01:00:10 localhost kern.info kernel: [    0.169205] ASID allocator initialised with 65536 entries
Jan  1 01:00:10 localhost kern.info kernel: [    0.198568] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Jan  1 01:00:10 localhost kern.info kernel: [    0.204877] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:58:22, variant start
Jan  1 01:00:10 localhost kern.info kernel: [    0.208890] raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2
Jan  1 01:00:10 localhost kern.info kernel: [    0.254900] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
Jan  1 01:00:10 localhost kern.notice kernel: [    0.258344] SCSI subsystem initialized
Jan  1 01:00:10 localhost kern.debug kernel: [    0.258739] libata version 3.00 loaded.
Jan  1 01:00:10 localhost kern.info kernel: [    0.259105] usbcore: registered new interface driver usbfs
Jan  1 01:00:10 localhost kern.info kernel: [    0.259203] usbcore: registered new interface driver hub
Jan  1 01:00:10 localhost kern.info kernel: [    0.259299] usbcore: registered new device driver usb
Jan  1 01:00:10 localhost kern.info kernel: [    0.260635] NetLabel: Initializing
Jan  1 01:00:10 localhost kern.info kernel: [    0.260654] NetLabel:  domain hash size = 128
Jan  1 01:00:10 localhost kern.info kernel: [    0.260670] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
Jan  1 01:00:10 localhost kern.info kernel: [    0.260842] NetLabel:  unlabeled traffic allowed by default
Jan  1 01:00:10 localhost kern.info kernel: [    0.261665] clocksource: Switched to clocksource arch_sys_counter
Jan  1 01:00:10 localhost kern.notice kernel: [    2.027577] VFS: Disk quotas dquot_6.6.0
Jan  1 01:00:10 localhost kern.info kernel: [    2.027717] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Jan  1 01:00:10 localhost kern.notice kernel: [    2.028012] FS-Cache: Loaded
Jan  1 01:00:11 localhost kern.info kernel: [    2.028452] CacheFiles: Loaded
Jan  1 01:00:11 localhost kern.info kernel: [    2.028895] simple-framebuffer 3e402000.framebuffer: framebuffer at 0x3e402000, 0x7e9000 bytes, mapped to 0x(____ptrval____)
Jan  1 01:00:11 localhost kern.info kernel: [    2.028918] simple-framebuffer 3e402000.framebuffer: format=a8r8g8b8, mode=1920x1080x32, linelength=7680
Jan  1 01:00:11 localhost kern.info kernel: [    2.029586] Console: switching to colour frame buffer device 240x67
Jan  1 01:00:11 localhost kern.info kernel: [    2.064983] simple-framebuffer 3e402000.framebuffer: fb0: simplefb registered!
Jan  1 01:00:11 localhost kern.info kernel: [    2.080688] NET: Registered protocol family 2
Jan  1 01:00:11 localhost kern.info kernel: [    2.080897] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
Jan  1 01:00:11 localhost kern.info kernel: [    2.082692] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
Jan  1 01:00:11 localhost kern.info kernel: [    2.082749] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
Jan  1 01:00:11 localhost kern.info kernel: [    2.083027] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
Jan  1 01:00:11 localhost kern.info kernel: [    2.083253] TCP: Hash tables configured (established 8192 bind 8192)
Jan  1 01:00:11 localhost kern.info kernel: [    2.083454] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
Jan  1 01:00:11 localhost kern.info kernel: [    2.083517] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
Jan  1 01:00:11 localhost kern.info kernel: [    2.083893] NET: Registered protocol family 1
Jan  1 01:00:11 localhost kern.info kernel: [    2.085091] RPC: Registered named UNIX socket transport module.
Jan  1 01:00:11 localhost kern.info kernel: [    2.085108] RPC: Registered udp transport module.
Jan  1 01:00:11 localhost kern.info kernel: [    2.085123] RPC: Registered tcp transport module.
Jan  1 01:00:11 localhost kern.info kernel: [    2.085138] RPC: Registered tcp NFSv4.1 backchannel transport module.
Jan  1 01:00:11 localhost kern.info kernel: [    2.087807] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
Jan  1 01:00:11 localhost kern.notice kernel: [    2.093454] Initialise system trusted keyrings
Jan  1 01:00:11 localhost kern.info kernel: [    2.094006] workingset: timestamp_bits=46 max_order=18 bucket_order=0
Jan  1 01:00:11 localhost kern.info kernel: [    2.106597] zbud: loaded
Jan  1 01:00:11 localhost kern.notice kernel: [    2.111307] FS-Cache: Netfs 'nfs' registered for caching
Jan  1 01:00:11 localhost kern.notice kernel: [    2.112673] NFS: Registering the id_resolver key type
Jan  1 01:00:11 localhost kern.notice kernel: [    2.112736] Key type id_resolver registered
Jan  1 01:00:11 localhost kern.notice kernel: [    2.112752] Key type id_legacy registered
Jan  1 01:00:11 localhost kern.info kernel: [    2.112933] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Jan  1 01:00:11 localhost kern.info kernel: [    2.112953] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
Jan  1 01:00:11 localhost kern.notice kernel: [    2.116157] Key type asymmetric registered
Jan  1 01:00:11 localhost kern.notice kernel: [    2.116176] Asymmetric key parser 'x509' registered
Jan  1 01:00:11 localhost kern.info kernel: [    2.116256] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Jan  1 01:00:11 localhost kern.info kernel: [    2.117212] io scheduler mq-deadline registered
Jan  1 01:00:11 localhost kern.info kernel: [    2.117232] io scheduler kyber registered
Jan  1 01:00:11 localhost kern.info kernel: [    2.129219] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
Jan  1 01:00:11 localhost kern.info kernel: [    2.132774] bcm2835-rng 3f104000.rng: hwrng registered
Jan  1 01:00:11 localhost kern.info kernel: [    2.133434] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Jan  1 01:00:11 localhost kern.info kernel: [    2.135242] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Jan  1 01:00:11 localhost kern.warn kernel: [    2.135783] cacheinfo: Unable to detect cache hierarchy for CPU 0
Jan  1 01:00:11 localhost kern.info kernel: [    2.153337] brd: module loaded
Jan  1 01:00:11 localhost kern.info kernel: [    2.171075] loop: module loaded
Jan  1 01:00:11 localhost kern.info kernel: [    2.173399] Loading iSCSI transport class v2.0-870.
Jan  1 01:00:11 localhost kern.info kernel: [    2.175754] libphy: Fixed MDIO Bus: probed
Jan  1 01:00:11 localhost kern.info kernel: [    2.176094] tun: Universal TUN/TAP device driver, 1.6
Jan  1 01:00:11 localhost kern.info kernel: [    2.176556] usbcore: registered new interface driver lan78xx
Jan  1 01:00:11 localhost kern.info kernel: [    2.176643] usbcore: registered new interface driver smsc95xx
Jan  1 01:00:11 localhost kern.info kernel: [    2.176673] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Jan  1 01:00:11 localhost kern.warn kernel: [    2.377608] Core Release: 2.80a
Jan  1 01:00:11 localhost kern.warn kernel: [    2.377659] Setting default values for core params
Jan  1 01:00:11 localhost kern.warn kernel: [    2.377697] Finished setting default values for core params
Jan  1 01:00:11 localhost kern.warn kernel: [    2.578171] Using Buffer DMA mode
Jan  1 01:00:11 localhost kern.warn kernel: [    2.578188] Periodic Transfer Interrupt Enhancement - disabled
Jan  1 01:00:11 localhost kern.warn kernel: [    2.578204] Multiprocessor Interrupt Enhancement - disabled
Jan  1 01:00:11 localhost kern.warn kernel: [    2.578220] OTG VER PARAM: 0, OTG VER FLAG: 0
Jan  1 01:00:11 localhost kern.warn kernel: [    2.578243] Dedicated Tx FIFOs mode
Jan  1 01:00:11 localhost kern.warn kernel: [    2.586626] 
Jan  1 01:00:11 localhost kern.warn kernel: [    2.586651] WARN::dwc_otg_hcd_init:1072: FIQ DMA bounce buffers: virt = ffffffc0117cb000 dma = 0x00000000fa400000 len=9024
Jan  1 01:00:11 localhost kern.warn kernel: [    2.586689] FIQ FSM acceleration enabled for :
Jan  1 01:00:11 localhost kern.warn kernel: [    2.586689] Non-periodic Split Transactions
Jan  1 01:00:11 localhost kern.warn kernel: [    2.586689] Periodic Split Transactions
Jan  1 01:00:11 localhost kern.warn kernel: [    2.586689] High-Speed Isochronous Endpoints
Jan  1 01:00:11 localhost kern.warn kernel: [    2.586689] Interrupt/Control Split Transaction hack enabled
Jan  1 01:00:11 localhost kern.debug kernel: [    2.586708] dwc_otg: Microframe scheduler enabled
Jan  1 01:00:11 localhost kern.warn kernel: [    2.586764] 
Jan  1 01:00:11 localhost kern.warn kernel: [    2.586782] WARN::hcd_init_fiq:496: MPHI regs_base at ffffffc0113d5000
Jan  1 01:00:11 localhost kern.info kernel: [    2.586883] dwc_otg 3f980000.usb: DWC OTG Controller
Jan  1 01:00:11 localhost kern.info kernel: [    2.586932] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Jan  1 01:00:11 localhost kern.info kernel: [    2.587002] dwc_otg 3f980000.usb: irq 74, io mem 0x00000000
Jan  1 01:00:11 localhost kern.warn kernel: [    2.587059] Init: Port Power? op_state=1
Jan  1 01:00:11 localhost kern.warn kernel: [    2.587074] Init: Power Port (0)
Jan  1 01:00:11 localhost kern.info kernel: [    2.587617] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
Jan  1 01:00:11 localhost kern.info kernel: [    2.587639] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jan  1 01:00:11 localhost kern.info kernel: [    2.587657] usb usb1: Product: DWC OTG Controller
Jan  1 01:00:11 localhost kern.info kernel: [    2.587677] usb usb1: Manufacturer: Linux 5.10.92 dwc_otg_hcd
Jan  1 01:00:11 localhost kern.info kernel: [    2.587694] usb usb1: SerialNumber: 3f980000.usb
Jan  1 01:00:11 localhost kern.info kernel: [    2.588762] hub 1-0:1.0: USB hub found
Jan  1 01:00:11 localhost kern.info kernel: [    2.588853] hub 1-0:1.0: 1 port detected
Jan  1 01:00:11 localhost kern.debug kernel: [    2.590618] dwc_otg: FIQ enabled
Jan  1 01:00:11 localhost kern.debug kernel: [    2.590635] dwc_otg: NAK holdoff enabled
Jan  1 01:00:11 localhost kern.debug kernel: [    2.590650] dwc_otg: FIQ split-transaction FSM enabled
Jan  1 01:00:11 localhost kern.debug kernel: [    2.590674] Module dwc_common_port init
Jan  1 01:00:11 localhost kern.info kernel: [    2.592034] usbcore: registered new interface driver uas
Jan  1 01:00:11 localhost kern.info kernel: [    2.592161] usbcore: registered new interface driver usb-storage
Jan  1 01:00:11 localhost kern.info kernel: [    2.592686] mousedev: PS/2 mouse device common for all mice
Jan  1 01:00:11 localhost kern.info kernel: [    2.595081] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Jan  1 01:00:11 localhost kern.info kernel: [    2.599170] sdhci: Secure Digital Host Controller Interface driver
Jan  1 01:00:11 localhost kern.info kernel: [    2.599191] sdhci: Copyright(c) Pierre Ossman
Jan  1 01:00:11 localhost kern.info kernel: [    2.600051] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
Jan  1 01:00:11 localhost kern.info kernel: [    2.600966] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Jan  1 01:00:11 localhost kern.info kernel: [    2.601330] sdhci-pltfm: SDHCI platform and OF driver helper
Jan  1 01:00:11 localhost kern.info kernel: [    2.605769] ledtrig-cpu: registered to indicate activity on CPUs
Jan  1 01:00:11 localhost kern.info kernel: [    2.605850] hid: raw HID events driver (C) Jiri Kosina
Jan  1 01:00:11 localhost kern.info kernel: [    2.606006] usbcore: registered new interface driver usbhid
Jan  1 01:00:11 localhost kern.info kernel: [    2.606014] usbhid: USB HID core driver
Jan  1 01:00:11 localhost kern.info kernel: [    2.611056] NET: Registered protocol family 17
Jan  1 01:00:11 localhost kern.notice kernel: [    2.611391] Key type dns_resolver registered
Jan  1 01:00:11 localhost kern.info kernel: [    2.611886] registered taskstats version 1
Jan  1 01:00:11 localhost kern.notice kernel: [    2.611906] Loading compiled-in X.509 certificates
Jan  1 01:00:11 localhost kern.info kernel: [    2.626413] 3f215040.serial: ttyS0 at MMIO 0x3f215040 (irq = 71, base_baud = 31250000) is a 16550
Jan  1 01:00:11 localhost kern.info kernel: [    2.627248] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Jan  1 01:00:11 localhost kern.info kernel: [    2.628550] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
Jan  1 01:00:11 localhost kern.info kernel: [    2.628561] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
Jan  1 01:00:11 localhost kern.info kernel: [    2.651360] sdhost: log_buf @ (____ptrval____) (c2f8a000)
Jan  1 01:00:11 localhost kern.warn kernel: [    2.678285] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Jan  1 01:00:11 localhost kern.warn kernel: [    2.679980] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jan  1 01:00:11 localhost kern.warn kernel: [    2.681712] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jan  1 01:00:11 localhost kern.warn kernel: [    2.684776] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Jan  1 01:00:11 localhost kern.warn kernel: [    2.697844] Indeed it is in host mode hprt0 = 00021501
Jan  1 01:00:11 localhost kern.info kernel: [    2.706709] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Jan  1 01:00:11 localhost kern.info kernel: [    2.712623] of_cfs_init
Jan  1 01:00:11 localhost kern.info kernel: [    2.712704] of_cfs_init: OK
Jan  1 01:00:11 localhost kern.info kernel: [    2.714687] Waiting 5 sec before mounting root device...
Jan  1 01:00:11 localhost kern.notice kernel: [    2.733980] random: fast init done
Jan  1 01:00:11 localhost kern.info kernel: [    2.812439] mmc1: new high speed SDIO card at address 0001
Jan  1 01:00:11 localhost kern.warn kernel: [    2.816963] mmc0: host does not support reading read-only switch, assuming write-enable
Jan  1 01:00:11 localhost kern.info kernel: [    2.820120] mmc0: new high speed SDHC card at address 59b4
Jan  1 01:00:11 localhost kern.info kernel: [    2.821447] mmcblk0: mmc0:59b4 USDU1 15.0 GiB
Jan  1 01:00:11 localhost kern.info kernel: [    2.824521]  mmcblk0: p1 p2 p3
Jan  1 01:00:11 localhost kern.info kernel: [    2.885710] usb 1-1: new high-speed USB device number 2 using dwc_otg
Jan  1 01:00:11 localhost kern.warn kernel: [    2.885954] Indeed it is in host mode hprt0 = 00001101
Jan  1 01:00:11 localhost kern.info kernel: [    3.094245] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00
Jan  1 01:00:11 localhost kern.info kernel: [    3.094268] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jan  1 01:00:11 localhost kern.info kernel: [    3.095380] hub 1-1:1.0: USB hub found
Jan  1 01:00:11 localhost kern.info kernel: [    3.095579] hub 1-1:1.0: 5 ports detected
Jan  1 01:00:11 localhost kern.info kernel: [    3.381695] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Jan  1 01:00:11 localhost kern.info kernel: [    3.482257] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
Jan  1 01:00:11 localhost kern.info kernel: [    3.482279] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jan  1 01:00:11 localhost kern.info kernel: [    3.485343] smsc95xx v2.0.0
Jan  1 01:00:11 localhost kern.info kernel: [    3.534515] libphy: smsc95xx-mdiobus: probed
Jan  1 01:00:11 localhost kern.info kernel: [    3.536336] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:49:4c:0e
Jan  1 01:00:11 localhost kern.info kernel: [    3.613756] usb 1-1.4: new low-speed USB device number 4 using dwc_otg
Jan  1 01:00:11 localhost kern.info kernel: [    3.732270] usb 1-1.4: New USB device found, idVendor=046d, idProduct=c517, bcdDevice=38.10
Jan  1 01:00:11 localhost kern.info kernel: [    3.732293] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Jan  1 01:00:11 localhost kern.info kernel: [    3.732313] usb 1-1.4: Product: USB Receiver
Jan  1 01:00:11 localhost kern.info kernel: [    3.732332] usb 1-1.4: Manufacturer: Logitech
Jan  1 01:00:11 localhost kern.info kernel: [    3.746168] input: Logitech USB Receiver as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/0003:046D:C517.0001/input/input0
Jan  1 01:00:11 localhost kern.info kernel: [    3.806582] hid-generic 0003:046D:C517.0001: input,hidraw0: USB HID v1.10 Keyboard [Logitech USB Receiver] on usb-3f980000.usb-1.4/input0
Jan  1 01:00:11 localhost kern.info kernel: [    3.836154] input: Logitech USB Receiver Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C517.0002/input/input1
Jan  1 01:00:11 localhost kern.info kernel: [    3.836686] input: Logitech USB Receiver Consumer Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C517.0002/input/input2
Jan  1 01:00:11 localhost kern.notice kernel: [    3.854595] random: crng init done
Jan  1 01:00:11 localhost kern.info kernel: [    3.893962] input: Logitech USB Receiver System Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C517.0002/input/input3
Jan  1 01:00:11 localhost kern.info kernel: [    3.894582] hid-generic 0003:046D:C517.0002: input,hiddev96,hidraw1: USB HID v1.10 Mouse [Logitech USB Receiver] on usb-3f980000.usb-1.4/input1
Jan  1 01:00:11 localhost kern.info kernel: [    3.973736] usb 1-1.5: new full-speed USB device number 5 using dwc_otg
Jan  1 01:00:11 localhost kern.info kernel: [    4.080514] usb 1-1.5: New USB device found, idVendor=1b1f, idProduct=c020, bcdDevice= 1.00
Jan  1 01:00:11 localhost kern.info kernel: [    4.080536] usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan  1 01:00:11 localhost kern.info kernel: [    4.080556] usb 1-1.5: Product: eQ-3 HmIP-RFUSB
Jan  1 01:00:11 localhost kern.info kernel: [    4.080574] usb 1-1.5: Manufacturer: Silicon Labs
Jan  1 01:00:11 localhost kern.info kernel: [    4.080593] usb 1-1.5: SerialNumber: 3014F711A000041D89B5520E
Jan  1 01:00:11 localhost kern.info kernel: [    7.916492] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Jan  1 01:00:11 localhost kern.info kernel: [    7.916630] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Jan  1 01:00:11 localhost kern.info kernel: [    7.917905] devtmpfs: mounted
Jan  1 01:00:11 localhost kern.info kernel: [    7.928115] Freeing unused kernel memory: 3264K
Jan  1 01:00:11 localhost kern.info kernel: [    7.933813] Run /sbin/init as init process
Jan  1 01:00:11 localhost kern.debug kernel: [    7.933824]   with arguments:
Jan  1 01:00:11 localhost kern.debug kernel: [    7.933833]     /sbin/init
Jan  1 01:00:11 localhost kern.debug kernel: [    7.933841]     lapic
Jan  1 01:00:11 localhost kern.debug kernel: [    7.933849]     empty
Jan  1 01:00:11 localhost kern.debug kernel: [    7.933856]   with environment:
Jan  1 01:00:11 localhost kern.debug kernel: [    7.933864]     HOME=/
Jan  1 01:00:11 localhost kern.debug kernel: [    7.933872]     TERM=linux
Jan  1 01:00:11 localhost kern.debug kernel: [    7.933880]     kgdboc=tty2
Jan  1 01:00:11 localhost kern.debug kernel: [    7.933887]     scandelay=5
Jan  1 01:00:11 localhost kern.debug kernel: [    7.933895]     pti=on
Jan  1 01:00:11 localhost kern.info kernel: [    8.752128] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
Jan  1 01:00:11 localhost kern.info kernel: [   10.394528] zram: Added device: zram0
Jan  1 01:00:11 localhost kern.info kernel: [   10.437598] zram0: detected capacity change from 0 to 1052176384
Jan  1 01:00:11 localhost kern.info kernel: [   10.501724] Adding 1027512k swap on /dev/zram0.  Priority:75 extents:1 across:1027512k SSFS
Jan  1 01:00:11 localhost kern.info kernel: [   10.579576] i2c /dev entries driver
Jan  1 01:00:11 localhost daemon.info kernel: [   11.110420] udevd[275]: starting version 3.2.10
Jan  1 01:00:11 localhost daemon.info kernel: [   11.168419] udevd[276]: starting eudev-3.2.10
Jan  1 01:00:11 localhost kern.warn kernel: [   11.413851] generic_raw_uart: loading out-of-tree module taints kernel.
Jan  1 01:00:11 localhost kern.info kernel: [   11.431479] pl011_raw_uart 3f201000.serial: there is not valid maps for state default
Jan  1 01:00:11 localhost kern.info kernel: [   11.431835] pl011_raw_uart 3f201000.serial: Initialized pl011 device; mapbase=0x3f201000; irq=99; clockrate=47999625
Jan  1 01:00:11 localhost kern.info kernel: [   11.432507] pl011_raw_uart 3f201000.serial: Missing I2C driver of rtc device, trying to load
Jan  1 01:00:11 localhost kern.info kernel: [   11.432528] pl011_raw_uart 3f201000.serial: Requesting module i2c:rx8130
Jan  1 01:00:11 localhost kern.warn kernel: [   11.474126] rtc-ds1307: probe of 1-0032 failed with error -121
Jan  1 01:00:11 localhost kern.warn kernel: [   11.478820] rtc-ds1307: probe of 1-0032 failed with error -121
Jan  1 01:00:11 localhost kern.info kernel: [   11.479563] raw-uart raw-uart: Reset radio module
Jan  1 01:00:11 localhost kern.info kernel: [   11.529872] usb 1-1.5: Found eQ-3 HmIP-RFUSB with serial 3014F711A000041D89B5520E at usb-3f980000.usb-1.5
Jan  1 01:00:11 localhost kern.info kernel: [   11.530211] raw-uart raw-uart1: Reset radio module
Jan  1 01:00:11 localhost kern.info kernel: [   11.530226] raw-uart raw-uart1: Registered new raw-uart device using underlying device eQ-3 HmIP-RFUSB@usb-3f980000.usb-1.5.
Jan  1 01:00:11 localhost kern.info kernel: [   11.532590] usbcore: registered new interface driver hb_rf_usb_2
Jan  1 01:00:11 localhost kern.info kernel: [   11.608884] logitech-djreceiver 0003:046D:C517.0001: hidraw0: USB HID v1.10 Keyboard [Logitech USB Receiver] on usb-3f980000.usb-1.4/input0
Jan  1 01:00:11 localhost kern.info kernel: [   11.657836] raw-uart raw-uart: Registered new raw-uart device using underlying device GPIO@3f201000.serial.
Jan  1 01:00:11 localhost kern.notice kernel: [   11.695835] cfg80211: Loading compiled-in X.509 certificates for regulatory database
Jan  1 01:00:11 localhost kern.info kernel: [   11.744454] logitech-djreceiver 0003:046D:C517.0002: hiddev96,hidraw1: USB HID v1.10 Mouse [Logitech USB Receiver] on usb-3f980000.usb-1.4/input1
Jan  1 01:00:11 localhost kern.notice kernel: [   11.794714] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Jan  1 01:00:11 localhost kern.info kernel: [   11.828625] logitech-djreceiver 0003:046D:C517.0002: device of type 27 Mhz (0x02) connected on slot 1
Jan  1 01:00:11 localhost kern.info kernel: [   11.833581] input: Logitech Wireless Mouse PID:0039 Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C517.0002/0003:046D:0039.0003/input/input6
Jan  1 01:00:11 localhost kern.info kernel: [   11.834447] hid-generic 0003:046D:0039.0003: input,hidraw2: USB HID v1.11 Mouse [Logitech Wireless Mouse PID:0039] on usb-3f980000.usb-1.4/input1:1
Jan  1 01:00:11 localhost kern.info kernel: [   11.845723] logitech-djreceiver 0003:046D:C517.0002: device of type 27 Mhz (0x02) connected on slot 3
Jan  1 01:00:11 localhost kern.info kernel: [   11.851051] input: Logitech Wireless Keyboard PID:005a Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C517.0002/0003:046D:005A.0004/input/input10
Jan  1 01:00:11 localhost kern.info kernel: [   11.852482] input: Logitech Wireless Keyboard PID:005a Consumer Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C517.0002/0003:046D:005A.0004/input/input11
Jan  1 01:00:11 localhost kern.info kernel: [   11.853930] input: Logitech Wireless Keyboard PID:005a System Control as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C517.0002/0003:046D:005A.0004/input/input12
Jan  1 01:00:11 localhost kern.debug kernel: [   11.854056] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
Jan  1 01:00:11 localhost kern.info kernel: [   11.854453] hid-generic 0003:046D:005A.0004: input,hidraw3: USB HID v1.11 Keyboard [Logitech Wireless Keyboard PID:005a] on usb-3f980000.usb-1.4/input1:3
Jan  1 01:00:11 localhost kern.info kernel: [   11.861388] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Jan  1 01:00:11 localhost kern.info kernel: [   11.861807] usbcore: registered new interface driver brcmfmac
Jan  1 01:00:11 localhost kern.info kernel: [   11.926647] logitech-hidpp-device 0003:046D:0039.0003: HID++ 1.0 device connected.
Jan  1 01:00:12 localhost kern.info kernel: [   12.063059] input: Logitech Wireless Mouse PID:0039 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C517.0002/0003:046D:0039.0003/input/input16
Jan  1 01:00:12 localhost kern.info kernel: [   12.063743] logitech-hidpp-device 0003:046D:0039.0003: input,hidraw2: USB HID v1.11 Mouse [Logitech Wireless Mouse PID:0039] on usb-3f980000.usb-1.4/input1:1
Jan  1 01:00:12 localhost kern.info kernel: [   12.077950] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Jan  1 01:00:12 localhost kern.info kernel: [   12.078106] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Jan  1 01:00:12 localhost kern.info kernel: [   12.078195] brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available
Jan  1 01:00:12 localhost kern.info kernel: [   12.079016] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Oct 22 2019 01:59:28 version 7.45.98.94 (r723000 CY) FWID 01-3b33decd
Jan  1 01:00:12 localhost kern.info kernel: [   12.223702] logitech-hidpp-device 0003:046D:005A.0004: HID++ 1.0 device connected.
Jan  1 01:00:12 localhost kern.info kernel: [   12.332215] input: Logitech Wireless Keyboard PID:005a as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.1/0003:046D:C517.0002/0003:046D:005A.0004/input/input17
Jan  1 01:00:12 localhost kern.info kernel: [   12.333579] logitech-hidpp-device 0003:046D:005A.0004: input,hidraw3: USB HID v1.11 Keyboard [Logitech Wireless Keyboard PID:005a] on usb-3f980000.usb-1.4/input1:3
Jan  1 01:00:12 localhost daemon.err kernel: [   12.471030] udevd[282]: Unknown key identifier 'coffee'
Jan  1 01:00:12 localhost daemon.err kernel: [   12.479607] udevd[279]: Error calling EVIOCSKEYCODE on device node '/dev/input/event0' (scan code 0xc0183, key code 226): Invalid argument
Jan  1 01:00:12 localhost daemon.err kernel: [   12.479665] udevd[279]: Error calling EVIOCSKEYCODE on device node '/dev/input/event0' (scan code 0xc1001, key code 216): Invalid argument
Jan  1 01:00:12 localhost daemon.err kernel: [   12.479706] udevd[279]: Error calling EVIOCSKEYCODE on device node '/dev/input/event0' (scan code 0xc1002, key code 212): Invalid argument
Jan  1 01:00:12 localhost daemon.err kernel: [   12.479745] udevd[279]: Error calling EVIOCSKEYCODE on device node '/dev/input/event0' (scan code 0xc1003, key code 392): Invalid argument
Jan  1 01:00:12 localhost daemon.err kernel: [   12.479784] udevd[279]: Error calling EVIOCSKEYCODE on device node '/dev/input/event0' (scan code 0xc1004, key code 393): Invalid argument
Jan  1 01:00:12 localhost daemon.err kernel: [   12.479824] udevd[279]: Error calling EVIOCSKEYCODE on device node '/dev/input/event0' (scan code 0xc1005, key code 442): Invalid argument
Jan  1 01:00:12 localhost daemon.err kernel: [   12.479863] udevd[279]: Error calling EVIOCSKEYCODE on device node '/dev/input/event0' (scan code 0xc100a, key code 235): Invalid argument
Jan  1 01:00:12 localhost daemon.err kernel: [   12.479902] udevd[279]: Error calling EVIOCSKEYCODE on device node '/dev/input/event0' (scan code 0xc100b, key code 168): Invalid argument
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ 3f00b880.mailbox(2) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ doorbell(3) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ dwc_otg_sim-fiq(51) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ IRQ(60) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ IRQ(62) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ IRQ(63) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ IRQ(66) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ dwc_otg_hcd:usb1(74) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ 3f804000.i2c(95) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ 3f204000.spi(96) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ mmc0(98) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ mmc1(104) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ arch_timer(180) guessed as class 0
Jan  1 01:00:12 localhost daemon.debug /usr/sbin/irqbalance: IRQ arm-pmu(183) guessed as class 0
Jan  1 01:00:12 localhost user.notice rfkill: block set for type bluetooth
Jan  1 01:00:13 localhost kern.info kernel: [   13.164025] SMSC LAN8700 usb-001:003:01: attached PHY driver [SMSC LAN8700] (mii_bus:phy_addr=usb-001:003:01, irq=POLL)
Jan  1 01:00:13 localhost kern.info kernel: [   13.164405] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Jan  1 01:00:13 localhost kern.info kernel: [   13.178784] smsc95xx 1-1.1:1.0 eth0: Link is Down
Jan  1 01:00:15 localhost kern.info kernel: [   15.230756] smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off
Jan  1 01:00:17 localhost syslog.info syslogd exiting
Jan  1 01:00:17 homematic-raspi syslog.info syslogd started: BusyBox v1.34.1
Jan  1 01:00:17 homematic-raspi kern.notice kernel: klogd started: BusyBox v1.34.1 (2022-02-26 11:31:39 UTC)
Jan  1 01:00:17 homematic-raspi daemon.info udhcpc[397]: started, v1.34.1
Jan  1 01:00:17 homematic-raspi daemon.info udhcpc[397]: broadcasting discover
Jan  1 01:00:17 homematic-raspi daemon.info udhcpc[397]: broadcasting select for 10.0.0.31, server 10.0.0.250
Jan  1 01:00:17 homematic-raspi daemon.info udhcpc[397]: lease of 10.0.0.31 obtained from 10.0.0.250, lease time 864000
Jan  1 01:00:17 homematic-raspi kern.info kernel: [   17.750077] NET: Registered protocol family 10
Jan  1 01:00:17 homematic-raspi kern.info kernel: [   17.758678] Segment Routing with IPv6
Jan  1 01:00:18 homematic-raspi user.info firewall: configuration set
Jan  1 01:00:18 homematic-raspi user.notice rfkill: block set for type wlan
Jan  1 01:00:18 homematic-raspi daemon.info ifplugd(eth0)[503]: ifplugd 0.28 initializing.
Jan  1 01:00:18 homematic-raspi daemon.info ifplugd(eth0)[503]: Using interface eth0/B8:27:EB:49:4C:0E with driver <smsc95xx> (version: 5.10.92)
Jan  1 01:00:18 homematic-raspi daemon.info ifplugd(eth0)[503]: Using detection mode: SIOCETHTOOL
Jan  1 01:00:18 homematic-raspi daemon.info ifplugd(eth0)[503]: Initialization complete, link beat detected.
Jan  1 01:00:18 homematic-raspi daemon.info ifplugd(eth0)[503]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Jan  1 01:00:18 homematic-raspi daemon.warn ifplugd(eth0)[503]: client: ifup: interface eth0 already configured
Jan  1 01:00:19 homematic-raspi daemon.info ifplugd(eth0)[503]: Program executed successfully.
Jan  1 01:00:19 homematic-raspi daemon.info ifplugd(wlan0)[508]: ifplugd 0.28 initializing.
Jan  1 01:00:19 homematic-raspi daemon.info ifplugd(wlan0)[508]: Using interface wlan0/B8:27:EB:1C:19:5B with driver <brcmfmac> (version: 7.45.98.94)
Jan  1 01:00:19 homematic-raspi daemon.info ifplugd(wlan0)[508]: Using detection mode: wireless extension
Jan  1 01:00:19 homematic-raspi daemon.info ifplugd(wlan0)[508]: Initialization complete, link beat not detected.
Mar 24 17:54:50 homematic-raspi daemon.notice ntpdate[513]: step time server 131.188.3.222 offset +1648140861.432546 sec
Mar 24 17:54:50 homematic-raspi daemon.info chronyd[516]: chronyd version 4.0 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH +IPV6 -DEBUG)
Mar 24 17:54:50 homematic-raspi kern.info kernel: [   28.982236] raw-uart raw-uart: Reset radio module
Mar 24 17:54:50 homematic-raspi kern.info kernel: [   29.162082] raw-uart raw-uart1: Reset radio module
Mar 24 17:54:54 homematic-raspi daemon.debug /usr/sbin/irqbalance: IRQ 3f201000.serial(99) guessed as class 0
Mar 24 17:54:55 homematic-raspi kern.info kernel: [   33.757763] cam1-reg: disabling
Mar 24 17:54:55 homematic-raspi kern.info kernel: [   33.757804] cam-dummy-reg: disabling
Mar 24 17:55:12 homematic-raspi daemon.err xinetd[614]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Mar 24 17:55:12 homematic-raspi daemon.crit xinetd[614]: 614 {init_services} no services. Exiting...
Mar 24 17:55:12 homematic-raspi user.info root: Updating RF Lan Gateway Coprocessor Firmware
Mar 24 17:55:12 homematic-raspi user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Mar 24 17:55:12 homematic-raspi user.info root: Updating RF Lan Gateway Firmware
Mar 24 17:55:12 homematic-raspi user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Mar 24 17:55:13 homematic-raspi kern.info kernel: [   51.590818] eq3loop: created slave mmd_hmip
Mar 24 17:55:13 homematic-raspi kern.info kernel: [   51.591501] eq3loop: created slave mmd_bidcos
Mar 24 17:55:15 homematic-raspi kern.info kernel: [   53.775503] eq3loop: eq3loop_open_slave() mmd_bidcos
Mar 24 17:55:40 homematic-raspi kern.info kernel: [   78.724452] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 24 17:55:40 homematic-raspi kern.info kernel: [   78.724844] eq3loop: eq3loop_close_slave() mmd_hmip
Mar 24 17:55:40 homematic-raspi kern.info kernel: [   78.729840] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 24 17:55:40 homematic-raspi kern.info kernel: [   78.730140] eq3loop: eq3loop_close_slave() mmd_hmip
Mar 24 17:55:40 homematic-raspi kern.info kernel: [   78.731648] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 24 17:55:40 homematic-raspi kern.info kernel: [   78.731982] eq3loop: eq3loop_close_slave() mmd_hmip
Mar 24 17:55:40 homematic-raspi kern.info kernel: [   78.745975] eq3loop: eq3loop_open_slave() mmd_hmip
Mar 24 17:55:58 homematic-raspi daemon.info chronyd[516]: Selected source 10.0.0.250
Mar 24 16:55:59 homematic-raspi daemon.info : starting pid 1034, tty '': '/bin/mv /tmp/boot.log /var/log/boot.log'
Mar 24 16:55:59 homematic-raspi daemon.info : starting pid 1035, tty '/dev/null': '/usr/bin/monit -Ic /etc/monitrc'
Mar 24 17:55:59 homematic-raspi user.info monit[1035]: Starting Monit 5.30.0 daemon with http interface at /var/run/monit.sock
Mar 24 17:55:59 homematic-raspi user.info monit[1035]: 'homematic-raspi' Monit 5.30.0 started
Mar 24 17:56:00 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00041D89B5520E:0","CARRIER_SENSE_LEVEL"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: CARRIER_SENSE_LEVEL"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Mar 24 17:56:00 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00041D89B5520E:0","CARRIER_SENSE_LEVEL"}) [CallGetValue():iseXmlRpc.cpp:1435]
Mar 24 17:56:00 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Mar 24 17:56:20 homematic-raspi user.err monit[1035]: 'sshdEnabled' status failed (1) -- no output
Mar 24 17:56:20 homematic-raspi user.err monit[1035]: 'hs485dEnabled' status failed (2) -- grep: /var/etc/hs485d.conf: No such file or directory
Mar 24 17:56:20 homematic-raspi user.err monit[1035]: 'hmlangwEnabled' status failed (1) -- no output
Mar 24 17:56:20 homematic-raspi user.warn monit[1035]: 'hasUSB' status failed (1) -- no output
Mar 24 17:56:20 homematic-raspi user.err monit[1035]: 'hb_rf_eth-CheckEnabled' status failed (1) -- no output
Mar 24 17:56:20 homematic-raspi user.warn monit[1035]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Mar 24 17:56:20 homematic-raspi user.warn monit[1035]: 'rpi4usb3Check' status failed (1) -- no output
Mar 24 17:56:20 homematic-raspi user.err monit[1035]: 'tailscaleEnabled' status failed (1) -- no output
Mar 24 17:56:20 homematic-raspi user.err monit[1035]: Lookup for '/media/usb1' filesystem failed  -- not found in /proc/self/mounts
Mar 24 17:56:20 homematic-raspi user.err monit[1035]: Filesystem '/media/usb1' not mounted
Mar 24 17:56:20 homematic-raspi user.err monit[1035]: 'usb1' unable to read filesystem '/media/usb1' state
Mar 24 17:56:20 homematic-raspi user.info monit[1035]: 'usb1' trying to restart
Mar 24 17:56:38 homematic-raspi user.warn monit[1035]: 'hasUSB' status failed (1) -- no output
Mar 24 17:56:38 homematic-raspi user.err monit[1035]: 'wlan0CheckEnabled' status failed (1) -- no output
Mar 24 17:56:38 homematic-raspi user.warn monit[1035]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Mar 24 17:56:38 homematic-raspi user.warn monit[1035]: 'rpi4usb3Check' status failed (1) -- no output
Mar 24 17:56:38 homematic-raspi user.err monit[1035]: Filesystem '/media/usb1' not mounted
Mar 24 17:56:38 homematic-raspi user.err monit[1035]: 'usb1' unable to read filesystem '/media/usb1' state
Mar 24 17:56:38 homematic-raspi user.info monit[1035]: 'usb1' trying to restart
Mar 24 17:56:56 homematic-raspi user.warn monit[1035]: 'hasUSB' status failed (1) -- no output
Mar 24 17:56:56 homematic-raspi user.err monit[1035]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Mar 24 17:56:56 homematic-raspi user.err monit[1035]: 'rpi4usb3Check' status failed (1) -- no output
Mar 24 17:56:56 homematic-raspi user.err monit[1035]: Filesystem '/media/usb1' not mounted
Mar 24 17:56:56 homematic-raspi user.err monit[1035]: 'usb1' unable to read filesystem '/media/usb1' state
Mar 24 17:56:56 homematic-raspi user.info monit[1035]: 'usb1' trying to restart
Mar 24 17:57:14 homematic-raspi user.warn monit[1035]: 'hasUSB' status failed (1) -- no output
Mar 24 17:57:14 homematic-raspi user.err monit[1035]: Filesystem '/media/usb1' not mounted
Mar 24 17:57:14 homematic-raspi user.err monit[1035]: 'usb1' unable to read filesystem '/media/usb1' state
Mar 24 17:57:14 homematic-raspi user.info monit[1035]: 'usb1' trying to restart
Mar 24 17:57:32 homematic-raspi user.err monit[1035]: 'hasUSB' status failed (1) -- no output


***** hmserver.log *****
Mar 24 17:55:36 de.eq3.cbcs.server.core.vertx.KeyServerWorker ERROR [vert.x-worker-thread-1] Missing key server configuration parameter (Network.Key) for  mode: KEYSERVER_LOCAL 

Oft kann auch - auf der Konsole sichtbar - der HmIPServer nicht gestartet werden (mit geschätzt 5 Minuten Wartezeit/Timeout), gefolgt von einer Alarmmeldung:

Und jetzt bin auch ich am Ende meiner Weisheit angelangt...
Was tun?
Bin gerne auch bereit, was zu testen...


Roland

[Markus33]

Ich verwende ebenfalls folgende Konfiguration:
RF-Module/Firmware: HMIP-RFUSB (4.4.16)
Product: raspmatic_rpi4 (3.61.7.20220226)


Internet funktioniert laut Status info:
CCU Software Info
Product: raspmatic_rpi4 (3.61.7.20220226)
ReGaHss: R1.00.0388.0229
TCL: 8.2.3
Status: SD(1) NTP(1) Link(1) Internet(1) IP(1)


Die Homematic IP Geräte werden jedoch NICHT ERKANNT ?
Kein RSSI unter DevConfig bei HMIP-RF

(die alten RF geräte laufen)

Was kann ich hier noch testen um dem Fehler näher zu kommen ?

Duty Cycle sieht normal aus:

ccu.PNG (15.53 KiB) 1291 mal betrachtet