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.56k stars 192 forks source link

Regelmäßige Status Freezes #809

Closed Jensilein closed 4 years ago

Jensilein commented 4 years ago

Hallo, ich habe seit ein paar Tagen massive Probleme mit meiner Raspberrymatic (3.51.6.20200229). Nach einer gewissen Zeit „frieren“ die Stati meiner Fenster- und Türöffnungskontakte ein. Ich bin langsam am Verzweifeln und kann mir da keinen Reim drauf machen. Ich habe diverse Hoppe-Enocean Fenstergriffe, die über Cuxd angebunden sind sowie eine handvoll HM-IP Fenstergriffe HM-Sec-RHS. Ich habe am Setup nichts verändert, trotzdem tritt das Phänomen immer wieder auf. Zunächst scheint alles stabil zu laufen, aber nach einiger Zeit (gefühlt so ca. 2 Tage) kommt der freeze. Duty Cycle ist völlig unauffällig. Update: Heute wieder das selbe Problem. Gestern die RM noch einmal komplett neu aufgesetzt, Backup eingespielt und bis heute Nachmittag lief alles stabil. Vorhin dann wieder die ersten Meldungen „Gerätekommunikation gestört“. Wenn ich einen Verbindungstest bei den Geräten mache, kommen folgende Fehlermeldungen:

Apr 6 14:10:59 homematic-raspi local0.err ReGaHss: ERROR: ScriptRuntimeError: string channels = "";string timestamps = ""; [ExecError():iseESPexec.cpp:12798] Apr 6 14:11:02 homematic-raspi local0.err ReGaHss: ERROR: ScriptRuntimeError: string channels = "";string timestamps = ""; [ExecError():iseESPexec.cpp:12798] Apr 6 14:11:05 homematic-raspi local0.err ReGaHss: ERROR: ScriptRuntimeError: string channels = "";string timestamps = ""; [ExecError():iseESPexec.cpp:12798] Apr 6 14:11:09 homematic-raspi local0.err ReGaHss: ERROR: ScriptRuntimeError: string channels = "";string timestamps = ""; [ExecError():iseESPexec.cpp:12798] Apr 6 14:11:11 homematic-raspi local0.err ReGaHss: ERROR: ScriptRuntimeError: string channels = "";string timestamps = ""; [ExecError():iseESPexec.cpp:12798] Apr 6 14:11:14 homematic-raspi local0.err ReGaHss: ERROR: no event dp exists for chn id = 10808 [StartCommunicationTest():iseManageChannel.cpp:1341] Apr 6 14:11:14 homematic-raspi local0.err ReGaHss: ERROR: ScriptRuntimeError: string channels = "";string timestamps = ""; [ExecError():iseESPexec.cpp:12798] Apr 6 14:11:17 homematic-raspi local0.err ReGaHss: ERROR: ScriptRuntimeError: string channels = "";string timestamps = ""; [ExecError():iseESPexec.cpp:12798] Apr 6 14:11:20 homematic-raspi local0.err ReGaHss: ERROR: ScriptRuntimeError: string channels = "";string timestamps = ""; [ExecError():iseESPexec.cpp:12798] Apr 6 14:11:23 homematic-raspi local0.err ReGaHss: ERROR: ScriptRuntimeError: string channels = "";string timestamps = ""; [ExecError():iseESPexec.cpp:12798] Apr 6 14:11:26 homematic-raspi local0.err ReGaHss: ERROR: ScriptRuntimeError: string channels = "";string timestamps = ""; [ExecError():iseESPexec.cpp:12798]

Anbei der Vollständigkeit halber mal auch noch die Ausgabe von dmesg:

root@homematic-raspi:~# dmesg

[ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.19.105 (damato@bilbo) (gcc version 8.3.0 (Buildroot 2019.11.1-02333-gcc1bc83d)) #1 SMP PREEMPT Fri Feb 28 20:56:53 CET 2020 [ 0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d [ 0.000000] CPU: div instructions available: patching division code [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache [ 0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] cma: Reserved 8 MiB at 0x3d800000 [ 0.000000] On node 0 totalpages: 253952 [ 0.000000] Normal zone: 2232 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 253952 pages, LIFO batch:63 [ 0.000000] random: get_random_bytes called from start_kernel+0xb0/0x4c0 with crng_init=0 [ 0.000000] percpu: Embedded 17 pages/cpu s37004 r8192 d24436 u69632 [ 0.000000] pcpu-alloc: s37004 r8192 d24436 u69632 alloc=17*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 251720 [ 0.000000] Kernel command line: dwc_otg.lpm_enable=0 sdhci_bcm2708.enable_llm=0 console=tty2 kgdboc=tty2 scandelay=5 root=PARTUUID=deedbeef-02 ro noswap rootfstype=ext4 elevator=deadline fsck.repair=yes lapic rootwait rootdelay=5 consoleblank=120 logo.nologo quiet loglevel=0 net.ifnames=0 usb-storage.quirks= coherent_pool=1M 8250.nr_uarts=1 bcm2708_fb.fbwidth=720 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:E1:8E:DC vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 empty [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] allocated 1015808 bytes of page_ext [ 0.000000] Memory: 982772K/1015808K available (8192K kernel code, 599K rwdata, 2192K rodata, 1024K init, 827K bss, 24844K reserved, 8192K cma-reserved) [ 0.000000] Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xbe800000 - 0xff800000 (1040 MB) lowmem : 0x80000000 - 0xbe000000 ( 992 MB) modules : 0x7f000000 - 0x80000000 ( 16 MB) .text : 0x(ptrval) - 0x(ptrval) (9184 kB) .init : 0x(ptrval) - 0x(ptrval) (1024 kB) .data : 0x(ptrval) - 0x(ptrval) ( 600 kB) .bss : 0x(ptrval) - 0x(ptrval) ( 828 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] ftrace: allocating 27337 entries in 81 pages [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] Tasks RCU enabled. [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns [ 0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns [ 0.000020] Switching to timer-based delay loop, resolution 52ns [ 0.000286] Console: colour dummy device 80x30 [ 0.000307] console [tty2] enabled [ 0.000354] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=76800) [ 0.000370] pid_max: default: 32768 minimum: 301 [ 0.000534] Security Framework initialized [ 0.000544] Yama: becoming mindful. [ 0.000780] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.000798] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.001747] CPU: Testing write buffer coherency: ok [ 0.002256] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.024091] Setting up static identity map for 0x100000 - 0x10003c [ 0.032085] rcu: Hierarchical SRCU implementation. [ 0.048160] smp: Bringing up secondary CPUs ... [ 0.080685] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.112776] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 [ 0.144900] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 [ 0.145066] smp: Brought up 1 node, 4 CPUs [ 0.145079] SMP: Total of 4 processors activated (153.60 BogoMIPS). [ 0.145086] CPU: All CPU(s) started in HYP mode. [ 0.145093] CPU: Virtualization extensions available. [ 0.146061] devtmpfs: initialized [ 0.161320] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 [ 0.161691] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.161712] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 0.162323] pinctrl core: initialized pinctrl subsystem [ 0.163313] NET: Registered protocol family 16 [ 0.167209] DMA: preallocated 1024 KiB pool for atomic coherent allocations [ 0.172547] cpuidle: using governor ladder [ 0.172588] cpuidle: using governor menu [ 0.173765] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.173774] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.176806] bcm2835-mbox 3f00b880.mailbox: mailbox enabled [ 0.184853] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-01-22 17:25, variant start [ 0.188870] raspberrypi-firmware soc:firmware: Firmware hash is 53a54c770c493957d99bf49762dfabc4eee00e45 [ 0.228173] bcm2835-dma 3f007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1 [ 0.230108] SCSI subsystem initialized [ 0.230373] usbcore: registered new interface driver usbfs [ 0.230433] usbcore: registered new interface driver hub [ 0.230543] usbcore: registered new device driver usb [ 0.231450] NetLabel: Initializing [ 0.231459] NetLabel: domain hash size = 128 [ 0.231466] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.231558] NetLabel: unlabeled traffic allowed by default [ 0.232784] clocksource: Switched to clocksource arch_sys_counter [ 0.341084] VFS: Disk quotas dquot_6.6.0 [ 0.341188] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.341407] FS-Cache: Loaded [ 0.341619] CacheFiles: Loaded [ 0.341785] simple-framebuffer 3eaa9000.framebuffer: framebuffer at 0x3eaa9000, 0x151800 bytes, mapped to 0x(ptrval) [ 0.341800] simple-framebuffer 3eaa9000.framebuffer: format=a8r8g8b8, mode=720x480x32, linelength=2880 [ 0.347980] Console: switching to colour frame buffer device 90x30 [ 0.353681] simple-framebuffer 3eaa9000.framebuffer: fb0: simplefb registered! [ 0.363586] NET: Registered protocol family 2 [ 0.364432] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) [ 0.364474] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.364586] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 0.364824] TCP: Hash tables configured (established 8192 bind 8192) [ 0.364983] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.365032] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.365289] NET: Registered protocol family 1 [ 0.365978] RPC: Registered named UNIX socket transport module. [ 0.365987] RPC: Registered udp transport module. [ 0.365994] RPC: Registered tcp transport module. [ 0.366001] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.368354] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available [ 0.372089] Initialise system trusted keyrings [ 0.372323] workingset: timestamp_bits=14 max_order=18 bucket_order=4 [ 0.380928] zbud: loaded [ 0.382948] FS-Cache: Netfs 'nfs' registered for caching [ 0.383549] NFS: Registering the id_resolver key type [ 0.383582] Key type id_resolver registered [ 0.383589] Key type id_legacy registered [ 0.383614] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 0.386432] Key type asymmetric registered [ 0.386443] Asymmetric key parser 'x509' registered [ 0.386497] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) [ 0.386669] io scheduler noop registered [ 0.386679] io scheduler deadline registered (default) [ 0.386870] io scheduler cfq registered [ 0.386880] io scheduler mq-deadline registered (default) [ 0.386888] io scheduler kyber registered [ 0.391759] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled [ 0.393715] bcm2835-rng 3f104000.rng: hwrng registered [ 0.394108] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) [ 0.394822] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 [ 0.406577] brd: module loaded [ 0.418204] loop: module loaded [ 0.418971] Loading iSCSI transport class v2.0-870. [ 0.424248] libphy: Fixed MDIO Bus: probed [ 0.424352] usbcore: registered new interface driver lan78xx [ 0.424432] usbcore: registered new interface driver smsc95xx [ 0.424448] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 0.424704] dwc_otg 3f980000.usb: base=(ptrval) [ 0.624939] Core Release: 2.80a [ 0.624949] Setting default values for core params [ 0.624976] Finished setting default values for core params [ 0.825277] Using Buffer DMA mode [ 0.825285] Periodic Transfer Interrupt Enhancement - disabled [ 0.825292] Multiprocessor Interrupt Enhancement - disabled [ 0.825301] OTG VER PARAM: 0, OTG VER FLAG: 0 [ 0.825312] Dedicated Tx FIFOs mode [ 0.825641] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = bd904000 dma = 0xfd904000 len=9024 [ 0.825669] FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled [ 0.825678] dwc_otg: Microframe scheduler enabled [ 0.825736] WARN::hcd_init_fiq:457: FIQ on core 1 [ 0.825747] WARN::hcd_init_fiq:458: FIQ ASM at 8066ff18 length 36 [ 0.825759] WARN::hcd_init_fiq:497: MPHI regs_base at be810000 [ 0.825796] dwc_otg 3f980000.usb: DWC OTG Controller [ 0.825830] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 [ 0.825866] dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 [ 0.825915] Init: Port Power? op_state=1 [ 0.825922] Init: Power Port (0) [ 0.826209] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 [ 0.826222] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 0.826233] usb usb1: Product: DWC OTG Controller [ 0.826244] usb usb1: Manufacturer: Linux 4.19.105 dwc_otg_hcd [ 0.826254] usb usb1: SerialNumber: 3f980000.usb [ 0.826917] hub 1-0:1.0: USB hub found [ 0.826975] hub 1-0:1.0: 1 port detected [ 0.827643] dwc_otg: FIQ enabled [ 0.827651] dwc_otg: NAK holdoff enabled [ 0.827658] dwc_otg: FIQ split-transaction FSM enabled [ 0.827670] Module dwc_common_port init [ 0.827918] usbcore: registered new interface driver uas [ 0.828017] usbcore: registered new interface driver usb-storage [ 0.828335] mousedev: PS/2 mouse device common for all mice [ 0.829471] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 0.829832] bcm2835-cpufreq: min=600000 max=1200000 [ 0.830400] sdhci: Secure Digital Host Controller Interface driver [ 0.830407] sdhci: Copyright(c) Pierre Ossman [ 0.830851] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe [ 0.831318] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe [ 0.831474] sdhci-pltfm: SDHCI platform and OF driver helper [ 0.835515] ledtrig-cpu: registered to indicate activity on CPUs [ 0.835567] hidraw: raw HID events driver (C) Jiri Kosina [ 0.835660] usbcore: registered new interface driver usbhid [ 0.835664] usbhid: USB HID core driver [ 0.836205] vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0 [ 0.838516] NET: Registered protocol family 17 [ 0.838593] Key type dns_resolver registered [ 0.839092] Registering SWP/SWPB emulation handler [ 0.839479] registered taskstats version 1 [ 0.839491] Loading compiled-in X.509 certificates [ 0.845125] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 53, base_baud = 31250000) is a 16550 [ 0.845435] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 0.845965] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 [ 0.845983] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated [ 0.870285] sdhost: log_buf @ (ptrval) (fd907000) [ 0.887915] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [ 0.889504] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 0.891093] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 0.893966] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 0.919771] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) [ 0.922474] of_cfs_init [ 0.922515] of_cfs_init: OK [ 0.923505] Waiting 5 sec before mounting root device... [ 0.971726] random: fast init done [ 1.011661] mmc1: new high speed SDIO card at address 0001 [ 1.014424] mmc0: host does not support reading read-only switch, assuming write-enable [ 1.017699] mmc0: new high speed SDHC card at address aaaa [ 1.018716] mmcblk0: mmc0:aaaa SC32G 29.7 GiB [ 1.021349] mmcblk0: p1 p2 p3 [ 1.048937] Indeed it is in host mode hprt0 = 00021501 [ 1.236833] usb 1-1: new high-speed USB device number 2 using dwc_otg [ 1.236963] Indeed it is in host mode hprt0 = 00001101 [ 1.445080] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 [ 1.445094] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 1.445750] hub 1-1:1.0: USB hub found [ 1.445842] hub 1-1:1.0: 5 ports detected [ 1.732829] usb 1-1.1: new high-speed USB device number 3 using dwc_otg [ 1.833069] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 [ 1.833083] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 1.835699] smsc95xx v1.0.6 [ 1.896243] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e1:8e:dc [ 1.972835] usb 1-1.5: new full-speed USB device number 4 using dwc_otg [ 2.105785] usb 1-1.5: New USB device found, idVendor=0403, idProduct=6010, bcdDevice= 5.00 [ 2.105798] usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 2.105809] usb 1-1.5: Product: FAM V2.4 [ 2.105820] usb 1-1.5: Manufacturer: Eltako GmbH [ 2.105830] usb 1-1.5: SerialNumber: FT1Y9VCW [ 2.336829] usb 1-1.3: new high-speed USB device number 5 using dwc_otg [ 2.437781] usb 1-1.3: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 [ 2.437795] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 2.437807] usb 1-1.3: Product: Ultra Fit [ 2.437817] usb 1-1.3: Manufacturer: SanDisk [ 2.437827] usb 1-1.3: SerialNumber: 4C530000301102118314 [ 2.438583] usb-storage 1-1.3:1.0: USB Mass Storage device detected [ 2.439271] scsi host0: usb-storage 1-1.3:1.0 [ 3.457800] scsi 0:0:0:0: Direct-Access SanDisk Ultra Fit 1.00 PQ: 0 ANSI: 6 [ 3.458710] sd 0:0:0:0: [sda] 30031872 512-byte logical blocks: (15.4 GB/14.3 GiB) [ 3.459585] sd 0:0:0:0: [sda] Write Protect is off [ 3.459603] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 [ 3.460072] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA [ 3.469228] sda: sda1 [ 3.472372] sd 0:0:0:0: [sda] Attached SCSI removable disk [ 6.126716] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) [ 6.126777] VFS: Mounted root (ext4 filesystem) readonly on device 179:2. [ 6.127536] devtmpfs: mounted [ 6.134031] Freeing unused kernel memory: 1024K [ 6.134493] Run /sbin/init as init process [ 6.780906] EXT4-fs: Warning: mounting with data=journal disables delayed allocation and O_DIRECT support! [ 6.791478] EXT4-fs (mmcblk0p3): mounted filesystem with journalled data mode. Opts: data=journal [ 7.029685] i2c /dev entries driver [ 7.119607] random: crng init done [ 7.458199] tun: Universal TUN/TAP device driver, 1.6 [ 7.586067] udevd[249]: starting version 3.2.9 [ 7.631129] udevd[254]: starting eudev-3.2.9 [ 7.751986] generic_raw_uart: loading out-of-tree module taints kernel. [ 7.757516] pl011_raw_uart 3f201000.serial: No valid reset pin configured in device tree [ 7.757655] pl011_raw_uart 3f201000.serial: Initialized pl011 device; mapbase=0x3f201000; irq=81; clockrate=47999625 [ 7.784690] rtc-rx8130 1-0032: Unable to read registers #28..#30 [ 7.784703] i2c i2c-1: probing for rx8130 failed [ 7.784735] rtc-rx8130: probe of 1-0032 failed with error -121 [ 7.897220] usbcore: registered new interface driver usbserial_generic [ 7.897257] usbserial: USB Serial support registered for generic [ 7.909833] usbcore: registered new interface driver ftdi_sio [ 7.909880] usbserial: USB Serial support registered for FTDI USB Serial Device [ 7.910073] ftdi_sio 1-1.5:1.0: FTDI USB Serial Device converter detected [ 7.910619] usb 1-1.5: Detected FT2232C [ 7.912133] usb 1-1.5: FTDI USB Serial Device converter now attached to ttyUSB0 [ 7.914591] ftdi_sio 1-1.5:1.1: FTDI USB Serial Device converter detected [ 7.914705] usb 1-1.5: Detected FT2232C [ 7.915730] usb 1-1.5: FTDI USB Serial Device converter now attached to ttyUSB1 [ 7.999872] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 8.024993] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 8.030895] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 [ 8.030910] cfg80211: failed to load regulatory.db [ 8.107258] brcmfmac: F1 signature read @0x18000000=0x1541a9a6 [ 8.112184] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [ 8.112366] usbcore: registered new interface driver brcmfmac [ 8.234973] FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. [ 8.331794] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 [ 8.331876] brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available [ 8.332502] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Oct 23 2017 03:55:53 version 7.45.98.38 (r674442 CY) FWID 01-e58d219f [ 25.183462] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup [ 26.762955] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 [ 28.709254] NET: Registered protocol family 10 [ 28.714878] Segment Routing with IPv6 [ 41.261293] eq3loop: created slave mmd_hmip [ 41.261613] eq3loop: created slave mmd_bidcos [ 43.434068] eq3loop: eq3loop_open_slave() mmd_bidcos [ 59.890071] eq3loop: eq3loop_open_slave() mmd_hmip [ 59.890265] eq3loop: eq3loop_close_slave() mmd_hmip [ 59.893828] eq3loop: eq3loop_open_slave() mmd_hmip [ 59.893959] eq3loop: eq3loop_close_slave() mmd_hmip [ 59.904139] eq3loop: eq3loop_open_slave() mmd_hmip root@homematic-raspi:~#

friedpa commented 4 years ago

Ich hatte das selbe Problem mit den HM-IP Geräten, du wirst zwar den Kopf schütteln, aber ich habe das Problem so beseitigen können, dass ich das externe Antennenkabel vom RB3+ mit Funkmodul zur Antenne anders gelegt habe (war parallel zu Datenkabeln und einem Stromkabel verlegt). Seit dem kein Freeze mehr, war sogar reproduzierbar. Wenn Du keine externe Antenne hast versuche einmal den RB+ zum Test woanders zu platzieren.

Jensilein commented 4 years ago

Es ist echt zum Mäuse melken. Habe die Antenne gestern Abend umpositioniert. Seitdem lief alles stabil durch, aber gerade eben wieder die erste „unreach“-Meldung, obwohl das HM-IP Gerät in unmittelbarer Nähe der RM bzw. Sichtweite der Antenne liegt. Was kann denn das nur sein?

friedpa commented 4 years ago

Was ich festgestellt habe ist, dass es nicht darauf ankommt, ob das HM-IP Gerät im selben Raum ist oder nicht (ich hatte den RB3+ und HM-IP Steckdosen im selben Raum), sondern einzig und alleine wie das Antennensignal "gestört" wird. Checke doch einmal welches Gerät jetzt zusätzlich in der Nähe des RB3+ oder des Antennekabels oder der Antenne selbst zugeschaltet wurde (Laptop, Drucker, Lampe mit ungeschirmten Trafo, ....). Als Versuch kannst Du noch probieren den RB in einen anderen Raum ohne "Elektrosmoke" zu bringen.

Jensilein commented 4 years ago

Vielen Dank für die Rückmeldung. Also ich muss ganz ehrlich sagen, das Ganze hat für mich doch eher den Charakter von Trial and Error bzw. Bastelbude. Unter einem stabilen und verlässlichen Betrieb stelle ich mir etwas anderes vor. Für jemanden, der viel Zeit hat und das Ganze als tagesfüllendes Hobby betreiben will, mag das ja ok sein, aber wenn man lediglich eine stabile Funktion haben und sich darauf verlassen möchte, ist das nicht wirklich etwas Gescheites. Ich hatte geplant, mir eine Alarmanlage mit HM-IP Komponenten aufzubauen, aber wenn regelmäßig die Alarmsirene nicht erreichbar ist bzw. man sich nicht sicher darauf verlassen kann, dann scheint mir das wohl kaum eine für solche Zwecke wirklich taugliche technische Basis zu sein...

friedpa commented 4 years ago

Wem sagst Du das, aber über dieses Stadium (und bei 80 Devices) der Erkenntnis bin ich schon drüber ....

Hypnos3 commented 4 years ago

@Jensilein: kleiner Hinweis:für Alarmanlagen gibt es andere Systeme, welche darauf spezialisiert sind. Ein Homematic System wird selbst mit vertretbarem Bastel Aufwand keine vollwertige Alarmanlage. Daher sollte man sich das genau überlegen.

Beispielsweise ist ein 868mhz Störender schnell und einfach zusammengebaut. Die üblichen Homematic Sensoren melden erst nach einer Stunde da sie gestört sind. Bis dahin ist die Wohnung ausgeräumt.

Die Gestört Meldungen bekommt man auch wenn man einen Dauersender irgendwo hat. Das muss nicht in der Nähe, sondern nur irgendwo in Funk Reichweite sein. Dieser Sender kann auch nur sporadisch Probleme bereiten.

Ich hatte letztens einen Temperatursensor bei dem die Batterien sich dem Ende neigten. Der verursachte bei anderen Homematic Komponenten on der Nähe auch sporadisch Gestört Meldungen.

Jensilein commented 4 years ago

@Hypnos3: Ja, das ist mir völlig klar, dass eine Homematic-Alarmanlage nicht vergleichbar ist mit einem darauf spezialisierten System. Gleichwohl hätte ich es einfach gerne ausprobiert und auch bewusst mit der Tatsache gelebt, keine „vollwertige“ Alarmanlage zu haben, aber so langsam verliere ich sowohl die Lust als auch das Vertrauen an diesem Projekt. Homematic-IP empfinde ich als äußerst „zickig“ wenn ich es mal mit meinen Worten beschreiben darf. Phasenweise läuft es stabil und zufriedenstellend und dann wieder Phasen, gefühlt aus dem heiteren Himmel kommend, wo das System einfach anfängt, zu spinnen und macht was es will, und das absolut ärgerlichste daran ist, dass man in der Regel nicht wirklich zügig die Fehlerursache findet, um diese dann gezielt beheben zu können. Man probiert erst dies und wieder jenes und hat entweder Glück oder auch nicht. Hier würde ich mir einfach mehr intuitive Unterstützung direkt aus dem System heraus wünschen, aber Wunsch und Realität sind nun mal eben (leider) zwei verschiedene Paar Schuhe. LG

jens-maus commented 4 years ago

Ich bitte diese Diskussionen hier zu beenden und stattdessen im Homematic Forum weiterzuführen. Das hier ist ein Bug/Issue-Tracker und kein Diskussionsforum!

Und bzgl des geschilderten Problems kann ich kein direkten Zusammenhang zu einem RaspberryMatic/ CCU spezifischen Problem/Bug erkennen. Kommunikationsstörungen kann es aus verschiedensten Gründen geben (das wurde schon mehrfach im Homematic Forum ausreichend diskutiert). Auch kann ich aus den Logmeldunhen heraus keine Prinzipiellen Dinge erkennen die auf einen Bug/Problem in RaspberryMatic schliessen lassen. Diese „ScriptRuntimeError“ kommen sicherlich aus einer anderen richtung! Ich vermite das hier eine externe Applikation zum abfragen/steuern der Geräte verwendet wird. Und diese sendet offensichtlich inkompatible Scriptanweisungen die zu diesen Fehlern führen und ggf sogar je nach abfragefrequenz datu führen können das es zu den beschriebenen Problemen kommt.

Daher erneut die Bitte das weiterhin im Homematic Forum zu diskutieren um die eigentliche Ursache zu finden. Und erst wenn in diese Forendiskussionen herauskommt das doch ein Bug/Problem in der RaspberryMatic / CCU Software vorliegt sollte hier im GitHub dazu ein erneutes Ticket aufgemacht werden.