Server Cannot start inMem-objects on port 9001

@rene-3 Ok, also nochmal: Suche einen wert für Timeout sodass ein "iob status" mit der korrekten Ausgabe kommt und nicht mit dem "Kann DB nicht öffnen" Fehler. Du sagtest das es mit den 600000 nach Wartezeit geklappt hat. Ist doch super.

Dann sollte ein "iob start" ebenfalls gehen - mit der genannten Wartezeit.

Dann sollte man weiterschauen können.

Das Problem ist das Du scheinbar irgendwie geschafft hast massssiiiggggg Objekte anzusammeln. Auf meine Frage ob Du ble installiert hast oder so hast Du noch nicht geantwortet.

ALternativ schau deine Backups an ... ist da eins wo das objects Backup massiv kleiner ist? Wenn ja könnte man das ggf nehmen - falls DU da was findest.

Rene 3

@apollon77

also mit dem derzeitig eingetragenen Wert ( Timeout ) von 30000 ( und iob start ) kommt nach Eingabe von iobroker status ( und ausgeschalteter GUI ) kein "kann DB nicht öffnen", sondern "iobroker is not running on this host"
Der iobroker läuft nicht bzw. ich habe keinerlei Zugriff.
Diese Ausgabe kommt so vom Raspi zurück ( ca. 2 Min. ), weswegen ich das Timeout momentan so bei mir belassen würde.
Jetzt war ich mal längere Zeit nur im Terminal, ohne was zu machen und habe einfach nochmal iobroker staus ausgeführt und es kommt nun die Meldung: Server cannot Start inMem-objekts on Port 9001: Failed to lock-DB File "opt/iobroker/iobroker-data/objects.jsonl"!
Voll verrückt - also es gibt mir mal diese und mal jene Meldung aus, auf iob status aus.
Wovon das jetzt wieder abhängt - keine Ahnung.

Mache ich iob stop und dann iobroker status bleibt wenigstens die Meldung gleich ( ( " iobroker is not running on this host " )
Das Vorgehen, erst den iobroker zu stoppen und dann den Iobroker status abzufragen scheint aber der richtige Weg zu sein.

Ich hab kein ble installiert - was soll das sein ? ( Abkürzung für irgendwas ? ) - jedenfalls fällt mir da jetzt momentan überhaupt nichts dazu ein.
Also auch die Anzahl meiner installierten Adapter in iobroker ist sehr überschaubar ( Anzahl < 14 - glaube ich ).

Backups habe ich auf dem NAS ( extern ) liegen und grüble grad wieder wie ich die Dateien von dort zum Pi bekomme.
Aber auf dem Pi ( local ) selbst könnten auch noch einige Backup Dateien rumgeistern.

Rene 3

@apollon77
habe jetzt Netzwerk auch noch raus und nochmal mit

ls -la /opt/iobroker/iobroker-data

die Datei objects.jsonl wächst und wächst bei mir in der Größe immer weiter an, obwohl der iobroker nicht läuft.

Thomas Braun

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

Datei objects.jsonl wächst und wächst bei mir in der Größe immer weiter an, obwohl der iobroker nicht läuft.

Und was steht drin?

apollon77

@rene-3 Also Wenn ioBroker läuft darf die objects.json sich nicht ändertn... Sicher das der nicht doch noch läuft?

ALso ich wäre bei "AB mit Dir ins Discord und schau das jemand von dort mit dir ne Anydesk session macht und sich dein system anschaut

Rene 3

@apollon77
also in Discord hatte ich auch schon mal #linux einen Versuch gestartet aber war wohl ein falscher Zeitpunkt.
Wie soll das mit der Anydesk Session gehen ?

Schaum

@rene-3 ich bin genau an der selben Stelle wie Du.
"host": "0.0.0.0",
und
Tomeout auf 30000

die Meldung erscheint dann bei mir auch wieder:
iob status
Server Cannot start inMem-objects on port 9001: Failed to lock DB file "/opt/iobroker/iobroker-data/objects.jsonl"!

Update:
Nachdem ich ein Backup eingespielt habe welches um die Hälfte läuft der Admin-Aufruf wieder.

apollon77

@schaum Wieviele Objekte hast Du jetzt noch?

Rene 3

@Schaum

also läuft deine iobroker Installation wieder ?
Das wäre ja super.
Bei mir verweigert

iobroker restore 2022_07_16 .... backupiobroker.tar.gz

den Dienst und antwortet weiterhin mit der bekannten Meldung.
Also ich bin langsam an einem Punkt - egal.

Kurze Frage hast du den Adapter homekit-controller installiert / geupdatet ?
Ich habe versucht mit iobroker del < Adapter > entsprechend zu löschen aber bricht ab.

Vom Adapter Aufruf bin ich noch weit entfernt

TheKilroy

@apollon77
Moin allerseits.
Habe seit gestern hier dasselbe Problem. Glücklicherweise nur auf dem Test-Server, ein RPI4 mit 4GB und SSD ohne GUI.
Während des Urlaubs gab es einen Stromausfall (log files sind wegen logrotate nicht mehr vorhanden). Allerdings lief der Server danach ohne Auffälligkeiten. Auch ein Systemupdate mittels "apt-get update" und "apt-get dist-upgrade" brachte kein Problem.
Nach einem "iob update" und "iob upgrade" startete der ioBroker nicht mehr und die "objects.jsonl" ist jetzt 1.4GB gross.
Die Fehlermeldungen sind exakt genau wie oben. Alle Lösungsansätze hatten, genau wie oben beschrieben, keinen Erfolg.
Ich wollte euch dies nur mitteilen, damit klar ist, dass es sich nicht um einen Einzelfall handelt.

pi@shsrv02:/var/log $ uname -m && which nodejs node npm && nodejs -v && node -v && npm -v && whoami && pwd && sudo apt update &> /dev/null && sudo apt update && apt policy nodejs
armv7l
/usr/bin/nodejs
/usr/bin/node
/usr/bin/npm
v14.20.0
v14.20.0
6.14.17
pi
/var/log
OK:1 http://archive.raspberrypi.org/debian buster InRelease
OK:2 https://deb.nodesource.com/node_14.x buster InRelease
OK:3 http://raspbian.raspberrypi.org/raspbian buster InRelease
Paketlisten werden gelesen... Fertig
Abh▒ngigkeitsbaum wird aufgebaut.
Statusinformationen werden eingelesen.... Fertig
Alle Pakete sind aktuell.
nodejs:
  Installiert:           14.20.0-deb-1nodesource1
  Installationskandidat: 14.20.0-deb-1nodesource1
  Versionstabelle:
 *** 14.20.0-deb-1nodesource1 500
        500 https://deb.nodesource.com/node_14.x buster/main armhf Packages
        100 /var/lib/dpkg/status
     10.24.0~dfsg-1~deb10u1 500
        500 http://raspbian.raspberrypi.org/raspbian buster/main armhf Packages
pi@shsrv02:/var/log $

Hier die Ausgabe für die Netzwerkports.

pi@shsrv02:/var/log $ sudo ss -tulpn | grep LISTEN
tcp     LISTEN   0        80             127.0.0.1:3306           0.0.0.0:*      users:(("mysqld",pid=620,fd=18))
tcp     LISTEN   0        128              0.0.0.0:22             0.0.0.0:*      users:(("sshd",pid=578,fd=3))
tcp     LISTEN   0        128                 [::]:22                [::]:*      users:(("sshd",pid=578,fd=4))
...
Ausgabe des "iobroker-data" Verzeichnisses
...
pi@shsrv02:~ $ ll -h /opt/iobroker/iobroker-data/
insgesamt 1,4G
4,0K drwxrwxr-x+ 10 iobroker iobroker 4,0K Sep 19 11:51 .
4,0K drwxrwxr-x+  9 iobroker iobroker 4,0K Sep 19 11:52 ..
4,0K drwxrwxr-x+  2 iobroker iobroker 4,0K Sep 18 17:07 backup-objects
4,0K drwxrwxr-x+ 34 iobroker iobroker 4,0K Jul 20 12:32 files
8,0K -rw-rwxr--+  1 iobroker iobroker 4,7K Sep 19 11:30 iobroker.json
4,0K drwxrwxr-x+  3 iobroker iobroker 4,0K Jul 16  2021 jarvis
4,0K drwxrwxr-x+  5 iobroker iobroker 4,0K Sep  1 19:53 node-red
4,0K -rw-rwxr--+  1 iobroker iobroker    3 Sep 18 18:44 notifications.json
5,5M -rw-rwxr--+  1 iobroker iobroker 5,5M Feb 13  2022 objects.json.bak.migrated
1,4G -rw-rwxr--+  1 iobroker iobroker 1,4G Sep 19 11:51 objects.jsonl
5,5M -rw-rwxr--+  1 iobroker iobroker 5,5M Feb 13  2022 objects.json.migrated
4,0K drwxrwxr-x+  2 iobroker iobroker 4,0K Dez  5  2020 sqlite
436K -rw-rwxr--+  1 iobroker iobroker 435K Feb 13  2022 states.json.bak.migrated
   0 -rw-rwxr--+  1 iobroker iobroker    0 Dez 22  2021 states.json.broken
9,9M -rw-rwxr--+  1 iobroker iobroker 9,9M Sep 19 11:51 states.jsonl
436K -rw-rwxr--+  1 iobroker iobroker 435K Feb 13  2022 states.json.migrated
4,0K drwxrwxr-x+  2 iobroker iobroker 4,0K Mai 10 18:11 yahka.0.hapdata
4,0K drwxrwxr-x+  2 iobroker iobroker 4,0K Mai 10 18:15 yahka.1.hapdata
 12K drwxrwxr-x+  2 iobroker iobroker  12K Sep 18 18:44 zigbee_0

Meine "iobroker.json". Hier habe ich auch verschiedene Einstellungen bei den Hosts versucht. "0.0.0.0" oder "127.0.0.1" haben keinen Unterschied gemacht.

pi@shsrv02:~ $ cat /opt/iobroker/iobroker-data/iobroker.json
{
  "system": {
    "memoryLimitMB": 0,
    "hostname": "",
    "statisticsInterval": 15000,
    "statisticsIntervalComment": "Interval how often the counters for input/output in adapters and controller will be updated",
    "checkDiskInterval": 300000,
    "checkDiskIntervalComment": "Interval how often the disk size will be checked",
    "noChmod": false,
    "instanceStartInterval": 2000,
    "noChmodComment": "Flag to test new feature with no chmod call. Must be deleted later and noChmod must be mainline (2018.06.04)",
    "compact": false,
    "compactComment": "Controller will try to start the instances as a part of the same process. No spawn will be done. Only by adapters that support it and have flag compact flag in io-package.json",
    "allowShellCommands": false,
    "allowShellCommandsComment": "Allow execution of \"shell\" sendToHost commands",
    "memLimitWarn": 100,
    "memLimitWarnComment": "If the available RAM is below this threshold on adapter start, a warning will be logged.",
    "memLimitError": 50,
    "memLimitErrorComment": "If the available RAM is below this threshold on adapter start, an error will be logged."
  },
  "multihostService": {
    "enabled": false,
    "secure": true
  },
  "network": {
    "IPv4": true,
    "IPv6": true,
    "bindAddress": null
  },
  "objects": {
    "type": "jsonl",
    "typeComment": "Possible values: 'file' - [port 9001], redis - [port 6379], couch - [port 5984].",
    "host": "127.0.0.1",
    "port": 9001,
    "user": "",
    "pass": "",
    "noFileCache": false,
    "connectTimeout": 600000,
    "writeFileInterval": 5000,
    "options": {
      "auth_pass": null,
      "retry_max_delay": 5000
    },
    "backup": {
      "disabled": false,
      "files": 24,
      "filesComment": "Minimal number of backup files, after the deletion will be executed according to backupTime settings",
      "hours": 48,
      "hoursComment": "All backups older than 48 hours will be deleted. But only if the number of files is greater than of backupNumber",
      "period": 120,
      "periodComment": "by default backup every 2 hours. Time is in minutes. To disable backup set the value to 0",
      "path": "",
      "pathComment": "Absolute path to backup directory or empty to backup in data directory"
    },
    "dataDir": "../../iobroker-data/"
  },
  "states": {
    "type": "jsonl",
    "typeComment": "Possible values: 'file' - [port 9000], 'redis' - [port 6379].",
    "host": "127.0.0.1",
    "port": 9000,
    "maxQueue": 1000,
    "user": "",
    "pass": "",
    "connectTimeout": 2000,
    "writeFileInterval": 30000,
    "options": {
      "auth_pass": null,
      "retry_max_delay": 5000
    },
    "backup": {
      "disabled": false,
      "files": 24,
      "filesComment": "Minimal number of backup files, after the deletion will be executed according to backupTime settings",
      "hours": 48,
      "hoursComment": "All backups older than 48 hours will be deleted. But only if the number of files is greater than of backupNumber",
      "period": 120,
      "periodComment": "by default backup every 2 hours. Time is in minutes. To disable backup set the value to 0",
      "path": "",
      "pathComment": "Absolute path to backup directory or empty to backup in data directory"
    },
    "dataDir": "../../iobroker-data/"
  },
  "log": {
    "level": "info",
    "maxDays": 7,
    "noStdout": true,
    "transport": {
      "file1": {
        "type": "file",
        "enabled": true,
        "filename": "log/iobroker",
        "fileext": ".log",
        "maxSize": null,
        "maxFiles": null
      },
      "syslog1": {
        "type": "syslog",
        "enabled": false,
        "host": "localhost",
        "hostComment": "The host running syslogd, defaults to localhost.",
        "portComment": "The port on the host that syslog is running on, defaults to syslogd's default port(514/UDP).",
        "protocol": "udp4",
        "protocolComment": "The network protocol to log over (e.g. tcp4, udp4, unix, unix-connect, etc).",
        "pathComment": "The path to the syslog dgram socket (i.e. /dev/log or /var/run/syslog for OS X).",
        "facilityComment": "Syslog facility to use (Default: local0).",
        "localhost": "iobroker",
        "localhostComment": "Host to indicate that log messages are coming from (Default: localhost).",
        "sysLogTypeComment": "The type of the syslog protocol to use (Default: BSD).",
        "app_nameComment": "The name of the application (Default: process.title).",
        "eolComment": "The end of line character to be added to the end of the message (Default: Message without modifications)."
      }
    }
  },
  "dataDirComment": "Always relative to iobroker.js-controller/",
  "plugins": {},
  "dataDir": "../../iobroker-data/"
}

Ausgabe "iob status" nach "iob fix"

pi@shsrv02:/opt/iobroker $ iob status
iobroker is not running on this host.


Objects type: jsonl
States  type: jsonl

Ausgabe log für "iob start". Das geht dann so weiter bis in alle Ewigkeit.

2022-09-19 14:12:29.118  - info: host.shsrv02.kilroy.local iobroker.js-controller version 4.0.23 js-controller starting
2022-09-19 14:12:29.125  - info: host.shsrv02.kilroy.local Copyright (c) 2014-2022 bluefox, 2014 hobbyquaker
2022-09-19 14:12:29.126  - info: host.shsrv02.kilroy.local hostname: shsrv02.kilroy.local, node: v14.20.0
2022-09-19 14:12:29.126  - info: host.shsrv02.kilroy.local ip addresses: 192.168.99.203 fd70:bbdd:5eb8::203 fd70:bbdd:5eb8:0:11ad:28db:a0e0:a98b fe80::8672:deec:d642:31d3
2022-09-19 14:12:59.146  - error: host.shsrv02.kilroy.local No connection to databases possible, restart
2022-09-19 14:12:59.163  - info: host.shsrv02.kilroy.local iobroker _restart
2022-09-19 14:13:05.151  - info: host.shsrv02.kilroy.local iobroker.js-controller version 4.0.23 js-controller starting
2022-09-19 14:13:05.158  - info: host.shsrv02.kilroy.local Copyright (c) 2014-2022 bluefox, 2014 hobbyquaker
2022-09-19 14:13:05.159  - info: host.shsrv02.kilroy.local hostname: shsrv02.kilroy.local, node: v14.20.0
2022-09-19 14:13:05.160  - info: host.shsrv02.kilroy.local ip addresses: 192.168.99.203 fd70:bbdd:5eb8::203 fd70:bbdd:5eb8:0:11ad:28db:a0e0:a98b fe80::8672:deec:d642:31d3
2022-09-19 14:13:35.180  - error: host.shsrv02.kilroy.local No connection to databases possible, restart
2022-09-19 14:13:35.197  - info: host.shsrv02.kilroy.local iobroker _restart
2022-09-19 14:13:41.145  - info: host.shsrv02.kilroy.local iobroker.js-controller version 4.0.23 js-controller starting
2022-09-19 14:13:41.152  - info: host.shsrv02.kilroy.local Copyright (c) 2014-2022 bluefox, 2014 hobbyquaker
2022-09-19 14:13:41.153  - info: host.shsrv02.kilroy.local hostname: shsrv02.kilroy.local, node: v14.20.0
2022-09-19 14:13:41.153  - info: host.shsrv02.kilroy.local ip addresses: 192.168.99.203 fd70:bbdd:5eb8::203 fd70:bbdd:5eb8:0:11ad:28db:a0e0:a98b fe80::8672:deec:d642:31d3
2022-09-19 14:14:11.172  - error: host.shsrv02.kilroy.local No connection to databases possible, restart
2022-09-19 14:14:11.189  - info: host.shsrv02.kilroy.local iobroker _restart

Verdammt viel Info, könnte aber helfen das Problem zu lokalisieren.
Für mich sieht es so aus, dass die "objects.jsonl" durch irgendetwas so riesig wird, dass ein internes Problem auftritt. Auf meinem aktiven Server mit einer Menge mehr Objekten ist genau diese Datei 15MB groß und nicht wie hier 1.4GB.

Gruß Kilroy

apollon77

@thekilroy Versuch mal bei ausgeschaltetem iobroekr an der Kommandozeile ein "iob list instances".Was passiert und wie lange dauert es? (EInfach laufen lassen).

AlCalzone

@TheKilroy kannst du die objects.jsonl mal zippen und mir hochladen? Ich schreib dir separat wo.
Würde die Datei gerne mal untersuchen, warum hier nix komprimiert wird.

TheKilroy

@apollon77 Hier die Ausgabe wie gewünscht.

pi@shsrv02:/opt/iobroker $ date && iob list instances && date
Mo 19. Sep 16:38:03 CEST 2022
  system.adapter.admin.0                  : admin                 : shsrv02.kilroy.local                     -  enabled, port: 8081, bind: 0.0.0.0, run as: admin
  system.adapter.cameras.0                : cameras               : shsrv02.kilroy.local                     -  enabled, port: 8200, bind: 127.0.0.1
  system.adapter.discovery.0              : discovery             : shsrv02.kilroy.local                     -  enabled
  system.adapter.echarts.0                : echarts               : shsrv02.kilroy.local                     -  enabled
  system.adapter.flot.0                   : flot                  : shsrv02.kilroy.local                     -  enabled
  system.adapter.followthesun.0           : followthesun          : shsrv02.kilroy.local                     -  enabled
  system.adapter.info.0                   : info                  : shsrv02.kilroy.local                     -  enabled
  system.adapter.jarvis.0                 : jarvis                : shsrv02.kilroy.local                     -  enabled
  system.adapter.linkeddevices.0          : linkeddevices         : shsrv02.kilroy.local                     -  enabled
  system.adapter.mpd.0                    : mpd                   : shsrv02.kilroy.local                     -  enabled, port: 6600
  system.adapter.node-red.0               : node-red              : shsrv02.kilroy.local                     -  enabled, port: 1880, bind: 0.0.0.0
  system.adapter.parser.0                 : parser                : shsrv02.kilroy.local                     -  enabled
  system.adapter.ping.0                   : ping                  : shsrv02.kilroy.local                     -  enabled
  system.adapter.rpi2.0                   : rpi2                  : shsrv02.kilroy.local                     -  enabled
  system.adapter.shelly.0                 : shelly                : shsrv02.kilroy.local                     - disabled, port: 1882, bind: 192.168.99.203
  system.adapter.sonoff.0                 : sonoff                : shsrv02.kilroy.local                     -  enabled, port: 1883, bind: 192.168.99.203
  system.adapter.sql.0                    : sql                   : shsrv02.kilroy.local                     -  enabled, port: 0
  system.adapter.web.0                    : web                   : shsrv02.kilroy.local                     -  enabled, port: 8082, bind: 0.0.0.0, run as: admin
  system.adapter.yahka.0                  : yahka                 : shsrv02.kilroy.local                     -  enabled
  system.adapter.zigbee.0                 : zigbee                : shsrv02.kilroy.local                     -  enabled, port: /dev/ttyACM0

+ instance is alive
Mo 19. Sep 16:58:53 CEST 2022

Ich hoffe das hilft weiter.

apollon77

@thekilroy Ist das objects.jsonl jetzt kleiner? 20 Minuten ist schon ne nummer ... Mit dem Wissen könntest Du jetzt das "connectTimeout" in den objects settings auf mindestens diese Zahl in MS setzen (also 1500000 oder so um bissl mehr zu nehmen) und schauen ob iobroker damit wieder startet ... Aber bitte in jedem Fall mal @AlCalzone das File zukommen lassen weil Wenn es nicht kleiner geworden ist wie verstehen müssen warum

NEWS

Server Cannot start inMem-objects on port 9001

Support us

899
Online

31.8k
Users

80.0k
Topics

1.3m
Posts

NEWS

Server Cannot start inMem-objects on port 9001

Support us

899Online

31.8kUsers

80.0kTopics

1.3mPosts

899
Online

31.8k
Users

80.0k
Topics

1.3m
Posts