RPi mit iobroker (slave) stürzt regelmässig ab
-
Systemdata Bitte Ausfüllen Hardwaresystem: RPi 3 Arbeitsspeicher: 1GB Festplattenart: USB Stick Betriebssystem: Raspbian 9 Node-Version: 12.16.3 Nodejs-Version: 12.16.3 NPM-Version: 6.1.4 Hallo,
bei mir läuft ein Multihost system mit einem slave. Genau dieser slave hängt sich seit einiger Zeit so ca. 1x im Monat auf. Der RPi ist dann gar nicht mehr erreichbar und es hilft nur ein Neustart durch Trennung vom Strom. Der Master läuft hingegen sehr stabil und stürzt fast nie ab.
Laut log des slaves scheint der Absturz immer so gegen 4-5Uhr nachts zu erfolgen. Da meine Internet Zwangstrennung auch zu dieser Zeit erfolgt, hatte ich mal an einen Zusammenhang gedacht. Im letzten Fall (s. log) war die Zwangstrennung allerdings um 4.17Uhr, wie man ja auch am Alexa Eintrag sehen kann. Allerdings lief das System ja noch bis 4.54Uhr.
Scheinbar wurde der Absturz ausgelöst durch einen Neustart des Controllers um 04:54:30. Was kann der Auslöser dafür sein bzw. wie finde ich den?
Da es doch unschön ist, wenn auf einmal b.B. die Rollos nicht mehr hochgehen wollte ich gerne der Sache mal auf den Grund gehen.
Hier ist der letzte log
2021-01-31 04:03:21.756 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:08:21.761 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:13:21.767 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:17:20.682 - [32minfo[39m: alexa2.0 (22783) Alexa-Push-Connection disconnected - retry: Retry Connection in 20s 2021-01-31 04:17:41.518 - [32minfo[39m: alexa2.0 (22783) Alexa-Push-Connection established. Disable Polling 2021-01-31 04:18:21.773 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:23:21.778 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:28:21.784 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:33:21.789 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:38:21.798 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:43:21.803 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:48:21.813 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:53:21.821 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:58:21.830 - [32minfo[39m: tankerkoenig.0 (1305) Reading data from tankerkoenig ... 2021-01-31 04:54:30.956 - [32minfo[39m: host.raspi-easymeter iobroker.js-controller version 3.1.6 js-controller starting 2021-01-31 04:54:30.966 - [32minfo[39m: host.raspi-easymeter Copyright (c) 2014-2020 bluefox, 2014 hobbyquaker 2021-01-31 04:54:30.968 - [32minfo[39m: host.raspi-easymeter hostname: raspi-easymeter, node: v12.16.3 2021-01-31 04:54:30.971 - [32minfo[39m: host.raspi-easymeter ip addresses: 2021-01-31 04:54:31.574 - [32minfo[39m: host.raspi-easymeter connected to Objects and States 2021-01-31 04:54:33.106 - [32minfo[39m: host.raspi-easymeter 32 instances found 2021-01-31 04:54:33.195 - [32minfo[39m: host.raspi-easymeter starting 9 instances 2021-01-31 04:54:35.636 - [32minfo[39m: host.raspi-easymeter instance system.adapter.javascript.1 started with pid 672 2021-01-31 04:54:38.623 - [32minfo[39m: host.raspi-easymeter instance system.adapter.yahka.0 started with pid 679 2021-01-31 04:54:41.248 - [32minfo[39m: host.raspi-easymeter instance system.adapter.fritzbox.0 started with pid 694 2021-01-31 04:54:43.117 - [32minfo[39m: yahka.0 (679) starting. Version 0.11.0 in /opt/iobroker/node_modules/iobroker.yahka, node: v12.16.3, js-controller: 3.1.6 2021-01-31 04:54:43.194 - [32minfo[39m: yahka.0 (679) adapter ready, checking config 2021-01-31 04:54:43.214 - [32minfo[39m: yahka.0 (679) adding Garagentor öffnen 2021-01-31 04:54:43.232 - [32minfo[39m: yahka.0 (679) adding Licht Haustür 2021-01-31 04:54:43.240 - [32minfo[39m: yahka.0 (679) adding Rollo Arbeitszimmer 2021-01-31 04:54:43.264 - [32minfo[39m: yahka.0 (679) adding Rollo Kueche 2021-01-31 04:54:43.280 - [32minfo[39m: yahka.0 (679) adding Rollo klein -
@thormann Bei Deiner Problembeschreibung solltest Du mal den syslog zu dieser Uhrzeit untersuchen.
Du sagtest ja, dass der Pi tot ist (lässt sich nicht mehr ansprechen). -
@meister-mopper
O.K., danke für den Hinweis.Allerdings werde ich da nicht schlau draus. Ich hab den relevanten Teil der syslog bis zum Ende mal angehangen:
Jan 31 04:49:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:50:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:51:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:52:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:53:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:54:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:55:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:56:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:57:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:58:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:58:12 raspi-easymeter bash[1235]: Sun, 31 Jan 2021 03:58:12 GMT EventedHTTPServer [::ffff:192.168.1.36] Sending HTTP event 'keepalive' with data: {"characteristics":[]} Jan 31 04:58:12 raspi-easymeter bash[1235]: Sun, 31 Jan 2021 03:58:12 GMT EventedHTTPServer [::ffff:192.168.1.44] Sending HTTP event 'keepalive' with data: {"characteristics":[]} Jan 31 04:59:09 raspi-easymeter bash[1235]: cat: /sys/devices/system/cpu/cpu0/cpufreq/cpuinfo_cur_freq: Permission denied Jan 31 04:54:20 raspi-easymeter fake-hwclock[92]: Sun 31 Jan 03:17:01 UTC 2021 Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Create Static Device Nodes in /dev. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Booting Linux on physical CPU 0x0 Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting udev Kernel Device Manager... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Linux version 4.19.66-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1253 SMP Thu Aug 15 11:49:46 BST 2019 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] CPU: div instructions available: patching division code Jan 31 04:54:20 raspi-easymeter systemd-fsck[128]: e2fsck 1.43.4 (31-Jan-2017) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Jan 31 04:54:20 raspi-easymeter systemd-fsck[128]: rootfs: clean, 124359/237120 files, 731220/958464 blocks Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Memory policy: Data cache writealloc Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] cma: Reserved 8 MiB at 0x3ac00000 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] On node 0 totalpages: 242688 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Normal zone: 2133 pages used for memmap Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Normal zone: 0 pages reserved Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Normal zone: 242688 pages, LIFO batch:63 Jan 31 04:54:20 raspi-easymeter systemd[1]: Started File System Check on Root Device. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] random: get_random_bytes called from start_kernel+0xac/0x4b4 with crng_init=0 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] percpu: Embedded 17 pages/cpu s39488 r8192 d21952 u69632 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] pcpu-alloc: s39488 r8192 d21952 u69632 alloc=17*4096 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Remount Root and Kernel File Systems... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 240555 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=/dev/sda2 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Memory: 939088K/970752K available (8192K kernel code, 629K rwdata, 2176K rodata, 1024K init, 821K bss, 23472K reserved, 8192K cma-reserved) Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Remount Root and Kernel File Systems. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] Virtual kernel memory layout: Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] vmalloc : 0xbb800000 - 0xff800000 (1088 MB) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] lowmem : 0x80000000 - 0xbb400000 ( 948 MB) Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Flush Journal to Persistent Storage... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] modules : 0x7f000000 - 0x80000000 ( 16 MB) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] .text : 0x(ptrval) - 0x(ptrval) (9184 kB) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] .init : 0x(ptrval) - 0x(ptrval) (1024 kB) Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting udev Coldplug all Devices... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] .data : 0x(ptrval) - 0x(ptrval) ( 630 kB) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] .bss : 0x(ptrval) - 0x(ptrval) ( 822 kB) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] ftrace: allocating 26394 entries in 78 pages Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] rcu: Hierarchical RCU implementation. Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Load/Save Random Seed... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys). Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Set the console keyboard layout. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000023] Switching to timer-based delay loop, resolution 52ns Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000288] Console: colour dummy device 80x30 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000907] console [tty1] enabled Jan 31 04:54:20 raspi-easymeter systemd[1]: Started udev Kernel Device Manager. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.000965] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.001010] pid_max: default: 32768 minimum: 301 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.001368] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.001404] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Load/Save Random Seed. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.002343] CPU: Testing write buffer coherency: ok Jan 31 04:54:20 raspi-easymeter kernel: [ 0.002828] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.003496] Setting up static identity map for 0x100000 - 0x10003c Jan 31 04:54:20 raspi-easymeter kernel: [ 0.003664] rcu: Hierarchical SRCU implementation. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.004478] smp: Bringing up secondary CPUs ... Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target Local File Systems (Pre). Jan 31 04:54:20 raspi-easymeter kernel: [ 0.005335] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.006261] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.007124] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.007244] smp: Brought up 1 node, 4 CPUs Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Flush Journal to Persistent Storage. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.007325] SMP: Total of 4 processors activated (153.60 BogoMIPS). Jan 31 04:54:20 raspi-easymeter kernel: [ 0.007349] CPU: All CPU(s) started in HYP mode. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.007370] CPU: Virtualization extensions available. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.008359] devtmpfs: initialized Jan 31 04:54:20 raspi-easymeter systemd[1]: Started udev Coldplug all Devices. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.021276] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.021550] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Jan 31 04:54:20 raspi-easymeter kernel: [ 0.021598] futex hash table entries: 1024 (order: 4, 65536 bytes) Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Show Plymouth Boot Screen... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.022204] pinctrl core: initialized pinctrl subsystem Jan 31 04:54:20 raspi-easymeter kernel: [ 0.023089] NET: Registered protocol family 16 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.026027] DMA: preallocated 1024 KiB pool for atomic coherent allocations Jan 31 04:54:20 raspi-easymeter kernel: [ 0.031731] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Jan 31 04:54:20 raspi-easymeter systemd[1]: Received SIGRTMIN+20 from PID 198 (plymouthd). Jan 31 04:54:20 raspi-easymeter kernel: [ 0.031768] hw-breakpoint: maximum watchpoint size is 8 bytes. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.031991] Serial: AMBA PL011 UART driver Jan 31 04:54:20 raspi-easymeter kernel: [ 0.034283] bcm2835-mbox 3f00b880.mailbox: mailbox enabled Jan 31 04:54:20 raspi-easymeter kernel: [ 0.069436] bcm2835-dma 3f007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.071359] SCSI subsystem initialized Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Show Plymouth Boot Screen. Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target Paths. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.071597] usbcore: registered new interface driver usbfs Jan 31 04:54:20 raspi-easymeter kernel: [ 0.071679] usbcore: registered new interface driver hub Jan 31 04:54:20 raspi-easymeter kernel: [ 0.071793] usbcore: registered new device driver usb Jan 31 04:54:20 raspi-easymeter kernel: [ 0.090303] raspberrypi-firmware soc:firmware: Attached to firmware from 2019-08-15 12:06, variant start Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target Encrypted Volumes. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.100133] raspberrypi-firmware soc:firmware: Firmware hash is 0e6daa5106dd4164474616408e0dc24f997ffcf3 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.111817] clocksource: Switched to clocksource arch_sys_counter Jan 31 04:54:20 raspi-easymeter kernel: [ 0.200946] VFS: Disk quotas dquot_6.6.0 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.201057] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.201267] FS-Cache: Loaded Jan 31 04:54:20 raspi-easymeter kernel: [ 0.201495] CacheFiles: Loaded Jan 31 04:54:20 raspi-easymeter kernel: [ 0.211505] NET: Registered protocol family 2 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.212330] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) Jan 31 04:54:20 raspi-easymeter systemd[1]: Found device /dev/serial1. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.212395] TCP established hash table entries: 8192 (order: 3, 32768 bytes) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.212526] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.212730] TCP: Hash tables configured (established 8192 bind 8192) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.212883] UDP hash table entries: 512 (order: 2, 16384 bytes) Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target Sound Card. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.212949] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.213279] NET: Registered protocol family 1 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.213882] RPC: Registered named UNIX socket transport module. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.213909] RPC: Registered udp transport module. Jan 31 04:54:20 raspi-easymeter systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.213931] RPC: Registered tcp transport module. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.213953] RPC: Registered tcp NFSv4.1 backchannel transport module. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.215586] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Jan 31 04:54:20 raspi-easymeter kernel: [ 0.218628] Initialise system trusted keyrings Jan 31 04:54:20 raspi-easymeter mtp-probe: checking bus 1, device 5: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5" Jan 31 04:54:20 raspi-easymeter kernel: [ 0.218839] workingset: timestamp_bits=14 max_order=18 bucket_order=4 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.228822] FS-Cache: Netfs 'nfs' registered for caching Jan 31 04:54:20 raspi-easymeter kernel: [ 0.229424] NFS: Registering the id_resolver key type Jan 31 04:54:20 raspi-easymeter kernel: [ 0.229470] Key type id_resolver registered Jan 31 04:54:20 raspi-easymeter mtp-probe: bus: 1, device: 5 was not an MTP device Jan 31 04:54:20 raspi-easymeter kernel: [ 0.229492] Key type id_legacy registered Jan 31 04:54:20 raspi-easymeter kernel: [ 0.229524] nfs4filelayout_init: NFSv4 File Layout Driver Registering... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.231882] Key type asymmetric registered Jan 31 04:54:20 raspi-easymeter kernel: [ 0.231911] Asymmetric key parser 'x509' registered Jan 31 04:54:20 raspi-easymeter mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3" Jan 31 04:54:20 raspi-easymeter kernel: [ 0.231980] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.232160] io scheduler noop registered Jan 31 04:54:20 raspi-easymeter kernel: [ 0.232185] io scheduler deadline registered (default) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.232378] io scheduler cfq registered Jan 31 04:54:20 raspi-easymeter mtp-probe: bus: 1, device: 4 was not an MTP device Jan 31 04:54:20 raspi-easymeter kernel: [ 0.232402] io scheduler mq-deadline registered (default) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.232426] io scheduler kyber registered Jan 31 04:54:20 raspi-easymeter kernel: [ 0.235585] bcm2708_fb soc:fb: FB found 1 display(s) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.244515] Console: switching to colour frame buffer device 82x26 Jan 31 04:54:20 raspi-easymeter mtp-probe: checking bus 1, device 3: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1" Jan 31 04:54:20 raspi-easymeter kernel: [ 0.251326] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 656x416 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.258076] bcm2835-rng 3f104000.rng: hwrng registered Jan 31 04:54:20 raspi-easymeter mtp-probe: bus: 1, device: 3 was not an MTP device Jan 31 04:54:20 raspi-easymeter systemd[1]: Found device STORAGE_DEVICE boot. Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting File System Check on /dev/disk/by-partuuid/c055cc9a-01... Jan 31 04:54:20 raspi-easymeter systemd-fsck[273]: fsck.fat 4.1 (2017-01-24) Jan 31 04:54:20 raspi-easymeter systemd-fsck[273]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 31 04:54:20 raspi-easymeter systemd-fsck[273]: Automatically removing dirty bit. Jan 31 04:54:20 raspi-easymeter systemd-fsck[273]: Performing changes. Jan 31 04:54:20 raspi-easymeter systemd-fsck[273]: /dev/sda1: 213 files, 45734/83705 clusters Jan 31 04:54:20 raspi-easymeter systemd[1]: Started File System Check on /dev/disk/by-partuuid/c055cc9a-01. Jan 31 04:54:20 raspi-easymeter systemd[1]: Mounting /boot... Jan 31 04:54:20 raspi-easymeter systemd[1]: Mounted /boot. Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target Local File Systems. Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Raise network interfaces... Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Create Volatile Files and Directories... Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Preprocess NFS configuration... Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Set console font and keymap... Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Preprocess NFS configuration. Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Set console font and keymap. Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target NFS client services. Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target Remote File Systems (Pre). Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target Remote File Systems. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.260764] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.266078] vc-sm: Videocore shared memory driver Jan 31 04:54:20 raspi-easymeter kernel: [ 0.268835] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.284700] brd: module loaded Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Create Volatile Files and Directories. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.297342] loop: module loaded Jan 31 04:54:20 raspi-easymeter kernel: [ 0.300396] Loading iSCSI transport class v2.0-870. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.303606] libphy: Fixed MDIO Bus: probed Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.306112] usbcore: registered new interface driver lan78xx Jan 31 04:54:20 raspi-easymeter kernel: [ 0.308567] usbcore: registered new interface driver smsc95xx Jan 31 04:54:20 raspi-easymeter kernel: [ 0.310920] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.338801] dwc_otg 3f980000.usb: base=(ptrval) Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Network Time Synchronization... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.541408] Core Release: 2.80a Jan 31 04:54:20 raspi-easymeter kernel: [ 0.543835] Setting default values for core params Jan 31 04:54:20 raspi-easymeter kernel: [ 0.546282] Finished setting default values for core params Jan 31 04:54:20 raspi-easymeter kernel: [ 0.749022] Using Buffer DMA mode Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.751409] Periodic Transfer Interrupt Enhancement - disabled Jan 31 04:54:20 raspi-easymeter kernel: [ 0.753852] Multiprocessor Interrupt Enhancement - disabled Jan 31 04:54:20 raspi-easymeter kernel: [ 0.756228] OTG VER PARAM: 0, OTG VER FLAG: 0 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.758574] Dedicated Tx FIFOs mode Jan 31 04:54:20 raspi-easymeter systemd-timesyncd[313]: System clock time unset or jumped backwards, restoring from recorded timestamp: Sun 2021-01-31 04:54:19 CET Jan 31 04:54:20 raspi-easymeter kernel: [ 0.761260] WARN::dwc_otg_hcd_init:1045: FIQ DMA bounce buffers: virt = bad04000 dma = 0xfad04000 len=9024 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.765866] FIQ FSM acceleration enabled for : Jan 31 04:54:20 raspi-easymeter kernel: [ 0.765866] Non-periodic Split Transactions Jan 31 04:54:20 raspi-easymeter kernel: [ 0.765866] Periodic Split Transactions Jan 31 04:54:20 raspi-easymeter systemd[1]: Time has been changed Jan 31 04:54:20 raspi-easymeter kernel: [ 0.765866] High-Speed Isochronous Endpoints Jan 31 04:54:20 raspi-easymeter kernel: [ 0.765866] Interrupt/Control Split Transaction hack enabled Jan 31 04:54:20 raspi-easymeter kernel: [ 0.776778] dwc_otg: Microframe scheduler enabled Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Network Time Synchronization. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.776837] WARN::hcd_init_fiq:457: FIQ on core 1 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.779067] WARN::hcd_init_fiq:458: FIQ ASM at 80654f4c length 36 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.781281] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.783542] dwc_otg 3f980000.usb: DWC OTG Controller Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target System Initialization. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.785806] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.788126] dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.790426] Init: Port Power? op_state=1 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.792687] Init: Power Port (0) Jan 31 04:54:20 raspi-easymeter systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.795096] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.799592] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.802020] usb usb1: Product: DWC OTG Controller Jan 31 04:54:20 raspi-easymeter kernel: [ 0.804388] usb usb1: Manufacturer: Linux 4.19.66-v7+ dwc_otg_hcd Jan 31 04:54:20 raspi-easymeter kernel: [ 0.806809] usb usb1: SerialNumber: 3f980000.usb Jan 31 04:54:20 raspi-easymeter systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.809753] hub 1-0:1.0: USB hub found Jan 31 04:54:20 raspi-easymeter kernel: [ 0.812044] hub 1-0:1.0: 1 port detected Jan 31 04:54:20 raspi-easymeter kernel: [ 0.814807] dwc_otg: FIQ enabled Jan 31 04:54:20 raspi-easymeter systemd[1]: Listening on triggerhappy.socket. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.814814] dwc_otg: NAK holdoff enabled Jan 31 04:54:20 raspi-easymeter kernel: [ 0.814819] dwc_otg: FIQ split-transaction FSM enabled Jan 31 04:54:20 raspi-easymeter kernel: [ 0.814830] Module dwc_common_port init Jan 31 04:54:20 raspi-easymeter kernel: [ 0.815103] usbcore: registered new interface driver usb-storage Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target Sockets. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.817563] mousedev: PS/2 mouse device common for all mice Jan 31 04:54:20 raspi-easymeter kernel: [ 0.820727] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jan 31 04:54:20 raspi-easymeter kernel: [ 0.823373] bcm2835-cpufreq: min=600000 max=1200000 Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target Basic System. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.826196] sdhci: Secure Digital Host Controller Interface driver Jan 31 04:54:20 raspi-easymeter kernel: [ 0.828603] sdhci: Copyright(c) Pierre Ossman Jan 31 04:54:20 raspi-easymeter kernel: [ 0.831370] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe Jan 31 04:54:20 raspi-easymeter kernel: [ 0.834305] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Regular background program processing daemon. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.836983] sdhci-pltfm: SDHCI platform and OF driver helper Jan 31 04:54:20 raspi-easymeter kernel: [ 0.841079] ledtrig-cpu: registered to indicate activity on CPUs Jan 31 04:54:20 raspi-easymeter kernel: [ 0.843875] hidraw: raw HID events driver (C) Jiri Kosina Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Login Service... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.846647] usbcore: registered new interface driver usbhid Jan 31 04:54:20 raspi-easymeter kernel: [ 0.849281] usbhid: USB HID core driver Jan 31 04:54:20 raspi-easymeter kernel: [ 0.852611] vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.856742] [vc_sm_connected_init]: start Jan 31 04:54:20 raspi-easymeter systemd[1]: Started D-Bus System Message Bus. Jan 31 04:54:20 raspi-easymeter kernel: [ 0.865471] [vc_sm_connected_init]: end - returning 0 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.869013] Initializing XFRM netlink socket Jan 31 04:54:20 raspi-easymeter kernel: [ 0.871441] NET: Registered protocol family 17 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.873978] Key type dns_resolver registered Jan 31 04:54:20 raspi-easymeter kernel: [ 0.876821] Registering SWP/SWPB emulation handler Jan 31 04:54:20 raspi-easymeter cron[352]: (CRON) INFO (pidfile fd = 3) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.879923] registered taskstats version 1 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.882366] Loading compiled-in X.509 certificates Jan 31 04:54:20 raspi-easymeter kernel: [ 0.891996] uart-pl011 3f201000.serial: cts_event_workaround enabled Jan 31 04:54:20 raspi-easymeter kernel: [ 0.894523] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 Jan 31 04:54:20 raspi-easymeter cron[352]: (CRON) INFO (Running @reboot jobs) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.901369] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jan 31 04:54:20 raspi-easymeter kernel: [ 0.903953] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Jan 31 04:54:20 raspi-easymeter kernel: [ 0.932570] sdhost: log_buf @ (ptrval) (fad07000) Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting WPA supplicant... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.970788] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.974776] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.978628] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 31 04:54:20 raspi-easymeter kernel: [ 0.982908] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)... Jan 31 04:54:20 raspi-easymeter kernel: [ 0.986370] of_cfs_init Jan 31 04:54:20 raspi-easymeter kernel: [ 0.988614] of_cfs_init: OK Jan 31 04:54:20 raspi-easymeter kernel: [ 0.991260] Waiting for root device /dev/sda2... Jan 31 04:54:20 raspi-easymeter kernel: [ 1.005606] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Jan 31 04:54:20 raspi-easymeter kernel: [ 1.031943] Indeed it is in host mode hprt0 = 00021501 Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting LSB: Autogenerate and use a swap file... Jan 31 04:54:20 raspi-easymeter kernel: [ 1.101682] random: fast init done Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Save/Restore Sound Card State... Jan 31 04:54:20 raspi-easymeter kernel: [ 1.204503] mmc1: new high speed SDIO card at address 0001 Jan 31 04:54:20 raspi-easymeter kernel: [ 1.241853] usb 1-1: new high-speed USB device number 2 using dwc_otg Jan 31 04:54:20 raspi-easymeter kernel: [ 1.244163] Indeed it is in host mode hprt0 = 00001101 Jan 31 04:54:20 raspi-easymeter kernel: [ 1.502102] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 31 04:54:20 raspi-easymeter kernel: [ 1.506599] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 31 04:54:20 raspi-easymeter kernel: [ 1.509532] hub 1-1:1.0: USB hub found Jan 31 04:54:20 raspi-easymeter kernel: [ 1.511872] hub 1-1:1.0: 5 ports detected Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting System Logging Service... Jan 31 04:54:20 raspi-easymeter kernel: [ 1.831847] usb 1-1.1: new high-speed USB device number 3 using dwc_otg Jan 31 04:54:20 raspi-easymeter kernel: [ 1.962085] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jan 31 04:54:20 raspi-easymeter kernel: [ 1.966583] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 31 04:54:20 raspi-easymeter kernel: [ 1.971658] smsc95xx v1.0.6 Jan 31 04:54:20 raspi-easymeter kernel: [ 2.065251] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:79:6f:ef Jan 31 04:54:20 raspi-easymeter kernel: [ 2.161849] usb 1-1.3: new full-speed USB device number 4 using dwc_otg Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting triggerhappy global hotkey daemon... Jan 31 04:54:20 raspi-easymeter kernel: [ 2.296575] usb 1-1.3: New USB device found, idVendor=10c4, idProduct=ea60, bcdDevice= 1.00 Jan 31 04:54:20 raspi-easymeter kernel: [ 2.302316] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jan 31 04:54:20 raspi-easymeter kernel: [ 2.305348] usb 1-1.3: Product: CP2104 USB to UART Bridge Controller Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 31 04:54:20 raspi-easymeter kernel: [ 2.308382] usb 1-1.3: Manufacturer: Silicon Labs Jan 31 04:54:20 raspi-easymeter kernel: [ 2.311369] usb 1-1.3: SerialNumber: 01304FF0 Jan 31 04:54:20 raspi-easymeter kernel: [ 2.611841] usb 1-1.5: new high-speed USB device number 5 using dwc_otg Jan 31 04:54:20 raspi-easymeter kernel: [ 2.746027] usb 1-1.5: New USB device found, idVendor=05e3, idProduct=0727, bcdDevice= 2.50 Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Disable WiFi if country not set... Jan 31 04:54:20 raspi-easymeter kernel: [ 2.751750] usb 1-1.5: New USB device strings: Mfr=3, Product=4, SerialNumber=2 Jan 31 04:54:20 raspi-easymeter kernel: [ 2.754725] usb 1-1.5: Product: USB Storage Jan 31 04:54:20 raspi-easymeter kernel: [ 2.757601] usb 1-1.5: Manufacturer: Generic Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting dhcpcd on all interfaces... Jan 31 04:54:20 raspi-easymeter kernel: [ 2.760487] usb 1-1.5: SerialNumber: 000000000250 Jan 31 04:54:20 raspi-easymeter kernel: [ 2.764351] usb-storage 1-1.5:1.0: USB Mass Storage device detected Jan 31 04:54:20 raspi-easymeter kernel: [ 2.767833] scsi host0: usb-storage 1-1.5:1.0 Jan 31 04:54:20 raspi-easymeter kernel: [ 3.833174] scsi 0:0:0:0: Direct-Access Generic STORAGE DEVICE 0250 PQ: 0 ANSI: 0 Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 31 04:54:20 raspi-easymeter kernel: [ 4.057533] sd 0:0:0:0: [sda] 7761920 512-byte logical blocks: (3.97 GB/3.70 GiB) Jan 31 04:54:20 raspi-easymeter kernel: [ 4.064022] sd 0:0:0:0: [sda] Write Protect is off Jan 31 04:54:20 raspi-easymeter kernel: [ 4.066768] sd 0:0:0:0: [sda] Mode Sense: 0b 00 00 08 Jan 31 04:54:20 raspi-easymeter kernel: [ 4.067697] sd 0:0:0:0: [sda] No Caching mode page found Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target System Time Synchronized. Jan 31 04:54:20 raspi-easymeter kernel: [ 4.070395] sd 0:0:0:0: [sda] Assuming drive cache: write through Jan 31 04:54:20 raspi-easymeter kernel: [ 4.078039] sda: sda1 sda2 Jan 31 04:54:20 raspi-easymeter kernel: [ 4.084301] sd 0:0:0:0: [sda] Attached SCSI removable disk Jan 31 04:54:20 raspi-easymeter kernel: [ 4.091011] EXT4-fs (sda2): INFO: recovery required on readonly filesystem Jan 31 04:54:20 raspi-easymeter systemd[1]: apt-daily.timer: Adding 3h 11min 6.731962s random time. Jan 31 04:54:20 raspi-easymeter kernel: [ 4.093699] EXT4-fs (sda2): write access will be enabled during recovery Jan 31 04:54:20 raspi-easymeter kernel: [ 5.410588] EXT4-fs (sda2): orphan cleanup on readonly fs Jan 31 04:54:20 raspi-easymeter kernel: [ 5.448633] EXT4-fs (sda2): 4 orphan inodes deleted Jan 31 04:54:20 raspi-easymeter kernel: [ 5.451235] EXT4-fs (sda2): recovery complete Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Daily apt download activities. Jan 31 04:54:20 raspi-easymeter kernel: [ 6.042784] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null) Jan 31 04:54:20 raspi-easymeter kernel: [ 6.047660] VFS: Mounted root (ext4 filesystem) readonly on device 8:2. Jan 31 04:54:20 raspi-easymeter kernel: [ 6.061737] devtmpfs: mounted Jan 31 04:54:20 raspi-easymeter kernel: [ 6.069669] Freeing unused kernel memory: 1024K Jan 31 04:54:20 raspi-easymeter kernel: [ 6.072654] Run /sbin/init as init process Jan 31 04:54:20 raspi-easymeter systemd[1]: apt-daily-upgrade.timer: Adding 8min 39.634359s random time. Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Daily apt upgrade and clean activities. Jan 31 04:54:20 raspi-easymeter systemd[1]: Reached target Timers. Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Raise network interfaces. Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Save/Restore Sound Card State. Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Disable WiFi if country not set. Jan 31 04:54:20 raspi-easymeter thd[368]: Found socket passed from systemd Jan 31 04:54:20 raspi-easymeter systemd[1]: Started triggerhappy global hotkey daemon. Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Login Service. Jan 31 04:54:20 raspi-easymeter systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 31 04:54:20 raspi-easymeter kernel: [ 6.992645] NET: Registered protocol family 10 Jan 31 04:54:20 raspi-easymeter kernel: [ 6.996675] Segment Routing with IPv6 Jan 31 04:54:20 raspi-easymeter dphys-swapfile[359]: Starting dphys-swapfile swapfile setup ... Jan 31 04:54:20 raspi-easymeter kernel: [ 7.037314] random: systemd: uninitialized urandom read (16 bytes read) Jan 31 04:54:20 raspi-easymeter kernel: [ 7.042857] random: systemd: uninitialized urandom read (16 bytes read) Jan 31 04:54:20 raspi-easymeter kernel: [ 7.168512] random: systemd-gpt-aut: uninitialized urandom read (16 bytes read) Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Found user 'avahi' (UID 108) and group 'avahi' (GID 112). Jan 31 04:54:20 raspi-easymeter kernel: [ 8.592187] EXT4-fs (sda2): re-mounted. Opts: (null) Jan 31 04:54:20 raspi-easymeter kernel: [ 9.142322] random: crng init done Jan 31 04:54:20 raspi-easymeter kernel: [ 9.142334] random: 7 urandom warning(s) missed due to ratelimiting Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Successfully dropped root privileges. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.282311] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.284815] bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 31 04:54:20 raspi-easymeter kernel: [ 9.284830] [vc_sm_connected_init]: start Jan 31 04:54:20 raspi-easymeter kernel: [ 9.311715] [vc_sm_connected_init]: installed successfully Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: avahi-daemon 0.6.32 starting up. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.434090] media: Linux media interface: v0.10 Jan 31 04:54:20 raspi-easymeter kernel: [ 9.462490] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.469447] bcm2835_audio soc:audio: card created with 8 channels Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Load/Save RF Kill Switch Status. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.528338] videodev: Linux video capture interface: v2.00 Jan 31 04:54:20 raspi-easymeter kernel: [ 9.622915] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.624592] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.667157] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 04:54:20 raspi-easymeter raspi-config[358]: Checking if shift key is held down:Error opening '/dev/input/event*': No such file or directory Jan 31 04:54:20 raspi-easymeter kernel: [ 9.667162] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.685021] bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 31 04:54:20 raspi-easymeter kernel: [ 9.685035] bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 31 04:54:20 raspi-easymeter kernel: [ 9.687921] bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 31 04:54:20 raspi-easymeter kernel: [ 9.687933] bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 31 04:54:20 raspi-easymeter kernel: [ 9.691376] bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Successfully called chroot(). Jan 31 04:54:20 raspi-easymeter systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Successfully dropped remaining capabilities. Jan 31 04:54:20 raspi-easymeter raspi-config[358]: No. Switching to ondemand scaling governor. Jan 31 04:54:20 raspi-easymeter systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed). Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: No service file found in /etc/avahi/services. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.691392] bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Network interface enumeration completed. Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Server startup complete. Host name is raspi-easymeter.local. Local service cookie is 2382540255. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.845348] cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 31 04:54:20 raspi-easymeter kernel: [ 9.854661] usbcore: registered new interface driver uas Jan 31 04:54:20 raspi-easymeter dhcpcd[371]: dev: loaded udev Jan 31 04:54:20 raspi-easymeter kernel: [ 9.858073] usbcore: registered new interface driver usbserial_generic Jan 31 04:54:20 raspi-easymeter liblogging-stdlog: [origin software="rsyslogd" swVersion="8.24.0" x-pid="366" x-info="http://www.rsyslog.com"] start Jan 31 04:54:20 raspi-easymeter kernel: [ 9.858156] usbserial: USB Serial support registered for generic Jan 31 04:54:20 raspi-easymeter kernel: [ 9.874314] usbcore: registered new interface driver cp210x Jan 31 04:54:20 raspi-easymeter kernel: [ 9.874405] usbserial: USB Serial support registered for cp210x Jan 31 04:54:20 raspi-easymeter systemd[1]: Started System Logging Service. Jan 31 04:54:20 raspi-easymeter kernel: [ 9.874670] cp210x 1-1.3:1.0: cp210x converter detected Jan 31 04:54:20 raspi-easymeter kernel: [ 9.881788] usb 1-1.3: cp210x converter now attached to ttyUSB0 Jan 31 04:54:20 raspi-easymeter kernel: [ 9.882619] sd 0:0:0:0: Attached scsi generic sg0 type 0 Jan 31 04:54:20 raspi-easymeter kernel: [ 9.900895] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 31 04:54:20 raspi-easymeter kernel: [ 9.995747] brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Jan 31 04:54:20 raspi-easymeter kernel: [ 10.001991] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jan 31 04:54:20 raspi-easymeter kernel: [ 10.002283] usbcore: registered new interface driver brcmfmac Jan 31 04:54:20 raspi-easymeter kernel: [ 10.220103] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jan 31 04:54:20 raspi-easymeter kernel: [ 10.220226] brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available Jan 31 04:54:20 raspi-easymeter kernel: [ 10.221063] 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 Jan 31 04:54:20 raspi-easymeter kernel: [ 11.495217] uart-pl011 3f201000.serial: no DMA platform data Jan 31 04:54:20 raspi-easymeter dphys-swapfile[359]: want /var/swap=100MByte, checking existing: keeping it Jan 31 04:54:20 raspi-easymeter wpa_supplicant[357]: Successfully initialized wpa_supplicant Jan 31 04:54:20 raspi-easymeter systemd[1]: Started WPA supplicant. Jan 31 04:54:20 raspi-easymeter kernel: [ 11.803945] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k FS Jan 31 04:54:20 raspi-easymeter dphys-swapfile[359]: done. Jan 31 04:54:20 raspi-easymeter systemd[1]: Started LSB: Autogenerate and use a swap file. Jan 31 04:54:20 raspi-easymeter dhcpcd[371]: wlan0: starting wpa_supplicant Jan 31 04:54:20 raspi-easymeter dhcpcd-run-hooks[436]: wlan0: starting wpa_supplicant Jan 31 04:54:20 raspi-easymeter kernel: [ 12.040119] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready Jan 31 04:54:20 raspi-easymeter kernel: [ 12.040231] brcmfmac: power management disabled Jan 31 04:54:20 raspi-easymeter dhcpcd[371]: eth0: waiting for carrier Jan 31 04:54:20 raspi-easymeter dhcpcd[371]: wlan0: waiting for carrier Jan 31 04:54:20 raspi-easymeter dhcpcd[371]: wlan0: carrier acquired Jan 31 04:54:20 raspi-easymeter kernel: [ 12.462989] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jan 31 04:54:20 raspi-easymeter kernel: [ 12.463248] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready Jan 31 04:54:20 raspi-easymeter dhcpcd[371]: DUID 00:01:00:01:21:b0:d9:d5:b8:27:eb:2c:3a:ba Jan 31 04:54:20 raspi-easymeter dhcpcd[371]: wlan0: IAID eb:2c:3a:ba Jan 31 04:54:20 raspi-easymeter dhcpcd[371]: wlan0: adding address fe80::aaf5:75e5:7b42:f9cb Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::aaf5:75e5:7b42:f9cb. Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: New relevant interface wlan0.IPv6 for mDNS. Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Registering new address record for fe80::aaf5:75e5:7b42:f9cb on wlan0.*. Jan 31 04:54:20 raspi-easymeter dhcpcd[371]: wlan0: carrier lost Jan 31 04:54:20 raspi-easymeter dhcpcd[371]: wlan0: deleting address fe80::aaf5:75e5:7b42:f9cb Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Withdrawing address record for fe80::aaf5:75e5:7b42:f9cb on wlan0. Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::aaf5:75e5:7b42:f9cb. Jan 31 04:54:20 raspi-easymeter avahi-daemon[365]: Interface wlan0.IPv6 no longer relevant for mDNS. Jan 31 04:54:22 raspi-easymeter dhcpcd[371]: wlan0: carrier acquired Jan 31 04:54:22 raspi-easymeter kernel: [ 13.613592] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Jan 31 04:54:22 raspi-easymeter dhcpcd[371]: wlan0: IAID eb:2c:3a:ba Jan 31 04:54:22 raspi-easymeter dhcpcd[371]: wlan0: adding address fe80::895e:350f:81a1:3335 Jan 31 04:54:22 raspi-easymeter avahi-daemon[365]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::895e:350f:81a1:3335. Jan 31 04:54:22 raspi-easymeter avahi-daemon[365]: New relevant interface wlan0.IPv6 for mDNS. Jan 31 04:54:22 raspi-easymeter avahi-daemon[365]: Registering new address record for fe80::895e:350f:81a1:3335 on wlan0.*. Jan 31 04:54:22 raspi-easymeter dhcpcd[371]: wlan0: soliciting an IPv6 router Jan 31 04:54:22 raspi-easymeter dhcpcd[371]: wlan0: rebinding lease of 192.168.1.23 Jan 31 04:54:22 raspi-easymeter dhcpcd[371]: wlan0: probing address 192.168.1.23/24 Jan 31 04:54:23 raspi-easymeter dhcpcd[371]: wlan0: Router Advertisement from fe80::9a9b:cbff:fe14:a2a Jan 31 04:54:23 raspi-easymeter dhcpcd[371]: wlan0: adding address 2001:16b8:48a:1800:d1ef:dfcc:3470:b22e/64 Jan 31 04:54:23 raspi-easymeter avahi-daemon[365]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::895e:350f:81a1:3335. Jan 31 04:54:23 raspi-easymeter avahi-daemon[365]: Joining mDNS multicast group on interface wlan0.IPv6 with address 2001:16b8:48a:1800:d1ef:dfcc:3470:b22e. Jan 31 04:54:23 raspi-easymeter avahi-daemon[365]: Registering new address record for 2001:16b8:48a:1800:d1ef:dfcc:3470:b22e on wlan0.*. Jan 31 04:54:23 raspi-easymeter dhcpcd[371]: wlan0: adding route to 2001:16b8:48a:1800::/64 Jan 31 04:54:23 raspi-easymeter avahi-daemon[365]: Withdrawing address record for fe80::895e:350f:81a1:3335 on wlan0. Jan 31 04:54:23 raspi-easymeter dhcpcd[371]: wlan0: adding default route via fe80::9a9b:cbff:fe14:a2a Jan 31 04:54:23 raspi-easymeter dhcpcd[371]: wlan0: requesting DHCPv6 information Jan 31 04:54:24 raspi-easymeter dhcpcd[371]: Too few arguments. Jan 31 04:54:24 raspi-easymeter dhcpcd[371]: Too few arguments. Jan 31 04:54:24 raspi-easymeter kernel: [ 16.269511] Bluetooth: Core ver 2.22 Jan 31 04:54:24 raspi-easymeter kernel: [ 16.269614] NET: Registered protocol family 31 Jan 31 04:54:24 raspi-easymeter kernel: [ 16.269620] Bluetooth: HCI device and connection manager initialized Jan 31 04:54:24 raspi-easymeter kernel: [ 16.270567] Bluetooth: HCI socket layer initialized Jan 31 04:54:24 raspi-easymeter kernel: [ 16.270583] Bluetooth: L2CAP socket layer initialized Jan 31 04:54:24 raspi-easymeter kernel: [ 16.270621] Bluetooth: SCO socket layer initialized Jan 31 04:54:24 raspi-easymeter btuart[369]: bcm43xx_init Jan 31 04:54:24 raspi-easymeter btuart[369]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd Jan 31 04:54:24 raspi-easymeter btuart[369]: Set BDADDR UART: b8:27:eb:d3:c5:45 Jan 31 04:54:24 raspi-easymeter btuart[369]: Set Controller UART speed to 921600 bit/s Jan 31 04:54:24 raspi-easymeter btuart[369]: Device setup complete Jan 31 04:54:24 raspi-easymeter systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 31 04:54:24 raspi-easymeter kernel: [ 16.286059] Bluetooth: HCI UART driver ver 2.3 Jan 31 04:54:24 raspi-easymeter kernel: [ 16.286067] Bluetooth: HCI UART protocol H4 registered Jan 31 04:54:24 raspi-easymeter kernel: [ 16.286116] Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 31 04:54:24 raspi-easymeter kernel: [ 16.286240] Bluetooth: HCI UART protocol Broadcom registered Jan 31 04:54:24 raspi-easymeter systemd[1]: Starting Bluetooth service... Jan 31 04:54:24 raspi-easymeter systemd[1]: Created slice system-bthelper.slice. Jan 31 04:54:24 raspi-easymeter bluetoothd[521]: Bluetooth daemon 5.43 Jan 31 04:54:24 raspi-easymeter systemd[1]: Started Bluetooth service. Jan 31 04:54:24 raspi-easymeter systemd[1]: Reached target Bluetooth. Jan 31 04:54:24 raspi-easymeter systemd[1]: Started Raspberry Pi bluetooth helper. Jan 31 04:54:24 raspi-easymeter bluetoothd[521]: Starting SDP server Jan 31 04:54:24 raspi-easymeter kernel: [ 16.468472] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 31 04:54:24 raspi-easymeter kernel: [ 16.468480] Bluetooth: BNEP filters: protocol multicast Jan 31 04:54:24 raspi-easymeter kernel: [ 16.468494] Bluetooth: BNEP socket layer initialized Jan 31 04:54:24 raspi-easymeter dbus[355]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Jan 31 04:54:24 raspi-easymeter bluetoothd[521]: Bluetooth management interface 1.14 initialized Jan 31 04:54:24 raspi-easymeter bluetoothd[521]: Failed to obtain handles for "Service Changed" characteristic Jan 31 04:54:24 raspi-easymeter bluetoothd[521]: Sap driver initialization failed. Jan 31 04:54:24 raspi-easymeter bluetoothd[521]: sap-server: Operation not permitted (1) Jan 31 04:54:24 raspi-easymeter systemd[1]: Starting Hostname Service... Jan 31 04:54:24 raspi-easymeter bluetoothd[521]: Failed to set privacy: Rejected (0x0b) Jan 31 04:54:25 raspi-easymeter dbus[355]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 31 04:54:25 raspi-easymeter systemd[1]: Started Hostname Service. Jan 31 04:54:25 raspi-easymeter dhcpcd[371]: forked to background, child pid 577 Jan 31 04:54:25 raspi-easymeter systemd[1]: Started dhcpcd on all interfaces. Jan 31 04:54:25 raspi-easymeter systemd[1]: Reached target Network. Jan 31 04:54:25 raspi-easymeter systemd[1]: Starting /etc/rc.local Compatibility... Jan 31 04:54:25 raspi-easymeter systemd[1]: Starting Permit User Sessions... Jan 31 04:54:26 raspi-easymeter systemd[1]: Started ioBroker Server. Jan 31 04:54:26 raspi-easymeter systemd[1]: Starting OpenBSD Secure Shell server... Jan 31 04:54:26 raspi-easymeter systemd[1]: Started /etc/rc.local Compatibility. Jan 31 04:54:26 raspi-easymeter systemd[1]: Started Permit User Sessions. Jan 31 04:54:26 raspi-easymeter systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 31 04:54:26 raspi-easymeter systemd[1]: Starting Hold until boot process finishes up... Jan 31 04:54:26 raspi-easymeter systemd[1]: Received SIGRTMIN+21 from PID 198 (plymouthd). Jan 31 04:54:26 raspi-easymeter systemd[1]: Started Terminate Plymouth Boot Screen. Jan 31 04:54:26 raspi-easymeter systemd[1]: Started Hold until boot process finishes up. Jan 31 04:54:26 raspi-easymeter systemd[1]: Started Getty on tty1. Jan 31 04:54:26 raspi-easymeter systemd[1]: Reached target Login Prompts. Jan 31 04:54:26 raspi-easymeter systemd[1]: Started OpenBSD Secure Shell server. Jan 31 04:54:26 raspi-easymeter systemd[1]: Reached target Multi-User System. Jan 31 04:54:26 raspi-easymeter systemd[1]: Reached target Graphical Interface. Jan 31 04:54:26 raspi-easymeter systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 31 04:54:26 raspi-easymeter systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 31 04:54:26 raspi-easymeter systemd[1]: Startup finished in 6.755s (kernel) + 11.648s (userspace) = 18.404s. Jan 31 04:54:27 raspi-easymeter dhcpcd[577]: wlan0: leased 192.168.1.23 for 864000 seconds Jan 31 04:54:27 raspi-easymeter avahi-daemon[365]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.23. Jan 31 04:54:27 raspi-easymeter avahi-daemon[365]: New relevant interface wlan0.IPv4 for mDNS. Jan 31 04:54:27 raspi-easymeter dhcpcd[577]: wlan0: adding route to 192.168.1.0/24 Jan 31 04:54:27 raspi-easymeter avahi-daemon[365]: Registering new address record for 192.168.1.23 on wlan0.IPv4. Jan 31 04:54:27 raspi-easymeter dhcpcd[577]: wlan0: adding default route via 192.168.1.1 Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.admin.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.discovery.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.hm-rpc.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.hm-rega.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.hm-rpc.1" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.cloud.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.javascript.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.javascript.1" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.web.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.vis.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.vis-history.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.vis-hqwidgets.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.yahka.0" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.js2fs.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.history.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.fritzbox.0" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.rpi2.0" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.samsung.0" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.scenes.0" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.tankerkoenig.0" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.sayit.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.node-red.0" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.vis-metro.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.info.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.vis-jqui-mfd.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.vis-lcars.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.iot.0" for host "raspberrypi" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.denon.0" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.alexa2.0" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.smartmeter.0" for host "raspi-easymeter" Jan 31 04:54:33 raspi-easymeter bash[580]: host.raspi-easymeter check instance "system.adapter.admin.1" for host "raspi-easymeter" Jan 31 04:54:43 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:43 GMT EventedHTTPServer Server listening on port 45149 Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT EventedHTTPServer [::ffff:192.168.1.36] New connection from client Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT EventedHTTPServer [::ffff:192.168.1.36] HTTP server listening on port 35229 Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT EventedHTTPServer [::ffff:192.168.1.36] HTTP request: /pair-verify Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT HAPServer [11:c8:a8:5a:50:42] HAP Request: POST /pair-verify Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT HAPServer [11:c8:a8:5a:50:42] Pair verify step 1/2 Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT EventedHTTPServer [::ffff:192.168.1.36] HTTP Response is finished Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT EventedHTTPServer [::ffff:192.168.1.41] New connection from client Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT EventedHTTPServer [::ffff:192.168.1.41] HTTP server listening on port 38659 Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT EventedHTTPServer [::ffff:192.168.1.41] HTTP request: /pair-verify Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT HAPServer [11:c8:a8:5a:50:42] HAP Request: POST /pair-verify Jan 31 04:54:44 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:44 GMT HAPServer [11:c8:a8:5a:50:42] Pair verify step 1/2 Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT EventedHTTPServer [::ffff:192.168.1.41] HTTP Response is finished Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT EventedHTTPServer [::ffff:192.168.1.36] HTTP request: /pair-verify Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT HAPServer [11:c8:a8:5a:50:42] HAP Request: POST /pair-verify Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT HAPServer [11:c8:a8:5a:50:42] Pair verify step 2/2 Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT HAPServer [11:c8:a8:5a:50:42] Client C8529CC9-9EF2-44CA-A834-148E1441EFE2 verification complete Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT EventedHTTPServer [::ffff:192.168.1.36] HTTP Response is finished Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT EventedHTTPServer [::ffff:192.168.1.41] HTTP request: /pair-verify Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT HAPServer [11:c8:a8:5a:50:42] HAP Request: POST /pair-verify Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT HAPServer [11:c8:a8:5a:50:42] Pair verify step 2/2 Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT HAPServer [11:c8:a8:5a:50:42] Client C8529CC9-9EF2-44CA-A834-148E1441EFE2 verification complete Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT EventedHTTPServer [::ffff:192.168.1.41] HTTP Response is finished Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT EventedHTTPServer [::ffff:192.168.1.36] HTTP request: /accessories Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT HAPServer [11:c8:a8:5a:50:42] HAP Request: GET /accessories Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT EventedHTTPServer [::ffff:192.168.1.36] HTTP Response is finished Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT EventedHTTPServer [::ffff:192.168.1.41] HTTP request: /accessories Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT HAPServer [11:c8:a8:5a:50:42] HAP Request: GET /accessories Jan 31 04:54:45 raspi-easymeter bash[580]: Sun, 31 Jan 2021 03:54:45 GMT EventedHTTPServer [::ffff:192.168.1.41] HTTP Response is finished -
@Thormann
Jou, pack das mal auch in </> code tags (wie im ersten Beitrag auch), sonst kann man das unschön lesen.
Hast Du einen crontab zum nächtlichen Neustart des Raspi? In der Mitte irgendwo steht sowas.Dann ist es vielleicht @Thomas-Braun einen Blick wert
?! -
@thormann sagte in RPi mit iobroker (slave) stürzt regelmässig ab:
EXT4-fs (sda2): 4 orphan inodes deleted
Jan 31 04:54:20 raspi-easymeter systemd-fsck[273]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt.
Da macht das Dateisystem die Grätsche.
Und einen Server solltest du auch ohne Desktopoberfläche betreiben. Die wird bei dir auch noch mitgeladen.
-
@Meister Mopper
Danke, Ich hab´s mal geändert. Dachte, mit Spoiler nimmt es weniger Platz weg.
Um 05.00Uhr startet ein crontab zum automatischen backup von iobroker, aber ohne Neustart. Und auch zeitlich passt es ja auch nicht ganz.
Danke, gibt´s eine Chance das zu reparieren? Oder besser den Stick wechseln?
Wie starte ich denn ohne Desktop?
-
@thormann sagte in RPi mit iobroker (slave) stürzt regelmässig ab:
Danke, gibt´s eine Chance das zu reparieren? Oder besser den Stick wechseln?
Würde ich nicht versuchen. Wechsel das Speichermedium aus.
Wie starte ich denn ohne Desktop?
Die Frage verstehe ich nicht. Strom einschalten und die Kiste bootet bis zum Login auf der Kommandozeile durch. Wenn der einzig sinnvolle RunLevel 3 gesetzt ist natürlich.
