search

jens-maus / RaspberryMatic

:house: A feature-rich but lightweight, buildroot-based Linux operating system alternative for your CloudFree CCU3/ELV-Charly 'homematicIP CCU' IoT smarthome central. Running as a pure virtual appliance (ProxmoxVE, Home Assistant, LXC, Docker/OCI, Kubernetes/K8s, etc.) on a dedicated embedded device (RaspberryPi, etc.) or generic x86/ARM hardware.
https://raspberrymatic.de
Apache License 2.0
1.54k stars 190 forks source link

WatchDog: unclean-shutdown appears regularly (~2 days). #2200

Closed schup011 closed 1 year ago

schup011 commented 1 year ago

Describe the issue you are experiencing

The system seems to crash quite regularly, approximately every 2-3 days. It does not occur at the same time of the day, but at different times. I tried to implement any measures I found in the web for preventing crashes:

Describe the behavior you expected

No crashes. Continuous operation.

Steps to reproduce the issue

  1. Just wait until the next crash.

What is the version this bug report is based on?

3.67.10.20230114

Which base platform are you running?

rpi0 (RaspberryPi)

Which HomeMatic/homematicIP radio module are you using?

HM-MOD-RPI-PCB

Anything in the logs that might be useful for us?

***** messages *****
Jan  1 01:00:21 localhost syslog.info syslogd started: BusyBox v1.35.0
Jan  1 01:00:21 localhost kern.notice kernel: klogd started: BusyBox v1.35.0 (2023-01-14 07:40:59 UTC)
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Booting Linux on physical CPU 0x0
Jan  1 01:00:21 localhost kern.notice kernel: [    0.000000] Linux version 5.15.84 (builder@c4f2767c8bf2) (arm-buildroot-linux-gnueabihf-gcc.br_real (Buildroot -g2e69482) 11.3.0, GNU ld (GNU Binutils) 2.38) #1 PREEMPT Sat Jan 14 08:12:05 UTC 2023
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] OF: fdt: Machine model: Raspberry Pi Zero W Rev 1.1
Jan  1 01:00:21 localhost kern.notice kernel: [    0.000000] random: crng init done
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Memory policy: Data cache writeback
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Reserved memory: created CMA memory pool at 0x1a000000, size 64 MiB
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Zone ranges:
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000]   Normal   [mem 0x0000000000000000-0x000000001dffffff]
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Movable zone start for each node
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Early memory node ranges
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x000000001dffffff]
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000001dffffff]
Jan  1 01:00:21 localhost kern.debug kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Jan  1 01:00:21 localhost kern.debug kernel: [    0.000000] pcpu-alloc: [0] 0 
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 121800
Jan  1 01:00:21 localhost kern.notice kernel: [    0.000000] Kernel command line: dwc_otg.lpm_enable=0 sdhci_bcm2708.enable_llm=0 console=tty2 root=PARTUUID=deedbeef-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 smsc95xx.macaddr=B8:27:EB:8C:A1:CC vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000  empty
Jan  1 01:00:21 localhost kern.notice kernel: [    0.000000] Unknown kernel command line parameters "empty", will be passed to user space.
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] allocated 491520 bytes of page_ext
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] mem auto-init: stack:off, heap alloc:on, heap free:on
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] mem auto-init: clearing system memory may take some time...
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] Memory: 405704K/491520K available (9641K kernel code, 1239K rwdata, 2912K rodata, 432K init, 540K bss, 20280K reserved, 65536K cma-reserved)
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] ftrace: allocating 31012 entries in 91 pages
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] ftrace: allocated 91 pages with 5 groups
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] trace event string verifier disabled
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] rcu: Preemptible hierarchical RCU implementation.
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] rcu:     RCU event tracing is enabled.
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000]  Trampoline variant of Tasks RCU enabled.
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000]  Rude variant of Tasks RCU enabled.
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
Jan  1 01:00:21 localhost kern.info kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Jan  1 01:00:21 localhost kern.info kernel: [    0.000008] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
Jan  1 01:00:21 localhost kern.info kernel: [    0.000077] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
Jan  1 01:00:21 localhost kern.info kernel: [    0.000188] bcm2835: system timer (irq = 27)
Jan  1 01:00:21 localhost kern.info kernel: [    0.001154] Console: colour dummy device 80x30
Jan  1 01:00:21 localhost kern.info kernel: [    0.001285] printk: console [tty2] enabled
Jan  1 01:00:21 localhost kern.info kernel: [    0.001384] Calibrating delay loop... 697.34 BogoMIPS (lpj=1394688)
Jan  1 01:00:21 localhost kern.info kernel: [    0.028369] pid_max: default: 32768 minimum: 301
Jan  1 01:00:21 localhost kern.info kernel: [    0.028963] LSM: Security Framework initializing
Jan  1 01:00:21 localhost kern.info kernel: [    0.029023] Yama: becoming mindful.
Jan  1 01:00:21 localhost kern.info kernel: [    0.029532] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.029585] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.032526] cgroup: Disabling memory control group subsystem
Jan  1 01:00:21 localhost kern.info kernel: [    0.032965] CPU: Testing write buffer coherency: ok
Jan  1 01:00:21 localhost kern.info kernel: [    0.036748] Setting up static identity map for 0x8220 - 0x8258
Jan  1 01:00:21 localhost kern.info kernel: [    0.037229] rcu: Hierarchical SRCU implementation.
Jan  1 01:00:21 localhost kern.info kernel: [    0.038960] devtmpfs: initialized
Jan  1 01:00:21 localhost kern.info kernel: [    0.058897] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
Jan  1 01:00:21 localhost kern.info kernel: [    0.059383] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
Jan  1 01:00:21 localhost kern.info kernel: [    0.059454] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.121100] pinctrl core: initialized pinctrl subsystem
Jan  1 01:00:21 localhost kern.info kernel: [    0.124127] NET: Registered PF_NETLINK/PF_ROUTE protocol family
Jan  1 01:00:21 localhost kern.info kernel: [    0.133026] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Jan  1 01:00:21 localhost kern.info kernel: [    0.140819] audit: initializing netlink subsys (disabled)
Jan  1 01:00:21 localhost kern.info kernel: [    0.142715] thermal_sys: Registered thermal governor 'step_wise'
Jan  1 01:00:21 localhost kern.info kernel: [    0.143265] cpuidle: using governor ladder
Jan  1 01:00:21 localhost kern.info kernel: [    0.143392] cpuidle: using governor menu
Jan  1 01:00:21 localhost kern.info kernel: [    0.145008] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
Jan  1 01:00:21 localhost kern.info kernel: [    0.145043] hw-breakpoint: maximum watchpoint size is 4 bytes.
Jan  1 01:00:21 localhost kern.notice kernel: [    0.150286] audit: type=2000 audit(0.136:1): state=initialized audit_enabled=0 res=1
Jan  1 01:00:21 localhost kern.info kernel: [    0.158879] bcm2835-mbox 2000b880.mailbox: mailbox enabled
Jan  1 01:00:21 localhost kern.info kernel: [    0.182737] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-01-05T10:48:31, variant start
Jan  1 01:00:21 localhost kern.info kernel: [    0.186776] raspberrypi-firmware soc:firmware: Firmware hash is 8ba17717fbcedd4c3b6d4bce7e50c7af4155cba9
Jan  1 01:00:21 localhost kern.info kernel: [    0.257359] Kprobes globally optimized
Jan  1 01:00:21 localhost kern.info kernel: [    0.269855] bcm2835-dma 20007000.dma: DMA legacy API manager, dmachans=0x1
Jan  1 01:00:21 localhost kern.notice kernel: [    0.274762] SCSI subsystem initialized
Jan  1 01:00:21 localhost kern.debug kernel: [    0.275443] libata version 3.00 loaded.
Jan  1 01:00:21 localhost kern.info kernel: [    0.276768] usbcore: registered new interface driver usbfs
Jan  1 01:00:21 localhost kern.info kernel: [    0.276930] usbcore: registered new interface driver hub
Jan  1 01:00:21 localhost kern.info kernel: [    0.277079] usbcore: registered new device driver usb
Jan  1 01:00:21 localhost kern.warn kernel: [    0.277692] usb_phy_generic phy: supply vcc not found, using dummy regulator
Jan  1 01:00:21 localhost kern.warn kernel: [    0.278186] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
Jan  1 01:00:21 localhost kern.info kernel: [    0.278718] pps_core: LinuxPPS API ver. 1 registered
Jan  1 01:00:21 localhost kern.info kernel: [    0.278751] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Jan  1 01:00:21 localhost kern.info kernel: [    0.278815] PTP clock support registered
Jan  1 01:00:21 localhost kern.info kernel: [    0.281003] NetLabel: Initializing
Jan  1 01:00:21 localhost kern.info kernel: [    0.281044] NetLabel:  domain hash size = 128
Jan  1 01:00:21 localhost kern.info kernel: [    0.281069] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
Jan  1 01:00:21 localhost kern.info kernel: [    0.281301] NetLabel:  unlabeled traffic allowed by default
Jan  1 01:00:21 localhost kern.info kernel: [    0.283640] clocksource: Switched to clocksource timer
Jan  1 01:00:21 localhost kern.notice kernel: [    0.415773] VFS: Disk quotas dquot_6.6.0
Jan  1 01:00:21 localhost kern.info kernel: [    0.415936] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Jan  1 01:00:21 localhost kern.notice kernel: [    0.416286] FS-Cache: Loaded
Jan  1 01:00:21 localhost kern.info kernel: [    0.423850] CacheFiles: Loaded
Jan  1 01:00:21 localhost kern.info kernel: [    0.424274] simple-framebuffer 1eaa9000.framebuffer: framebuffer at 0x1eaa9000, 0x151800 bytes
Jan  1 01:00:21 localhost kern.info kernel: [    0.424324] simple-framebuffer 1eaa9000.framebuffer: format=a8r8g8b8, mode=720x480x32, linelength=2880
Jan  1 01:00:21 localhost kern.info kernel: [    0.425432] Console: switching to colour frame buffer device 90x30
Jan  1 01:00:21 localhost kern.info kernel: [    0.439809] simple-framebuffer 1eaa9000.framebuffer: fb0: simplefb registered!
Jan  1 01:00:21 localhost kern.info kernel: [    0.485573] NET: Registered PF_INET protocol family
Jan  1 01:00:21 localhost kern.info kernel: [    0.485930] IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.488497] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.488601] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.488696] TCP established hash table entries: 4096 (order: 2, 16384 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.488802] TCP bind hash table entries: 4096 (order: 2, 16384 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.488888] TCP: Hash tables configured (established 4096 bind 4096)
Jan  1 01:00:21 localhost kern.info kernel: [    0.489087] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.489152] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
Jan  1 01:00:21 localhost kern.info kernel: [    0.489730] NET: Registered PF_UNIX/PF_LOCAL protocol family
Jan  1 01:00:21 localhost kern.info kernel: [    0.497024] RPC: Registered named UNIX socket transport module.
Jan  1 01:00:21 localhost kern.info kernel: [    0.497072] RPC: Registered udp transport module.
Jan  1 01:00:21 localhost kern.info kernel: [    0.497091] RPC: Registered tcp transport module.
Jan  1 01:00:21 localhost kern.info kernel: [    0.497108] RPC: Registered tcp NFSv4.1 backchannel transport module.
Jan  1 01:00:21 localhost kern.warn kernel: [    0.499217] armv6-pmu arm-pmu: hw perfevents: no irqs for PMU, sampling events not supported
Jan  1 01:00:21 localhost kern.info kernel: [    0.499321] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available
Jan  1 01:00:21 localhost kern.notice kernel: [    2.209739] Initialise system trusted keyrings
Jan  1 01:00:21 localhost kern.info kernel: [    2.210418] workingset: timestamp_bits=14 max_order=17 bucket_order=3
Jan  1 01:00:21 localhost kern.info kernel: [    2.223805] zbud: loaded
Jan  1 01:00:21 localhost kern.notice kernel: [    2.230090] FS-Cache: Netfs 'nfs' registered for caching
Jan  1 01:00:21 localhost kern.notice kernel: [    2.231845] NFS: Registering the id_resolver key type
Jan  1 01:00:21 localhost kern.notice kernel: [    2.231943] Key type id_resolver registered
Jan  1 01:00:21 localhost kern.notice kernel: [    2.231968] Key type id_legacy registered
Jan  1 01:00:21 localhost kern.info kernel: [    2.232195] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Jan  1 01:00:21 localhost kern.info kernel: [    2.232231] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
Jan  1 01:00:21 localhost kern.notice kernel: [    2.237590] Key type asymmetric registered
Jan  1 01:00:21 localhost kern.notice kernel: [    2.237643] Asymmetric key parser 'x509' registered
Jan  1 01:00:21 localhost kern.info kernel: [    2.238000] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Jan  1 01:00:21 localhost kern.info kernel: [    2.238495] io scheduler mq-deadline registered
Jan  1 01:00:21 localhost kern.info kernel: [    2.238538] io scheduler kyber registered
Jan  1 01:00:21 localhost kern.info kernel: [    2.252907] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
Jan  1 01:00:21 localhost kern.info kernel: [    2.257029] bcm2835-rng 20104000.rng: hwrng registered
Jan  1 01:00:21 localhost kern.info kernel: [    2.257898] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
Jan  1 01:00:21 localhost kern.info kernel: [    2.260864] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000
Jan  1 01:00:21 localhost kern.info kernel: [    2.290728] brd: module loaded
Jan  1 01:00:21 localhost kern.info kernel: [    2.308831] loop: module loaded
Jan  1 01:00:21 localhost kern.info kernel: [    2.309334] Loading iSCSI transport class v2.0-870.
Jan  1 01:00:21 localhost kern.info kernel: [    2.316436] tun: Universal TUN/TAP device driver, 1.6
Jan  1 01:00:21 localhost kern.info kernel: [    2.317637] usbcore: registered new interface driver lan78xx
Jan  1 01:00:21 localhost kern.info kernel: [    2.317837] usbcore: registered new interface driver smsc95xx
Jan  1 01:00:21 localhost kern.info kernel: [    2.317889] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Jan  1 01:00:21 localhost kern.debug kernel: [    2.318220] dwc_otg: FIQ enabled
Jan  1 01:00:21 localhost kern.debug kernel: [    2.318248] dwc_otg: NAK holdoff enabled
Jan  1 01:00:21 localhost kern.debug kernel: [    2.318266] dwc_otg: FIQ split-transaction FSM enabled
Jan  1 01:00:21 localhost kern.debug kernel: [    2.318299] Module dwc_common_port init
Jan  1 01:00:21 localhost kern.info kernel: [    2.319249] usbcore: registered new interface driver uas
Jan  1 01:00:21 localhost kern.info kernel: [    2.319426] usbcore: registered new interface driver usb-storage
Jan  1 01:00:21 localhost kern.info kernel: [    2.320515] mousedev: PS/2 mouse device common for all mice
Jan  1 01:00:21 localhost kern.info kernel: [    2.326141] sdhci: Secure Digital Host Controller Interface driver
Jan  1 01:00:21 localhost kern.info kernel: [    2.326185] sdhci: Copyright(c) Pierre Ossman
Jan  1 01:00:21 localhost kern.err kernel: [    2.326908] Error: Driver 'sdhost-bcm2835' is already registered, aborting...
Jan  1 01:00:21 localhost kern.info kernel: [    2.326943] sdhci-pltfm: SDHCI platform and OF driver helper
Jan  1 01:00:21 localhost kern.info kernel: [    2.330783] ledtrig-cpu: registered to indicate activity on CPUs
Jan  1 01:00:21 localhost kern.info kernel: [    2.330989] hid: raw HID events driver (C) Jiri Kosina
Jan  1 01:00:21 localhost kern.info kernel: [    2.331284] usbcore: registered new interface driver usbhid
Jan  1 01:00:21 localhost kern.info kernel: [    2.331310] usbhid: USB HID core driver
Jan  1 01:00:21 localhost kern.info kernel: [    2.340805] NET: Registered PF_PACKET protocol family
Jan  1 01:00:21 localhost kern.notice kernel: [    2.341513] Key type dns_resolver registered
Jan  1 01:00:21 localhost kern.info kernel: [    2.344133] registered taskstats version 1
Jan  1 01:00:21 localhost kern.notice kernel: [    2.344214] Loading compiled-in X.509 certificates
Jan  1 01:00:21 localhost kern.notice kernel: [    2.356910] Loaded X.509 cert 'Build time autogenerated kernel key: f14c83c00f65485c774edff1f6c4923912e90307'
Jan  1 01:00:21 localhost kern.info kernel: [    2.380804] 20215040.serial: ttyS0 at MMIO 0x20215040 (irq = 53, base_baud = 31250000) is a 16550
Jan  1 01:00:21 localhost kern.info kernel: [    2.383049] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Jan  1 01:00:21 localhost kern.info kernel: [    2.384052] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Jan  1 01:00:21 localhost kern.info kernel: [    2.386467] mmc-bcm2835 20300000.mmcnr: mmc_debug:0 mmc_debug2:0
Jan  1 01:00:21 localhost kern.info kernel: [    2.386519] mmc-bcm2835 20300000.mmcnr: DMA channel allocated
Jan  1 01:00:21 localhost kern.info kernel: [    2.416083] sdhost: log_buf @ 54a143f0 (9a142000)
Jan  1 01:00:21 localhost kern.info kernel: [    2.475757] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Jan  1 01:00:21 localhost kern.info kernel: [    2.499716] of_cfs_init
Jan  1 01:00:21 localhost kern.info kernel: [    2.499825] of_cfs_init: OK
Jan  1 01:00:21 localhost kern.info kernel: [    2.507107] Waiting 5 sec before mounting root device...
Jan  1 01:00:21 localhost kern.warn kernel: [    2.569346] mmc0: host does not support reading read-only switch, assuming write-enable
Jan  1 01:00:21 localhost kern.info kernel: [    2.575890] mmc0: new high speed SDHC card at address aaaa
Jan  1 01:00:21 localhost kern.info kernel: [    2.577608] mmcblk0: mmc0:aaaa SD32G 29.7 GiB 
Jan  1 01:00:21 localhost kern.info kernel: [    2.583535]  mmcblk0: p1 p2 p3
Jan  1 01:00:21 localhost kern.info kernel: [    2.586800] mmcblk0: mmc0:aaaa SD32G 29.7 GiB (quirks 0x00004000)
Jan  1 01:00:21 localhost kern.info kernel: [    2.589233] mmc1: new high speed SDIO card at address 0001
Jan  1 01:00:21 localhost kern.info kernel: [    7.672103] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
Jan  1 01:00:21 localhost kern.info kernel: [    7.672310] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Jan  1 01:00:21 localhost kern.info kernel: [    7.673870] devtmpfs: mounted
Jan  1 01:00:21 localhost kern.info kernel: [    7.681140] Freeing unused kernel image (initmem) memory: 432K
Jan  1 01:00:21 localhost kern.warn kernel: [    7.681200] Kernel memory protection not selected by kernel config.
Jan  1 01:00:21 localhost kern.info kernel: [    7.681291] Run /sbin/init as init process
Jan  1 01:00:21 localhost kern.debug kernel: [    7.681313]   with arguments:
Jan  1 01:00:21 localhost kern.debug kernel: [    7.681327]     /sbin/init
Jan  1 01:00:21 localhost kern.debug kernel: [    7.681341]     empty
Jan  1 01:00:21 localhost kern.debug kernel: [    7.681354]   with environment:
Jan  1 01:00:21 localhost kern.debug kernel: [    7.681366]     HOME=/
Jan  1 01:00:21 localhost kern.debug kernel: [    7.681380]     TERM=linux
Jan  1 01:00:21 localhost kern.err kernel: [    9.085417] EXT4-fs (mmcblk0p3): Mount option "commit=30" incompatible with ext2
Jan  1 01:00:21 localhost kern.info kernel: [    9.105627] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: commit=30. Quota mode: none.
Jan  1 01:00:21 localhost kern.info kernel: [   11.218473] zram: Added device: zram0
Jan  1 01:00:21 localhost kern.info kernel: [   11.302765] zram0: detected capacity change from 0 to 990512
Jan  1 01:00:21 localhost kern.info kernel: [   11.373475] Adding 495252k swap on /dev/zram0.  Priority:75 extents:1 across:495252k SSFS
Jan  1 01:00:21 localhost kern.info kernel: [   11.606668] i2c_dev: i2c /dev entries driver
Jan  1 01:00:21 localhost daemon.info kernel: [   21.604050] udevd[921]: starting version 3.2.11
Jan  1 01:00:21 localhost daemon.info kernel: [   21.713683] udevd[922]: starting eudev-3.2.11
Jan  1 01:00:23 localhost kern.warn kernel: [   23.279234] generic_raw_uart: loading out-of-tree module taints kernel.
Jan  1 01:00:23 localhost kern.info kernel: [   23.528530] pl011_raw_uart 20201000.serial: there is not valid maps for state default
Jan  1 01:00:23 localhost kern.info kernel: [   23.528901] pl011_raw_uart 20201000.serial: Initialized pl011 device; mapbase=0x20201000; irq=81; clockrate=48000000
Jan  1 01:00:23 localhost kern.info kernel: [   23.528984] pl011_raw_uart 20201000.serial: Missing I2C driver of rtc device, trying to load
Jan  1 01:00:23 localhost kern.info kernel: [   23.529011] pl011_raw_uart 20201000.serial: Requesting module i2c:rx8130
Jan  1 01:00:23 localhost kern.warn kernel: [   23.659928] rtc-ds1307: probe of 1-0032 failed with error -121
Jan  1 01:00:23 localhost kern.warn kernel: [   23.666354] rtc-ds1307: probe of 1-0032 failed with error -121
Jan  1 01:00:23 localhost kern.info kernel: [   23.667249] raw-uart raw-uart: Reset radio module
Jan  1 01:00:23 localhost kern.info kernel: [   23.843830] raw-uart raw-uart: Registered new raw-uart device using underlying device GPIO@20201000.serial.
Jan  1 01:00:24 localhost kern.warn kernel: [   24.032251] dwc2 20980000.usb: supply vusb_d not found, using dummy regulator
Jan  1 01:00:24 localhost kern.warn kernel: [   24.032758] dwc2 20980000.usb: supply vusb_a not found, using dummy regulator
Jan  1 01:00:24 localhost kern.info kernel: [   24.248006] dwc2 20980000.usb: EPs: 8, dedicated fifos, 4080 entries in SPRAM
Jan  1 01:00:24 localhost kern.info kernel: [   24.249394] dwc2 20980000.usb: DWC OTG Controller
Jan  1 01:00:24 localhost kern.info kernel: [   24.249489] dwc2 20980000.usb: new USB bus registered, assigned bus number 1
Jan  1 01:00:24 localhost kern.info kernel: [   24.249590] dwc2 20980000.usb: irq 33, io mem 0x20980000
Jan  1 01:00:24 localhost kern.info kernel: [   24.250286] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
Jan  1 01:00:24 localhost kern.info kernel: [   24.250327] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jan  1 01:00:24 localhost kern.info kernel: [   24.250351] usb usb1: Product: DWC OTG Controller
Jan  1 01:00:24 localhost kern.info kernel: [   24.250370] usb usb1: Manufacturer: Linux 5.15.84 dwc2_hsotg
Jan  1 01:00:24 localhost kern.info kernel: [   24.250388] usb usb1: SerialNumber: 20980000.usb
Jan  1 01:00:24 localhost kern.info kernel: [   24.253566] hub 1-0:1.0: USB hub found
Jan  1 01:00:24 localhost kern.info kernel: [   24.256345] hub 1-0:1.0: 1 port detected
Jan  1 01:00:24 localhost kern.info kernel: [   24.563910] usb 1-1: new high-speed USB device number 2 using dwc2
Jan  1 01:00:24 localhost kern.info kernel: [   24.772512] usb 1-1: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.11
Jan  1 01:00:24 localhost kern.info kernel: [   24.772571] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Jan  1 01:00:24 localhost kern.info kernel: [   24.772596] usb 1-1: Product: USB 2.0 Hub
Jan  1 01:00:24 localhost kern.info kernel: [   24.773959] hub 1-1:1.0: USB hub found
Jan  1 01:00:24 localhost kern.info kernel: [   24.774154] hub 1-1:1.0: 4 ports detected
Jan  1 01:00:24 localhost kern.notice kernel: [   24.868579] cfg80211: Loading compiled-in X.509 certificates for regulatory database
Jan  1 01:00:25 localhost kern.notice kernel: [   25.155381] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Jan  1 01:00:25 localhost kern.info kernel: [   25.163842] usb 1-1.1: new high-speed USB device number 3 using dwc2
Jan  1 01:00:25 localhost kern.info kernel: [   25.378288] usb 1-1.1: New USB device found, idVendor=0b95, idProduct=772a, bcdDevice= 0.01
Jan  1 01:00:25 localhost kern.info kernel: [   25.378348] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jan  1 01:00:25 localhost kern.info kernel: [   25.378395] usb 1-1.1: Product: AX88x72A
Jan  1 01:00:25 localhost kern.info kernel: [   25.378417] usb 1-1.1: Manufacturer: ASIX Elec. Corp.
Jan  1 01:00:25 localhost kern.info kernel: [   25.378437] usb 1-1.1: SerialNumber: F001A9
Jan  1 01:00:25 localhost kern.debug kernel: [   25.603589] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
Jan  1 01:00:25 localhost kern.info kernel: [   25.614540] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Jan  1 01:00:25 localhost kern.warn kernel: [   25.616838] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.raspberrypi,model-zero-w.bin failed with error -2
Jan  1 01:00:25 localhost kern.info kernel: [   25.630464] usbcore: registered new interface driver brcmfmac
Jan  1 01:00:25 localhost kern.info kernel: [   25.876651] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Jan  1 01:00:25 localhost kern.info kernel: [   25.877006] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Jan  1 01:00:25 localhost kern.info kernel: [   25.889850] 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
Jan  1 01:00:26 localhost kern.info kernel: [   26.310435] Asix Electronics AX88772A usb-001:003:10: attached PHY driver (mii_bus:phy_addr=usb-001:003:10, irq=POLL)
Jan  1 01:00:26 localhost kern.info kernel: [   26.314551] asix 1-1.1:1.0 eth0: register 'asix' at usb-20980000.usb-1.1, ASIX AX88772 USB 2.0 Ethernet, 38:4b:70:f0:01:a9
Jan  1 01:00:26 localhost kern.info kernel: [   26.321261] usbcore: registered new interface driver asix
Jan  1 01:00:27 localhost user.notice rfkill: block set for type bluetooth
Jan  1 01:00:27 localhost kern.info kernel: [   27.372271] asix 1-1.1:1.0 eth0: Link is Down
Jan  1 01:00:30 localhost kern.info kernel: [   30.544852] asix 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off
Jan  1 01:00:31 localhost syslog.info syslogd exiting
Jan  1 01:00:31 RaspMatic syslog.info syslogd started: BusyBox v1.35.0
Jan  1 01:00:31 RaspMatic kern.notice kernel: klogd started: BusyBox v1.35.0 (2023-01-14 07:40:59 UTC)
Jan  1 01:00:32 RaspMatic kern.info kernel: [   32.608362] NET: Registered PF_INET6 protocol family
Jan  1 01:00:32 RaspMatic kern.info kernel: [   32.615417] Segment Routing with IPv6
Jan  1 01:00:32 RaspMatic kern.info kernel: [   32.615554] In-situ OAM (IOAM) with IPv6
Jan  1 01:00:33 RaspMatic kern.info kernel: [   33.767916] cam-dummy-reg: disabling
Jan  1 01:00:33 RaspMatic user.info firewall: configuration set
Jan  1 01:00:34 RaspMatic user.notice rfkill: block set for type wlan
Jan  1 01:00:34 RaspMatic daemon.info ifplugd(wlan0)[1114]: ifplugd 0.28 initializing.
Jan  1 01:00:34 RaspMatic daemon.info ifplugd(wlan0)[1114]: Using interface wlan0/B8:27:EB:D9:F4:99 with driver <brcmfmac> (version: 7.45.98)
Jan  1 01:00:34 RaspMatic daemon.info ifplugd(wlan0)[1114]: Using detection mode: wireless extension
Jan  1 01:00:34 RaspMatic daemon.info ifplugd(wlan0)[1114]: Initialization complete, link beat not detected.
Jan  1 01:00:34 RaspMatic daemon.info ifplugd(eth0)[1118]: ifplugd 0.28 initializing.
Jan  1 01:00:34 RaspMatic daemon.info ifplugd(eth0)[1118]: Using interface eth0/38:4B:70:F0:01:A9 with driver <asix> (version: 22-Dec-2011)
Jan  1 01:00:34 RaspMatic daemon.info ifplugd(eth0)[1118]: Using detection mode: SIOCETHTOOL
Jan  1 01:00:34 RaspMatic daemon.info ifplugd(eth0)[1118]: Initialization complete, link beat detected.
Jan  1 01:00:34 RaspMatic daemon.info ifplugd(eth0)[1118]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Jan  1 01:00:34 RaspMatic daemon.warn ifplugd(eth0)[1118]: client: ifup: interface eth0 already configured
Jan  1 01:00:34 RaspMatic daemon.info ifplugd(eth0)[1118]: Program executed successfully.
Feb  1 01:37:36 RaspMatic daemon.notice ntpdate[1124]: step time server 131.188.3.220 offset +1675211811.577457 sec
Feb  1 01:37:36 RaspMatic daemon.info chronyd[1127]: chronyd version 4.3 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH +IPV6 -DEBUG)
Feb  1 01:37:36 RaspMatic daemon.warn chronyd[1127]: Running with root privileges
Feb  1 01:37:37 RaspMatic kern.info kernel: [   45.449723] raw-uart raw-uart: Reset radio module
Feb  1 01:37:47 RaspMatic daemon.err xinetd[1218]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Feb  1 01:37:47 RaspMatic daemon.crit xinetd[1218]: 1218 {init_services} no services. Exiting...
Feb  1 01:37:48 RaspMatic auth.info sshd[1255]: Server listening on 0.0.0.0 port 22.
Feb  1 01:37:48 RaspMatic auth.info sshd[1255]: Server listening on :: port 22.
Feb  1 01:37:48 RaspMatic user.info root: Updating RF Lan Gateway Coprocessor Firmware
Feb  1 01:37:49 RaspMatic user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Feb  1 01:37:49 RaspMatic user.info root: Updating RF Lan Gateway Firmware
Feb  1 01:37:49 RaspMatic user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Feb  1 01:37:49 RaspMatic kern.info kernel: [   58.114489] eq3loop: created slave mmd_hmip
Feb  1 01:37:49 RaspMatic kern.info kernel: [   58.118321] eq3loop: created slave mmd_bidcos
Feb  1 01:37:51 RaspMatic kern.info kernel: [   60.219530] eq3loop: eq3loop_open_slave() mmd_bidcos
Feb  1 01:37:51 RaspMatic kern.info kernel: [   60.221131] eq3loop: eq3loop_close_slave() mmd_bidcos
Feb  1 01:37:51 RaspMatic kern.info kernel: [   60.242519] eq3loop: eq3loop_open_slave() mmd_hmip
Feb  1 01:37:51 RaspMatic kern.info kernel: [   60.242921] eq3loop: eq3loop_close_slave() mmd_hmip
Feb  1 01:37:52 RaspMatic kern.info kernel: [   60.736228] eq3loop: eq3loop_open_slave() mmd_bidcos
Feb  1 01:38:43 RaspMatic daemon.info chronyd[1127]: Selected source 129.70.132.37 (3.de.pool.ntp.org)
Feb  1 01:38:46 RaspMatic daemon.info chronyd[1127]: Source 185.120.22.12 replaced with 85.214.96.5 (0.de.pool.ntp.org)
Feb  1 01:39:33 RaspMatic kern.info kernel: [  162.044168] eq3loop: eq3loop_open_slave() mmd_hmip
Feb  1 01:39:33 RaspMatic kern.info kernel: [  162.049555] eq3loop: eq3loop_close_slave() mmd_hmip
Feb  1 01:39:33 RaspMatic kern.info kernel: [  162.081616] eq3loop: eq3loop_open_slave() mmd_hmip
Feb  1 01:39:33 RaspMatic kern.info kernel: [  162.082457] eq3loop: eq3loop_close_slave() mmd_hmip
Feb  1 01:39:33 RaspMatic kern.info kernel: [  162.091206] eq3loop: eq3loop_open_slave() mmd_hmip
Feb  1 01:39:33 RaspMatic kern.info kernel: [  162.097315] eq3loop: eq3loop_close_slave() mmd_hmip
Feb  1 01:39:33 RaspMatic kern.info kernel: [  162.259607] eq3loop: eq3loop_open_slave() mmd_hmip
Feb  1 00:40:22 RaspMatic daemon.info : starting pid 1643, tty '': '/bin/mv /tmp/boot.log /var/log/boot.log'
Feb  1 00:40:22 RaspMatic daemon.info : starting pid 1644, tty '/dev/null': '/usr/bin/monit -Ic /etc/monitrc'
Feb  1 01:40:22 RaspMatic user.info monit[1644]: Starting Monit 5.30.0 daemon with http interface at /var/run/monit.sock
Feb  1 01:40:22 RaspMatic user.info monit[1644]: 'RaspMatic' Monit 5.30.0 started
Feb  1 01:40:33 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:0","DUTY_CYCLE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:33 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:33 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:0","LOW_BAT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:33 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:33 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:0","OPERATING_VOLTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:33 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:33 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:0","OPERATING_VOLTAGE_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:33 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:33 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:0","RSSI_DEVICE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:33 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:0","RSSI_PEER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:0","UNREACH"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","ACTIVE_PROFILE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","ACTUAL_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","ACTUAL_TEMPERATURE_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","BOOST_TIME"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","FROST_PROTECTION"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:34 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:35 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","LEVEL"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:35 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:35 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","LEVEL_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:35 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:35 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","PARTY_MODE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:35 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","PARTY_SET_POINT_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","PARTY_TIME_END"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","PARTY_TIME_START"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","QUICK_VETO_TIME"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","SET_POINT_MODE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","SET_POINT_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","SWITCH_POINT_OCCURED"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:36 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:37 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","VALVE_ADAPTION"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:37 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:37 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","VALVE_STATE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:37 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:37 RaspMatic local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00201D898CEC37:1","WINDOW_STATE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb  1 01:40:37 RaspMatic local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb  1 01:40:40 RaspMatic user.err monit[1644]: 'irqbalanceEnabled' status failed (1) -- no output
Feb  1 01:40:44 RaspMatic user.err monit[1644]: 'hs485dEnabled' status failed (1) -- no output
Feb  1 01:40:44 RaspMatic user.err monit[1644]: 'hmlangwEnabled' status failed (1) -- no output
Feb  1 01:40:44 RaspMatic user.warn monit[1644]: 'hasUSB' status failed (1) -- no output
Feb  1 01:40:44 RaspMatic user.err monit[1644]: 'internetCheckEnabled' status failed (0) -- no output
Feb  1 01:40:44 RaspMatic user.err monit[1644]: 'uncleanShutdownCheck' status failed (0) -- no output
Feb  1 01:40:44 RaspMatic user.info monit[1644]: 'uncleanShutdownCheck' exec: '/bin/sh -c /bin/triggerAlarm.tcl 'Unclean shutdown or system crash identified' 'WatchDog: unclean-shutdown' true ; rm -f /var/status/uncleanShutdown'
Feb  1 01:40:44 RaspMatic user.err monit[1644]: 'hb_rf_eth-CheckEnabled' status failed (1) -- no output
Feb  1 01:40:44 RaspMatic user.err monit[1644]: 'coProcessorCheck' status failed (1) -- no output
Feb  1 01:40:44 RaspMatic user.warn monit[1644]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Feb  1 01:40:44 RaspMatic user.warn monit[1644]: 'rpi4usb3Check' status failed (1) -- no output
Feb  1 01:40:44 RaspMatic user.err monit[1644]: 'tailscaleEnabled' status failed (1) -- no output
Feb  1 01:40:45 RaspMatic user.err monit[1644]: Lookup for '/media/usb1' filesystem failed  -- not found in /proc/self/mounts
Feb  1 01:40:45 RaspMatic user.err monit[1644]: Filesystem '/media/usb1' not mounted
Feb  1 01:40:45 RaspMatic user.err monit[1644]: 'usb1' unable to read filesystem '/media/usb1' state
Feb  1 01:40:45 RaspMatic user.info monit[1644]: 'usb1' trying to restart
Feb  1 01:41:03 RaspMatic user.warn monit[1644]: 'hasUSB' status failed (1) -- no output
Feb  1 01:41:03 RaspMatic user.err monit[1644]: 'wlan0CheckEnabled' status failed (1) -- no output
Feb  1 01:41:03 RaspMatic user.err monit[1644]: 'uncleanShutdownCheck' status failed (0) -- no output
Feb  1 01:41:03 RaspMatic user.warn monit[1644]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Feb  1 01:41:03 RaspMatic user.warn monit[1644]: 'rpi4usb3Check' status failed (1) -- no output
Feb  1 01:41:04 RaspMatic user.err monit[1644]: Filesystem '/media/usb1' not mounted
Feb  1 01:41:04 RaspMatic user.err monit[1644]: 'usb1' unable to read filesystem '/media/usb1' state
Feb  1 01:41:04 RaspMatic user.info monit[1644]: 'usb1' trying to restart
Feb  1 01:41:22 RaspMatic user.warn monit[1644]: 'hasUSB' status failed (1) -- no output
Feb  1 01:41:22 RaspMatic user.info monit[1644]: 'uncleanShutdownCheck' status succeeded (1) -- no output
Feb  1 01:41:22 RaspMatic user.err monit[1644]: 'eMMCLifeTimeCheck' status failed (1) -- no output
Feb  1 01:41:22 RaspMatic user.err monit[1644]: 'rpi4usb3Check' status failed (1) -- no output
Feb  1 01:41:22 RaspMatic user.err monit[1644]: Filesystem '/media/usb1' not mounted
Feb  1 01:41:22 RaspMatic user.err monit[1644]: 'usb1' unable to read filesystem '/media/usb1' state
Feb  1 01:41:22 RaspMatic user.info monit[1644]: 'usb1' trying to restart
Feb  1 01:41:40 RaspMatic user.warn monit[1644]: 'hasUSB' status failed (1) -- no output
Feb  1 01:41:41 RaspMatic user.err monit[1644]: Filesystem '/media/usb1' not mounted
Feb  1 01:41:41 RaspMatic user.err monit[1644]: 'usb1' unable to read filesystem '/media/usb1' state
Feb  1 01:41:41 RaspMatic user.info monit[1644]: 'usb1' trying to restart
Feb  1 01:41:59 RaspMatic user.err monit[1644]: 'hasUSB' status failed (1) -- no output
Feb  1 07:05:13 RaspMatic user.info monit[27797]: Reinitializing monit daemon
Feb  1 07:05:14 RaspMatic user.info monit[1644]: Reinitializing Monit -- control file '/etc/monitrc'
Feb  1 07:05:15 RaspMatic user.info monit[1644]: 'RaspMatic' Monit reloaded
Feb  1 07:05:54 RaspMatic auth.info sshd[27858]: Unable to negotiate with 192.168.0.79 port 64325: no matching host key type found. Their offer: ssh-rsa,ssh-dss [preauth]

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

Additional information

See last log entry: I cannot connect via Putty from my laptop, it says: no matching host key type found. How can this connection be achieved?

jp112sdl commented 1 year ago

I cannot connect via Putty from my laptop, it says: no matching host key type found. How can this connection be achieved?

Did you try some workarounds? Google for no matching host key type found. Their offer: ssh-rsa,ssh-dss [preauth] will give you a lot of hints

schup011 commented 1 year ago

Thanks, I succeeded previously with connecting via PUTTY, so I thought there could have been a change in RaspberryMatic and that might be a known issue. Anyway, SSH connection is not the problem here, I usually do not need to connect to the Raspberry, that would be only important if needed for debugging the described issue (unclean shutdown).

schup011 commented 1 year ago

I have set up a syslog-ng server to get a log previous to the crash. Please give a hint on other measures I can do for debugging this. Thanks!

schup011 commented 1 year ago

OK, it took a little longer to reproduce, but it did:

Here are the last lines before the crash. Can anybody detect something strange or unusual?

Feb  5 20:33:20 192.168.0.148 20230205T213320,310 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718956:4: key:PARTY_START_MONTH = 1
Feb  5 20:33:20 192.168.0.148 20230205T213320,315 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718956:4: key:PARTY_START_YEAR = 0
Feb  5 20:33:20 192.168.0.148 20230205T213320,317 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718956:4: key:PARTY_STOP_TIME = 0
Feb  5 20:33:20 192.168.0.148 20230205T213320,318 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718956:4: key:PARTY_STOP_DAY = 1
Feb  5 20:33:20 192.168.0.148 20230205T213320,324 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718956:4: key:PARTY_STOP_MONTH = 1
Feb  5 20:33:20 192.168.0.148 20230205T213320,325 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718956:4: key:PARTY_STOP_YEAR = 0
Feb  5 20:33:20 192.168.0.148 20230205T213320,332 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Send Response for : system.multicall
Feb  5 20:33:20 192.168.0.148 20230205T213320,349 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@1de07f5
Feb  5 20:33:24 192.168.0.148 20230205T213324,256 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@1d0115c
Feb  5 20:33:24 192.168.0.148 20230205T213324,282 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Incoming Request: system.multicall
Feb  5 20:33:24 192.168.0.148 20230205T213324,287 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:CONTROL_MODE = 1
Feb  5 20:33:24 192.168.0.148 20230205T213324,290 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Send Response for : system.multicall
Feb  5 20:33:24 192.168.0.148 20230205T213324,312 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@1d0115c
Feb  5 20:33:24 192.168.0.148 20230205T213324,321 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@33fee1
Feb  5 20:33:24 192.168.0.148 20230205T213324,344 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Incoming Request: system.multicall
Feb  5 20:33:24 192.168.0.148 20230205T213324,345 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:FAULT_REPORTING = 0
Feb  5 20:33:24 192.168.0.148 20230205T213324,347 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:BATTERY_STATE = 3.0
Feb  5 20:33:24 192.168.0.148 20230205T213324,348 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:VALVE_STATE = 0
Feb  5 20:33:24 192.168.0.148 20230205T213324,350 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:BOOST_STATE = 0
Feb  5 20:33:24 192.168.0.148 20230205T213324,351 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:ACTUAL_TEMPERATURE = 28.9
Feb  5 20:33:24 192.168.0.148 20230205T213324,353 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:SET_TEMPERATURE = 19.0
Feb  5 20:33:24 192.168.0.148 20230205T213324,354 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:PARTY_TEMPERATURE = 5.0
Feb  5 20:33:24 192.168.0.148 20230205T213324,356 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:PARTY_START_TIME = 0
Feb  5 20:33:24 192.168.0.148 20230205T213324,357 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:PARTY_START_DAY = 1
Feb  5 20:33:24 192.168.0.148 20230205T213324,359 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:PARTY_START_MONTH = 1
Feb  5 20:33:24 192.168.0.148 20230205T213324,360 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:PARTY_START_YEAR = 0
Feb  5 20:33:24 192.168.0.148 20230205T213324,361 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:PARTY_STOP_TIME = 0
Feb  5 20:33:24 192.168.0.148 20230205T213324,363 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:PARTY_STOP_DAY = 1
Feb  5 20:33:24 192.168.0.148 20230205T213324,364 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:PARTY_STOP_MONTH = 1
Feb  5 20:33:24 192.168.0.148 20230205T213324,365 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device REQ1162579:4: key:PARTY_STOP_YEAR = 0
Feb  5 20:33:24 192.168.0.148 20230205T213324,369 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Send Response for : system.multicall
Feb  5 20:33:24 192.168.0.148 20230205T213324,378 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@33fee1
Feb  5 20:33:45 192.168.0.148 20230205T213345,859 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@e56ff7
Feb  5 20:33:45 192.168.0.148 20230205T213345,869 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.group Incoming Request: system.listMethods
Feb  5 20:33:45 192.168.0.148 20230205T213345,870 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega 1426 DEBUG [vert.x-eventloop-thread-0] system.listMethods
Feb  5 20:33:45 192.168.0.148 20230205T213345,873 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.group Send Response for : system.listMethods
Feb  5 20:33:45 192.168.0.148 20230205T213345,886 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@e56ff7
Feb  5 20:33:54 192.168.0.148 20230205T213354,822 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@18b00dc
Feb  5 20:33:54 192.168.0.148 20230205T213354,897 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Incoming Request: system.multicall
Feb  5 20:33:54 192.168.0.148 20230205T213354,899 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:CONTROL_MODE = 1
Feb  5 20:33:54 192.168.0.148 20230205T213354,914 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Send Response for : system.multicall
Feb  5 20:33:54 192.168.0.148 20230205T213354,956 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@18b00dc
Feb  5 20:33:54 192.168.0.148 20230205T213354,973 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@1d5c79b
Feb  5 20:33:55 192.168.0.148 20230205T213355,021 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Incoming Request: system.multicall
Feb  5 20:33:55 192.168.0.148 20230205T213355,023 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:FAULT_REPORTING = 0
Feb  5 20:33:55 192.168.0.148 20230205T213355,029 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:BATTERY_STATE = 2.3
Feb  5 20:33:55 192.168.0.148 20230205T213355,030 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:VALVE_STATE = 0
Feb  5 20:33:55 192.168.0.148 20230205T213355,036 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:BOOST_STATE = 0
Feb  5 20:33:55 192.168.0.148 20230205T213355,038 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:ACTUAL_TEMPERATURE = 23.2
Feb  5 20:33:55 192.168.0.148 20230205T213355,044 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:SET_TEMPERATURE = 20.0
Feb  5 20:33:55 192.168.0.148 20230205T213355,045 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:PARTY_TEMPERATURE = 5.0
Feb  5 20:33:55 192.168.0.148 20230205T213355,046 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:PARTY_START_TIME = 0
Feb  5 20:33:55 192.168.0.148 20230205T213355,052 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:PARTY_START_DAY = 1
Feb  5 20:33:55 192.168.0.148 20230205T213355,054 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:PARTY_START_MONTH = 1
Feb  5 20:33:55 192.168.0.148 20230205T213355,059 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:PARTY_START_YEAR = 0
Feb  5 20:33:55 192.168.0.148 20230205T213355,061 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:PARTY_STOP_TIME = 0
Feb  5 20:33:55 192.168.0.148 20230205T213355,062 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:PARTY_STOP_DAY = 1
Feb  5 20:33:55 192.168.0.148 20230205T213355,068 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:PARTY_STOP_MONTH = 1
Feb  5 20:33:55 192.168.0.148 20230205T213355,069 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1718946:4: key:PARTY_STOP_YEAR = 0
Feb  5 20:33:55 192.168.0.148 20230205T213355,076 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Send Response for : system.multicall
Feb  5 20:33:55 192.168.0.148 20230205T213355,094 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@1d5c79b
Feb  5 20:33:56 192.168.0.148 20230205T213356,030 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@13497a4
Feb  5 20:33:56 192.168.0.148 20230205T213356,058 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Incoming Request: system.multicall
Feb  5 20:33:56 192.168.0.148 20230205T213356,060 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:CONTROL_MODE = 1
Feb  5 20:33:56 192.168.0.148 20230205T213356,063 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Send Response for : system.multicall
Feb  5 20:33:56 192.168.0.148 20230205T213356,097 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@13497a4
Feb  5 20:33:56 192.168.0.148 20230205T213356,106 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@196aef6
Feb  5 20:33:56 192.168.0.148 20230205T213356,130 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Incoming Request: system.multicall
Feb  5 20:33:56 192.168.0.148 20230205T213356,131 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:FAULT_REPORTING = 0
Feb  5 20:33:56 192.168.0.148 20230205T213356,133 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:BATTERY_STATE = 2.5
Feb  5 20:33:56 192.168.0.148 20230205T213356,134 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:VALVE_STATE = 0
Feb  5 20:33:56 192.168.0.148 20230205T213356,136 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:BOOST_STATE = 0
Feb  5 20:33:56 192.168.0.148 20230205T213356,137 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:ACTUAL_TEMPERATURE = 22.0
Feb  5 20:33:56 192.168.0.148 20230205T213356,139 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:SET_TEMPERATURE = 20.0
Feb  5 20:33:56 192.168.0.148 20230205T213356,141 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:PARTY_TEMPERATURE = 5.0
Feb  5 20:33:56 192.168.0.148 20230205T213356,143 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:PARTY_START_TIME = 0
Feb  5 20:33:56 192.168.0.148 20230205T213356,144 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:PARTY_START_DAY = 1
Feb  5 20:33:56 192.168.0.148 20230205T213356,151 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:PARTY_START_MONTH = 1
Feb  5 20:33:56 192.168.0.148 20230205T213356,153 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:PARTY_START_YEAR = 0
Feb  5 20:33:56 192.168.0.148 20230205T213356,154 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:PARTY_STOP_TIME = 0
Feb  5 20:33:56 192.168.0.148 20230205T213356,160 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:PARTY_STOP_DAY = 1
Feb  5 20:33:56 192.168.0.148 20230205T213356,161 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:PARTY_STOP_MONTH = 1
Feb  5 20:33:56 192.168.0.148 20230205T213356,162 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707078:4: key:PARTY_STOP_YEAR = 0
Feb  5 20:33:56 192.168.0.148 20230205T213356,167 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Send Response for : system.multicall
Feb  5 20:33:56 192.168.0.148 20230205T213356,177 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@196aef6
Feb  5 20:33:58 192.168.0.148 20230205T213358,832 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@1e3bee1
Feb  5 20:33:58 192.168.0.148 20230205T213358,873 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Incoming Request: system.multicall
Feb  5 20:33:58 192.168.0.148 20230205T213358,874 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:CONTROL_MODE = 1
Feb  5 20:33:58 192.168.0.148 20230205T213358,876 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:FAULT_REPORTING = 0
Feb  5 20:33:58 192.168.0.148 20230205T213358,878 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:BATTERY_STATE = 2.9
Feb  5 20:33:58 192.168.0.148 20230205T213358,879 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:VALVE_STATE = 81
Feb  5 20:33:58 192.168.0.148 20230205T213358,880 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:BOOST_STATE = 0
Feb  5 20:33:58 192.168.0.148 20230205T213358,882 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:ACTUAL_TEMPERATURE = 23.5
Feb  5 20:33:58 192.168.0.148 20230205T213358,884 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:SET_TEMPERATURE = 22.0
Feb  5 20:33:58 192.168.0.148 20230205T213358,885 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:PARTY_TEMPERATURE = 5.0
Feb  5 20:33:58 192.168.0.148 20230205T213358,887 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:PARTY_START_TIME = 0
Feb  5 20:33:58 192.168.0.148 20230205T213358,889 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:PARTY_START_DAY = 1
Feb  5 20:33:58 192.168.0.148 20230205T213358,890 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:PARTY_START_MONTH = 1
Feb  5 20:33:58 192.168.0.148 20230205T213358,891 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:PARTY_START_YEAR = 0
Feb  5 20:33:58 192.168.0.148 20230205T213358,893 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:PARTY_STOP_TIME = 0
Feb  5 20:33:58 192.168.0.148 20230205T213358,894 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:PARTY_STOP_DAY = 1
Feb  5 20:33:58 192.168.0.148 20230205T213358,896 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:PARTY_STOP_MONTH = 1
Feb  5 20:33:58 192.168.0.148 20230205T213358,897 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1707102:4: key:PARTY_STOP_YEAR = 0
Feb  5 20:33:58 192.168.0.148 20230205T213358,904 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Send Response for : system.multicall
Feb  5 20:33:58 192.168.0.148 20230205T213358,913 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@1e3bee1
Feb  5 20:34:02 192.168.0.148 20230205T213402,812 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@1681422
Feb  5 20:34:02 192.168.0.148 20230205T213402,856 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Incoming Request: system.multicall
Feb  5 20:34:02 192.168.0.148 20230205T213402,858 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:CONTROL_MODE = 1
Feb  5 20:34:02 192.168.0.148 20230205T213402,873 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Send Response for : system.multicall
Feb  5 20:34:02 192.168.0.148 20230205T213402,933 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@1681422
Feb  5 20:34:02 192.168.0.148 20230205T213402,950 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@18f928
Feb  5 20:34:03 192.168.0.148 20230205T213403,013 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Incoming Request: system.multicall
Feb  5 20:34:03 192.168.0.148 20230205T213403,027 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:FAULT_REPORTING = 0
Feb  5 20:34:03 192.168.0.148 20230205T213403,029 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:BATTERY_STATE = 3.0
Feb  5 20:34:03 192.168.0.148 20230205T213403,030 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:VALVE_STATE = 32
Feb  5 20:34:03 192.168.0.148 20230205T213403,044 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:BOOST_STATE = 0
Feb  5 20:34:03 192.168.0.148 20230205T213403,045 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:ACTUAL_TEMPERATURE = 23.7
Feb  5 20:34:03 192.168.0.148 20230205T213403,059 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:SET_TEMPERATURE = 21.0
Feb  5 20:34:03 192.168.0.148 20230205T213403,061 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:PARTY_TEMPERATURE = 5.0
Feb  5 20:34:03 192.168.0.148 20230205T213403,062 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:PARTY_START_TIME = 0
Feb  5 20:34:03 192.168.0.148 20230205T213403,079 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:PARTY_START_DAY = 1
Feb  5 20:34:03 192.168.0.148 20230205T213403,081 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:PARTY_START_MONTH = 1
Feb  5 20:34:03 192.168.0.148 20230205T213403,082 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:PARTY_START_YEAR = 0
Feb  5 20:34:03 192.168.0.148 20230205T213403,096 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:PARTY_STOP_TIME = 0
Feb  5 20:34:03 192.168.0.148 20230205T213403,097 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:PARTY_STOP_DAY = 1
Feb  5 20:34:03 192.168.0.148 20230205T213403,099 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:PARTY_STOP_MONTH = 1
Feb  5 20:34:03 192.168.0.148 20230205T213403,117 de.eq3.ccu.bidcos.dispatcher.BidCosRpcDispatcher 1426 DEBUG [vert.x-eventloop-thread-0] event interface: BidCos-RF_java device OEQ1706693:4: key:PARTY_STOP_YEAR = 0
Feb  5 20:34:03 192.168.0.148 20230205T213403,132 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.bidcos Send Response for : system.multicall
Feb  5 20:34:03 192.168.0.148 20230205T213403,155 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@18f928
Feb  5 20:34:18 192.168.0.148 20230205T213418,500 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@aa936e
Feb  5 20:34:18 192.168.0.148 20230205T213418,510 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.group Incoming Request: system.listMethods
Feb  5 20:34:18 192.168.0.148 20230205T213418,511 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega 1426 DEBUG [vert.x-eventloop-thread-0] system.listMethods
Feb  5 20:34:18 192.168.0.148 20230205T213418,514 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.group Send Response for : system.listMethods
Feb  5 20:34:18 192.168.0.148 20230205T213418,526 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@aa936e
Feb  5 20:34:50 192.168.0.148 20230205T213450,275 de.eq3.ccu.server.internal.BasicAPIHttpVertxHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@11a97f7
Feb  5 20:34:50 192.168.0.148 20230205T213450,285 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.group Incoming Request: system.listMethods
Feb  5 20:34:50 192.168.0.148 20230205T213450,286 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega 1426 DEBUG [vert.x-eventloop-thread-0] system.listMethods
Feb  5 20:34:50 192.168.0.148 20230205T213450,289 de.eq3.ccu.server.internal.RpcMessageHandler 1426 DEBUG [vert.x-eventloop-thread-0] rpc.api.group Send Response for : system.listMethods
Feb  5 20:34:50 192.168.0.148 20230205T213450,303 de.eq3.ccu.server.internal.BasicAPIHttpResponseHandler 1426 DEBUG [vert.x-eventloop-thread-0] io.vertx.ext.web.impl.HttpServerRequestWrapper@11a97f7
schup011 commented 1 year ago

Any hints how this could be tracked down? I cannot see any errors in the log. Just looks like being interrupted. I would be happy to receive some tipps what could be done.

stale[bot] commented 1 year ago

Thanks for your contribution!
This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs within the next 7 days. Please check if the issue is still relevant in the most current version of RaspberryMatic and tell us. Also check that all relevant details,

Vielen Dank für die Unterstützung!
Dieses Problem wurde automatisch als veraltet markiert, da es in letzter Zeit keine Aktivitäten gab. Es wird geschlossen, wenn nicht innerhalb der nächsten 7 Tage weitere Aktivitäten stattfinden. Bitte überprüfen Sie, ob das Problem auch in der aktuellsten Version von RaspberryMatic noch relevant ist, und teilen Sie uns dies mit. Überprüfen Sie auch, ob alle relevanten Details, Logs und Reproduktionsschritte enthalten sind oder aktualisiert werden müssen.

schup011 commented 1 year ago

I was not able to find any reasons for a sudden shutdown or restart. But was seems to be an issue is the AX88772 device I am using to get the Raspberry Pi Zero connected to LAN, instead of the WiFi. I have disconnected the AX88772 adapter and am using the WiFi, and now it seems to behave normally.

Even if this is a solution for me, I would encourage the developers to investigate LAN solutions for the Raspberry Pi Zero platform, as this is a very welcome lightweight option and fully sufficient for building up a simple interface to all the Hm and HmIP devices.

So, I close this issue hoping that my comment and proposal is acknowledged.

schup011 commented 1 year ago

As commented above.