fhem startet nicht mehr

Begonnen von coolice, 06 Januar 2019, 14:08:20

Vorheriges Thema - Nächstes Thema

Wernieman

#15
mach doch mal ein
ls -lha /var/log/mysql/

Ansonsten gucke doch mal mit journalctl nach dem Fehler
https://wiki.ubuntuusers.de/systemd/journalctl/
journalctl -u fhem

p.s. was steht denn im System-log-File?
tail /var/log/messages
- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html

coolice

#16
Zitat von: Wernieman am 09 Januar 2019, 18:43:59
mach doch mal ein
ls -lha /var/log/mysql/

total 36K
drwxr-s--- 2 mysql adm  4.0K Jan  9 18:37 .
drwxr-xr-x 9 root  root 4.0K Jan  9 06:25 ..
-rw-r----- 1 mysql adm     0 Jan  9 17:44 error.log
-rw-r----- 1 mysql adm    20 Jan  8 06:25 error.log.1.gz
-rw-r----- 1 mysql adm    20 Jan  7 06:25 error.log.2.gz
-rw-r----- 1 mysql adm    20 Jan  6 21:13 error.log.3.gz
-rw-r----- 1 mysql adm    20 Jan  6 06:25 error.log.4.gz
-rw-r----- 1 mysql adm    20 Jan  4 06:25 error.log.5.gz
-rw-r----- 1 mysql adm    20 Jan  3 06:25 error.log.6.gz
-rw-r----- 1 mysql adm    20 Jan  2 06:25 error.log.7.gz

Ansonsten gucke doch mal mit journalctl nach dem Fehler
https://wiki.ubuntuusers.de/systemd/journalctl/
journalctl -u fhem

-- Logs begin at Sun 2019-01-06 13:13:46 CET, end at Wed 2019-01-09 18:51:04 CET. --
Jan 06 13:48:26 raspberrypi systemd[1]: Starting LSB: FHEM server...
Jan 06 13:48:28 raspberrypi fhem[357]: Starting fhem...
Jan 06 13:48:29 raspberrypi fhem[357]: DBI connect('database=fhem;localhost=db;port=3306','fhemuser',...) failed: Can't connect to local MySQL server through socket
Jan 06 13:48:29 raspberrypi systemd[1]: fhem.service: Control process exited, code=exited status=2
Jan 06 13:48:29 raspberrypi systemd[1]: Failed to start LSB: FHEM server.
Jan 06 13:48:29 raspberrypi systemd[1]: fhem.service: Unit entered failed state.
Jan 06 13:48:29 raspberrypi systemd[1]: fhem.service: Failed with result 'exit-code'.
Jan 06 21:12:50 raspberrypi systemd[1]: Starting LSB: FHEM server...
Jan 06 21:12:50 raspberrypi fhem[18982]: Starting fhem...
Jan 06 21:12:51 raspberrypi fhem[18982]: DBI connect('database=fhem;localhost=db;port=3306','fhemuser',...) failed: Can't connect to local MySQL server through socke
Jan 06 21:12:51 raspberrypi systemd[1]: fhem.service: Control process exited, code=exited status=2
Jan 06 21:12:51 raspberrypi systemd[1]: Failed to start LSB: FHEM server.
Jan 06 21:12:51 raspberrypi systemd[1]: fhem.service: Unit entered failed state.
Jan 06 21:12:51 raspberrypi systemd[1]: fhem.service: Failed with result 'exit-code'.

p.s. was steht denn im System-log-File?
tail /var/log/messages

Jan  6 13:48:31 raspberrypi kernel: [   12.851028] Bluetooth: HCI UART protocol H4 registered
Jan  6 13:48:31 raspberrypi kernel: [   12.851034] Bluetooth: HCI UART protocol Three-wire (H5) registered
Jan  6 13:48:31 raspberrypi kernel: [   12.851252] Bluetooth: HCI UART protocol Broadcom registered
Jan  6 13:48:31 raspberrypi kernel: [   13.026676] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Jan  6 13:48:31 raspberrypi kernel: [   13.026687] Bluetooth: BNEP filters: protocol multicast
Jan  6 13:48:31 raspberrypi kernel: [   13.026715] Bluetooth: BNEP socket layer initialized
Jan  7 06:25:02 raspberrypi liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="343" x-info="http://www.rsyslog.com"] rsyslogd was HUPed
Jan  7 06:25:02 raspberrypi liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="343" x-info="http://www.rsyslog.com"] rsyslogd was HUPed
Jan  8 06:25:01 raspberrypi liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="343" x-info="http://www.rsyslog.com"] rsyslogd was HUPed
Jan  9 06:25:01 raspberrypi liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="343" x-info="http://www.rsyslog.com"] rsyslogd was HUPed

journalctl -p err -b

Jan 06 13:13:47 raspberrypi kernel: brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43430-sdio.bin for chip 0x00a9a6(43430) rev 0x000001
Jan 06 13:13:47 raspberrypi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Oct 23 2017 03:55:53 version 7.45.98.38 (r674442 CY) FWID 01-e58d219f
Jan 06 13:13:47 raspberrypi kernel: brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 7.11.15 Compiler: 1.24.2 ClmImport: 1.24.1 Creation: 2014-05-26 10
Jan 06 13:48:29 raspberrypi systemd[1]: Failed to start LSB: FHEM server.
Jan 06 13:48:31 raspberrypi bluetoothd[477]: Failed to obtain handles for "Service Changed" characteristic
Jan 06 13:48:31 raspberrypi bluetoothd[477]: Sap driver initialization failed.
Jan 06 13:48:31 raspberrypi bluetoothd[477]: sap-server: Operation not permitted (1)
Jan 06 13:48:32 raspberrypi bluetoothd[477]: Failed to set privacy: Rejected (0x0b)
Jan 06 13:48:39 raspberrypi systemd[1]: Failed to start MariaDB database server.
Jan 06 14:10:10 raspberrypi systemd[1]: Failed to start MariaDB database server.
Jan 06 21:12:51 raspberrypi systemd[1]: Failed to start LSB: FHEM server.
Jan 06 21:13:31 raspberrypi systemd[1]: Failed to start MariaDB database server.
Jan 09 17:37:25 raspberrypi systemd[1]: Failed to start MariaDB database server.
Jan 09 17:45:00 raspberrypi systemd[1]: Failed to start MariaDB database server.


DasQ

Zitat von: coolice am 09 Januar 2019, 17:53:34
pi@raspberrypi:/opt/fhem $ systemctl status mysqld
● mariadb.service - MariaDB database server
   Loaded: loaded (/lib/systemd/system/mariadb.service; enabled; vendor preset: enabled)
   Active: failed (Result: exit-code) since Wed 2019-01-09 17:45:00 CET; 2min 49s ago
  Process: 26804 ExecStart=/usr/sbin/mysqld $MYSQLD_OPTS $_WSREP_NEW_CLUSTER $_WSREP_START_POSI
  Process: 26718 ExecStartPre=/bin/sh -c [ ! -e /usr/bin/galera_recovery ] && VAR= ||   VAR=`/u
  Process: 26714 ExecStartPre=/bin/sh -c systemctl unset-environment _WSREP_START_POSITION (cod
  Process: 26711 ExecStartPre=/usr/bin/install -m 755 -o mysql -g root -d /var/run/mysqld (code
Main PID: 26804 (code=exited, status=1/FAILURE)
   Status: "MariaDB server is down"


Jan 09 17:39:04 raspberrypi systemd[1]: Starting Clean php session files...
-- Subject: Unit phpsessionclean.service has begun start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
--
-- Unit phpsessionclean.service has begun starting up.
Jan 09 17:39:04 raspberrypi systemd[1]: Started Clean php session files.
-- Subject: Unit phpsessionclean.service has finished start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
--
-- Unit phpsessionclean.service has finished starting up.
--
-- The start-up result is done.
Jan 09 17:41:22 raspberrypi sudo[26510]:       pi : TTY=pts/0 ; PWD=/home/pi ; USER=root ; COMM
Jan 09 17:41:22 raspberrypi sudo[26510]: pam_unix(sudo:session): session opened for user root b
Jan 09 17:41:22 raspberrypi sudo[26510]: pam_unix(sudo:session): session closed for user root
Jan 09 17:43:08 raspberrypi sudo[26602]:       pi : TTY=pts/0 ; PWD=/opt/fhem ; USER=root ; COM
Jan 09 17:43:09 raspberrypi sudo[26602]: pam_unix(sudo:session): session opened for user root b
Jan 09 17:43:09 raspberrypi sudo[26602]: pam_unix(sudo:session): session closed for user root
Jan 09 17:43:45 raspberrypi sudo[26632]:       pi : TTY=pts/0 ; PWD=/opt/fhem ; USER=root ; COM
Jan 09 17:43:46 raspberrypi sudo[26632]: pam_unix(sudo:session): session opened for user root b
Jan 09 17:44:26 raspberrypi sudo[26632]: pam_unix(sudo:session): session closed for user root
Jan 09 17:44:54 raspberrypi sudo[26684]:       pi : TTY=pts/0 ; PWD=/opt/fhem ; USER=root ; COM
Jan 09 17:44:54 raspberrypi sudo[26684]: pam_unix(sudo:session): session opened for user root b
Jan 09 17:44:55 raspberrypi systemd[1]: Starting MariaDB database server...
-- Subject: Unit mariadb.service has begun start-up
-- Defined-By: systemd
-- Support: https://www.debian.org/support
--
-- Unit mariadb.service has begun starting up.
Jan 09 17:44:57 raspberrypi mysqld[26804]: 2019-01-09 17:44:57 1988669440 [Note] /usr/sbin/mysq
Jan 09 17:45:00 raspberrypi systemd[1]: mariadb.service: Main process exited, code=exited, stat
Jan 09 17:45:00 raspberrypi systemd[1]: Failed to start MariaDB database server.
-- Subject: Unit mariadb.service has failed
-- Defined-By: systemd
-- Support: https://www.debian.org/support
--
-- Unit mariadb.service has failed.
--
-- The result is failed.
Jan 09 17:45:00 raspberrypi systemd[1]: mariadb.service: Unit entered failed state.
Jan 09 17:45:00 raspberrypi systemd[1]: mariadb.service: Failed with result 'exit-code'.
Jan 09 17:45:00 raspberrypi sudo[26684]: pam_unix(sudo:session): session closed for user root


kannst du diese beiden "logs" nochmals komplett posten? das scheint mir rechts abgeschnitten. im zweifelsfall in eine text > datei umleiten
Fhem on MacMini/Ubuntu.
Absoluter Befürworter der Konsequenten-Kleinschreibung https://de.wikipedia.org/wiki/Kleinschreibung
Infos zu Klimawandel http://www.globalcarbonatlas.org

coolice

#18
pi@raspberrypi:~ $ systemctl status mysqld
● mariadb.service - MariaDB database server
   Loaded: loaded (/lib/systemd/system/mariadb.service; enabled; vendor preset: enabled)
   Active: failed (Result: exit-code) since Wed 2019-01-09 17:45:00 CET; 1h 40min ago
  Process: 26804 ExecStart=/usr/sbin/mysqld $MYSQLD_OPTS $_WSREP_NEW_CLUSTER $_WSREP_START_POSITION (code=exited, status=1/FAILURE)
  Process: 26718 ExecStartPre=/bin/sh -c [ ! -e /usr/bin/galera_recovery ] && VAR= ||   VAR=`/usr/bin/galera_recovery`; [ $? -eq 0 ]   && systemctl set-environment _WSREP_START_POSI
  Process: 26714 ExecStartPre=/bin/sh -c systemctl unset-environment _WSREP_START_POSITION (code=exited, status=0/SUCCESS)
  Process: 26711 ExecStartPre=/usr/bin/install -m 755 -o mysql -g root -d /var/run/mysqld (code=exited, status=0/SUCCESS)
Main PID: 26804 (code=exited, status=1/FAILURE)
   Status: "MariaDB server is down"

Jan 09 17:44:55 raspberrypi systemd[1]: Starting MariaDB database server...
Jan 09 17:44:57 raspberrypi mysqld[26804]: 2019-01-09 17:44:57 1988669440 [Note] /usr/sbin/mysqld (mysqld 10.1.23-MariaDB-9+deb9u1) starting as process 26804 ...
Jan 09 17:45:00 raspberrypi systemd[1]: mariadb.service: Main process exited, code=exited, status=1/FAILURE
Jan 09 17:45:00 raspberrypi systemd[1]: Failed to start MariaDB database server.
Jan 09 17:45:00 raspberrypi systemd[1]: mariadb.service: Unit entered failed state.
Jan 09 17:45:00 raspberrypi systemd[1]: mariadb.service: Failed with result 'exit-code'.


Hab leider den Überblick verloren mit welchen Befehl ich das zweite log bekommen habe.

Wernieman

wegen Logfiles/journalctrl
Und bitte erst Versuchen MariaDB zustarten und dann die Ausgabe.

Alternativ (besser)
Mehrere ssh-Sessions öffnen, mit jeweils:
tail -f /var/log/mesages
journalctl -f


Nun in jedem Fester 1 mal "Enter" drücken, es entsteht eine Leerzeile

Dann in einer zusätzlichen Fenster mariadb starten
-> Alles ab lder obigen Leerzeile ist interessant ....
- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html

coolice

#20
Zitat von: Wernieman am 09 Januar 2019, 19:38:52
wegen Logfiles/journalctrl
Und bitte erst Versuchen MariaDB zustarten und dann die Ausgabe.

Alternativ (besser)
Mehrere ssh-Sessions öffnen, mit jeweils:
tail -f /var/log/mesages
journalctl -f


Nun in jedem Fester 1 mal "Enter" drücken, es entsteht eine Leerzeile

Dann in einer zusätzlichen Fenster mariadb starten
-> Alles ab lder obigen Leerzeile ist interessant ....

Ok aber bei tail -f /var/log/mesages kommt tail: cannot open '/var/log/mesages' for reading: No such file or directory
tail: no files remaining


pi@raspberrypi:~ $ journalctl -f
-- Logs begin at Sun 2019-01-06 13:13:46 CET. --
Jan 09 19:50:58 raspberrypi systemd[1]: Started Session c12 of user pi.
Jan 09 19:51:09 raspberrypi sudo[577]:       pi : TTY=pts/0 ; PWD=/home/pi ; USER=root ; COMMAND=/etc/init.d/mysql start
Jan 09 19:51:09 raspberrypi sudo[577]: pam_unix(sudo:session): session opened for user root by pi(uid=0)
Jan 09 19:51:09 raspberrypi systemd[1]: Starting MariaDB database server...
Jan 09 19:51:11 raspberrypi mysqld[695]: 2019-01-09 19:51:11 1988161536 [Note] /usr/sbin/mysqld (mysqld 10.1.23-MariaDB-9+deb9u1) starting as process 695 ...
Jan 09 19:51:14 raspberrypi systemd[1]: mariadb.service: Main process exited, code=exited, status=1/FAILURE
Jan 09 19:51:14 raspberrypi systemd[1]: Failed to start MariaDB database server.
Jan 09 19:51:14 raspberrypi systemd[1]: mariadb.service: Unit entered failed state.
Jan 09 19:51:14 raspberrypi systemd[1]: mariadb.service: Failed with result 'exit-code'.
Jan 09 19:51:14 raspberrypi sudo[577]: pam_unix(sudo:session): session closed for user root

mark79

Ich habe gesehen, das euch ein "s" fehlt, ich habe noch eins abzugeben. ;D Also für /var/log/mes(s)ages

journalctl mag ich auch nicht, du kannst mysql direkt starten, dann sieht man den Output in der Console.

sudo -u mysql bash
/usr/sbin/mysqld
Rock64 4GB mit Debian Strech, FHEM im LXC, Sonoff Switches/Touch, HM Thermostate, HMUART/Zigbee2MQTT@MapleCUN, ESP RGBWW Wifi Controller, ESP8266 Door Sensor/Briefkastenwächter, BT CSL Stick, BT iTags, Alexa, FireTV, RPi2 mit Kodi, Xiaomi Vacuum v1/Smarthome Komponenten

coolice

Zitat von: mark79 am 09 Januar 2019, 19:53:42
Ich habe gesehen, das euch ein "s" fehlt, ich habe noch eins abzugeben. ;D Also für /var/log/mes(s)ages

journalctl mag ich auch nicht, du kannst mysql direkt starten, dann sieht man den Output in der Console.

sudo -u mysql bash
/usr/sbin/mysqld

pi@raspberrypi:~ $ sudo -u mysql bash
mysql@raspberrypi:/home/pi$ /usr/sbin/mysqld
2019-01-09 19:58:02 1988390912 [Note] /usr/sbin/mysqld (mysqld 10.1.23-MariaDB-9+deb9u1) starting as process 1044 ...
mysql@raspberrypi:/home/pi$

mark79

Mhh komisch und gestartet ist der mysqld Dienst immer noch? ps aux | grep mysqld
Wenn ja, scheint das an den Start Scripten zu liegen.
Rock64 4GB mit Debian Strech, FHEM im LXC, Sonoff Switches/Touch, HM Thermostate, HMUART/Zigbee2MQTT@MapleCUN, ESP RGBWW Wifi Controller, ESP8266 Door Sensor/Briefkastenwächter, BT CSL Stick, BT iTags, Alexa, FireTV, RPi2 mit Kodi, Xiaomi Vacuum v1/Smarthome Komponenten

coolice

Zitat von: mark79 am 09 Januar 2019, 20:02:30
Mhh komisch und gestartet ist der mysqld Dienst immer noch? ps aux | grep mysqld
Wenn ja, scheint das an den Start Scripten zu liegen.

mysql@raspberrypi:/home/pi$ ps aux | grep mysqld
#mysql     1280  0.0  0.0   4372   544 pts/0    S+   20:03   0:00 grep mysqld

DasQ

#25
Zitat von: coolice am 09 Januar 2019, 19:45:25
Ok aber bei tail -f /var/log/mesages kommt tail: cannot open '/var/log/mesages' for reading: No such file or directory
tail: no files remaining

Er meinte hier fehlt das ,,s"

Und laut mariaDB Homepage sind die häufigsten Fehler, wenn nach nem Update alte Parameter in der config sind (my.cnf)
Fhem on MacMini/Ubuntu.
Absoluter Befürworter der Konsequenten-Kleinschreibung https://de.wikipedia.org/wiki/Kleinschreibung
Infos zu Klimawandel http://www.globalcarbonatlas.org

coolice

#26
Zitat von: DasQ am 09 Januar 2019, 20:09:56
Er meinte hier fehlt das ,,s"

Und laut mariaDB Homepage sind die häufigsten Fehler, wenn nach nem Update alte Parameter in der config sind (my.cnf)

my.cnf gibt es bei mir leider. nicht nur

drwxr-xr-x  2 root root 4096 May 20  2018 conf.d
-rw-------  1 root root  277 May 20  2018 debian.cnf
-rwxr-xr-x  1 root root 1509 Jun  7  2017 debian-start
-rw-r--r--  1 root root  869 Jun  7  2017 mariadb.cnf
drwxr-xr-x  2 root root 4096 Jan  9 17:34 mariadb.conf.d
lrwxrwxrwx  1 root root   24 May 20  2018 my.cnf -> /etc/alternatives/my.cnf
-rw-r--r--  1 root root  839 Jul  9  2016 my.cnf.fallback


OK doch.

da steht nicht viel drin

# The MariaDB configuration file
#
# The MariaDB/MySQL tools read configuration files in the following order:
# 1. "/etc/mysql/mariadb.cnf" (this file) to set global defaults,
# 2. "/etc/mysql/conf.d/*.cnf" to set global options.
# 3. "/etc/mysql/mariadb.conf.d/*.cnf" to set MariaDB-only options.
# 4. "~/.my.cnf" to set user-specific options.
#
# If the same option is defined multiple times, the last one will apply.
#
# One can use all long options that the program supports.
# Run program with --help to get a list of available options and with
# --print-defaults to see which it would actually understand and use.

#
# This group is read both both by the client and the server
# use it for options that affect everything
#
[client-server]

# Import all .cnf files from configuration directory
!includedir /etc/mysql/conf.d/
!includedir /etc/mysql/mariadb.conf.d/

mark79

Die my.cnf ist die alte config für mysql, du verwendest mariadb und die server config dafür liegt in /etc/mysql/mariadb.conf.d/50-server.cnf

Aber wo das Problem ist kann ich dir auch nicht sagen.

Du musst erstmal eine spezifische Fehlermeldung finden. Ich mach das dann immer so, das ich die Fehlermeldung google und dann eine Lösung finde, wie man das Problem löst.
Rock64 4GB mit Debian Strech, FHEM im LXC, Sonoff Switches/Touch, HM Thermostate, HMUART/Zigbee2MQTT@MapleCUN, ESP RGBWW Wifi Controller, ESP8266 Door Sensor/Briefkastenwächter, BT CSL Stick, BT iTags, Alexa, FireTV, RPi2 mit Kodi, Xiaomi Vacuum v1/Smarthome Komponenten

Wernieman

mal sicherheitshalber gefragt .... hast Du mysql noch auf dem rechner oder nicht?
dpkg -l | grep -e mysql -e mariadb


und sorry wegen des fehlenden (s), aber etwas Mitdenken (siehe 2-3 Beiträge vorher) solltest Du bitte ...

was steht denn nun (in der richtigen /var/log/messages)
- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html

Benni

Viel interessanter wäre wahrscheinlich aber doch, was im error log der DB steht.

Es sollte dort beim Startversuch schon was eingetragen werden, auch wenn das weiter oben nicht so aussah.
Da das Problem ja aber schon mindestens seit 6.1. besteht, würde sich eventuell auch mal ein Blick in eines der dort aufgelisteten Log-Archive lohnen.

Sollte mysql/mariadb beim Start partout nicht in die Logs schreiben, könnte das auch auf (Dateisystem-) Berechtigungsprobleme als mögliche Ursache hinweisen. (unwahrscheinlich!)

Ansonsten gibt es dort, wo die Datei ibdata1 liegt auch noch die Redo-Logs (i.d.R. ib_logfile0 und ib_logfile1). Diese machen manchmal auch Probleme beim mysql-Start (bes. nach Update oder Restore v. mysql).
Diese beiden dazu mal testweise in ein anderes Verzeichnis verschieben und versuchen, ob sich mysql/mariadb dann starten lässt.

gb#