WatchDog Alarm: unclean shutdown

Einrichtung, Nutzung und Hilfe zu RaspberryMatic (OCCU auf Raspberry Pi)

Moderatoren: jmaus, Co-Administratoren

bobinger
Beiträge: 21
Registriert: 09.08.2022, 10:21
System: CCU und Access Point

Re: WatchDog Alarm: unclean shutdown

Beitrag von bobinger » 09.10.2022, 09:56

Feste-Ip-Adresse hat nichts gebracht. Heute am Morgen um 7:17:34 gab es wieder den gleichen Watch-Dog-Alarm. Log-Datei:

Code: Alles auswählen

Oct  9 05:17:13 homematic-raspi daemon.info : starting pid 1734, tty '': '/bin/mv /tmp/boot.log /var/log/boot.log'
Oct  9 05:17:13 homematic-raspi daemon.info : starting pid 1735, tty '/dev/null': '/usr/bin/monit -Ic /etc/monitrc'
Oct  9 07:17:13 homematic-raspi user.info monit[1735]: Starting Monit 5.30.0 daemon with http interface at /var/run/monit.sock
Oct  9 07:17:13 homematic-raspi user.info monit[1735]: 'homematic-raspi' Monit 5.30.0 started
Oct  9 07:17:17 homematic-raspi user.err rfd: HSSParameter::GetValue() id=ENERGY_COUNTER failed getting physical value.
Oct  9 07:17:17 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:17:17 homematic-raspi user.err rfd: HSSParameter::GetValue() id=ENERGY_COUNTER failed getting physical value.
Oct  9 07:17:17 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:17:17 homematic-raspi user.err rfd: HSSParameter::GetValue() id=GAS_ENERGY_COUNTER failed getting physical value.
Oct  9 07:17:17 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","GAS_ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","GAS_ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:17:17 homematic-raspi user.err rfd: HSSParameter::GetValue() id=IEC_ENERGY_COUNTER failed getting physical value.
Oct  9 07:17:17 homematic-raspi user.err rfd: HSSParameter::GetValue() id=BOOT failed getting physical value.
Oct  9 07:17:17 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","IEC_ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:17:17 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","BOOT"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","BOOT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","IEC_ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:17:17 homematic-raspi user.err rfd: HSSParameter::GetValue() id=IEC_ENERGY_COUNTER failed getting physical value.
Oct  9 07:17:17 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","IEC_ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","IEC_ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:17:17 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:17:24 homematic-raspi daemon.info chronyd[1239]: Selected source 193.158.22.13 (1.de.pool.ntp.org)
Oct  9 07:17:34 homematic-raspi user.err monit[1735]: 'hs485dEnabled' status failed (2) -- grep: /var/etc/hs485d.conf: No such file or directory
Oct  9 07:17:34 homematic-raspi user.err monit[1735]: 'hmlangwEnabled' status failed (1) -- no output
Oct  9 07:17:34 homematic-raspi user.err monit[1735]: 'internetCheckEnabled' status failed (0) -- no output
Oct  9 07:17:34 homematic-raspi user.err monit[1735]: 'uncleanShutdownCheck' status failed (0) -- no output
Oct  9 07:17:34 homematic-raspi user.info monit[1735]: 'uncleanShutdownCheck' exec: '/bin/sh -c /bin/triggerAlarm.tcl 'Unclean shutdown or system crash identified' 'WatchDog: unclean-shutdown' true ; rm -f /var/status/uncleanShutdown'
Oct  9 07:17:34 homematic-raspi user.err monit[1735]: 'hb_rf_eth-CheckEnabled' status failed (1) -- no output
Oct  9 07:17:34 homematic-raspi user.err monit[1735]: 'coProcessorCheck' status failed (1) -- no output
Oct  9 07:17:34 homematic-raspi user.warn monit[1735]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Oct  9 07:17:34 homematic-raspi user.warn monit[1735]: 'rpi4usb3Check' status failed (1) -- no output
Oct  9 07:17:34 homematic-raspi user.err monit[1735]: 'tailscaleEnabled' status failed (1) -- no output
Oct  9 07:17:53 homematic-raspi user.err monit[1735]: 'wlan0CheckEnabled' status failed (1) -- no output
Oct  9 07:17:53 homematic-raspi user.err monit[1735]: 'uncleanShutdownCheck' status failed (0) -- no output
Oct  9 07:17:53 homematic-raspi user.warn monit[1735]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Oct  9 07:17:53 homematic-raspi user.warn monit[1735]: 'rpi4usb3Check' status failed (1) -- no output
Oct  9 07:18:11 homematic-raspi user.info monit[1735]: 'uncleanShutdownCheck' status succeeded (1) -- no output
Oct  9 07:18:11 homematic-raspi user.err monit[1735]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Oct  9 07:18:11 homematic-raspi user.err monit[1735]: 'rpi4usb3Check' status failed (1) -- no output
Oct  9 07:18:23 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9D89BB73D5:1","PARTY_TIME_START"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_START"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"000E9D89BB73D5:1","PARTY_TIME_START"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:18:23 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9D89BB73D5:1","PARTY_TIME_END"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_END"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"000E9D89BB73D5:1","PARTY_TIME_END"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:18:23 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9D89BB73D5:1","PARTY_SET_POINT_TEMPERATURE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_SET_POINT_TEMPERATURE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"000E9D89BB73D5:1","PARTY_SET_POINT_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:18:23 homematic-raspi user.err rfd: HSSParameter::GetValue() id=POWER failed getting physical value.
Oct  9 07:18:23 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","POWER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","POWER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:18:23 homematic-raspi user.err rfd: HSSParameter::GetValue() id=IEC_POWER failed getting physical value.
Oct  9 07:18:23 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","IEC_POWER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","IEC_POWER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:18:23 homematic-raspi user.err rfd: HSSParameter::GetValue() id=POWER failed getting physical value.
Oct  9 07:18:23 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","POWER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","POWER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:18:23 homematic-raspi user.err rfd: HSSParameter::GetValue() id=GAS_POWER failed getting physical value.
Oct  9 07:18:23 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","GAS_POWER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","GAS_POWER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct  9 07:18:23 homematic-raspi user.err rfd: HSSParameter::GetValue() id=IEC_POWER failed getting physical value.
Oct  9 07:18:23 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","IEC_POWER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","IEC_POWER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct  9 07:18:23 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
SEQ1374883 ist der Gasverbrauchszähler und PEQ054426 ist der Ferraris-Strom-Zähler. Andere Energieverbrauchszähler habe ich nicht in Betrieb.
Kann die Ursache darin liegen, dass Daten von den Energiezählern nicht abgerufen werden können?

bobinger
Beiträge: 21
Registriert: 09.08.2022, 10:21
System: CCU und Access Point

Re: WatchDog Alarm: unclean shutdown

Beitrag von bobinger » 23.10.2022, 11:56

Das Problem bleibt ungelöst. Heute um 7:29:21 ist wieder WatchDogAlarm gemeldet mit 'unclean shutdown'. Unter 'Hilfe' ist 'uptime' von 2 h 5 min angegeben. Das passt zum Zeitpunkt des Shutdowns. Solche Ereignisse kommen ca. einmal die Woche vor. Softwareupdate auf Vers. 3.65.11.20221005 ist inzwischen auch erfolgt.
Hier Log-Datei:

Code: Alles auswählen

***** messages *****
Oct 23 07:27:48 localhost syslog.info syslogd started: BusyBox v1.35.0
Oct 23 07:27:48 localhost kern.notice kernel: klogd started: BusyBox v1.35.0 (2022-10-05 14:51:43 UTC)
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
Oct 23 07:27:48 localhost kern.notice kernel: [    0.000000] Linux version 5.15.61 (builder@a1ec84ae9e45) (aarch64-buildroot-linux-gnu-gcc.br_real (Buildroot -g6a4d9f0) 11.3.0, GNU ld (GNU Binutils) 2.37) #1 SMP PREEMPT Wed Oct 5 15:10:09 UTC 2022
Oct 23 07:27:48 localhost kern.notice kernel: [    0.000000] random: crng init done
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] efi: UEFI not found.
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Reserved memory: created CMA memory pool at 0x000000003a000000, size 64 MiB
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Zone ranges:
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000]   DMA      [mem 0x0000000000000000-0x000000003dffffff]
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000]   DMA32    empty
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000]   Normal   empty
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Movable zone start for each node
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Early memory node ranges
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x000000003dffffff]
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003dffffff]
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] On node 0, zone DMA: 8192 pages in unavailable ranges
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] percpu: Embedded 26 pages/cpu s68056 r8192 d30248 u106496
Oct 23 07:27:48 localhost kern.debug kernel: [    0.000000] pcpu-alloc: s68056 r8192 d30248 u106496 alloc=26*4096
Oct 23 07:27:48 localhost kern.debug kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Detected VIPT I-cache on CPU0
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] CPU features: detected: ARM erratum 843419
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] CPU features: detected: ARM erratum 845719
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 249984
Oct 23 07:27:48 localhost kern.notice kernel: [    0.000000] Kernel command line: dwc_otg.lpm_enable=0 sdhci_bcm2708.enable_llm=0 console=tty2 root=PARTUUID=6145818f-02 ro rootfstype=ext4 fsck.repair=yes rootwait rootdelay=5 consoleblank=120 logo.nologo quiet loglevel=0 init_on_alloc=1 init_on_free=1 slab_nomerge iomem=relaxed net.ifnames=0 usb-storage.quirks=2109:0715:u,152d:0578:u,152d:0579:u,152d:1561:u,174c:0829:u,14b0:0206:u,174c:55aa:u  coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=720 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  empty
Oct 23 07:27:48 localhost kern.notice kernel: [    0.000000] Unknown kernel command line parameters "empty", will be passed to user space.
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] mem auto-init: stack:off, heap alloc:on, heap free:on
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] mem auto-init: clearing system memory may take some time...
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Memory: 909256K/1015808K available (10688K kernel code, 1718K rwdata, 3636K rodata, 3328K init, 931K bss, 41016K reserved, 65536K cma-reserved)
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] ftrace: allocating 33124 entries in 130 pages
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] ftrace: allocated 130 pages with 2 groups
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] trace event string verifier disabled
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] rcu: Preemptible hierarchical RCU implementation.
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] rcu: 	RCU event tracing is enabled.
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] 	Trampoline variant of Tasks RCU enabled.
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] 	Rude variant of Tasks RCU enabled.
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] Root IRQ handler: bcm2836_arm_irqchip_handle_irq
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Oct 23 07:27:48 localhost kern.info kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Oct 23 07:27:48 localhost kern.info kernel: [    0.000001] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Oct 23 07:27:48 localhost kern.info kernel: [    0.000374] Console: colour dummy device 80x25
Oct 23 07:27:48 localhost kern.info kernel: [    0.000439] printk: console [tty2] enabled
Oct 23 07:27:48 localhost kern.info kernel: [    0.000511] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800)
Oct 23 07:27:48 localhost kern.info kernel: [    0.000548] pid_max: default: 32768 minimum: 301
Oct 23 07:27:48 localhost kern.info kernel: [    0.000739] LSM: Security Framework initializing
Oct 23 07:27:48 localhost kern.info kernel: [    0.000773] Yama: becoming mindful.
Oct 23 07:27:48 localhost kern.info kernel: [    0.001033] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.001066] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.002726] cgroup: Disabling memory control group subsystem
Oct 23 07:27:48 localhost kern.info kernel: [    0.006074] rcu: Hierarchical SRCU implementation.
Oct 23 07:27:48 localhost kern.info kernel: [    0.007382] EFI services will not be available.
Oct 23 07:27:48 localhost kern.info kernel: [    0.008143] smp: Bringing up secondary CPUs ...
Oct 23 07:27:48 localhost kern.info kernel: [    0.009641] Detected VIPT I-cache on CPU1
Oct 23 07:27:48 localhost kern.info kernel: [    0.009733] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
Oct 23 07:27:48 localhost kern.info kernel: [    0.011365] Detected VIPT I-cache on CPU2
Oct 23 07:27:48 localhost kern.info kernel: [    0.011424] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
Oct 23 07:27:48 localhost kern.info kernel: [    0.013106] Detected VIPT I-cache on CPU3
Oct 23 07:27:48 localhost kern.info kernel: [    0.013162] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
Oct 23 07:27:48 localhost kern.info kernel: [    0.013373] smp: Brought up 1 node, 4 CPUs
Oct 23 07:27:48 localhost kern.info kernel: [    0.013399] SMP: Total of 4 processors activated.
Oct 23 07:27:48 localhost kern.info kernel: [    0.013415] CPU features: detected: 32-bit EL0 Support
Oct 23 07:27:48 localhost kern.info kernel: [    0.013432] CPU features: detected: CRC32 instructions
Oct 23 07:27:48 localhost kern.info kernel: [    0.050080] CPU: All CPU(s) started at EL2
Oct 23 07:27:48 localhost kern.info kernel: [    0.050182] alternatives: patching kernel code
Oct 23 07:27:48 localhost kern.info kernel: [    0.051889] devtmpfs: initialized
Oct 23 07:27:48 localhost kern.notice kernel: [    0.073901] Enabled cp15_barrier support
Oct 23 07:27:48 localhost kern.notice kernel: [    0.073953] Enabled setend support
Oct 23 07:27:48 localhost kern.info kernel: [    0.074221] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
Oct 23 07:27:48 localhost kern.info kernel: [    0.074263] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.139454] pinctrl core: initialized pinctrl subsystem
Oct 23 07:27:48 localhost kern.info kernel: [    0.140817] DMI not present or invalid.
Oct 23 07:27:48 localhost kern.info kernel: [    0.141471] NET: Registered PF_NETLINK/PF_ROUTE protocol family
Oct 23 07:27:48 localhost kern.info kernel: [    0.155096] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
Oct 23 07:27:48 localhost kern.info kernel: [    0.155704] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
Oct 23 07:27:48 localhost kern.info kernel: [    0.158114] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
Oct 23 07:27:48 localhost kern.info kernel: [    0.158304] audit: initializing netlink subsys (disabled)
Oct 23 07:27:48 localhost kern.notice kernel: [    0.158741] audit: type=2000 audit(0.156:1): state=initialized audit_enabled=0 res=1
Oct 23 07:27:48 localhost kern.info kernel: [    0.159399] thermal_sys: Registered thermal governor 'step_wise'
Oct 23 07:27:48 localhost kern.info kernel: [    0.159719] cpuidle: using governor ladder
Oct 23 07:27:48 localhost kern.info kernel: [    0.159803] cpuidle: using governor menu
Oct 23 07:27:48 localhost kern.info kernel: [    0.160191] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
Oct 23 07:27:48 localhost kern.info kernel: [    0.160474] ASID allocator initialised with 65536 entries
Oct 23 07:27:48 localhost kern.info kernel: [    0.172677] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Oct 23 07:27:48 localhost kern.info kernel: [    0.196906] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-08-26T14:04:36, variant start
Oct 23 07:27:48 localhost kern.info kernel: [    0.200930] raspberrypi-firmware soc:firmware: Firmware hash is 102f1e848393c2112206fadffaaf86db04e98326
Oct 23 07:27:48 localhost kern.info kernel: [    0.256453] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
Oct 23 07:27:48 localhost kern.notice kernel: [    0.259790] SCSI subsystem initialized
Oct 23 07:27:48 localhost kern.debug kernel: [    0.260212] libata version 3.00 loaded.
Oct 23 07:27:48 localhost kern.info kernel: [    0.260544] usbcore: registered new interface driver usbfs
Oct 23 07:27:48 localhost kern.info kernel: [    0.260625] usbcore: registered new interface driver hub
Oct 23 07:27:48 localhost kern.info kernel: [    0.260717] usbcore: registered new device driver usb
Oct 23 07:27:48 localhost kern.warn kernel: [    0.261368] usb_phy_generic phy: supply vcc not found, using dummy regulator
Oct 23 07:27:48 localhost kern.warn kernel: [    0.261674] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
Oct 23 07:27:48 localhost kern.info kernel: [    0.262024] pps_core: LinuxPPS API ver. 1 registered
Oct 23 07:27:48 localhost kern.info kernel: [    0.262042] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Oct 23 07:27:48 localhost kern.info kernel: [    0.262077] PTP clock support registered
Oct 23 07:27:48 localhost kern.info kernel: [    0.263259] NetLabel: Initializing
Oct 23 07:27:48 localhost kern.info kernel: [    0.263275] NetLabel:  domain hash size = 128
Oct 23 07:27:48 localhost kern.info kernel: [    0.263291] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
Oct 23 07:27:48 localhost kern.info kernel: [    0.263448] NetLabel:  unlabeled traffic allowed by default
Oct 23 07:27:48 localhost kern.info kernel: [    0.264380] clocksource: Switched to clocksource arch_sys_counter
Oct 23 07:27:48 localhost kern.notice kernel: [    0.377271] VFS: Disk quotas dquot_6.6.0
Oct 23 07:27:48 localhost kern.info kernel: [    0.377415] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Oct 23 07:27:48 localhost kern.notice kernel: [    0.377681] FS-Cache: Loaded
Oct 23 07:27:48 localhost kern.info kernel: [    0.378100] CacheFiles: Loaded
Oct 23 07:27:48 localhost kern.info kernel: [    0.378374] simple-framebuffer 3eaa9000.framebuffer: framebuffer at 0x3eaa9000, 0x151800 bytes
Oct 23 07:27:48 localhost kern.info kernel: [    0.378400] simple-framebuffer 3eaa9000.framebuffer: format=a8r8g8b8, mode=720x480x32, linelength=2880
Oct 23 07:27:48 localhost kern.info kernel: [    0.379010] Console: switching to colour frame buffer device 90x30
Oct 23 07:27:48 localhost kern.info kernel: [    0.384852] simple-framebuffer 3eaa9000.framebuffer: fb0: simplefb registered!
Oct 23 07:27:48 localhost kern.info kernel: [    0.400618] NET: Registered PF_INET protocol family
Oct 23 07:27:48 localhost kern.info kernel: [    0.400821] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.402420] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.402480] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.402521] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.402649] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.402853] TCP: Hash tables configured (established 8192 bind 8192)
Oct 23 07:27:48 localhost kern.info kernel: [    0.403017] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.403091] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
Oct 23 07:27:48 localhost kern.info kernel: [    0.403430] NET: Registered PF_UNIX/PF_LOCAL protocol family
Oct 23 07:27:48 localhost kern.info kernel: [    0.404900] RPC: Registered named UNIX socket transport module.
Oct 23 07:27:48 localhost kern.info kernel: [    0.404922] RPC: Registered udp transport module.
Oct 23 07:27:48 localhost kern.info kernel: [    0.404938] RPC: Registered tcp transport module.
Oct 23 07:27:48 localhost kern.info kernel: [    0.404953] RPC: Registered tcp NFSv4.1 backchannel transport module.
Oct 23 07:27:48 localhost kern.info kernel: [    0.407765] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
Oct 23 07:27:48 localhost kern.notice kernel: [    1.735808] Initialise system trusted keyrings
Oct 23 07:27:48 localhost kern.info kernel: [    1.736431] workingset: timestamp_bits=46 max_order=18 bucket_order=0
Oct 23 07:27:48 localhost kern.info kernel: [    1.745705] zbud: loaded
Oct 23 07:27:48 localhost kern.notice kernel: [    1.749029] FS-Cache: Netfs 'nfs' registered for caching
Oct 23 07:27:48 localhost kern.notice kernel: [    1.750160] NFS: Registering the id_resolver key type
Oct 23 07:27:48 localhost kern.notice kernel: [    1.750215] Key type id_resolver registered
Oct 23 07:27:48 localhost kern.notice kernel: [    1.750232] Key type id_legacy registered
Oct 23 07:27:48 localhost kern.info kernel: [    1.750381] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Oct 23 07:27:48 localhost kern.info kernel: [    1.750401] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
Oct 23 07:27:48 localhost kern.notice kernel: [    1.752989] Key type asymmetric registered
Oct 23 07:27:48 localhost kern.notice kernel: [    1.753008] Asymmetric key parser 'x509' registered
Oct 23 07:27:48 localhost kern.info kernel: [    1.753226] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Oct 23 07:27:48 localhost kern.info kernel: [    1.753614] io scheduler mq-deadline registered
Oct 23 07:27:48 localhost kern.info kernel: [    1.753635] io scheduler kyber registered
Oct 23 07:27:48 localhost kern.info kernel: [    1.767255] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
Oct 23 07:27:48 localhost kern.info kernel: [    1.770232] bcm2835-rng 3f104000.rng: hwrng registered
Oct 23 07:27:48 localhost kern.info kernel: [    1.771089] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Oct 23 07:27:48 localhost kern.info kernel: [    1.772936] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Oct 23 07:27:48 localhost kern.info kernel: [    1.792338] brd: module loaded
Oct 23 07:27:48 localhost kern.info kernel: [    1.807054] loop: module loaded
Oct 23 07:27:48 localhost kern.info kernel: [    1.807562] Loading iSCSI transport class v2.0-870.
Oct 23 07:27:48 localhost kern.info kernel: [    1.812102] tun: Universal TUN/TAP device driver, 1.6
Oct 23 07:27:48 localhost kern.info kernel: [    1.813003] usbcore: registered new interface driver lan78xx
Oct 23 07:27:48 localhost kern.info kernel: [    1.813089] usbcore: registered new interface driver smsc95xx
Oct 23 07:27:48 localhost kern.info kernel: [    1.813116] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Oct 23 07:27:48 localhost kern.warn kernel: [    2.014037] Core Release: 2.80a
Oct 23 07:27:48 localhost kern.warn kernel: [    2.014063] Setting default values for core params
Oct 23 07:27:48 localhost kern.warn kernel: [    2.014095] Finished setting default values for core params
Oct 23 07:27:48 localhost kern.warn kernel: [    2.214571] Using Buffer DMA mode
Oct 23 07:27:48 localhost kern.warn kernel: [    2.214588] Periodic Transfer Interrupt Enhancement - disabled
Oct 23 07:27:48 localhost kern.warn kernel: [    2.214603] Multiprocessor Interrupt Enhancement - disabled
Oct 23 07:27:48 localhost kern.warn kernel: [    2.214618] OTG VER PARAM: 0, OTG VER FLAG: 0
Oct 23 07:27:48 localhost kern.warn kernel: [    2.214642] Dedicated Tx FIFOs mode
Oct 23 07:27:48 localhost kern.warn kernel: [    2.222948] 
Oct 23 07:27:48 localhost kern.warn kernel: [    2.222966] WARN::dwc_otg_hcd_init:1072: FIQ DMA bounce buffers: virt = ffffffc009859000 dma = 0x00000000fa400000 len=9024
Oct 23 07:27:48 localhost kern.warn kernel: [    2.223011] FIQ FSM acceleration enabled for :
Oct 23 07:27:48 localhost kern.warn kernel: [    2.223011] Non-periodic Split Transactions
Oct 23 07:27:48 localhost kern.warn kernel: [    2.223011] Periodic Split Transactions
Oct 23 07:27:48 localhost kern.warn kernel: [    2.223011] High-Speed Isochronous Endpoints
Oct 23 07:27:48 localhost kern.warn kernel: [    2.223011] Interrupt/Control Split Transaction hack enabled
Oct 23 07:27:48 localhost kern.debug kernel: [    2.223034] dwc_otg: Microframe scheduler enabled
Oct 23 07:27:48 localhost kern.warn kernel: [    2.223095] 
Oct 23 07:27:48 localhost kern.warn kernel: [    2.223108] WARN::hcd_init_fiq:496: MPHI regs_base at ffffffc009445000
Oct 23 07:27:48 localhost kern.info kernel: [    2.223207] dwc_otg 3f980000.usb: DWC OTG Controller
Oct 23 07:27:48 localhost kern.info kernel: [    2.223251] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Oct 23 07:27:48 localhost kern.info kernel: [    2.223317] dwc_otg 3f980000.usb: irq 74, io mem 0x00000000
Oct 23 07:27:48 localhost kern.warn kernel: [    2.223382] Init: Port Power? op_state=1
Oct 23 07:27:48 localhost kern.warn kernel: [    2.223396] Init: Power Port (0)
Oct 23 07:27:48 localhost kern.info kernel: [    2.223944] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
Oct 23 07:27:48 localhost kern.info kernel: [    2.223972] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Oct 23 07:27:48 localhost kern.info kernel: [    2.223994] usb usb1: Product: DWC OTG Controller
Oct 23 07:27:48 localhost kern.info kernel: [    2.224013] usb usb1: Manufacturer: Linux 5.15.61 dwc_otg_hcd
Oct 23 07:27:48 localhost kern.info kernel: [    2.224032] usb usb1: SerialNumber: 3f980000.usb
Oct 23 07:27:48 localhost kern.info kernel: [    2.225162] hub 1-0:1.0: USB hub found
Oct 23 07:27:48 localhost kern.info kernel: [    2.225259] hub 1-0:1.0: 1 port detected
Oct 23 07:27:48 localhost kern.debug kernel: [    2.226458] dwc_otg: FIQ enabled
Oct 23 07:27:48 localhost kern.debug kernel: [    2.226477] dwc_otg: NAK holdoff enabled
Oct 23 07:27:48 localhost kern.debug kernel: [    2.226491] dwc_otg: FIQ split-transaction FSM enabled
Oct 23 07:27:48 localhost kern.debug kernel: [    2.226527] Module dwc_common_port init
Oct 23 07:27:48 localhost kern.info kernel: [    2.227885] usbcore: registered new interface driver uas
Oct 23 07:27:48 localhost kern.info kernel: [    2.228001] usbcore: registered new interface driver usb-storage
Oct 23 07:27:48 localhost kern.info kernel: [    2.228585] mousedev: PS/2 mouse device common for all mice
Oct 23 07:27:48 localhost kern.info kernel: [    2.234223] sdhci: Secure Digital Host Controller Interface driver
Oct 23 07:27:48 localhost kern.info kernel: [    2.234245] sdhci: Copyright(c) Pierre Ossman
Oct 23 07:27:48 localhost kern.err kernel: [    2.234972] Error: Driver 'sdhost-bcm2835' is already registered, aborting...
Oct 23 07:27:48 localhost kern.info kernel: [    2.234991] sdhci-pltfm: SDHCI platform and OF driver helper
Oct 23 07:27:48 localhost kern.info kernel: [    2.239721] ledtrig-cpu: registered to indicate activity on CPUs
Oct 23 07:27:48 localhost kern.info kernel: [    2.240015] hid: raw HID events driver (C) Jiri Kosina
Oct 23 07:27:48 localhost kern.info kernel: [    2.240155] usbcore: registered new interface driver usbhid
Oct 23 07:27:48 localhost kern.info kernel: [    2.240164] usbhid: USB HID core driver
Oct 23 07:27:48 localhost kern.info kernel: [    2.245330] NET: Registered PF_PACKET protocol family
Oct 23 07:27:48 localhost kern.notice kernel: [    2.245749] Key type dns_resolver registered
Oct 23 07:27:48 localhost kern.info kernel: [    2.246757] registered taskstats version 1
Oct 23 07:27:48 localhost kern.notice kernel: [    2.246775] Loading compiled-in X.509 certificates
Oct 23 07:27:48 localhost kern.info kernel: [    2.262238] 3f215040.serial: ttyS0 at MMIO 0x3f215040 (irq = 71, base_baud = 31250000) is a 16550
Oct 23 07:27:48 localhost kern.info kernel: [    2.263411] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Oct 23 07:27:48 localhost kern.info kernel: [    2.263809] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Oct 23 07:27:48 localhost kern.info kernel: [    2.265344] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
Oct 23 07:27:48 localhost kern.info kernel: [    2.265361] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
Oct 23 07:27:48 localhost kern.info kernel: [    2.292181] sdhost: log_buf @ 0000000064179532 (c2fa9000)
Oct 23 07:27:48 localhost kern.warn kernel: [    2.336603] Indeed it is in host mode hprt0 = 00021501
Oct 23 07:27:48 localhost kern.info kernel: [    2.360516] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Oct 23 07:27:48 localhost kern.info kernel: [    2.364789] of_cfs_init
Oct 23 07:27:48 localhost kern.info kernel: [    2.364889] of_cfs_init: OK
Oct 23 07:27:48 localhost kern.info kernel: [    2.366733] Waiting 5 sec before mounting root device...
Oct 23 07:27:48 localhost kern.warn kernel: [    2.428231] mmc0: host does not support reading read-only switch, assuming write-enable
Oct 23 07:27:48 localhost kern.info kernel: [    2.434355] mmc0: new high speed SDHC card at address e624
Oct 23 07:27:48 localhost kern.info kernel: [    2.437924] mmc1: new high speed SDIO card at address 0001
Oct 23 07:27:48 localhost kern.info kernel: [    2.439148] mmcblk0: mmc0:e624 SM32G 29.7 GiB 
Oct 23 07:27:48 localhost kern.info kernel: [    2.443008]  mmcblk0: p1 p2 p3
Oct 23 07:27:48 localhost kern.info kernel: [    2.443754] mmcblk0: mmc0:e624 SM32G 29.7 GiB
Oct 23 07:27:48 localhost kern.info kernel: [    2.528472] usb 1-1: new high-speed USB device number 2 using dwc_otg
Oct 23 07:27:48 localhost kern.warn kernel: [    2.528632] Indeed it is in host mode hprt0 = 00001101
Oct 23 07:27:48 localhost kern.info kernel: [    2.736986] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00
Oct 23 07:27:48 localhost kern.info kernel: [    2.737016] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Oct 23 07:27:48 localhost kern.info kernel: [    2.738212] hub 1-1:1.0: USB hub found
Oct 23 07:27:48 localhost kern.info kernel: [    2.738402] hub 1-1:1.0: 5 ports detected
Oct 23 07:27:48 localhost kern.info kernel: [    3.028494] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Oct 23 07:27:48 localhost kern.info kernel: [    3.128973] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
Oct 23 07:27:48 localhost kern.info kernel: [    3.129002] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Oct 23 07:27:48 localhost kern.info kernel: [    3.132026] smsc95xx v2.0.0
Oct 23 07:27:48 localhost kern.info kernel: [    3.249804] SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=POLL)
Oct 23 07:27:48 localhost kern.info kernel: [    3.250994] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:4c:3a:e5
Oct 23 07:27:48 localhost kern.info kernel: [    3.328494] usb 1-1.5: new high-speed USB device number 4 using dwc_otg
Oct 23 07:27:48 localhost kern.info kernel: [    3.431430] usb 1-1.5: New USB device found, idVendor=0930, idProduct=6544, bcdDevice= 1.00
Oct 23 07:27:48 localhost kern.info kernel: [    3.431460] usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Oct 23 07:27:48 localhost kern.info kernel: [    3.431483] usb 1-1.5: Product: TransMemory     
Oct 23 07:27:48 localhost kern.info kernel: [    3.431502] usb 1-1.5: Manufacturer: TOSHIBA 
Oct 23 07:27:48 localhost kern.info kernel: [    3.431521] usb 1-1.5: SerialNumber: 0022CFF6BDF8C0211377D345
Oct 23 07:27:48 localhost kern.info kernel: [    3.432715] usb-storage 1-1.5:1.0: USB Mass Storage device detected
Oct 23 07:27:48 localhost kern.info kernel: [    3.433786] scsi host0: usb-storage 1-1.5:1.0
Oct 23 07:27:48 localhost kern.notice kernel: [    4.466773] scsi 0:0:0:0: Direct-Access     TOSHIBA  TransMemory      1.00 PQ: 0 ANSI: 4
Oct 23 07:27:48 localhost kern.notice kernel: [    4.469047] sd 0:0:0:0: [sda] 15155200 512-byte logical blocks: (7.76 GB/7.23 GiB)
Oct 23 07:27:48 localhost kern.notice kernel: [    4.469530] sd 0:0:0:0: [sda] Write Protect is off
Oct 23 07:27:48 localhost kern.debug kernel: [    4.469567] sd 0:0:0:0: [sda] Mode Sense: 45 00 00 00
Oct 23 07:27:48 localhost kern.notice kernel: [    4.470025] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
Oct 23 07:27:48 localhost kern.info kernel: [    4.475795]  sda: sda1
Oct 23 07:27:48 localhost kern.notice kernel: [    4.478826] sd 0:0:0:0: [sda] Attached SCSI removable disk
Oct 23 07:27:48 localhost kern.info kernel: [    7.409722] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
Oct 23 07:27:48 localhost kern.info kernel: [    7.409839] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Oct 23 07:27:48 localhost kern.info kernel: [    7.410766] devtmpfs: mounted
Oct 23 07:27:48 localhost kern.info kernel: [    7.421102] Freeing unused kernel memory: 3328K
Oct 23 07:27:48 localhost kern.info kernel: [    7.428461] Run /sbin/init as init process
Oct 23 07:27:48 localhost kern.debug kernel: [    7.428477]   with arguments:
Oct 23 07:27:48 localhost kern.debug kernel: [    7.428484]     /sbin/init
Oct 23 07:27:48 localhost kern.debug kernel: [    7.428492]     empty
Oct 23 07:27:48 localhost kern.debug kernel: [    7.428499]   with environment:
Oct 23 07:27:48 localhost kern.debug kernel: [    7.428506]     HOME=/
Oct 23 07:27:48 localhost kern.debug kernel: [    7.428514]     TERM=linux
Oct 23 07:27:48 localhost kern.err kernel: [    8.448196] EXT4-fs (mmcblk0p3): Mount option "commit=30" incompatible with ext2
Oct 23 07:27:48 localhost kern.info kernel: [    8.462332] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: commit=30. Quota mode: none.
Oct 23 07:27:48 localhost kern.info kernel: [   10.123851] zram: Added device: zram0
Oct 23 07:27:48 localhost kern.info kernel: [   10.164576] zram0: detected capacity change from 0 to 2054056
Oct 23 07:27:48 localhost kern.info kernel: [   10.209016] Adding 1027024k swap on /dev/zram0.  Priority:75 extents:1 across:1027024k SSFS
Oct 23 07:27:48 localhost kern.info kernel: [   10.283028] i2c_dev: i2c /dev entries driver
Oct 23 07:27:48 localhost kern.info kernel: [   10.321015] rtc-ds1307 1-0032: registered as rtc0
Oct 23 07:27:48 localhost kern.warn kernel: [   10.344310] dummy_rx8130: loading out-of-tree module taints kernel.
Oct 23 07:27:49 localhost daemon.info kernel: [   13.449041] udevd[950]: starting version 3.2.11
Oct 23 07:27:49 localhost daemon.info kernel: [   13.497228] udevd[951]: starting eudev-3.2.11
Oct 23 07:27:49 localhost kern.info kernel: [   13.728818] pl011_raw_uart 3f201000.serial: there is not valid maps for state default
Oct 23 07:27:49 localhost kern.info kernel: [   13.732024] pl011_raw_uart 3f201000.serial: Initialized pl011 device; mapbase=0x3f201000; irq=99; clockrate=47999625
Oct 23 07:27:49 localhost kern.info kernel: [   13.732118] pl011_raw_uart 3f201000.serial: Detected RPI-RF-MOD, using alternative reset pin
Oct 23 07:27:49 localhost kern.info kernel: [   13.761069] raw-uart raw-uart: Reset radio module
Oct 23 07:27:49 localhost kern.info kernel: [   13.940482] raw-uart raw-uart: Registered new raw-uart device using underlying device GPIO@3f201000.serial.
Oct 23 07:27:49 localhost kern.notice kernel: [   14.039366] cfg80211: Loading compiled-in X.509 certificates for regulatory database
Oct 23 07:27:49 localhost user.info usbmount[972]: /dev/mmcblk0 does not contain a filesystem or disklabel
Oct 23 07:27:49 localhost kern.notice kernel: [   14.142203] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Oct 23 07:27:49 localhost kern.debug kernel: [   14.188797] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
Oct 23 07:27:49 localhost kern.info kernel: [   14.196053] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Oct 23 07:27:49 localhost kern.warn kernel: [   14.197200] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.raspberrypi,3-model-b.bin failed with error -2
Oct 23 07:27:49 localhost kern.info kernel: [   14.199391] usbcore: registered new interface driver brcmfmac
Oct 23 07:27:50 localhost kern.info kernel: [   14.414365] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Oct 23 07:27:50 localhost kern.info kernel: [   14.414541] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Oct 23 07:27:50 localhost kern.info kernel: [   14.422933] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897
Oct 23 07:27:54 localhost user.info usbmount[973]: /dev/sda does not contain a filesystem or disklabel
Oct 23 07:27:54 localhost user.info usbmount[1011]: executing command: mount -tvfat -onoexec,nodev,noatime,nodiratime,utf8 /dev/sda1 /media/usb1
Oct 23 07:27:54 localhost kern.warn kernel: [   19.227384] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Oct 23 07:27:54 localhost user.info usbmount[1011]: executing command: run-parts /etc/usbmount/mount.d
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ (2) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ VCHIQ doorbell(3) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ (51) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ DMA IRQ(60) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ DMA IRQ(62) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ DMA IRQ(63) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ DMA IRQ(66) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ dwc_otg, dwc_otg_pcd, dwc_otg_hcd:usb1(74) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ (95) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ (96) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ (98) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ (104) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ (180) guessed as class 0
Oct 23 07:27:55 localhost daemon.debug /usr/sbin/irqbalance: IRQ (183) guessed as class 0
Oct 23 07:27:55 localhost user.notice rfkill: block set for type bluetooth
Oct 23 07:27:55 localhost kern.info kernel: [   19.869794] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Oct 23 07:27:55 localhost kern.info kernel: [   19.986461] smsc95xx 1-1.1:1.0 eth0: Link is Down
Oct 23 07:27:57 localhost kern.info kernel: [   22.182461] smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off
Oct 23 07:27:59 localhost syslog.info syslogd exiting
Oct 23 07:27:59 homematic-raspi syslog.info syslogd started: BusyBox v1.35.0
Oct 23 07:27:59 homematic-raspi kern.notice kernel: klogd started: BusyBox v1.35.0 (2022-10-05 14:51:43 UTC)
Oct 23 07:28:00 homematic-raspi user.info firewall: configuration set
Oct 23 07:28:00 homematic-raspi user.notice rfkill: block set for type wlan
Oct 23 07:28:00 homematic-raspi daemon.info ifplugd(eth0)[1224]: ifplugd 0.28 initializing.
Oct 23 07:28:00 homematic-raspi daemon.info ifplugd(eth0)[1224]: Using interface eth0/B8:27:EB:4C:3A:E5 with driver <smsc95xx> (version: 5.15.61)
Oct 23 07:28:00 homematic-raspi daemon.info ifplugd(eth0)[1224]: Using detection mode: SIOCETHTOOL
Oct 23 07:28:00 homematic-raspi daemon.info ifplugd(eth0)[1224]: Initialization complete, link beat detected.
Oct 23 07:28:00 homematic-raspi daemon.info ifplugd(eth0)[1224]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Oct 23 07:28:00 homematic-raspi daemon.warn ifplugd(eth0)[1224]: client: ifup: interface eth0 already configured
Oct 23 07:28:00 homematic-raspi daemon.info ifplugd(eth0)[1224]: Program executed successfully.
Oct 23 07:28:00 homematic-raspi daemon.info ifplugd(wlan0)[1229]: ifplugd 0.28 initializing.
Oct 23 07:28:00 homematic-raspi daemon.info ifplugd(wlan0)[1229]: Using interface wlan0/B8:27:EB:19:6F:B0 with driver <brcmfmac> (version: 7.45.98)
Oct 23 07:28:00 homematic-raspi daemon.info ifplugd(wlan0)[1229]: Using detection mode: wireless extension
Oct 23 07:28:00 homematic-raspi daemon.info ifplugd(wlan0)[1229]: Initialization complete, link beat not detected.
Oct 23 07:28:00 homematic-raspi kern.info kernel: [   25.111565] NET: Registered PF_INET6 protocol family
Oct 23 07:28:00 homematic-raspi kern.info kernel: [   25.114091] Segment Routing with IPv6
Oct 23 07:28:00 homematic-raspi kern.info kernel: [   25.114143] In-situ OAM (IOAM) with IPv6
Oct 23 07:28:08 homematic-raspi daemon.notice ntpdate[1234]: step time server 91.205.175.192 offset +0.806463 sec
Oct 23 07:28:08 homematic-raspi daemon.info chronyd[1240]: chronyd version 4.2 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH +IPV6 -DEBUG)
Oct 23 07:28:08 homematic-raspi daemon.warn chronyd[1240]: Running with root privileges
Oct 23 07:28:08 homematic-raspi kern.info kernel: [   32.002672] raw-uart raw-uart: Reset radio module
Oct 23 07:28:10 homematic-raspi kern.info kernel: [   33.764481] cam-dummy-reg: disabling
Oct 23 07:28:13 homematic-raspi daemon.err xinetd[1331]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Oct 23 07:28:13 homematic-raspi daemon.crit xinetd[1331]: 1331 {init_services} no services. Exiting...
Oct 23 07:28:13 homematic-raspi auth.info sshd[1366]: Server listening on 0.0.0.0 port 22.
Oct 23 07:28:13 homematic-raspi auth.info sshd[1366]: Server listening on :: port 22.
Oct 23 07:28:13 homematic-raspi user.info root: Updating RF Lan Gateway Coprocessor Firmware
Oct 23 07:28:13 homematic-raspi user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Oct 23 07:28:13 homematic-raspi user.info root: Updating RF Lan Gateway Firmware
Oct 23 07:28:13 homematic-raspi user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Oct 23 07:28:13 homematic-raspi kern.info kernel: [   37.447369] eq3loop: created slave mmd_hmip
Oct 23 07:28:13 homematic-raspi kern.info kernel: [   37.448057] eq3loop: created slave mmd_bidcos
Oct 23 07:28:15 homematic-raspi kern.info kernel: [   39.481154] eq3loop: eq3loop_open_slave() mmd_bidcos
Oct 23 07:28:15 homematic-raspi kern.info kernel: [   39.481247] eq3loop: eq3loop_close_slave() mmd_bidcos
Oct 23 07:28:15 homematic-raspi kern.info kernel: [   39.485522] eq3loop: eq3loop_open_slave() mmd_hmip
Oct 23 07:28:15 homematic-raspi kern.info kernel: [   39.485596] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 23 07:28:16 homematic-raspi kern.info kernel: [   39.628316] eq3loop: eq3loop_open_slave() mmd_bidcos
Oct 23 07:28:16 homematic-raspi daemon.debug /usr/sbin/irqbalance: IRQ (99) guessed as class 0
Oct 23 07:28:42 homematic-raspi kern.info kernel: [   66.131834] eq3loop: eq3loop_open_slave() mmd_hmip
Oct 23 07:28:42 homematic-raspi kern.info kernel: [   66.132174] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 23 07:28:42 homematic-raspi kern.info kernel: [   66.136445] eq3loop: eq3loop_open_slave() mmd_hmip
Oct 23 07:28:42 homematic-raspi kern.info kernel: [   66.136764] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 23 07:28:42 homematic-raspi kern.info kernel: [   66.137986] eq3loop: eq3loop_open_slave() mmd_hmip
Oct 23 07:28:42 homematic-raspi kern.info kernel: [   66.138259] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 23 07:28:42 homematic-raspi kern.info kernel: [   66.149191] eq3loop: eq3loop_open_slave() mmd_hmip
Oct 23 05:29:00 homematic-raspi daemon.info : starting pid 1728, tty '': '/bin/mv /tmp/boot.log /var/log/boot.log'
Oct 23 05:29:00 homematic-raspi daemon.info : starting pid 1729, tty '/dev/null': '/usr/bin/monit -Ic /etc/monitrc'
Oct 23 07:29:00 homematic-raspi user.info monit[1729]: Starting Monit 5.30.0 daemon with http interface at /var/run/monit.sock
Oct 23 07:29:00 homematic-raspi user.info monit[1729]: 'homematic-raspi' Monit 5.30.0 started
Oct 23 07:29:04 homematic-raspi user.err rfd: HSSParameter::GetValue() id=ENERGY_COUNTER failed getting physical value.
Oct 23 07:29:04 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 07:29:04 homematic-raspi user.err rfd: HSSParameter::GetValue() id=ENERGY_COUNTER failed getting physical value.
Oct 23 07:29:04 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 07:29:04 homematic-raspi user.err rfd: HSSParameter::GetValue() id=GAS_ENERGY_COUNTER failed getting physical value.
Oct 23 07:29:04 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","GAS_ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 07:29:04 homematic-raspi user.err rfd: HSSParameter::GetValue() id=BOOT failed getting physical value.
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","GAS_ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 07:29:04 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","BOOT"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","BOOT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 07:29:04 homematic-raspi user.err rfd: HSSParameter::GetValue() id=GAS_ENERGY_COUNTER failed getting physical value.
Oct 23 07:29:04 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","GAS_ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","GAS_ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 07:29:04 homematic-raspi user.err rfd: HSSParameter::GetValue() id=IEC_ENERGY_COUNTER failed getting physical value.
Oct 23 07:29:04 homematic-raspi user.err rfd: HSSParameter::GetValue() id=BOOT failed getting physical value.
Oct 23 07:29:04 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","IEC_ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","IEC_ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 07:29:04 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","BOOT"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 07:29:04 homematic-raspi user.err rfd: HSSParameter::GetValue() id=IEC_ENERGY_COUNTER failed getting physical value.
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","BOOT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 07:29:04 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","IEC_ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","IEC_ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 07:29:15 homematic-raspi daemon.info chronyd[1240]: Selected source 94.130.35.4 (de.pool.ntp.org)
Oct 23 07:29:21 homematic-raspi user.err monit[1729]: 'hs485dEnabled' status failed (2) -- grep: /var/etc/hs485d.conf: No such file or directory
Oct 23 07:29:21 homematic-raspi user.err monit[1729]: 'hmlangwEnabled' status failed (1) -- no output
Oct 23 07:29:21 homematic-raspi user.err monit[1729]: 'internetCheckEnabled' status failed (0) -- no output
Oct 23 07:29:21 homematic-raspi user.err monit[1729]: 'uncleanShutdownCheck' status failed (0) -- no output
Oct 23 07:29:21 homematic-raspi user.info monit[1729]: 'uncleanShutdownCheck' exec: '/bin/sh -c /bin/triggerAlarm.tcl 'Unclean shutdown or system crash identified' 'WatchDog: unclean-shutdown' true ; rm -f /var/status/uncleanShutdown'
Oct 23 07:29:21 homematic-raspi user.err monit[1729]: 'hb_rf_eth-CheckEnabled' status failed (1) -- no output
Oct 23 07:29:21 homematic-raspi user.err monit[1729]: 'coProcessorCheck' status failed (1) -- no output
Oct 23 07:29:21 homematic-raspi user.warn monit[1729]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Oct 23 07:29:21 homematic-raspi user.warn monit[1729]: 'rpi4usb3Check' status failed (1) -- no output
Oct 23 07:29:21 homematic-raspi user.err monit[1729]: 'tailscaleEnabled' status failed (1) -- no output
Oct 23 07:29:39 homematic-raspi user.err monit[1729]: 'wlan0CheckEnabled' status failed (1) -- no output
Oct 23 07:29:39 homematic-raspi user.err monit[1729]: 'uncleanShutdownCheck' status failed (0) -- no output
Oct 23 07:29:39 homematic-raspi user.warn monit[1729]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Oct 23 07:29:39 homematic-raspi user.warn monit[1729]: 'rpi4usb3Check' status failed (1) -- no output
Oct 23 07:29:57 homematic-raspi user.info monit[1729]: 'uncleanShutdownCheck' status succeeded (1) -- no output
Oct 23 07:29:57 homematic-raspi user.err monit[1729]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Oct 23 07:29:57 homematic-raspi user.err monit[1729]: 'rpi4usb3Check' status failed (1) -- no output
Oct 23 09:19:15 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9D89BB73D5:1","PARTY_TIME_START"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_START"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"000E9D89BB73D5:1","PARTY_TIME_START"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 09:19:15 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9D89BB73D5:1","PARTY_TIME_END"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_END"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"000E9D89BB73D5:1","PARTY_TIME_END"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 09:19:15 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9D89BB73D5:1","PARTY_SET_POINT_TEMPERATURE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_SET_POINT_TEMPERATURE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"000E9D89BB73D5:1","PARTY_SET_POINT_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 09:19:15 homematic-raspi user.err rfd: HSSParameter::GetValue() id=POWER failed getting physical value.
Oct 23 09:19:15 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","POWER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","POWER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 09:19:15 homematic-raspi user.err rfd: HSSParameter::GetValue() id=IEC_POWER failed getting physical value.
Oct 23 09:19:15 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","IEC_POWER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","IEC_POWER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 09:19:15 homematic-raspi user.err rfd: HSSParameter::GetValue() id=GAS_POWER failed getting physical value.
Oct 23 09:19:15 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","GAS_POWER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","GAS_POWER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Oct 23 09:19:15 homematic-raspi user.err rfd: HSSParameter::GetValue() id=IEC_POWER failed getting physical value.
Oct 23 09:19:15 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"PEQ0544426:1","IEC_POWER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"PEQ0544426:1","IEC_POWER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Oct 23 09:19:15 homematic-raspi local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]


***** hmserver.log *****
2022-10-23 07:28:39,485 de.eq3.cbcs.server.core.vertx.KeyServerWorker ERROR [vert.x-worker-thread-0] Missing key server configuration parameter (Network.Key) for  mode: KEYSERVER_LOCAL 


MichaelN
Beiträge: 9562
Registriert: 27.04.2020, 10:34
System: CCU
Hat sich bedankt: 696 Mal
Danksagung erhalten: 1608 Mal

Re: WatchDog Alarm: unclean shutdown

Beitrag von MichaelN » 23.10.2022, 12:06

Man braucht das Log File von vor dem Absturz
LG, Michael.

Wenn du eine App zur Bedienung brauchst, dann hast du kein Smarthome.

Wettervorhersage über AccuWeather oder OpenWeatherMap+++ Rollladensteuerung 2.0 +++ JSON-API-Ausgaben auswerten +++ undokumentierte Skript-Befehle und Debugging-Tipps +++

bobinger
Beiträge: 21
Registriert: 09.08.2022, 10:21
System: CCU und Access Point

Re: WatchDog Alarm: unclean shutdown

Beitrag von bobinger » 23.10.2022, 16:55

Danke für die Antwort.
Wie komme ich an dieses LogFile?
Absturz war lt. Alarmmeldung um 7:29:21 Uhr. Um 7.29:04 kommt zum ersten Mal eine Meldung mit failed:

Code: Alles auswählen

Oct 23 07:29:04 homematic-raspi local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"SEQ1374883:1","ENERGY_COUNTER"}, result: [faultCode:-1,faultString:"Failure"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Oct 23 07:29:04 homematic-raspi local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1007, params: {"SEQ1374883:1","ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1435]
"SEQ1374883" ist der Sensor für den Gasverbrauch (Reed-Kontakt), der an einem HM-ES-TX-WM hängt. Zur Zeit gibt es hier nichts zu messen, da kein Gas verbraucht wird.

Sorry für die Nachfrage, aber ich bin bei Homematic und spez. Rasperrymatic ein ziemlicher Anfänger.
Zuletzt geändert von bobinger am 23.10.2022, 17:04, insgesamt 1-mal geändert.

MichaelN
Beiträge: 9562
Registriert: 27.04.2020, 10:34
System: CCU
Hat sich bedankt: 696 Mal
Danksagung erhalten: 1608 Mal

Re: WatchDog Alarm: unclean shutdown

Beitrag von MichaelN » 23.10.2022, 16:58

Du musst einen externen Log Server aufsetzen.
LG, Michael.

Wenn du eine App zur Bedienung brauchst, dann hast du kein Smarthome.

Wettervorhersage über AccuWeather oder OpenWeatherMap+++ Rollladensteuerung 2.0 +++ JSON-API-Ausgaben auswerten +++ undokumentierte Skript-Befehle und Debugging-Tipps +++

Benutzeravatar
Baxxy
Beiträge: 10648
Registriert: 18.12.2018, 15:45
System: Alternative CCU (auf Basis OCCU)
Hat sich bedankt: 597 Mal
Danksagung erhalten: 2180 Mal

Re: WatchDog Alarm: unclean shutdown

Beitrag von Baxxy » 23.10.2022, 17:03

Komisch das es immer Sonntags morgen ist. Zumindest was man bisher so las.

Der BB-Check läuft jeden Tag um 01:59 und FSTRIM Samstags um 04:00... die fallen damit als Verdächtige aus.

bobinger
Beiträge: 21
Registriert: 09.08.2022, 10:21
System: CCU und Access Point

Re: WatchDog Alarm: unclean shutdown

Beitrag von bobinger » 23.10.2022, 17:06

Dank für die Mühe und die Antwort.
Wie setzt man solchen einen Log-Server auf. Kannst Du mir hier einen Tipp oder URL-Link geben?

Slice
Beiträge: 1192
Registriert: 03.02.2016, 14:44
System: Alternative CCU (auf Basis OCCU)
Wohnort: irgendwo aus Süd BaWü
Hat sich bedankt: 135 Mal
Danksagung erhalten: 82 Mal

Re: WatchDog Alarm: unclean shutdown

Beitrag von Slice » 23.10.2022, 18:49

bobinger hat geschrieben:
23.10.2022, 17:06
Wie setzt man solchen einen Log-Server auf. Kannst Du mir hier einen Tipp oder URL-Link geben?
Du kannst ja mal hier nachschauen:
viewtopic.php?f=47&t=72929
Dort wurden schonmal die ein oder anderen Möglichkeiten diskutiert.

Grüße,
Slice
----------------------------------------------------------------------------------------
Raspi3B+ Bullseye mit HB-RF-ETH und RPI-RF-MOD auf piVCCU-FW 3.73.9 / Addons: CuxD v2.11 - E-Mail v1.7.6 - Patcher v1.0.0 - Philips Hue v3.2.5 - Programme drucken v2.6 - Scriptparser v1.11 - XML-API v2.3
Geräte: 141 / Kanäle: 791 / Datenpunkte: 6080 / SysVars: 275 / Programme: 161 / Regadom IDs: 14010 / 48 CUxD-Kanäle in 3 CUxD-Geräten
Intel NUC i3-5010U @ 2,1 GHz mit 16 GB RAM & 512 GB SSD für Proxmox mit ioBroker VM und CCU-Historian/InfluxDB/Grafana VM
----------------------------------------------------------------------------------------
Projekte im Forum: HomeHub v4.1 / Fritzbox-Anruferliste für HomeHub
----------------------------------------------------------------------------------------

bobinger
Beiträge: 21
Registriert: 09.08.2022, 10:21
System: CCU und Access Point

Re: WatchDog Alarm: unclean shutdown

Beitrag von bobinger » 24.10.2022, 10:35

Vielen Dank für die Tipps. Ich werde mir das mit dem Log-Server mal ansehen. Vielleicht kann ich hier einen Raspberry älteren Datum einsetzen.
Auch Dank auch für den Hinweis, dass der Alarm offensichtlich immer am Sonntag in der Frühe auftritt. Welcher cronjob läuft denn da? Ich habe mit unter crontab -e mal die Liste angesehen. Bis auf den Portforwarding-Check kann ich am Sonntag nichts entdecken. Seltsamerweise taucht die Zeile zweimal auf:

Code: Alles auswählen

@homematic-raspi:~# crontab -e
/11 * * * * [ -c /dev/rtc0 ] && /sbin/hwclock --systohc --utc
 3,9,15,21 * * * /bin/SetInterfaceClock
 4 * * * /usr/sbin/logrotate /etc/logrotate.conf || logger -p error -t "logrota
/1 * * * * /bin/updateDCVars.tcl >/dev/null 2>/dev/null
 0 * * * /bin/nice /bin/cronBackup.sh >/dev/null 2>/dev/null
 4 * * 6 [ ! -e /etc/config/NoFSTRIM ] && /bin/nice /sbin/fstrim --all --quiet
9 1 * * * [ ! -e /etc/config/NoBadBlocksCheck ] && /bin/nice /bin/checkBadBlock
/10 * * * * [ -d /media/usb0/measurement ] && /bin/nice /usr/bin/rsync -aogX --
 12 * * * sleep $((RANDOM % 900))s && /bin/checkAddonUpdates.sh >/dev/null 2>/d
 4 * * 0 [ ! -e /etc/config/NoPortForwardingCheck ] && sleep $((RANDOM % 900))s
 3,9,15,21 * * * /bin/SetInterfaceClock
 4 * * * /usr/sbin/logrotate /etc/logrotate.conf || logger -p error -t "logrotate" "logrotate aborted with error $?"
/1 * * * * /bin/updateDCVars.tcl >/dev/null 2>/dev/null
 0 * * * /bin/nice /bin/cronBackup.sh >/dev/null 2>/dev/null
 4 * * 6 [ ! -e /etc/config/NoFSTRIM ] && /bin/nice /sbin/fstrim --all --quiet >/dev/null 2>/dev/null
9 1 * * * [ ! -e /etc/config/NoBadBlocksCheck ] && /bin/nice /bin/checkBadBlocks.sh >/dev/null 2>/dev/null
/10 * * * * [ -d /media/usb0/measurement ] && /bin/nice /usr/bin/rsync -aogX --delete-after --no-whole-file --checksum /tmp/measurement/ /media/usb0/measurement/ >/dev/null 2>/dev/null
 12 * * * sleep $((RANDOM % 900))s && /bin/checkAddonUpdates.sh >/dev/null 2>/dev/null
 4 * * 0 [ ! -e /etc/config/NoPortForwardingCheck ] && sleep $((RANDOM % 900))s && /bin/checkPortForwarding.sh >/dev/null 2>/dev/null

Gibt es noch eine weitere Crontabelle auf dem RaspberryMatic?
Vielen Dank erst einmal.

MichaelN
Beiträge: 9562
Registriert: 27.04.2020, 10:34
System: CCU
Hat sich bedankt: 696 Mal
Danksagung erhalten: 1608 Mal

Re: WatchDog Alarm: unclean shutdown

Beitrag von MichaelN » 24.10.2022, 10:54

Den Portforward-Check kannst Du in den erweiterten Einstellungen deaktivieren. Wäre mal ein Versuch wert ob das die Ursache ist.
LG, Michael.

Wenn du eine App zur Bedienung brauchst, dann hast du kein Smarthome.

Wettervorhersage über AccuWeather oder OpenWeatherMap+++ Rollladensteuerung 2.0 +++ JSON-API-Ausgaben auswerten +++ undokumentierte Skript-Befehle und Debugging-Tipps +++

Antworten

Zurück zu „RaspberryMatic“