Jahresrückblick 2025 – unser neuer Blogbeitrag ist online! ✨

[besa]

Betriebssystem
linux
Betriebssystem
linux
Architektur
arm
CPUs
4
Geschwindigkeit
1416 MHz
Modell
ARMv7 Processor rev 1 (v7l)
RAM
1.96 GB
System Betriebszeit
22:26:17
Node.js
v12.18.2
NPM
6.14.5
Festplatte Größe
14.32 GB
Festplatte frei
7.92 GB
Anzahl der Adapter
301
Betriebszeit
00:34:29
Aktive Instanzen
24
Hostname
tinkerboard

[besa]

Sorry, das war nur die Systeminfo. Ich schreibe hier meinen erstene Beitrag und habe nachdem IOBROKER seit 6 Monaten stabil lief nun das Problem, dass das System jede Nacht abstürzt und es morgens nicht mehr erreichrbar ist.

Im Log sind folgende Einträge:

2020-07-06 00:27:22.407 - info: javascript.0 (3818) script.js.common.Licht.Aussenbeleuchtung: 2
2020-07-06 00:27:29.896 - error: node-red.0 (10929) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:27:53.090 - info: javascript.0 (3818) script.js.common.Licht.Aussenbeleuchtung: 3
2020-07-06 00:28:07.679 - error: node-red.0 (10929) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:30.952 - error: kodi.0 (3609) read ECONNRESET
2020-07-06 00:28:47.793 - error: kodi.0 (3609) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.833 - error: javascript.0 (3818) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.814 - error: host.tinkerboard connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.787 - error: pushover.0 (3676) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.802 - error: backitup.0 (13399) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.798 - error: sql.0 (4698) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.782 - error: discovery.0 (2589) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.815 - error: lgtv.0 (3652) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.796 - error: yahka.0 (6729) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.795 - error: harmony.0 (13608) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.809 - error: snmp.0 (3719) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.800 - error: email.0 (2625) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.792 - error: node-red.0 (10248) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.804 - error: tr-064.0 (9698) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.797 - error: zwave2.0 (7473) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.788 - error: hm-rpc.2 (2994) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.810 - error: hm-rega.0 (2665) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.805 - error: sonoff.0 (4102) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.813 - error: hm-rpc.1 (2975) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.792 - error: web.0 (5926) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.817 - error: hm-rpc.0 (2914) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.799 - error: info.0 (3029) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.813 - error: cloud.0 (2550) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:47.818 - error: admin.0 (2309) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:48.255 - error: shelly.0 (3691) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:28:53.012 - info: javascript.0 (3818) script.js.common.Licht.Aussenbeleuchtung: 2
2020-07-06 00:29:00.241 - error: node-red.0 (10929) connect ECONNREFUSED 127.0.0.1:6379
2020-07-06 00:29:23.128 - info: javascript.0 (3818) script.js.common.Licht.Aussenbeleuchtung: 3
2020-07-06 00:29:24.455 - info: info.0 (3029) Popup news was read...
2020-07-06 00:29:34.155 - error: sonoff.0 (4102) read ECONNRESET
2020-07-06 00:29:35.559 - error: shelly.0 (3691) read ECONNRESET
2020-07-06 00:29:36.171 - error: node-red.0 (10929) connect ECONNREFUSED 127.0.0.1:6379

[besa]

Es wäre toll wenn mir jemand weiterhlefen könnte. Ich bin mir unsicher ob das Problem durch eines der letzten Aktualisierung der Adapter bzw. Updates ausgelöst wurde. Ich versuche das System immer aktuell zu halten. Kann aber nicht nachvollziehen, ob es einen Zusammenhang gibt. Mein Netz läuft ansonsten stabil. Der IOBROKER ist über LAN verbunden.

[besa]

Also nachdem was ich gegoogelt habe ist der Port 6379 mit dem Localhost der Redis-Server. Rufe ich den Redis-Server auf bekomme ich folgende Meldungen:

linaro@tinkerboard:~$ redis-server
15933:C 07 Jul 06:46:31.001 # Warning: no config file specified, using the defau                                  lt config. In order to specify a config file use redis-server /path/to/redis.con                                  f
15933:M 07 Jul 06:46:31.004 * Increased maximum number of open files to 10032 (i                                  t was originally set to 1024).
15933:M 07 Jul 06:46:31.005 # Warning: 32 bit instance detected but no memory li                                  mit set. Setting 3 GB maxmemory limit with 'noeviction' policy now.
                _._
           _.-``__ ''-._
      _.-``    `.  `_.  ''-._           Redis 3.2.6 (00000000/0) 32 bit
  .-`` .-```.  ```\/    _.,_ ''-._
 (    '      ,       .-`  | `,    )     Running in standalone mode
 |`-._`-...-` __...-.``-._|'` _.-'|     Port: 6379
 |    `-._   `._    /     _.-'    |     PID: 15933
  `-._    `-._  `-./  _.-'    _.-'
 |`-._`-._    `-.__.-'    _.-'_.-'|
 |    `-._`-._        _.-'_.-'    |           http://redis.io
  `-._    `-._`-.__.-'_.-'    _.-'
 |`-._`-._    `-.__.-'    _.-'_.-'|
 |    `-._`-._        _.-'_.-'    |
  `-._    `-._`-.__.-'_.-'    _.-'
      `-._    `-.__.-'    _.-'
          `-._        _.-'
              `-.__.-'

15933:M 07 Jul 06:46:31.007 # WARNING: The TCP backlog setting of 511 cannot be                                   enforced because /proc/sys/net/core/somaxconn is set to the lower value of 128.
15933:M 07 Jul 06:46:31.007 # Server started, Redis version 3.2.6
15933:M 07 Jul 06:46:31.008 # WARNING overcommit_memory is set to 0! Background                                   save may fail under low memory condition. To fix this issue add 'vm.overcommit_m                                  emory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.ove                                  rcommit_memory=1' for this to take effect.
15933:M 07 Jul 06:46:31.010 * The server is now ready to accept connections on port 6379

[Meister Mopper]

@besa sagte in Absturz jede Nacht - ECONNRESET - ECONNREFUSED:

15933:M 07 Jul 06:46:31.008 # WARNING overcommit_memory is set to 0! Background save may fail under low memory condition. To fix this issue add 'vm.overcommit_m emory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.ove rcommit_memory=1' for this to take effect.

Bei so vielen laufenden Adaptern: Wieviel Arbeitsspeicher ist denn noch frei. Gib mal 'top' in der Konsole ein und poste hier das Ergebnis.

[besa]

@Meister-Mopper

top - 08:24:57 up 5 min,  2 users,  load average: 0,24, 0,19, 0,09
Tasks: 183 total,   1 running, 182 sleeping,   0 stopped,   0 zombie
%Cpu(s): 15,0 us,  3,9 sy,  0,0 ni, 81,1 id,  0,0 wa,  0,0 hi,  0,0 si,  0,0 st
KiB Mem :  2060400 total,  1609648 free,   201248 used,   249504 buff/cache
KiB Swap:   102396 total,   102396 free,        0 used.  1826856 avail Mem

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU %MEM     TIME+ COMMAND
 4100 iobroker  20   0  157192  69684  26412 S  54,7  3,4   0:01.68 node
    1 root      20   0   26736   5136   3812 S   2,3  0,2   0:04.17 systemd
 4057 linaro    20   0    7160   2840   2296 R   1,6  0,1   0:00.48 top
  179 root      20   0   13904   4808   4340 S   1,3  0,2   0:01.24 systemd-jo+
  431 message+  20   0    5536   3124   2464 S   1,0  0,2   0:00.88 dbus-daemon
  623 root      20   0    4412   1772   1540 S   1,0  0,1   0:01.07 link_monit+
  913 root      20   0  194760  56052  41768 S   0,7  2,7   0:03.08 Xorg
 4194 redis     20   0       0      0      0 D   0,7  0,0   0:00.02 run-parts
    7 root      20   0       0      0      0 S   0,3  0,0   0:00.24 rcu_sched
   24 root      20   0       0      0      0 S   0,3  0,0   0:00.06 kworker/3:0
   97 root      20   0       0      0      0 S   0,3  0,0   0:00.23 kworker/u8+
  134 root      20   0       0      0      0 S   0,3  0,0   0:00.03 kworker/u8+
  486 root      20   0   23100   2236   1676 S   0,3  0,1   0:00.24 rsyslogd
  622 root      20   0    3424    348    304 S   0,3  0,0   0:00.04 tail
    2 root      20   0       0      0      0 S   0,0  0,0   0:00.01 kthreadd
    3 root      20   0       0      0      0 S   0,0  0,0   0:00.03 ksoftirqd/0
    5 root       0 -20       0      0      0 S   0,0  0,0   0:00.00 kworker/0:+

[Meister Mopper]

@besa Der wirft ja nur KiB aus, bei mir MiB:

Tasks:  64 total,   2 running,  62 sleeping,   0 stopped,   0 zombie
%Cpu(s): 90.3 us,  0.0 sy,  0.0 ni,  9.7 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
MiB Mem :   4096.0 total,    983.8 free,   2744.1 used,    368.0 buff/cache
MiB Swap:   2048.0 total,   2017.5 free,     30.5 used.   1351.9 avail Mem 

[Thomas Braun]

@besa Konsolenausgaben bitte in CodeTags setzen.

Dann das System ohne laufenden X-Server betreiben.

[besa]

@Meister-Mopper

Datenträger verfügbar: 49.2 %, gesamte RAM-Nutzung: 987 MB / Frei: 62% = 1.241 MB [Host: tinkerboard - 20 Prozesse]

Ich habe deshalb ein MULTI-HOST System eingerichtet und ein Teil gestern ausgelagert. Das brachte aber auch keinen Erfolg. MIt über 62% freien Speicher glaube ich hier nicht an einen Speicherfehler. Aber es könnte natürlich sein, dass der Redis-Server in einen Overflow oder Speicherengpass kommt, daher erst nachts. Tagsüber habe ich bisher immer einen Neustart gehabt.

[besa]

@Thomas-Braun

Mit X-Server meinst Du ohne Redis-Server, oder ?. Ja dann wird es sehr langsam.

[Thomas Braun]

@besa Nein, ich meine die graphische Oberfläche des Systems. Die läuft da unnötiger Weise mit und frisst Ressourcen.

uname -a
who -r

[besa]

@Thomas-Braun
AH Danke. Sehe gerade dass Redis mir folgendes meldet:

7908:M 07 Jul 08:48:04.146 # Background saving error
7908:M 07 Jul 08:48:10.062 * 100 changes in 300 seconds. Saving...
7908:M 07 Jul 08:48:10.063 * Background saving started by pid 19857
19857:C 07 Jul 08:48:10.063 # Failed opening the RDB file dump.rdb (in server root dir /etc/redis) for saving: Permission denied

[besa]

@besa

Das ständig mit einer neuen PID

[Thomas Braun]

@besa Alles krumm aufgesetzt, scheint mir.

Aber ohne Konsolenrückmeldung keine Hilfe möglich. Viel Spaß noch, ich bin dann raus.

[besa]

@Thomas-Braun
Sorry
Hier die Rückmeldung

linaro@tinkerboard:/etc/redis$ uname -a
Linux tinkerboard 4.4.132+ #1 SMP Wed Aug 21 19:15:55 CST 2019 armv7l GNU/Linux
linaro@tinkerboard:/etc/redis$ who -r
         Runlevel 5   2020-07-07 08:19
linaro@tinkerboard:/etc/redis$

[Nordischerjung]

@besa

setze mal deine kopierten Zeilen von der Konsole in Code tags </> diese Symbol anklicken und dazwischen einfügen
dann ercheint in der Antwort diese Fenster

hier steht dann dein Text

[besa]

@Nordischerjung

linaro@tinkerboard:/etc/redis$ uname -a
Linux tinkerboard 4.4.132+ #1 SMP Wed Aug 21 19:15:55 CST 2019 armv7l GNU/Linux

linaro@tinkerboard:/etc/redis$ who -r
         Runlevel 5   2020-07-07 08:19

[Nordischerjung]

@besa
guck mal hier, da hat @Thomas-Braun mir mal erklärt, das der Runlevel 5 nicht gut für ein Server ist. Vielleicht solltest du den mal auf Level 3 ändern, weiß aber nicht ob die Befehle die gleichen sind.

https://forum.iobroker.net/topic/33702/iobroker-startet-sporadisch-neu/20

da kann @Thomas-Braun dir bestimmt weiterhelfen

[besa]

@Nordischerjung

Gemäß der Anleitung von Thomas- Braun habe ich auf Runlevel 3 umgestellt

sudo whoami
systemctl get-default
sudo systemctl set-default multi-user.target
systemctl get-default

und dann

sudo reboot

Dann

linaro@tinkerboard:/opt/iobroker$ who -r
         Runlevel 3   2020-07-07 09:30

Ergebnis: IOBROKER läuft nicht mehr.

[besa]

@besa

dann den Redis Server manuell gestartet mit

redis-server

IOBROKER läuft wieder !!!!!!!!!

aber Redis gibt folgende Meldungen aus

6122:C 07 Jul 09:40:36.703 # Warning: no config file specified, using the default config. In order to specify a config file use redis-server /path/to/redis.conf
6122:M 07 Jul 09:40:36.704 * Increased maximum number of open files to 10032 (it was originally set to 1024).
6122:M 07 Jul 09:40:36.705 # Warning: 32 bit instance detected but no memory limit set. Setting 3 GB maxmemory limit with 'noeviction' policy now.
                _._
           _.-``__ ''-._
      _.-``    `.  `_.  ''-._           Redis 3.2.6 (00000000/0) 32 bit
  .-`` .-```.  ```\/    _.,_ ''-._
 (    '      ,       .-`  | `,    )     Running in standalone mode
 |`-._`-...-` __...-.``-._|'` _.-'|     Port: 6379
 |    `-._   `._    /     _.-'    |     PID: 6122
  `-._    `-._  `-./  _.-'    _.-'
 |`-._`-._    `-.__.-'    _.-'_.-'|
 |    `-._`-._        _.-'_.-'    |           http://redis.io
  `-._    `-._`-.__.-'_.-'    _.-'
 |`-._`-._    `-.__.-'    _.-'_.-'|
 |    `-._`-._        _.-'_.-'    |
  `-._    `-._`-.__.-'_.-'    _.-'
      `-._    `-.__.-'    _.-'
          `-._        _.-'
              `-.__.-'

6122:M 07 Jul 09:40:36.706 # WARNING: The TCP backlog setting of 511 cannot be enforced because /proc/sys/net/core/somaxconn is set to the lower value of 128.
6122:M 07 Jul 09:40:36.706 # Server started, Redis version 3.2.6
6122:M 07 Jul 09:40:36.706 * The server is now ready to accept connections on port 6379
^C6122:signal-handler (1594107715) Received SIGINT scheduling shutdown...
6122:M 07 Jul 09:41:55.917 # User requested shutdown...
6122:M 07 Jul 09:41:55.917 * Saving the final RDB snapshot before exiting.
6122:M 07 Jul 09:41:55.955 * DB saved on disk
6122:M 07 Jul 09:41:55.955 # Redis is now ready to exit, bye bye...