NEWS
Spontane Iobroker abstürze
ioBroker Allgemein
13
Beiträge
4
Kommentatoren
978
Aufrufe
4
Watching
-
Hallo zusammen,
ich kämpfe seit Wochen mit Iobroker abstürzen. Ich habe alles mehrfach komplett neu aufgesetzt aber irgendwie läuft alles für ein paar stunden sauber und dann stürzt er ab und ich komme auch nicht mehr per SSH drauf muss also hart den Strom weg nehmen. Anbei mal der das journal file vom letzten Absturz heute Nacht und ein iob Diag von heute morgen nach dem harten Neustart. Vielleicht hat ja jemand eine Idee für mich.
Skript v.2023-10-10 *** BASE SYSTEM *** Static hostname: raspberrypi Icon name: computer Operating System: Debian GNU/Linux 12 (bookworm) Kernel: Linux 6.6.20+rpt-rpi-v8 Architecture: arm64 Model : Raspberry Pi 3 Model B Plus Rev 1.3 Docker : false Virtualization : none Kernel : aarch64 Userland : arm64 Systemuptime and Load: 07:35:27 up 9 min, 2 users, load average: 0.38, 0.38, 0.30 CPU threads: 4 *** RASPBERRY THROTTLING *** Current issues: No throttling issues detected. Previously detected issues: No throttling issues detected. *** Time and Time Zones *** Local time: Fri 2024-04-19 07:35:27 CEST Universal time: Fri 2024-04-19 05:35:27 UTC RTC time: n/a Time zone: Europe/Berlin (CEST, +0200) System clock synchronized: yes NTP service: active RTC in local TZ: no *** User and Groups *** pi /home/pi pi adm dialout cdrom sudo audio video plugdev games users input render netdev gpio i2c spi iobroker *** X-Server-Setup *** X-Server: false Desktop: Terminal: tty Boot Target: multi-user.target *** MEMORY *** total used free shared buff/cache available Mem: 951M 130M 640M 180K 234M 820M Swap: 104M 14M 90M Total: 1.1G 145M 731M 907 M total memory 124 M used memory 144 M active memory 85 M inactive memory 610 M free memory 12 M buffer memory 211 M swap cache 99 M total swap 13 M used swap 86 M free swap Raspberry only: oom events: 0 lifetime oom required: 0 Mbytes total time in oom handler: 0 ms max time spent in oom handler: 0 ms *** FAILED SERVICES *** UNIT LOAD ACTIVE SUB DESCRIPTION * iobroker.service loaded failed failed ioBroker Server LOAD = Reflects whether the unit definition was properly loaded. ACTIVE = The high-level unit activation state, i.e. generalization of SUB. SUB = The low-level unit activation state, values depend on unit type. 1 loaded units listed. *** FILESYSTEM *** Filesystem Type Size Used Avail Use% Mounted on udev devtmpfs 318M 0 318M 0% /dev tmpfs tmpfs 91M 1008K 90M 2% /run /dev/mmcblk0p2 ext4 29G 3.6G 24G 14% / tmpfs tmpfs 454M 0 454M 0% /dev/shm tmpfs tmpfs 5.0M 12K 5.0M 1% /run/lock /dev/mmcblk0p1 vfat 510M 63M 448M 13% /boot/firmware tmpfs tmpfs 91M 0 91M 0% /run/user/1000 Messages concerning ext4 filesystem in dmesg: [Fri Apr 19 07:25:40 2024] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyS0,115200 console=tty1 root=PARTUUID=fb40b1b6-02 rootfstype=ext4 fsck.repair=yes rootwait [Fri Apr 19 07:25:45 2024] EXT4-fs (mmcblk0p2): mounted filesystem 93c89e92-8f2e-4522-ad32-68faed883d2f ro with ordered data mode. Quota mode: none. [Fri Apr 19 07:25:48 2024] EXT4-fs (mmcblk0p2): re-mounted 93c89e92-8f2e-4522-ad32-68faed883d2f r/w. Quota mode: none. Show mounted filesystems \(real ones only\): TARGET SOURCE FSTYPE OPTIONS / /dev/mmcblk0p2 ext4 rw,noatime `-/boot/firmware /dev/mmcblk0p1 vfat rw,relatime,fmask=0022,dmask=0022,codepage=437,iocharset=ascii,shortname=mixed,errors=remount-ro Files in neuralgic directories: /var: 584M /var/ 206M /var/cache 200M /var/cache/apt 162M /var/lib 133M /var/lib/apt/lists Archived and active journals take up 115.5M in the file system. /opt/iobroker/backups: 15M /opt/iobroker/backups/ /opt/iobroker/iobroker-data: 195M /opt/iobroker/iobroker-data/ 126M /opt/iobroker/iobroker-data/files 57M /opt/iobroker/iobroker-data/files/javascript.admin 38M /opt/iobroker/iobroker-data/files/javascript.admin/static 37M /opt/iobroker/iobroker-data/files/javascript.admin/static/js The five largest files in iobroker-data are: 22M /opt/iobroker/iobroker-data/files/web.admin/static/js/main.c05ba1d3.js.map 14M /opt/iobroker/iobroker-data/objects.jsonl 8.3M /opt/iobroker/iobroker-data/files/web.admin/static/js/main.c05ba1d3.js 7.7M /opt/iobroker/iobroker-data/files/vis-2/customWidgets.js 7.4M /opt/iobroker/iobroker-data/states.jsonl USB-Devices by-id: USB-Sticks - Avoid direct links to /dev/* in your adapter setups, please always prefer the links 'by-id': find: '/dev/serial/by-id/': No such file or directory *** NodeJS-Installation *** /usr/bin/nodejs v18.20.1 /usr/bin/node v18.20.1 /usr/bin/npm 10.5.0 /usr/bin/npx 10.5.0 /usr/bin/corepack 0.25.2 nodejs: Installed: 18.20.1-1nodesource1 Candidate: 18.20.2-1nodesource1 Version table: 18.20.2-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages *** 18.20.1-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 100 /var/lib/dpkg/status 18.20.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.19.1-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.19.0+dfsg-6~deb12u1 500 500 http://deb.debian.org/debian-security bookworm-security/main arm64 Packages 18.19.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.18.2-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.18.1-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.18.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.17.1-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.17.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.16.1-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.16.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.15.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.14.2-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.14.1-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.14.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.13.0+dfsg1-1 500 500 http://deb.debian.org/debian bookworm/main arm64 Packages 18.13.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.12.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.11.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.10.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.9.1-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.9.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.8.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.7.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.6.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.5.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.4.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.3.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.2.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.1.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages 18.0.0-1nodesource1 1001 500 https://deb.nodesource.com/node_18.x nodistro/main arm64 Packages Temp directories causing npm8 problem: 0 No problems detected Errors in npm tree: *** ioBroker-Installation *** ioBroker Status iobroker is not running on this host. Objects type: jsonl States type: jsonl Core adapters versions js-controller: 5.0.19 admin: 6.13.16 javascript: 7.8.0 Adapters from github: 0 Adapter State system.adapter.admin.0 : admin : raspberrypi - enabled, port: 8081, bind: 0.0.0.0, run as: admin system.adapter.backitup.0 : backitup : raspberrypi - enabled system.adapter.discovery.0 : discovery : raspberrypi - enabled system.adapter.javascript.0 : javascript : raspberrypi - enabled system.adapter.mqtt.0 : mqtt : raspberrypi - enabled, port: 1883, bind: 0.0.0.0 system.adapter.node-red.0 : node-red : raspberrypi - enabled, port: 1880, bind: 0.0.0.0 system.adapter.web.0 : web : raspberrypi - enabled, port: 8082, bind: 0.0.0.0, run as: admin system.adapter.wled.0 : wled : raspberrypi - enabled + instance is alive Enabled adapters with bindings system.adapter.admin.0 : admin : raspberrypi - enabled, port: 8081, bind: 0.0.0.0, run as: admin system.adapter.mqtt.0 : mqtt : raspberrypi - enabled, port: 1883, bind: 0.0.0.0 system.adapter.node-red.0 : node-red : raspberrypi - enabled, port: 1880, bind: 0.0.0.0 system.adapter.web.0 : web : raspberrypi - enabled, port: 8082, bind: 0.0.0.0, run as: admin ioBroker-Repositories stable : http://download.iobroker.net/sources-dist.json beta : http://download.iobroker.net/sources-dist-latest.json Active repo(s): stable Installed ioBroker-Instances Used repository: stable Adapter "admin" : 6.13.16 , installed 6.13.16 Adapter "backitup" : 2.11.0 , installed 2.10.11 [Updatable] Adapter "discovery" : 4.4.0 , installed 4.4.0 Adapter "javascript" : 7.8.0 , installed 7.8.0 Controller "js-controller": 5.0.19 , installed 5.0.19 Adapter "mqtt" : 5.2.0 , installed 5.2.0 Adapter "node-red" : 5.2.0 , installed 5.2.0 Adapter "simple-api" : 2.7.2 , installed 2.7.2 Adapter "socketio" : 6.6.1 , installed 6.6.0 [Updatable] Adapter "vis-2" : 2.9.32 , installed 2.9.32 Adapter "web" : 6.2.5 , installed 6.2.3 [Updatable] Adapter "wled" : 0.7.2 , installed 0.7.2 Adapter "ws" : 2.5.11 , installed 2.5.10 [Updatable] Objects and States Please stand by - This may take a while Objects: 478 States: 301 *** OS-Repositories and Updates *** Hit:1 http://deb.debian.org/debian bookworm InRelease Hit:2 http://deb.debian.org/debian-security bookworm-security InRelease Hit:3 http://deb.debian.org/debian bookworm-updates InRelease Hit:4 http://archive.raspberrypi.com/debian bookworm InRelease Hit:5 https://deb.nodesource.com/node_18.x nodistro InRelease Reading package lists... Pending Updates: 2 *** Listening Ports *** Active Internet connections (only servers) Proto Recv-Q Send-Q Local Address Foreign Address State User Inode PID/Program name tcp 0 0 0.0.0.0:22 0.0.0.0:* LISTEN 0 640 606/sshd: /usr/sbin tcp6 0 0 :::22 :::* LISTEN 0 642 606/sshd: /usr/sbin udp 0 0 0.0.0.0:34456 0.0.0.0:* 104 4559 429/avahi-daemon: r udp 0 0 0.0.0.0:5353 0.0.0.0:* 104 4557 429/avahi-daemon: r udp6 0 0 :::47312 :::* 104 4560 429/avahi-daemon: r udp6 0 0 :::5353 :::* 104 4558 429/avahi-daemon: r udp6 0 0 fe80::140a:f456:aef:546 :::* 0 5771 487/NetworkManager *** Log File - Last 25 Lines *** 2024-04-19 07:34:28.393 - info: host.raspberrypi stopInstance system.adapter.mqtt.0 send kill signal 2024-04-19 07:34:28.394 - info: host.raspberrypi stopInstance system.adapter.discovery.0 send kill signal 2024-04-19 07:34:28.394 - info: host.raspberrypi stopInstance system.adapter.wled.0 send kill signal 2024-04-19 07:34:28.394 - info: host.raspberrypi stopInstance system.adapter.web.0 send kill signal 2024-04-19 07:34:28.412 - info: web.0 (951) terminated http server on port 8082 2024-04-19 07:34:28.422 - info: web.0 (951) terminating 2024-04-19 07:34:28.424 - info: web.0 (951) Terminated (NO_ERROR): Without reason 2024-04-19 07:34:28.696 - info: node-red.0 (833) terminating 2024-04-19 07:34:28.702 - info: discovery.0 (917) terminating 2024-04-19 07:34:28.703 - info: web.0 (951) terminating 2024-04-19 07:34:28.707 - info: javascript.0 (877) terminating 2024-04-19 07:34:28.713 - info: admin.0 (826) terminating 2024-04-19 07:34:28.717 - info: mqtt.0 (901) terminating 2024-04-19 07:34:28.725 - info: backitup.0 (895) terminating 2024-04-19 07:34:28.736 - info: wled.0 (936) terminating 2024-04-19 07:34:29.141 - info: host.raspberrypi instance system.adapter.discovery.0 terminated with code 0 (NO_ERROR) 2024-04-19 07:34:29.184 - info: host.raspberrypi instance system.adapter.javascript.0 terminated with code 0 (NO_ERROR) 2024-04-19 07:34:29.266 - info: host.raspberrypi instance system.adapter.node-red.0 terminated with code 0 (NO_ERROR) 2024-04-19 07:34:29.267 - info: host.raspberrypi instance system.adapter.backitup.0 terminated with code 0 (NO_ERROR) 2024-04-19 07:34:29.268 - info: host.raspberrypi instance system.adapter.mqtt.0 terminated with code 0 (NO_ERROR) 2024-04-19 07:34:29.268 - info: host.raspberrypi instance system.adapter.wled.0 terminated with code 0 (NO_ERROR) 2024-04-19 07:34:29.303 - info: host.raspberrypi instance system.adapter.web.0 terminated with code 0 (NO_ERROR) 2024-04-19 07:34:29.329 - info: host.raspberrypi instance system.adapter.admin.0 terminated with code 0 (NO_ERROR) 2024-04-19 07:34:29.330 - info: host.raspberrypi All instances are stopped. 2024-04-19 07:34:29.506 - info: host.raspberrypi terminatedApr 19 02:17:04 raspberrypi kernel: Bluetooth: HCI UART driver ver 2.3 Apr 19 02:17:04 raspberrypi kernel: Bluetooth: HCI UART protocol H4 registered Apr 19 02:17:04 raspberrypi kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Apr 19 02:17:04 raspberrypi kernel: Bluetooth: HCI UART protocol Broadcom registered Apr 19 02:17:04 raspberrypi kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Apr 19 02:17:04 raspberrypi kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Apr 19 02:17:04 raspberrypi kernel: uart-pl011 3f201000.serial: no DMA platform data Apr 19 02:17:04 raspberrypi kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Apr 19 02:17:04 raspberrypi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Apr 19 02:17:04 raspberrypi kernel: usbcore: registered new interface driver brcmfmac Apr 19 02:17:04 raspberrypi kernel: Bluetooth: hci0: BCM: chip id 107 Apr 19 02:17:04 raspberrypi kernel: Bluetooth: hci0: BCM: features 0x2f Apr 19 02:17:04 raspberrypi kernel: Bluetooth: hci0: BCM4345C0 Apr 19 02:17:04 raspberrypi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Apr 19 02:17:04 raspberrypi kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.raspberrypi,3-model-b-plus.hcd' Patch Apr 19 02:17:04 raspberrypi kernel: brcmfmac_wcc: brcmf_wcc_attach: executing Apr 19 02:17:04 raspberrypi kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Apr 19 02:17:04 raspberrypi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Apr 15 2021 03:03:20 version 7.45.234 (4ca95bb CY) FWID 01-996384e2 Apr 19 02:17:04 raspberrypi systemd[1]: Finished networking.service - Raise network interfaces. -- Boot 3fcc07cff1f944b4b203c3a84456ac7a -- Apr 19 02:43:24 raspberrypi bash[553]: host.raspberrypi Delete log file iobroker.2024-04-11.log.gz -- Boot f601c30efc5a44cc901b82289dd9b584 -- Apr 19 02:17:04 raspberrypi systemd-timesyncd[346]: System clock time unset or jumped backwards, restoring from recorded timestamp: Fri 2024-04-19 03:03:07 CEST Apr 19 03:03:07 raspberrypi systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Apr 19 03:03:07 raspberrypi systemd[1]: Reached target sysinit.target - System Initialization. Apr 19 03:03:07 raspberrypi systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 19 03:03:07 raspberrypi systemd[1]: Reached target time-set.target - System Time Set. Apr 19 03:03:07 raspberrypi systemd[1]: Started apt-daily.timer - Daily apt download activities. Apr 19 03:03:07 raspberrypi systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Apr 19 03:03:07 raspberrypi systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Apr 19 03:03:07 raspberrypi systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Apr 19 03:03:07 raspberrypi systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Apr 19 03:03:07 raspberrypi systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 19 03:03:07 raspberrypi systemd[1]: Started man-db.timer - Daily man-db regeneration. Apr 19 03:03:07 raspberrypi systemd[1]: Reached target timers.target - Timer Units. Apr 19 03:03:07 raspberrypi systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Apr 19 03:03:07 raspberrypi systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 19 03:03:07 raspberrypi systemd[1]: Listening on triggerhappy.socket. Apr 19 03:03:07 raspberrypi kernel: Console: switching to colour dummy device 80x25 Apr 19 03:03:07 raspberrypi systemd[1]: Reached target sockets.target - Socket Units. Apr 19 03:03:07 raspberrypi systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPc> Apr 19 03:03:07 raspberrypi systemd[1]: Reached target basic.target - Basic System. Apr 19 03:03:07 raspberrypi kernel: vc4-drm soc:gpu: bound 3f400000.hvs (ops vc4_hvs_ops [vc4]) Apr 19 03:03:07 raspberrypi kernel: Registered IR keymap rc-cec Apr 19 03:03:07 raspberrypi kernel: rc rc0: vc4-hdmi as /devices/platform/soc/3f902000.hdmi/rc/rc0 Apr 19 03:03:07 raspberrypi kernel: input: vc4-hdmi as /devices/platform/soc/3f902000.hdmi/rc/rc0/input0 Apr 19 03:03:07 raspberrypi kernel: input: vc4-hdmi HDMI Jack as /devices/platform/soc/3f902000.hdmi/sound/card1/input1 Apr 19 03:03:07 raspberrypi kernel: vc4-drm soc:gpu: bound 3f902000.hdmi (ops vc4_hdmi_ops [vc4]) Apr 19 03:03:07 raspberrypi kernel: vc4-drm soc:gpu: bound 3f004000.txp (ops vc4_txp_ops [vc4]) Apr 19 03:03:07 raspberrypi kernel: vc4-drm soc:gpu: bound 3f206000.pixelvalve (ops vc4_crtc_ops [vc4]) Apr 19 03:03:07 raspberrypi kernel: vc4-drm soc:gpu: bound 3f207000.pixelvalve (ops vc4_crtc_ops [vc4]) Apr 19 03:03:07 raspberrypi kernel: vc4-drm soc:gpu: bound 3f807000.pixelvalve (ops vc4_crtc_ops [vc4]) Apr 19 03:03:07 raspberrypi kernel: vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4]) Apr 19 03:03:07 raspberrypi systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Apr 19 03:03:07 raspberrypi systemd[1]: Started cron.service - Regular background program processing daemon. Apr 19 03:03:07 raspberrypi kernel: [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0 Apr 19 03:03:07 raspberrypi kernel: vc4-drm soc:gpu: [drm] Cannot find any crtc or sizes Apr 19 03:03:07 raspberrypi kernel: vc4-drm soc:gpu: [drm] Cannot find any crtc or sizes Apr 19 03:03:07 raspberrypi systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 19 03:03:07 raspberrypi systemd[1]: Starting dphys-swapfile.service - dphys-swapfile - set up, mount/unmount, and delete a swap file... Apr 19 03:03:07 raspberrypi cron[433]: (CRON) INFO (pidfile fd = 3) Apr 19 03:03:07 raspberrypi systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Apr 19 03:03:07 raspberrypi systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Apr 19 03:03:07 raspberrypi cron[433]: (CRON) INFO (Running @reboot jobs) Apr 19 03:03:07 raspberrypi systemd[1]: Starting polkit.service - Authorization Manager... Apr 19 03:03:07 raspberrypi systemd[1]: Starting raspi-config.service - LSB: Switch to ondemand cpu governor (unless shift key is pressed)... Apr 19 03:03:07 raspberrypi systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Apr 19 03:03:07 raspberrypi systemd[1]: Starting sshswitch.service - Turn on SSH if /boot/ssh or /boot/firmware/ssh is present... Apr 19 03:03:07 raspberrypi systemd[1]: Starting systemd-logind.service - User Login Management... Apr 19 03:03:07 raspberrypi systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b> Apr 19 03:03:07 raspberrypi systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Apr 19 03:03:07 raspberrypi avahi-daemon[429]: Found user 'avahi' (UID 104) and group 'avahi' (GID 110). Apr 19 03:03:07 raspberrypi avahi-daemon[429]: Successfully dropped root privileges. Apr 19 03:03:07 raspberrypi avahi-daemon[429]: avahi-daemon 0.8 starting up. Apr 19 03:03:07 raspberrypi rpi-eeprom-update[446]: Device does not a have a Raspberry Pi bootloader EEPROM (e.g. Pi 4 or Pi 5). Skipping bootloader update. Apr 19 03:03:07 raspberrypi systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Apr 19 03:03:07 raspberrypi thd[452]: Found socket passed from systemd Apr 19 03:03:07 raspberrypi systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Apr 19 03:03:07 raspberrypi systemd[1]: sshswitch.service: Deactivated successfully. Apr 19 03:03:07 raspberrypi systemd[1]: Finished sshswitch.service - Turn on SSH if /boot/ssh or /boot/firmware/ssh is present. Apr 19 03:03:07 raspberrypi kernel: Bluetooth: hci0: BCM: features 0x2f Apr 19 03:03:07 raspberrypi polkitd[439]: Started polkitd version 122 Apr 19 03:03:07 raspberrypi kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190 Apr 19 03:03:07 raspberrypi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382 Apr 19 03:03:07 raspberrypi kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Apr 19 03:03:07 raspberrypi systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 19 03:03:07 raspberrypi avahi-daemon[429]: Successfully called chroot(). Apr 19 03:03:07 raspberrypi avahi-daemon[429]: Successfully dropped remaining capabilities. Apr 19 03:03:07 raspberrypi dphys-swapfile[436]: want /var/swap=100MByte, checking existing: keeping it Apr 19 03:03:07 raspberrypi avahi-daemon[429]: No service file found in /etc/avahi/services. Apr 19 03:03:07 raspberrypi systemd[1]: Starting NetworkManager.service - Network Manager... Apr 19 03:03:07 raspberrypi avahi-daemon[429]: Joining mDNS multicast group on interface lo.IPv6 with address ::1. Apr 19 03:03:07 raspberrypi avahi-daemon[429]: New relevant interface lo.IPv6 for mDNS. Apr 19 03:03:07 raspberrypi systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Apr 19 03:03:07 raspberrypi avahi-daemon[429]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Apr 19 03:03:07 raspberrypi avahi-daemon[429]: New relevant interface lo.IPv4 for mDNS. Apr 19 03:03:07 raspberrypi avahi-daemon[429]: Network interface enumeration completed. Apr 19 03:03:07 raspberrypi avahi-daemon[429]: Registering new address record for ::1 on lo.*. Apr 19 03:03:07 raspberrypi avahi-daemon[429]: Registering new address record for 127.0.0.1 on lo.IPv4. Apr 19 03:03:07 raspberrypi systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Apr 19 03:03:07 raspberrypi kernel: Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SS Apr 19 03:03:07 raspberrypi systemd[1]: Finished dphys-swapfile.service - dphys-swapfile - set up, mount/unmount, and delete a swap file. Apr 19 03:03:07 raspberrypi polkitd[439]: Loading rules from directory /etc/polkit-1/rules.d Apr 19 03:03:07 raspberrypi polkitd[439]: Loading rules from directory /usr/share/polkit-1/rules.d Apr 19 03:03:07 raspberrypi polkitd[439]: Finished loading, compiling and executing 4 rules Apr 19 03:03:08 raspberrypi systemd[1]: Started polkit.service - Authorization Manager. Apr 19 03:03:08 raspberrypi polkitd[439]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Apr 19 03:03:08 raspberrypi systemd[1]: Starting ModemManager.service - Modem Manager... Apr 19 03:03:08 raspberrypi wpa_supplicant[488]: Successfully initialized wpa_supplicant Apr 19 03:03:08 raspberrypi systemd[1]: Started wpa_supplicant.service - WPA supplicant. Apr 19 03:03:08 raspberrypi systemd[1]: e2scrub_reap.service: Deactivated successfully. Apr 19 03:03:08 raspberrypi systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Apr 19 03:03:08 raspberrypi systemd-logind[451]: New seat seat0. Apr 19 03:03:08 raspberrypi systemd[1]: Started systemd-logind.service - User Login Management. Apr 19 03:03:08 raspberrypi ModemManager[502]: <info> ModemManager (version 1.20.4) starting in system bus... Apr 19 03:03:08 raspberrypi raspi-config[445]: Checking if shift key is held down: No. Switching to ondemand scaling governor. Apr 19 03:03:08 raspberrypi systemd[1]: Started raspi-config.service - LSB: Switch to ondemand cpu governor (unless shift key is pressed). Apr 19 03:03:08 raspberrypi avahi-daemon[429]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 2796040434. Apr 19 03:03:08 raspberrypi systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 19 03:03:08 raspberrypi systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Apr 19 03:03:08 raspberrypi NetworkManager[487]: <info> [1713488588.7832] NetworkManager (version 1.42.4) is starting... (boot:f601c30e-fc5a-44cc-901b-82289dd9b584) Apr 19 03:03:08 raspberrypi NetworkManager[487]: <info> [1713488588.7835] Read config: /etc/NetworkManager/NetworkManager.conf (lib: no-mac-addr-change.conf) Apr 19 03:03:08 raspberrypi systemd[1]: Started NetworkManager.service - Network Manager. Apr 19 03:03:08 raspberrypi systemd[1]: Reached target network.target - Network. Apr 19 03:03:08 raspberrypi NetworkManager[487]: <info> [1713488588.8146] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager" Apr 19 03:03:08 raspberrypi systemd[1]: Starting NetworkManager-wait-online.service - Network Manager Wait Online... Apr 19 03:03:08 raspberrypi systemd[1]: Started iobroker.service - ioBroker Server. Apr 19 03:03:08 raspberrypi NetworkManager[487]: <info> [1713488588.9079] manager[0x557d697000]: monitoring kernel firmware directory '/lib/firmware'. Apr 19 03:03:08 raspberrypi NetworkManager[487]: <info> [1713488588.9082] monitoring ifupdown state file '/run/network/ifstate'. Apr 19 03:03:08 raspberrypi dbus-daemon[435]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.7' (uid=0 pid=487 comm="/us> Apr 19 03:03:08 raspberrypi systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Apr 19 03:03:08 raspberrypi systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 19 03:03:08 raspberrypi systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Apr 19 03:03:09 raspberrypi (udev-worker)[508]: event1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 19 03:03:09 raspberrypi (udev-worker)[505]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 19 03:03:09 raspberrypi systemd-logind[451]: Watching system buttons on /dev/input/event0 (vc4-hdmi) Apr 19 03:03:09 raspberrypi systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 19 03:03:09 raspberrypi systemd[1]: Created slice system-bthelper.slice - Slice /system/bthelper. Apr 19 03:03:09 raspberrypi systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Apr 19 03:03:09 raspberrypi systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Apr 19 03:03:09 raspberrypi systemd[1]: Starting hciuart.service - Configure Bluetooth Modems connected by UART... Apr 19 03:03:09 raspberrypi alsactl[575]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 19 03:03:09 raspberrypi alsactl[575]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 19 03:03:09 raspberrypi systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Apr 19 03:03:09 raspberrypi systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Apr 19 03:03:09 raspberrypi systemd[1]: Reached target sound.target - Sound Card. Apr 19 03:03:09 raspberrypi systemd[1]: hciuart.service: Deactivated successfully. Apr 19 03:03:09 raspberrypi systemd[1]: Started hciuart.service - Configure Bluetooth Modems connected by UART. Apr 19 03:03:09 raspberrypi systemd[1]: Starting bthelper@hci0.service - Raspberry Pi bluetooth helper... Apr 19 03:03:09 raspberrypi dbus-daemon[435]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 19 03:03:09 raspberrypi systemd[1]: Started systemd-hostnamed.service - Hostname Service. Apr 19 03:03:09 raspberrypi bthelper[595]: Raspberry Pi BDADDR already set Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.4810] hostname: hostname: using hostnamed Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.4812] hostname: static hostname changed from (none) to "raspberrypi" Apr 19 03:03:09 raspberrypi systemd[1]: Finished bthelper@hci0.service - Raspberry Pi bluetooth helper. Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.4834] dns-mgr: init: dns=default,systemd-resolved rc-manager=symlink (auto) Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.4895] rfkill1: found Wi-Fi radio killswitch (at /sys/devices/platform/soc/3f300000.mmcnr/mmc_host/mmc1/mmc1:0001/mmc1:0001:1/ieee80211/phy0/r> Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.4906] manager[0x557d697000]: rfkill: Wi-Fi hardware radio set enabled Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.4908] manager[0x557d697000]: rfkill: WWAN hardware radio set enabled Apr 19 03:03:09 raspberrypi systemd[1]: Starting bluetooth.service - Bluetooth service... Apr 19 03:03:09 raspberrypi (uetoothd)[605]: ConfigurationDirectory 'bluetooth' already exists but the mode is different. (File system: 755 ConfigurationDirectoryMode: 555) Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.5778] Loaded device plugin: NMAtmManager (/usr/lib/aarch64-linux-gnu/NetworkManager/1.42.4/libnm-device-plugin-adsl.so) Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.6460] Loaded device plugin: NMTeamFactory (/usr/lib/aarch64-linux-gnu/NetworkManager/1.42.4/libnm-device-plugin-team.so) Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.7207] Loaded device plugin: NMWwanFactory (/usr/lib/aarch64-linux-gnu/NetworkManager/1.42.4/libnm-device-plugin-wwan.so) Apr 19 03:03:09 raspberrypi sshd[606]: Server listening on 0.0.0.0 port 22. Apr 19 03:03:09 raspberrypi sshd[606]: Server listening on :: port 22. Apr 19 03:03:09 raspberrypi systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.8180] Loaded device plugin: NMBluezManager (/usr/lib/aarch64-linux-gnu/NetworkManager/1.42.4/libnm-device-plugin-bluetooth.so) Apr 19 03:03:09 raspberrypi bluetoothd[605]: Bluetooth daemon 5.66 Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.8718] Loaded device plugin: NMWifiFactory (/usr/lib/aarch64-linux-gnu/NetworkManager/1.42.4/libnm-device-plugin-wifi.so) Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.8728] manager: rfkill: Wi-Fi enabled by radio killswitch; enabled by state file Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.8731] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.8733] manager: Networking is enabled by state file Apr 19 03:03:09 raspberrypi dbus-daemon[435]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.7' (uid=0 pid=487 c> Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.9020] settings: Loaded settings plugin: ifupdown ("/usr/lib/aarch64-linux-gnu/NetworkManager/1.42.4/libnm-settings-plugin-ifupdown.so") Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.9022] settings: Loaded settings plugin: keyfile (internal) Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.9022] ifupdown: management mode: unmanaged Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.9024] ifupdown: interface-parser: parsing file /etc/network/interfaces Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.9025] ifupdown: interface-parser: source line includes interfaces file(s) /etc/network/interfaces.d/* Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.9026] ifupdown: interfaces file /etc/network/interfaces.d/* doesn't exist Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.9027] ifupdown: interface-parser: finished parsing file /etc/network/interfaces Apr 19 03:03:09 raspberrypi bluetoothd[605]: Starting SDP server Apr 19 03:03:09 raspberrypi systemd[1]: Starting NetworkManager-dispatcher.service - Network Manager Script Dispatcher Service... Apr 19 03:03:09 raspberrypi systemd[1]: Started bluetooth.service - Bluetooth service. Apr 19 03:03:09 raspberrypi systemd[1]: Reached target bluetooth.target - Bluetooth Support. Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.9529] dhcp: init: Using DHCP client 'internal' Apr 19 03:03:09 raspberrypi NetworkManager[487]: <info> [1713488589.9549] manager: (lo): new Loopback device (/org/freedesktop/NetworkManager/Devices/1) Apr 19 03:03:09 raspberrypi dbus-daemon[435]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' Apr 19 03:03:09 raspberrypi systemd[1]: Started NetworkManager-dispatcher.service - Network Manager Script Dispatcher Service. Apr 19 03:03:09 raspberrypi bluetoothd[605]: profiles/audio/vcp.c:vcp_init() D-Bus experimental not enabled Apr 19 03:03:09 raspberrypi bluetoothd[605]: src/plugin.c:plugin_init() Failed to init vcp plugin Apr 19 03:03:09 raspberrypi bluetoothd[605]: profiles/audio/mcp.c:mcp_init() D-Bus experimental not enabled Apr 19 03:03:09 raspberrypi bluetoothd[605]: src/plugin.c:plugin_init() Failed to init mcp plugin Apr 19 03:03:09 raspberrypi bluetoothd[605]: profiles/audio/bap.c:bap_init() D-Bus experimental not enabled Apr 19 03:03:09 raspberrypi bluetoothd[605]: src/plugin.c:plugin_init() Failed to init bap plugin Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.0090] device (lo): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.0123] device (lo): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.0174] device (lo): Activation: starting connection 'lo' (6cd4cb79-7e66-4790-ab2c-494b82258b3d) Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.0233] manager: (eth0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2) Apr 19 03:03:10 raspberrypi systemd[1]: Started ModemManager.service - Modem Manager. Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.0336] settings: (eth0): created default wired connection 'Wired connection 1' Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.0339] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 19 03:03:10 raspberrypi kernel: Bluetooth: BNEP filters: protocol multicast Apr 19 03:03:10 raspberrypi kernel: Bluetooth: BNEP socket layer initialized Apr 19 03:03:10 raspberrypi kernel: lan78xx 1-1.1.1:1.0 eth0: kevent 4 may have been dropped Apr 19 03:03:10 raspberrypi kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Apr 19 03:03:10 raspberrypi bluetoothd[605]: Bluetooth management interface 1.22 initialized Apr 19 03:03:10 raspberrypi kernel: Bluetooth: MGMT ver 1.22 Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.0646] device (wlan0): driver supports Access Point (AP) mode Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.0738] manager: (wlan0): new 802.11 Wi-Fi device (/org/freedesktop/NetworkManager/Devices/3) Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.0761] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi kernel: NET: Registered PF_ALG protocol family Apr 19 03:03:10 raspberrypi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1395] device (lo): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1404] device (lo): state change: prepare -> config (reason 'none', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1425] device (lo): state change: config -> ip-config (reason 'none', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1449] device (eth0): carrier: link connected Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1467] device (lo): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1491] device (eth0): state change: unavailable -> disconnected (reason 'carrier-changed', sys-iface-state: 'managed') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1517] policy: auto-activating connection 'Wired connection 1' (116b7ad5-8446-3bc1-9bbf-4b02907f0584) Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1534] device (eth0): Activation: starting connection 'Wired connection 1' (116b7ad5-8446-3bc1-9bbf-4b02907f0584) Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1539] device (eth0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1569] manager: NetworkManager state is now CONNECTING Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1604] device (eth0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1740] modem-manager: ModemManager available Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1751] device (eth0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1780] dhcp4 (eth0): activation: beginning transaction (timeout in 45 seconds) Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.1938] device (lo): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.2064] device (lo): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.2093] device (lo): Activation: successful, device activated. Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.2124] dhcp4 (eth0): state changed new lease, address=192.168.178.66 Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.2142] policy: set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS Apr 19 03:03:10 raspberrypi avahi-daemon[429]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.66. Apr 19 03:03:10 raspberrypi avahi-daemon[429]: New relevant interface eth0.IPv4 for mDNS. Apr 19 03:03:10 raspberrypi avahi-daemon[429]: Registering new address record for 192.168.178.66 on eth0.IPv4. Apr 19 03:03:10 raspberrypi avahi-daemon[429]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::140a:f456:aefb:c0e6. Apr 19 03:03:10 raspberrypi avahi-daemon[429]: New relevant interface eth0.IPv6 for mDNS. Apr 19 03:03:10 raspberrypi avahi-daemon[429]: Registering new address record for fe80::140a:f456:aefb:c0e6 on eth0.*. Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.3402] device (eth0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed') Apr 19 03:03:10 raspberrypi bluetoothd[605]: profiles/sap/server.c:sap_server_register() Sap driver initialization failed. Apr 19 03:03:10 raspberrypi bluetoothd[605]: sap-server: Operation not permitted (1) Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.3847] device (eth0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.3855] device (eth0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.3872] manager: NetworkManager state is now CONNECTED_SITE Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.3885] device (eth0): Activation: successful, device activated. Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.3905] manager: NetworkManager state is now CONNECTED_GLOBAL Apr 19 03:03:10 raspberrypi bluetoothd[605]: Failed to set privacy: Rejected (0x0b) Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4679] device (wlan0): supplicant interface state: internal-starting -> disconnected Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4685] Wi-Fi P2P device controlled by interface wlan0 created Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4700] manager: (p2p-dev-wlan0): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/4) Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4712] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4744] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4769] device (p2p-dev-wlan0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed') Apr 19 03:03:12 raspberrypi NetworkManager[487]: <info> [1713488592.0018] dhcp6 (eth0): activation: beginning transaction (timeout in 45 seconds) Apr 19 03:03:12 raspberrypi NetworkManager[487]: <info> [1713488592.0039] policy: set 'Wired connection 1' (eth0) as default for IPv6 routing and DNS Apr 19 03:03:12 raspberrypi avahi-daemon[429]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::140a:f456:aefb:c0e6. Apr 19 03:03:12 raspberrypi avahi-daemon[429]: Joining mDNS multicast group on interface eth0.IPv6 with address 2001:9e8:af9f:dc00:ecba:10c1:87dd:e02d. Apr 19 03:03:12 raspberrypi NetworkManager[487]: <info> [1713488592.0231] dhcp6 (eth0): state changed new lease Apr 19 03:03:12 raspberrypi avahi-daemon[429]: Registering new address record for 2001:9e8:af9f:dc00:ecba:10c1:87dd:e02d on eth0.*. Apr 19 03:03:12 raspberrypi avahi-daemon[429]: Withdrawing address record for fe80::140a:f456:aefb:c0e6 on eth0. Apr 19 03:03:12 raspberrypi ModemManager[502]: <info> [base-manager] couldn't check support for device '/sys/devices/platform/soc/3f300000.mmcnr/mmc_host/mmc1/mmc1:0001/mmc1:0001:1': not supported by any plugin Apr 19 03:03:12 raspberrypi ModemManager[502]: <info> [base-manager] couldn't check support for device '/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.1': not supported by any plugin Apr 19 03:03:13 raspberrypi systemd[1]: systemd-rfkill.service: Deactivated successfully. Apr 19 03:03:14 raspberrypi bluetoothd[605]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Apr 19 03:03:14 raspberrypi bthelper[682]: Changing power off succeeded Apr 19 03:03:14 raspberrypi bthelper[603]: Changing power on succeeded Apr 19 03:03:17 raspberrypi NetworkManager[487]: <info> [1713488597.2141] manager: startup complete Apr 19 03:03:17 raspberrypi NetworkManager[487]: <info> [1713488597.2141] manager: startup complete Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.3905] manager: NetworkManager state is now CONNECTED_GLOBAL Apr 19 03:03:10 raspberrypi bluetoothd[605]: Failed to set privacy: Rejected (0x0b) Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4679] device (wlan0): supplicant interface state: internal-starting -> disconnected Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4685] Wi-Fi P2P device controlled by interface wlan0 created Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4700] manager: (p2p-dev-wlan0): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/4) Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4712] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4744] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed') Apr 19 03:03:10 raspberrypi NetworkManager[487]: <info> [1713488590.4769] device (p2p-dev-wlan0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed') Apr 19 03:03:12 raspberrypi NetworkManager[487]: <info> [1713488592.0018] dhcp6 (eth0): activation: beginning transaction (timeout in 45 seconds) Apr 19 03:03:12 raspberrypi NetworkManager[487]: <info> [1713488592.0039] policy: set 'Wired connection 1' (eth0) as default for IPv6 routing and DNS Apr 19 03:03:12 raspberrypi avahi-daemon[429]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::140a:f456:aefb:c0e6. Apr 19 03:03:12 raspberrypi avahi-daemon[429]: Joining mDNS multicast group on interface eth0.IPv6 with address 2001:9e8:af9f:dc00:ecba:10c1:87dd:e02d. Apr 19 03:03:12 raspberrypi NetworkManager[487]: <info> [1713488592.0231] dhcp6 (eth0): state changed new lease Apr 19 03:03:12 raspberrypi avahi-daemon[429]: Registering new address record for 2001:9e8:af9f:dc00:ecba:10c1:87dd:e02d on eth0.*. Apr 19 03:03:12 raspberrypi avahi-daemon[429]: Withdrawing address record for fe80::140a:f456:aefb:c0e6 on eth0. Apr 19 03:03:12 raspberrypi ModemManager[502]: <info> [base-manager] couldn't check support for device '/sys/devices/platform/soc/3f300000.mmcnr/mmc_host/mmc1/mmc1:0001/mmc1:0001:1': not supported by any plugin Apr 19 03:03:12 raspberrypi ModemManager[502]: <info> [base-manager] couldn't check support for device '/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.1': not supported by any plugin Apr 19 03:03:13 raspberrypi systemd[1]: systemd-rfkill.service: Deactivated successfully. Apr 19 03:03:14 raspberrypi bluetoothd[605]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Apr 19 03:03:14 raspberrypi bthelper[682]: Changing power off succeeded Apr 19 03:03:14 raspberrypi bthelper[603]: Changing power on succeeded Apr 19 03:03:17 raspberrypi NetworkManager[487]: <info> [1713488597.2141] manager: startup complete Apr 19 03:03:17 raspberrypi systemd[1]: Finished NetworkManager-wait-online.service - Network Manager Wait Online. Apr 19 03:03:17 raspberrypi systemd[1]: Reached target network-online.target - Network is Online. Apr 19 03:03:17 raspberrypi systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Apr 19 03:03:17 raspberrypi systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Apr 19 03:03:17 raspberrypi rc.local[687]: My IP address is 192.168.178.66 2001:9e8:af9f:dc00:ecba:10c1:87dd:e02d Apr 19 03:03:17 raspberrypi systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Apr 19 03:03:17 raspberrypi sm-notify[688]: Version 2.6.2 starting Apr 19 03:03:17 raspberrypi systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 19 03:03:17 raspberrypi systemd[1]: Reached target getty.target - Login Prompts. Apr 19 03:03:17 raspberrypi systemd[1]: Reached target multi-user.target - Multi-User System. Apr 19 03:03:17 raspberrypi systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Apr 19 03:03:17 raspberrypi systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Apr 19 03:03:17 raspberrypi systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Apr 19 03:03:17 raspberrypi systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Apr 19 03:03:17 raspberrypi systemd[1]: Startup finished in 6.030s (kernel) + 15.920s (userspace) = 21.951s. Apr 19 03:03:17 raspberrypi bash[560]: ================================== > LOG REDIRECT system.adapter.admin.0 => true [starting] Apr 19 03:03:18 raspberrypi bash[560]: host.raspberrypi check instance "system.adapter.admin.0" for host "raspberrypi" Apr 19 03:03:18 raspberrypi bash[560]: host.raspberrypi check instance "system.adapter.node-red.0" for host "raspberrypi" Apr 19 03:03:18 raspberrypi bash[560]: host.raspberrypi check instance "system.adapter.javascript.0" for host "raspberrypi" Apr 19 03:03:18 raspberrypi bash[560]: host.raspberrypi check instance "system.adapter.backitup.0" for host "raspberrypi" Apr 19 03:03:18 raspberrypi bash[560]: host.raspberrypi check instance "system.adapter.mqtt.0" for host "raspberrypi" Apr 19 03:03:18 raspberrypi bash[560]: host.raspberrypi check instance "system.adapter.discovery.0" for host "raspberrypi" Apr 19 03:03:18 raspberrypi bash[560]: host.raspberrypi check instance "system.adapter.wled.0" for host "raspberrypi" Apr 19 03:03:18 raspberrypi bash[560]: host.raspberrypi check instance "system.adapter.web.0" for host "raspberrypi" Apr 19 03:03:22 raspberrypi systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully. Apr 19 03:03:24 raspberrypi bash[560]: ================================== > LOG REDIRECT system.adapter.admin.0 => false [Process stopped] Apr 19 03:03:24 raspberrypi bash[560]: ================================== > LOG REDIRECT system.adapter.admin.0 => false [system.adapter.admin.0.logging] Apr 19 03:03:33 raspberrypi bash[560]: ================================== > LOG REDIRECT system.adapter.javascript.0 => false [Process stopped] Apr 19 03:03:33 raspberrypi bash[560]: ================================== > LOG REDIRECT system.adapter.javascript.0 => false [system.adapter.javascript.0.logging] Apr 19 03:03:35 raspberrypi systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 19 03:03:35 raspberrypi sshd[734]: Accepted password for pi from 192.168.178.91 port 54933 ssh2 Apr 19 03:03:35 raspberrypi sshd[734]: pam_unix(sshd:session): session opened for user pi(uid=1000) by (uid=0) Apr 19 03:03:36 raspberrypi systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Apr 19 03:03:36 raspberrypi systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Apr 19 03:03:36 raspberrypi systemd-logind[451]: New session 1 of user pi. Apr 19 03:03:36 raspberrypi systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Apr 19 03:03:36 raspberrypi systemd[1]: Starting user@1000.service - User Manager for UID 1000... Apr 19 03:03:36 raspberrypi (systemd)[752]: pam_unix(systemd-user:session): session opened for user pi(uid=1000) by (uid=0) Apr 19 03:03:36 raspberrypi kernel: systemd[752]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Apr 19 03:03:36 raspberrypi systemd[752]: Queued start job for default target default.target. Apr 19 03:03:36 raspberrypi systemd[752]: Created slice app.slice - User Application Slice. Apr 19 03:03:36 raspberrypi sshd[734]: pam_env(sshd:session): deprecated reading of user environment enabled Apr 19 03:03:36 raspberrypi systemd[752]: Reached target paths.target - Paths. Apr 19 03:03:36 raspberrypi systemd[752]: Reached target timers.target - Timers. Apr 19 03:03:36 raspberrypi systemd[752]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 19 03:03:36 raspberrypi systemd[752]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Apr 19 03:03:36 raspberrypi systemd[752]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Apr 19 03:03:36 raspberrypi systemd[752]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Apr 19 03:03:36 raspberrypi systemd[752]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Apr 19 03:03:36 raspberrypi systemd[752]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Apr 19 03:03:36 raspberrypi systemd[752]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 19 03:03:36 raspberrypi systemd[752]: Reached target sockets.target - Sockets. Apr 19 03:03:36 raspberrypi systemd[752]: Reached target basic.target - Basic System. Apr 19 03:03:36 raspberrypi systemd[752]: Reached target default.target - Main User Target. Apr 19 03:03:36 raspberrypi systemd[752]: Startup finished in 522ms. Apr 19 03:03:36 raspberrypi systemd[1]: Started user@1000.service - User Manager for UID 1000. Apr 19 03:03:36 raspberrypi systemd[1]: Started session-1.scope - Session 1 of User pi.@nftvirus sagte in Spontane Iobroker abstürze:
muss also hart den Strom weg nehmen
Hoffentlich nicht mit 'ner SD-Karte. Die verknusen das nämlich nicht gut.
Läuft zum Zeitpunkt des Absturzes vielleicht das Backup?
Ein Pi3 mit nur 1 GB RAM dürfte da schnell an seine Grenzen stoßen. -
@nftvirus sagte in Spontane Iobroker abstürze:
muss also hart den Strom weg nehmen
Hoffentlich nicht mit 'ner SD-Karte. Die verknusen das nämlich nicht gut.
Läuft zum Zeitpunkt des Absturzes vielleicht das Backup?
Ein Pi3 mit nur 1 GB RAM dürfte da schnell an seine Grenzen stoßen.@codierknecht leider doch mit SD karte drin.
Bzgl. Backup das ist ein spannender Hinweis. Um 2:47 heute morgen ist ein Backup gelaufen. ER ist allerdings erst um 3:03 abgestürzt.
Was ich in den letzten Tagen auch schon gesehen habe sind aber abstürze wenn er "arbeiten" muss also einmal bei nem Iob diag oder wenn ich auf den Admin zugreifen will. Hatte da ursrpünglich das Netzteil in verdacht aber das habe ich mittlerweile ausgetauscht und ein 4A Netzteil dran ohne Erfolg. Könnte es an zu langsamen SD Karten liegen? Nutze aktuell nur so günstige Lexar A1 Karten.
-
@codierknecht leider doch mit SD karte drin.
Bzgl. Backup das ist ein spannender Hinweis. Um 2:47 heute morgen ist ein Backup gelaufen. ER ist allerdings erst um 3:03 abgestürzt.
Was ich in den letzten Tagen auch schon gesehen habe sind aber abstürze wenn er "arbeiten" muss also einmal bei nem Iob diag oder wenn ich auf den Admin zugreifen will. Hatte da ursrpünglich das Netzteil in verdacht aber das habe ich mittlerweile ausgetauscht und ein 4A Netzteil dran ohne Erfolg. Könnte es an zu langsamen SD Karten liegen? Nutze aktuell nur so günstige Lexar A1 Karten.
@nftvirus Hast Du die Möglichkeit, die Dateisysteme auf der SD-Karte z. B. eingesteckt an einem externen Linux-Rechner zu testen?
Ggfs. wäre auch die Swap-Größe zu erhöhen eine Idee...
Nachtrag:
Um 2:47 heute morgen ist ein Backup gelaufen. ER ist allerdings erst um 3:03 abgestürzt.
Hmm mein Rechner ist nicht um so viel schneller, als Dein PI 4 (siehe Signatur). so sieht das Backup-Logging von heute Nacht aus - es dauert schon ein paar Minuten. 16 Minuten scheint mir aber schon recht viel. Ist das Logging des Backups in der untenstehenden Form bei Dir auch komplett vorhanden? (ist aber Debug, da muss das Loglevel des Adapters entsprechend eingestellt sein)
backitup.0 2024-04-19 02:44:33.219 debug [iobroker] exec: done backitup.0 2024-04-19 02:44:32.916 debug [iobroker/historyJSON] done backitup.0 2024-04-19 02:44:32.916 debug [iobroker/historyJSON] new history json values created backitup.0 2024-04-19 02:44:32.748 debug [iobroker/historyHTML] done backitup.0 2024-04-19 02:44:32.646 debug [iobroker/historyHTML] new history html values created backitup.0 2024-04-19 02:44:31.278 debug [iobroker/clean] done backitup.0 2024-04-19 02:44:31.267 debug [iobroker/clean] delete /opt/iobroker/backups/influxDB_2024_04_12-02_40_21_backupiobroker.tar.gz backitup.0 2024-04-19 02:44:31.262 debug [iobroker/clean] delete /opt/iobroker/backups/iobroker_2024_04_12-02_40_11_backupiobroker.tar.gz backitup.0 2024-04-19 02:44:28.092 debug [iobroker/influxDB] done backitup.0 2024-04-19 02:44:28.091 debug [iobroker/influxDB] InfluxDB-Backup for is finish backitup.0 2024-04-19 02:44:28.090 debug [iobroker/influxDB] InfluxDB tmp directory "/opt/iobroker/backups/influxDB_2024_04_19-02_40_19_backupiobroker" successfully deleted backitup.0 2024-04-19 02:44:27.916 debug [iobroker/influxDB] Try deleting the InfluxDB tmp directory: "/opt/iobroker/backups/influxDB_2024_04_19-02_40_19_backupiobroker" backitup.0 2024-04-19 02:44:27.913 debug [iobroker/influxDB] Backup created: /opt/iobroker/backups/influxDB_2024_04_19-02_40_19_backupiobroker.tar.gz backitup.0 2024-04-19 02:44:22.182 debug [iobroker/influxDB] Packed 451MB so far... backitup.0 2024-04-19 02:44:12.189 debug [iobroker/influxDB] Packed 394MB so far... backitup.0 2024-04-19 02:44:02.180 debug [iobroker/influxDB] Packed 338MB so far... backitup.0 2024-04-19 02:43:52.180 debug [iobroker/influxDB] Packed 284MB so far... backitup.0 2024-04-19 02:43:42.178 debug [iobroker/influxDB] Packed 228MB so far... backitup.0 2024-04-19 02:43:32.178 debug [iobroker/influxDB] Packed 171MB so far... backitup.0 2024-04-19 02:43:22.181 debug [iobroker/influxDB] Packed 113MB so far... backitup.0 2024-04-19 02:43:12.195 debug [iobroker/influxDB] Packed 56MB so far... backitup.0 2024-04-19 02:40:19.969 debug [iobroker/influxDB] InfluxDB Backup tmp directory created backitup.0 2024-04-19 02:40:19.964 debug [iobroker/influxDB] Start InfluxDB Backup ... backitup.0 2024-04-19 02:40:19.963 debug [iobroker/influxDB] InfluxDB-Backup started ... backitup.0 2024-04-19 02:40:19.811 debug [iobroker/iobroker] done backitup.0 2024-04-19 02:40:18.720 debug [iobroker/iobroker] This backup can only be restored with js-controller version up from 4.1 backitup.0 2024-04-19 02:40:18.720 debug [iobroker/iobroker] Backup created: /opt/iobroker/backups/iobroker_2024_04_19-02_40_10_backupiobroker.tar.gz backitup.0 2024-04-19 02:40:16.615 debug [iobroker/iobroker] host.iobroker-test-sicher 1570 objects saved backitup.0 2024-04-19 02:40:14.334 debug [iobroker/iobroker] host.iobroker-test-sicher 1207 states saved backitup.0 2024-04-19 02:40:10.065 debug Backup has started ... backitup.0 2024-04-19 02:40:10.042 debug The local storage check was completed successfully. On the ho -
@nftvirus Hast Du die Möglichkeit, die Dateisysteme auf der SD-Karte z. B. eingesteckt an einem externen Linux-Rechner zu testen?
Ggfs. wäre auch die Swap-Größe zu erhöhen eine Idee...
Nachtrag:
Um 2:47 heute morgen ist ein Backup gelaufen. ER ist allerdings erst um 3:03 abgestürzt.
Hmm mein Rechner ist nicht um so viel schneller, als Dein PI 4 (siehe Signatur). so sieht das Backup-Logging von heute Nacht aus - es dauert schon ein paar Minuten. 16 Minuten scheint mir aber schon recht viel. Ist das Logging des Backups in der untenstehenden Form bei Dir auch komplett vorhanden? (ist aber Debug, da muss das Loglevel des Adapters entsprechend eingestellt sein)
backitup.0 2024-04-19 02:44:33.219 debug [iobroker] exec: done backitup.0 2024-04-19 02:44:32.916 debug [iobroker/historyJSON] done backitup.0 2024-04-19 02:44:32.916 debug [iobroker/historyJSON] new history json values created backitup.0 2024-04-19 02:44:32.748 debug [iobroker/historyHTML] done backitup.0 2024-04-19 02:44:32.646 debug [iobroker/historyHTML] new history html values created backitup.0 2024-04-19 02:44:31.278 debug [iobroker/clean] done backitup.0 2024-04-19 02:44:31.267 debug [iobroker/clean] delete /opt/iobroker/backups/influxDB_2024_04_12-02_40_21_backupiobroker.tar.gz backitup.0 2024-04-19 02:44:31.262 debug [iobroker/clean] delete /opt/iobroker/backups/iobroker_2024_04_12-02_40_11_backupiobroker.tar.gz backitup.0 2024-04-19 02:44:28.092 debug [iobroker/influxDB] done backitup.0 2024-04-19 02:44:28.091 debug [iobroker/influxDB] InfluxDB-Backup for is finish backitup.0 2024-04-19 02:44:28.090 debug [iobroker/influxDB] InfluxDB tmp directory "/opt/iobroker/backups/influxDB_2024_04_19-02_40_19_backupiobroker" successfully deleted backitup.0 2024-04-19 02:44:27.916 debug [iobroker/influxDB] Try deleting the InfluxDB tmp directory: "/opt/iobroker/backups/influxDB_2024_04_19-02_40_19_backupiobroker" backitup.0 2024-04-19 02:44:27.913 debug [iobroker/influxDB] Backup created: /opt/iobroker/backups/influxDB_2024_04_19-02_40_19_backupiobroker.tar.gz backitup.0 2024-04-19 02:44:22.182 debug [iobroker/influxDB] Packed 451MB so far... backitup.0 2024-04-19 02:44:12.189 debug [iobroker/influxDB] Packed 394MB so far... backitup.0 2024-04-19 02:44:02.180 debug [iobroker/influxDB] Packed 338MB so far... backitup.0 2024-04-19 02:43:52.180 debug [iobroker/influxDB] Packed 284MB so far... backitup.0 2024-04-19 02:43:42.178 debug [iobroker/influxDB] Packed 228MB so far... backitup.0 2024-04-19 02:43:32.178 debug [iobroker/influxDB] Packed 171MB so far... backitup.0 2024-04-19 02:43:22.181 debug [iobroker/influxDB] Packed 113MB so far... backitup.0 2024-04-19 02:43:12.195 debug [iobroker/influxDB] Packed 56MB so far... backitup.0 2024-04-19 02:40:19.969 debug [iobroker/influxDB] InfluxDB Backup tmp directory created backitup.0 2024-04-19 02:40:19.964 debug [iobroker/influxDB] Start InfluxDB Backup ... backitup.0 2024-04-19 02:40:19.963 debug [iobroker/influxDB] InfluxDB-Backup started ... backitup.0 2024-04-19 02:40:19.811 debug [iobroker/iobroker] done backitup.0 2024-04-19 02:40:18.720 debug [iobroker/iobroker] This backup can only be restored with js-controller version up from 4.1 backitup.0 2024-04-19 02:40:18.720 debug [iobroker/iobroker] Backup created: /opt/iobroker/backups/iobroker_2024_04_19-02_40_10_backupiobroker.tar.gz backitup.0 2024-04-19 02:40:16.615 debug [iobroker/iobroker] host.iobroker-test-sicher 1570 objects saved backitup.0 2024-04-19 02:40:14.334 debug [iobroker/iobroker] host.iobroker-test-sicher 1207 states saved backitup.0 2024-04-19 02:40:10.065 debug Backup has started ... backitup.0 2024-04-19 02:40:10.042 debug The local storage check was completed successfully. On the ho -
@martinp ich habe zwei Linux Rechner da könnte ich also machen, kenne mich damit aber nicht wirklich aus wie ich das mit einer externen SD mache. Hast du einen Tip für mich?
-
@martinp Korrektur: Es wurde keine Backup Datei erstellt. hab mich im Datum vertan die letzte ist 2 Tage alt.
Nachtrag: du hast es ja schon geschrieben der Adapter müsste entsprechend eingestellt sein war er nicht.
2024-04-19 02:27:05.989 - info: node-red.0 (705) Node-RED: 19 Apr 02:27:05 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/a20684bd091b8670 2024-04-19 02:28:54.270 - info: node-red.0 (705) Node-RED: 19 Apr 02:28:54 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/7dd22e1522048751 2024-04-19 02:29:22.368 - info: node-red.0 (705) Node-RED: 19 Apr 02:29:22 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/79de3cd214269524 2024-04-19 02:29:53.100 - info: node-red.0 (705) Node-RED: 19 Apr 02:29:53 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/858073bd75b4350e 2024-04-19 02:31:26.166 - info: node-red.0 (705) Node-RED: 19 Apr 02:31:26 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/57a155939f442889 2024-04-19 02:31:32.977 - info: node-red.0 (705) Node-RED: 19 Apr 02:31:32 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/110ebe86e58f5fe5 2024-04-19 02:32:09.244 - info: node-red.0 (705) Node-RED: 19 Apr 02:32:09 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/5e160bb73797d7c9 2024-04-19 02:33:15.992 - info: node-red.0 (705) Node-RED: 19 Apr 02:33:15 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/339edf9418195951 2024-04-19 02:37:06.046 - info: node-red.0 (705) Node-RED: 19 Apr 02:37:06 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/a20684bd091b8670 2024-04-19 02:38:28.430 - info: node-red.0 (705) Node-RED: 19 Apr 02:38:28 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/79de3cd214269524 2024-04-19 02:38:48.304 - info: node-red.0 (705) Node-RED: 19 Apr 02:38:48 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/7dd22e1522048751 2024-04-19 02:39:51.130 - info: node-red.0 (705) Node-RED: 19 Apr 02:39:51 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/858073bd75b4350e 2024-04-19 02:41:05.008 - info: node-red.0 (705) Node-RED: 19 Apr 02:41:05 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/110ebe86e58f5fe5 2024-04-19 02:41:17.281 - info: node-red.0 (705) Node-RED: 19 Apr 02:41:17 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/5e160bb73797d7c9 2024-04-19 02:41:23.210 - info: node-red.0 (705) Node-RED: 19 Apr 02:41:23 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/57a155939f442889 2024-04-19 02:42:22.032 - info: node-red.0 (705) Node-RED: 19 Apr 02:42:22 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/339edf9418195951 2024-04-19 02:43:24.334 - info: host.raspberrypi Delete log file iobroker.2024-04-11.log.gz 2024-04-19 02:46:33.080 - info: node-red.0 (705) Node-RED: 19 Apr 02:46:33 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/a20684bd091b8670 2024-04-19 02:48:07.476 - info: node-red.0 (705) Node-RED: 19 Apr 02:48:07 - [info] [alexa-home-controller:Alexa Controller] Request data: 192.168.178.155-80/GET -> /api/c6260f982b43a226b5542b967f612ce/lights/79de3cd214269524 2024-04-19 03:03:15.640 - info: host.raspberrypi iobroker.js-controller version 5.0.19 js-controller starting 2024-04-19 03:03:15.652 - info: host.raspberrypi Copyright (c) 2014-2023 bluefox, 2014 hobbyquaker 2024-04-19 03:03:15.653 - info: host.raspberrypi hostname: raspberrypi, node: v18.20.1 2024-04-19 03:03:15.655 - info: host.raspberrypi ip addresses: 192.168.178.66 2001:9e8:af9f:dc00:ecba:10c1:87dd:e02d fe80::140a:f456:aefb:c0e6 2024-04-19 03:03:17.746 - info: host.raspberrypi connected to Objects and States 2024-04-19 03:03:17.810 - info: host.raspberrypi added notifications configuration of host 2024-04-19 03:03:18.948 - info: host.raspberrypi Delete state "system.host.raspberrypi.versions.nodeCurrent" 2024-04-19 03:03:18.952 - info: host.raspberrypi 8 instances found 2024-04-19 03:03:19.001 - info: host.raspberrypi starting 8 instances 2024-04-19 03:03:19.135 - info: host.raspberrypi Delete state "system.host.raspberrypi.versions.nodeNewest" 2024-04-19 03:03:19.192 - info: host.raspberrypi Delete state "system.host.raspberrypi.versions.nodeNewestNext" 2024-04-19 03:03:19.260 - info: host.raspberrypi instance system.adapter.admin.0 started with pid 698 2024-04-19 03:03:19.280 - info: host.raspberrypi Delete state "system.host.raspberrypi.versions.npmCurrent" 2024-04-19 03:03:19.339 - info: host.raspberrypi Delete state "system.host.raspberrypi.versions.npmNewest" 2024-04-19 03:03:19.400 - info: host.raspberrypi Delete state "system.host.raspberrypi.versions.npmNewestNext" 2024-04-19 03:03:19.459 - info: host.raspberrypi Some obsolete host states deleted. 2024-04-19 03:03:23.106 - info: host.raspberrypi instance system.adapter.node-red.0 started with pid 709 2024-04-19 03:03:23.594 - error: admin.0 (698) admin.0 already running 2024-04-19 03:03:23.603 - warn: admin.0 (698) Terminated (ADAPTER_ALREADY_RUNNING): Without reason 2024-04-19 03:03:24.363 - error: host.raspberrypi instance system.adapter.admin.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING) 2024-04-19 03:03:24.365 - info: host.raspberrypi Restart adapter system.adapter.admin.0 because enabled 2024-04-19 03:03:26.159 - error: node-red.0 (709) node-red.0 already running 2024-04-19 03:03:26.165 - warn: node-red.0 (709) Terminated (ADAPTER_ALREADY_RUNNING): Without reason 2024-04-19 03:03:26.745 - error: host.raspberrypi instance system.adapter.node-red.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING) 2024-04-19 03:03:26.747 - info: host.raspberrypi Restart adapter system.adapter.node-red.0 because enabled 2024-04-19 03:03:27.056 - info: host.raspberrypi instance system.adapter.javascript.0 started with pid 720 2024-04-19 03:03:31.055 - info: host.raspberrypi instance system.adapter.backitup.0 started with pid 727 2024-04-19 03:03:33.214 - error: javascript.0 (720) javascript.0 already running 2024-04-19 03:03:33.227 - warn: javascript.0 (720) Terminated (ADAPTER_ALREADY_RUNNING): Without reason 2024-04-19 03:03:33.863 - error: host.raspberrypi instance system.adapter.javascript.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING) 2024-04-19 03:03:33.865 - info: host.raspberrypi Restart adapter system.adapter.javascript.0 because enabled 2024-04-19 03:03:34.173 - error: backitup.0 (727) backitup.0 already running 2024-04-19 03:03:34.180 - warn: backitup.0 (727) Terminated (ADAPTER_ALREADY_RUNNING): Without reason 2024-04-19 03:03:34.772 - error: host.raspberrypi instance system.adapter.backitup.0 terminated with code 7 (ADAPTER_ALREADY_RUNNING) 2024-04-19 03:03:34.773 - info: host.raspberrypi Restart adapter system.adapter.backitup.0 because enabled 2024-04-19 03:03:35.057 - info: host.raspberrypi instance system.adapter.mqtt.0 started with pid 744 2024-04-19 07:26:22.454 - error: mqtt.0 (744) mqtt.0 already running -
@nftvirus Hast Du die Möglichkeit, die Dateisysteme auf der SD-Karte z. B. eingesteckt an einem externen Linux-Rechner zu testen?
Ggfs. wäre auch die Swap-Größe zu erhöhen eine Idee...
Nachtrag:
Um 2:47 heute morgen ist ein Backup gelaufen. ER ist allerdings erst um 3:03 abgestürzt.
Hmm mein Rechner ist nicht um so viel schneller, als Dein PI 4 (siehe Signatur). so sieht das Backup-Logging von heute Nacht aus - es dauert schon ein paar Minuten. 16 Minuten scheint mir aber schon recht viel. Ist das Logging des Backups in der untenstehenden Form bei Dir auch komplett vorhanden? (ist aber Debug, da muss das Loglevel des Adapters entsprechend eingestellt sein)
backitup.0 2024-04-19 02:44:33.219 debug [iobroker] exec: done backitup.0 2024-04-19 02:44:32.916 debug [iobroker/historyJSON] done backitup.0 2024-04-19 02:44:32.916 debug [iobroker/historyJSON] new history json values created backitup.0 2024-04-19 02:44:32.748 debug [iobroker/historyHTML] done backitup.0 2024-04-19 02:44:32.646 debug [iobroker/historyHTML] new history html values created backitup.0 2024-04-19 02:44:31.278 debug [iobroker/clean] done backitup.0 2024-04-19 02:44:31.267 debug [iobroker/clean] delete /opt/iobroker/backups/influxDB_2024_04_12-02_40_21_backupiobroker.tar.gz backitup.0 2024-04-19 02:44:31.262 debug [iobroker/clean] delete /opt/iobroker/backups/iobroker_2024_04_12-02_40_11_backupiobroker.tar.gz backitup.0 2024-04-19 02:44:28.092 debug [iobroker/influxDB] done backitup.0 2024-04-19 02:44:28.091 debug [iobroker/influxDB] InfluxDB-Backup for is finish backitup.0 2024-04-19 02:44:28.090 debug [iobroker/influxDB] InfluxDB tmp directory "/opt/iobroker/backups/influxDB_2024_04_19-02_40_19_backupiobroker" successfully deleted backitup.0 2024-04-19 02:44:27.916 debug [iobroker/influxDB] Try deleting the InfluxDB tmp directory: "/opt/iobroker/backups/influxDB_2024_04_19-02_40_19_backupiobroker" backitup.0 2024-04-19 02:44:27.913 debug [iobroker/influxDB] Backup created: /opt/iobroker/backups/influxDB_2024_04_19-02_40_19_backupiobroker.tar.gz backitup.0 2024-04-19 02:44:22.182 debug [iobroker/influxDB] Packed 451MB so far... backitup.0 2024-04-19 02:44:12.189 debug [iobroker/influxDB] Packed 394MB so far... backitup.0 2024-04-19 02:44:02.180 debug [iobroker/influxDB] Packed 338MB so far... backitup.0 2024-04-19 02:43:52.180 debug [iobroker/influxDB] Packed 284MB so far... backitup.0 2024-04-19 02:43:42.178 debug [iobroker/influxDB] Packed 228MB so far... backitup.0 2024-04-19 02:43:32.178 debug [iobroker/influxDB] Packed 171MB so far... backitup.0 2024-04-19 02:43:22.181 debug [iobroker/influxDB] Packed 113MB so far... backitup.0 2024-04-19 02:43:12.195 debug [iobroker/influxDB] Packed 56MB so far... backitup.0 2024-04-19 02:40:19.969 debug [iobroker/influxDB] InfluxDB Backup tmp directory created backitup.0 2024-04-19 02:40:19.964 debug [iobroker/influxDB] Start InfluxDB Backup ... backitup.0 2024-04-19 02:40:19.963 debug [iobroker/influxDB] InfluxDB-Backup started ... backitup.0 2024-04-19 02:40:19.811 debug [iobroker/iobroker] done backitup.0 2024-04-19 02:40:18.720 debug [iobroker/iobroker] This backup can only be restored with js-controller version up from 4.1 backitup.0 2024-04-19 02:40:18.720 debug [iobroker/iobroker] Backup created: /opt/iobroker/backups/iobroker_2024_04_19-02_40_10_backupiobroker.tar.gz backitup.0 2024-04-19 02:40:16.615 debug [iobroker/iobroker] host.iobroker-test-sicher 1570 objects saved backitup.0 2024-04-19 02:40:14.334 debug [iobroker/iobroker] host.iobroker-test-sicher 1207 states saved backitup.0 2024-04-19 02:40:10.065 debug Backup has started ... backitup.0 2024-04-19 02:40:10.042 debug The local storage check was completed successfully. On the ho -
@nftvirus möglicherweise ist der Pi in den Situationen, in Denen Du glaubst, er sei abgestürzt auch nur bis zum Anschlag beschäftigt. Sobald der Speicher nicht mehr ausreicht, muss geswappt werden, und die Performance sinkt auf Zeitlupe ...
Manchmal behebt sich der Speicher-Engpass von selber, wenn die entsprechende verursachende Aktion zu Ende gebracht worden ist ... dann ist der Pi auch wieder ansprechbar ...Läuft da noch irgendetwas auf dem System (influx, grafana?), das man temporär stillegen könnte?
1 GB sind wirklich wenig RAM
-
@nftvirus möglicherweise ist der Pi in den Situationen, in Denen Du glaubst, er sei abgestürzt auch nur bis zum Anschlag beschäftigt. Sobald der Speicher nicht mehr ausreicht, muss geswappt werden, und die Performance sinkt auf Zeitlupe ...
Manchmal behebt sich der Speicher-Engpass von selber, wenn die entsprechende verursachende Aktion zu Ende gebracht worden ist ... dann ist der Pi auch wieder ansprechbar ...Läuft da noch irgendetwas auf dem System (influx, grafana?), das man temporär stillegen könnte?
1 GB sind wirklich wenig RAM
@martinp Könnte Node red mal abschalten das kommuniziert mit Alexa aber ansonsten läuft aktuell nur noch der MQTT Broker und Backitup und der WLED Adapter. Aber es sind tatsächlich so im "Leerlauf" nur noch 165mb freier Arbeitsspeicher. Sollte vielleicht doch mal über nen neuen RPi 5 nachdenken.
Und wegen nur sehr langsam: Im journal file steht zwischen 3:03 heute morgen und dem Neustart heute morgen um 7: ... garnichts drin da müsste sonst ja hin und wieder irgendwas geloggt sein wenn er was tun würde.
-
@nftvirus sagte in Spontane Iobroker abstürze:
@martinp ist nur ein RPI3b+ kein 4er
und 11 Instanzen wenn ich es richtig gezählt habe?
Das ist schon an der Grenze für 1GB
-
@martinp Könnte Node red mal abschalten das kommuniziert mit Alexa aber ansonsten läuft aktuell nur noch der MQTT Broker und Backitup und der WLED Adapter. Aber es sind tatsächlich so im "Leerlauf" nur noch 165mb freier Arbeitsspeicher. Sollte vielleicht doch mal über nen neuen RPi 5 nachdenken.
Und wegen nur sehr langsam: Im journal file steht zwischen 3:03 heute morgen und dem Neustart heute morgen um 7: ... garnichts drin da müsste sonst ja hin und wieder irgendwas geloggt sein wenn er was tun würde.
@nftvirus said in Spontane Iobroker abstürze:
Im journal file steht zwischen 3:03 heute morgen und dem Neustart heute morgen um 7: ... garnichts drin da müsste sonst ja hin und wieder irgendwas geloggt sein wenn er was tun würde.
Ja, dass ist deutlich zu lang für eine "Denkpause"... ausgelöst durch Swapping
-
@nftvirus sagte in Spontane Iobroker abstürze:
@martinp ist nur ein RPI3b+ kein 4er
und 11 Instanzen wenn ich es richtig gezählt habe?
Das ist schon an der Grenze für 1GB
882
Online32.6k
Benutzer81.9k
Themen1.3m
Beiträge