Server Cannot start inMem-objects on port 9001

[bischemer]

Hallo IOB Community,
meine iob Installation startet nicht mehr. Als ich von einer Kurzreise zugekommen bin war meine vorher stabil laufende iob Installation (auf Raspberry4) down. Die bisherigen Versuche, start/stop der Instanz, komplett reboot, timeout auf 5000 waren alle erfolglos. Ich bekomme bei der Status Abfrage folgendes

pi@rpi4iobroker:/opt/iobroker $ iob status
Server Cannot start inMem-objects on port 9001: Failed to lock DB file "/opt/iobroker/iobroker-data/objects.jsonl"!

und beim Versuch eines updates mit sudo iobroker update kommt folgendes

No connection to databases possible ...

bin nun leider mit meinem beschränkten Linux Wissen am Ende. Jeder Tipp die Installation wieder zum laufen zu bekommen ist willkommen. DANKE

[haselchen]

@bischemer

All diese Fehler hatte ich nach einem JS Controller Update im Docker Container.
So ganz ohne Zutun wird dein System diese Nachrichten nicht anzeigen .
Da werden vermutlich Rechte kaputt sein . Oder die Migration von file auf jsonl hat nicht richtig geklappt.Vermutlich wird Iobroker fix das Problem nicht lösen.
Versuchen kannst du es natürlich.
Letztendlich half nur neuer Container und Backup einspielen.

[bischemer]

@haselchen
Danke für die schnelle Rückmeldung. Das mit iobroker fix hatte ich bisher noch nicht gemacht. Ein Versuch ist es Wert. Es war tatsächlich so, das System war stabil auch nach dem controller update auf 4.x und nach meiner Rückkehr lief es nicht mehr und startete auch nicht mehr. Jetzt teste ich erstmal iobroker fix.

[wendy2702]

@bischemer Wenn es eine Kurzreise war solltest du die Logs von dem Zeitraum ansehen.

iobroker und linux syslog ob da etwas auffälliges drin steht.

Läuft auf dem PI noch etwas anderes ausser iobroker?

[bischemer]

@wendy2702, ja es läuft noch eine Grafana Installation die aber ohne Probleme. Das mit den Logs ist eine gute Idee. Die Log files habe ich gefunden. Muss nur vorher mein beschränktes Linux Wissen auf Vordermann bringen. Da es sich bei den älteren um .gz files handelt. Das mache ich dann gleich mal

@haselchen, der iobroker fix Versuch war leider nicht von Erfolg gekrönt.

[Thomas Braun]

@bischemer sagte in Server Cannot start inMem-objects on port 9001:

Muss nur vorher mein beschränktes Linux Wissen auf Vordermann bringen. Da es sich bei den älteren um .gz files handelt.

Mit z. B.

zless /pfad/zur/log/datei.gz

kann man da direkt reinschauen.

[bischemer]

@thomas-braun, Danke ... ich habe mir jetzt zur Sicherheit mal die letzten Tage entpackt und angesehen. Gestern ging es irgendwann los mit dem Problem. Das ist nur ein Ausschnitt. Ich weiß leider nicht, wonach ich genau suchen muss.

2022-03-12 14:57:23.661  - ^[[31merror^[[39m: history.0 (1010) States database error: connect ECONNREFUSED 127.0.0.1:9000
2022-03-12 14:57:23.813  - ^[[31merror^[[39m: javascript.0 (29634) Objects database error: connect ECONNREFUSED 127.0.0.1:9001
2022-03-12 14:57:23.861  - ^[[31merror^[[39m: influxdb.0 (29733) Objects database error: connect ECONNREFUSED 127.0.0.1:9001
2022-03-12 14:57:28.671  - ^[[31merror^[[39m: history.0 (1010) States database error: connect ECONNREFUSED 127.0.0.1:9000
2022-03-12 14:57:28.694  - ^[[31merror^[[39m: history.0 (1010) Objects database error: connect ECONNREFUSED 127.0.0.1:9001
2022-03-12 14:57:29.308  - ^[[32minfo^[[39m: history.0 (1010) Terminated (START_IMMEDIATELY_AFTER_STOP): Without reason

[Thomas Braun]

@bischemer

Nach dem Zeitpunkt, wo es ohne Meldungen lief schauen.

Kann es sein, dass da ein anderer Service schon auf dem Port läuft?

sudo ss -tulpn | grep LISTEN

anschauen.

[bischemer]

das hier sind die letzten logs

2022-03-12 14:57:28.694  - ^[[31merror^[[39m: history.0 (1010) Objects database error: connect ECONNREFUSED 127.0.$
2022-03-12 14:57:29.308  - ^[[32minfo^[[39m: history.0 (1010) Terminated (START_IMMEDIATELY_AFTER_STOP): Without r$
2022-03-12 14:57:33.690  - ^[[33mwarn^[[39m: history.0 (1010) get state error: Connection is closed.

bevor immer wieder im 30 sec Takt folgendes kommt

2022-03-12 14:57:39.203  - ^[[32minfo^[[39m: host.rpi4iobroker iobroker.js-controller version 4.0.15 js-controller$
2022-03-12 14:57:39.210  - ^[[32minfo^[[39m: host.rpi4iobroker Copyright (c) 2014-2022 bluefox, 2014 hobbyquaker
2022-03-12 14:57:39.211  - ^[[32minfo^[[39m: host.rpi4iobroker hostname: rpi4iobroker, node: v14.18.2
2022-03-12 14:57:39.212  - ^[[32minfo^[[39m: host.rpi4iobroker ip addresses: 192.168.1.32 fe80::6152:e60e:7308:e5fe
2022-03-12 14:58:09.247  - ^[[31merror^[[39m: host.rpi4iobroker No connection to databases possible, restart
2022-03-12 14:58:09.264  - ^[[32minfo^[[39m: host.rpi4iobroker iobroker _restart

[bischemer]

@thomas-braun, leider nein. Habe eben Deinen Befehl abgesetzt und sehe nur bekannte Services wie Grafana und InfluxDB. Diese aber auf einem anderen Port

[Thomas Braun]

@bischemer

Zeig mal die Ausgabe.

[bischemer]

pi@rpi4iobroker:/opt/iobroker/log $ sudo ss -tulpn | grep LISTEN
tcp     LISTEN   0        128              0.0.0.0:22             0.0.0.0:*      users:(("sshd",pid=572,fd=3))
tcp     LISTEN   0        4096           127.0.0.1:8088           0.0.0.0:*      users:(("influxd",pid=548,fd=3))
tcp     LISTEN   0        4096                   *:8086                 *:*      users:(("influxd",pid=548,fd=41))
tcp     LISTEN   0        128                 [::]:22                [::]:*      users:(("sshd",pid=572,fd=4))
tcp     LISTEN   0        4096                   *:3000                 *:*      users:(("grafana-server",pid=544,fd=12))

[Thomas Braun]

@bischemer

ls -la /opt/iobroker/iobroker-data/objects.jsonl

[bischemer]

@thomas-braun

pi@rpi4iobroker:/opt/iobroker/log $ ls -la /opt/iobroker/iobroker-data/objects.jsonl
-rw-rwxr--+ 1 iobroker iobroker 5337230311 Mär 12 14:56 /opt/iobroker/iobroker-data/objects.jsonl

[Thomas Braun]

@bischemer

Ganz simple mal die Kiste neugestartet hattest du schon?

Schau mal mit

dmesg -T | grep -i ext4

in die Kernelringmessages.

[bischemer]

@thomas-braun

ja, einen Neustart hat das System schon hinter sich.

hier die Ausgabe mit Deinem Befehl

pi@rpi4iobroker:/opt/iobroker/log $ dmesg -T | grep -i ext4
[Mo Mär 14 16:26:14 2022] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1  smsc95xx.macaddr=DC:A6:32:47:F5:F1 vc_mem.mem_base=0x3eb00000 vc_mem.mem_size=0x3ff00000  console=ttyS0,115200 console=tty1 root=PARTUUID=51c7cc95-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[Mo Mär 14 16:26:15 2022] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
[Mo Mär 14 16:26:15 2022] VFS: Mounted root (ext4 filesystem) readonly on device 8:2.
[Mo Mär 14 16:26:17 2022] EXT4-fs (sda2): re-mounted. Opts: (null)

[Thomas Braun]

@bischemer

Okay, das ist unauffällig.

[wendy2702]

gibt es noch was bei

iob v

wenn nein, welche JS Version hattest du installiert?

[bischemer]

@wendy2702

pi@rpi4iobroker:/opt/iobroker/log $ iob v
4.0.15

und folgende Versionen sind installiert

pi@rpi4iobroker:/opt/iobroker/log $ node -v
v14.18.2
pi@rpi4iobroker:/opt/iobroker/log $ npm -v
6.14.15

[wendy2702]

@bischemer Node und NPM ist nicht aktuell.

Bringe dein System mal auf den aktuellen Stand. Für Node / NPM steht das in der Signatur von @Thomas-Braun

Wenn das OK ist mal:

cd /opt/iobroker/
sudo -H -u iobroker npm install iobroker.js-controller

Und hast du das Log von der iobroker fix Ausführung noch?