systemd fährt virtuell das System runter und wieder rauf???

[scientific]

Ich hab keine Ahnung, was das bedeuten soll...
Ich mache mein Backup mittels btrfs-Snapshots über eine systemd-unit. Und wenn ich journalctl -f laufen lasse, während diese Unit gestartet wird, erscheint im Journal folgendes.

Code: Alles auswählen

Aug 02 11:25:22 aldebaran sshd[21839]: Received disconnect from 127.0.0.1: 11: disconnected by user
Aug 02 11:25:22 aldebaran sshd[21839]: pam_unix(sshd:session): session closed for user root
Aug 02 11:25:22 aldebaran systemd-logind[555]: Removed session 179.
Aug 02 11:25:22 aldebaran systemd[21862]: Stopping Default.
Aug 02 11:25:22 aldebaran systemd[21862]: Stopped target Default.
Aug 02 11:25:22 aldebaran systemd[21862]: Stopping Basic System.
Aug 02 11:25:22 aldebaran systemd[21862]: Stopped target Basic System.
Aug 02 11:25:22 aldebaran systemd[21862]: Stopping Paths.
Aug 02 11:25:22 aldebaran systemd[21862]: Stopped target Paths.
Aug 02 11:25:22 aldebaran systemd[21862]: Stopping Timers.
Aug 02 11:25:22 aldebaran systemd[21862]: Stopped target Timers.
Aug 02 11:25:22 aldebaran systemd[21862]: Stopping Sockets.
Aug 02 11:25:22 aldebaran systemd[21862]: Stopped target Sockets.
Aug 02 11:25:22 aldebaran systemd[21862]: Starting Shutdown.
Aug 02 11:25:22 aldebaran systemd[21862]: Reached target Shutdown.
Aug 02 11:25:22 aldebaran systemd[21863]: pam_unix(systemd-user:session): session closed for user root
Aug 02 11:25:22 aldebaran systemd[21862]: Starting Exit the Session...
Aug 02 11:25:22 aldebaran systemd[21862]: Received SIGRTMIN+24 from PID 21880 (kill).
Aug 02 11:25:22 aldebaran sshd[21878]: Accepted publickey for root from 127.0.0.1 port 33248 ssh2: RSA 45:4e:27:4d:30:f5:3d:25:10:d0:92:88:53:77:1a:3b
Aug 02 11:25:22 aldebaran sshd[21878]: pam_unix(sshd:session): session opened for user root by (uid=0)
Aug 02 11:25:22 aldebaran systemd-logind[555]: New session 180 of user root.
Aug 02 11:25:22 aldebaran systemd[21884]: pam_unix(systemd-user:session): session opened for user root by (uid=0)
Aug 02 11:25:22 aldebaran systemd[21884]: Starting Paths.
Aug 02 11:25:22 aldebaran systemd[21884]: Reached target Paths.
Aug 02 11:25:22 aldebaran systemd[21884]: Starting Timers.
Aug 02 11:25:22 aldebaran systemd[21884]: Reached target Timers.
Aug 02 11:25:22 aldebaran systemd[21884]: Starting Sockets.
Aug 02 11:25:22 aldebaran systemd[21884]: Reached target Sockets.
Aug 02 11:25:22 aldebaran systemd[21884]: Starting Basic System.
Aug 02 11:25:22 aldebaran systemd[21884]: Reached target Basic System.
Aug 02 11:25:22 aldebaran systemd[21884]: Starting Default.
Aug 02 11:25:22 aldebaran systemd[21884]: Reached target Default.
Aug 02 11:25:22 aldebaran systemd[21884]: Startup finished in 6ms.
Aug 02 11:25:22 aldebaran sshd[21878]: Received disconnect from 127.0.0.1: 11: disconnected by user
Aug 02 11:25:22 aldebaran sshd[21878]: pam_unix(sshd:session): session closed for user root
Aug 02 11:25:22 aldebaran systemd-logind[555]: Removed session 180.
Aug 02 11:25:22 aldebaran systemd[21884]: Stopping Default.
Aug 02 11:25:22 aldebaran systemd[21884]: Stopped target Default.
Aug 02 11:25:22 aldebaran systemd[21884]: Stopping Basic System.
Aug 02 11:25:22 aldebaran systemd[21884]: Stopped target Basic System.
Aug 02 11:25:22 aldebaran systemd[21884]: Stopping Paths.
Aug 02 11:25:22 aldebaran systemd[21884]: Stopped target Paths.
Aug 02 11:25:22 aldebaran systemd[21884]: Stopping Timers.
Aug 02 11:25:22 aldebaran systemd[21884]: Stopped target Timers.
Aug 02 11:25:22 aldebaran systemd[21884]: Stopping Sockets.
Aug 02 11:25:22 aldebaran systemd[21884]: Stopped target Sockets.
Aug 02 11:25:22 aldebaran systemd[21884]: Starting Shutdown.
Aug 02 11:25:22 aldebaran systemd[21884]: Reached target Shutdown.
Aug 02 11:25:22 aldebaran systemd[21884]: Starting Exit the Session...
Aug 02 11:25:22 aldebaran systemd[21884]: Received SIGRTMIN+24 from PID 21907 (kill).
Aug 02 11:25:23 aldebaran sshd[21933]: Accepted publickey for root from 127.0.0.1 port 33249 ssh2: RSA 45:4e:27:4d:30:f5:3d:25:10:d0:92:88:53:77:1a:3b
Aug 02 11:25:23 aldebaran sshd[21933]: pam_unix(sshd:session): session opened for user root by (uid=0)
Aug 02 11:25:23 aldebaran systemd[21935]: pam_unix(systemd-user:session): session opened for user root by (uid=0)
Aug 02 11:25:23 aldebaran systemd-logind[555]: New session 181 of user root.
Aug 02 11:25:23 aldebaran systemd[21935]: Starting Paths.
Aug 02 11:25:23 aldebaran systemd[21935]: Reached target Paths.
Aug 02 11:25:23 aldebaran systemd[21935]: Starting Timers.
Aug 02 11:25:23 aldebaran systemd[21935]: Reached target Timers.
Aug 02 11:25:23 aldebaran systemd[21935]: Starting Sockets.
Aug 02 11:25:23 aldebaran systemd[21935]: Reached target Sockets.
Aug 02 11:25:23 aldebaran systemd[21935]: Starting Basic System.
Aug 02 11:25:23 aldebaran systemd[21935]: Reached target Basic System.
Aug 02 11:25:23 aldebaran systemd[21935]: Starting Default.
Aug 02 11:25:23 aldebaran systemd[21935]: Reached target Default.
Aug 02 11:25:23 aldebaran systemd[21935]: Startup finished in 9ms.
Aug 02 11:25:23 aldebaran sshd[21933]: Received disconnect from 127.0.0.1: 11: disconnected by user
Aug 02 11:25:23 aldebaran sshd[21933]: pam_unix(sshd:session): session closed for user root
Aug 02 11:25:23 aldebaran systemd-logind[555]: Removed session 181.
Aug 02 11:25:23 aldebaran systemd[21935]: Stopping Default.
Aug 02 11:25:23 aldebaran systemd[21935]: Stopped target Default.
Aug 02 11:25:23 aldebaran systemd[21935]: Stopping Basic System.
Aug 02 11:25:23 aldebaran systemd[21935]: Stopped target Basic System.
Aug 02 11:25:23 aldebaran systemd[21935]: Stopping Paths.
Aug 02 11:25:23 aldebaran systemd[21935]: Stopped target Paths.
Aug 02 11:25:23 aldebaran systemd[21935]: Stopping Timers.
Aug 02 11:25:23 aldebaran systemd[21935]: Stopped target Timers.
Aug 02 11:25:23 aldebaran systemd[21935]: Stopping Sockets.
Aug 02 11:25:23 aldebaran systemd[21935]: Stopped target Sockets.
Aug 02 11:25:23 aldebaran systemd[21935]: Starting Shutdown.
Aug 02 11:25:23 aldebaran systemd[21935]: Reached target Shutdown.
Aug 02 11:25:23 aldebaran systemd[21935]: Starting Exit the Session...
Aug 02 11:25:23 aldebaran systemd[21935]: Received SIGRTMIN+24 from PID 21955 (kill).
Aug 02 11:25:23 aldebaran systemd[21936]: pam_unix(systemd-user:session): session closed for user root
Aug 02 11:25:23 aldebaran /usr/local/bin/mkbtrbackup[22039]: [II](21224:0)        Transfer differences [parent /var/cache/btrfs_pool_SYSTEM/@debian-testing.20150729-101114.hourly_10 to /var/cache/btrfs_pool_SYSTEM/@debian-testing.20150802-112308.hourly_0 --> /var/cache/backup/aldebaran/@debian-testing.20150802-112308.hourly_0]...
Aug 02 11:25:23 aldebaran systemd-inhibit[11551]: [II](21224:0)        Transfer differences [parent /var/cache/btrfs_pool_SYSTEM/@debian-testing.20150729-101114.hourly_10 to /var/cache/btrfs_pool_SYSTEM/@debian-testing.20150802-112308.hourly_0 --> /var/cache/backup/aldebaran/@debian-testing.20150802-112308.hourly_0]...
Aug 02 11:25:25 aldebaran sshd[22096]: Accepted publickey for root from 127.0.0.1 port 33250 ssh2: RSA 45:4e:27:4d:30:f5:3d:25:10:d0:92:88:53:77:1a:3b
Aug 02 11:25:25 aldebaran sshd[22096]: pam_unix(sshd:session): session opened for user root by (uid=0)
Aug 02 11:25:25 aldebaran systemd[22114]: pam_unix(systemd-user:session): session opened for user root by (uid=0)
Aug 02 11:25:25 aldebaran systemd-logind[555]: New session 182 of user root.
Aug 02 11:25:25 aldebaran systemd[22114]: Starting Paths.
Aug 02 11:25:25 aldebaran systemd[22114]: Reached target Paths.
Aug 02 11:25:25 aldebaran systemd[22114]: Starting Timers.
Aug 02 11:25:25 aldebaran systemd[22114]: Reached target Timers.
Aug 02 11:25:25 aldebaran systemd[22114]: Starting Sockets.
Aug 02 11:25:25 aldebaran systemd[22114]: Reached target Sockets.
Aug 02 11:25:25 aldebaran systemd[22114]: Starting Basic System.
Aug 02 11:25:25 aldebaran systemd[22114]: Reached target Basic System.
Aug 02 11:25:25 aldebaran systemd[22114]: Starting Default.
Aug 02 11:25:25 aldebaran systemd[22114]: Reached target Default.
Aug 02 11:25:25 aldebaran systemd[22114]: Startup finished in 8ms.

Ich habe Probehalber einen ssh-Befehl mit pubkey-Auth im Skript eingebaut, damit ich später auch einmal auf einen entfernten Rechner das Backup übertragen kann. Warum schaut es aber so aus, als ob der Rechner runter und wieder raufgefahren wird, wo das doch gar nicht gemacht wird???

Hat dazu vielleicht irgendjemand eine Idee???

lg scientific

[scientific]

Das Phänomen besteht immer noch... Heute sah ich dann folgendes:

Code: Alles auswählen

Aug 10 09:46:08 aldebaran su[7135]: Successful su for www-data by root
Aug 10 09:46:08 aldebaran su[7135]: + ??? root:www-data
Aug 10 09:46:08 aldebaran su[7135]: pam_unix(su:session): session opened for user www-data by (uid=0)
Aug 10 09:46:08 aldebaran systemd[7136]: pam_unix(systemd-user:session): session opened for user www-data by (uid=0)
Aug 10 09:46:18 aldebaran su[7135]: pam_unix(su:session): session closed for user www-data
Aug 10 09:46:18 aldebaran su[7404]: Successful su for www-data by root
Aug 10 09:46:18 aldebaran su[7404]: + ??? root:www-data
Aug 10 09:46:18 aldebaran su[7404]: pam_unix(su:session): session opened for user www-data by (uid=0)
Aug 10 09:46:08 aldebaran systemd-logind[541]: New session c64 of user www-data.
Aug 10 09:46:08 aldebaran systemd[7136]: Starting Paths.
Aug 10 09:46:08 aldebaran systemd[7136]: Reached target Paths.
Aug 10 09:46:08 aldebaran systemd[7136]: Starting Timers.
Aug 10 09:46:08 aldebaran systemd[7136]: Reached target Timers.
Aug 10 09:46:08 aldebaran systemd[7136]: Starting Sockets.
Aug 10 09:46:08 aldebaran systemd[7136]: Reached target Sockets.
Aug 10 09:46:08 aldebaran systemd[7136]: Starting Basic System.
Aug 10 09:46:08 aldebaran systemd[7136]: Reached target Basic System.
Aug 10 09:46:08 aldebaran systemd[7136]: Starting Default.
Aug 10 09:46:08 aldebaran systemd[7136]: Reached target Default.
Aug 10 09:46:21 aldebaran systemd-logind[541]: Removed session c64.
Aug 10 09:46:22 aldebaran systemd[7136]: Stopping Default.
Aug 10 09:46:22 aldebaran systemd[7136]: Stopped target Default.
Aug 10 09:46:22 aldebaran systemd[7136]: Stopping Basic System.
Aug 10 09:46:22 aldebaran systemd[7136]: Stopped target Basic System.
Aug 10 09:46:22 aldebaran systemd[7136]: Stopping Paths.
Aug 10 09:46:22 aldebaran systemd[7136]: Stopped target Paths.
Aug 10 09:46:22 aldebaran systemd[7136]: Stopping Timers.
Aug 10 09:46:22 aldebaran systemd[7136]: Stopped target Timers.
Aug 10 09:46:22 aldebaran systemd[7136]: Stopping Sockets.
Aug 10 09:46:22 aldebaran systemd[7136]: Stopped target Sockets.
Aug 10 09:46:22 aldebaran systemd[7136]: Starting Shutdown.
Aug 10 09:46:22 aldebaran systemd[7136]: Reached target Shutdown.
Aug 10 09:46:22 aldebaran systemd[7136]: Starting Exit the Session...
Aug 10 09:46:22 aldebaran systemd[7137]: pam_unix(systemd-user:session): session closed for user www-data
Aug 10 09:46:22 aldebaran systemd[7136]: Received SIGRTMIN+24 from PID 7498 (kill).
Aug 10 09:46:22 aldebaran systemd-logind[541]: New session c65 of user www-data.
Aug 10 09:46:22 aldebaran su[7404]: pam_unix(su:session): session closed for user www-data

Das geschah während des Entfernen eines alten Kernels mittels apt-get.

Ich versteh das nicht... was geht hier vor?

lg scientific

[scientific]

auch hier niemand, der ähnliches beobachten kann???

[smutbert]

Nein, aber ich finde die Beobachtung und Frage unheimlich interessant ☺

(lieber hätte ich etwas sachdienliches geschrieben, aber ich weiß nichts, entschuldige)

[scientific]

Mir kam grad die idee, es könnte irgend ein user-spezifischer systemd sein...

Ich hab ein backup-skript für btrfs geschrieben das momentan nur lokal sichert (und noch in entwicklung ist). da mache ich die btrfs-aufrufe per ssh mit rsa-key-authentifizierung.

und jedesmal beim backup fährt systemd das system wie beschrieben scheinbar runter und rauf. und ich glaub einen zusammemhang mit dem login-prozess zu erkennen...

[scientific]

Das Phänomen hat offenbar mit einem root-login per ssh und rsa-key zu tun.

Der Aufruf ist mehrfach im Backup-Skript und dient dazu, das Backup auch auf einem entfernten Rechner abzulegen...

[scientific]

Ich habe grad eine Idee... bin mir nämlich nicht mehr sicher, ob ich das nun versucht habe umzusetzen oder nur daran gedacht habe...

Die Idee war, ssh in einem Container laufen zu lassen. Dann wäre es irgendwie erklärbar, dass ein Login im Log Spuren hinterlässt, als ob das System gestartet würde und der Logout einen Shutdown.

Ich hab noch ein spannendes Phänomen... Wenn ich mich von außerhalb auf meinen Rechner (Hinter einem Router mit Portweiterleitung) einloggen möchte, dann klappt das nicht. Auch mehrmalige Versuche klappen nicht.
Erst wenn ich einmal versuche mich mit dem Browser auf Port 80 (der nicht weitergeleitet wurde) zu verbinden, kann ich mich gleich danach auf Port 22 per ssh einloggen...
Ich hab aber keine Idee, welcher Mechanismus da wirken könnte. Ob das am Router liegt, ob das an meinem Linux-Rechner liegt?

Mir ist es bisher auch fast nie gelungen, mich am weitergeleiteten Port 143 mit dem Mailserver auf dem Linux-Rechner zu verbinden. Wenn ich im WLAN in dem sich der Laptop befindet mit meinem Handy bin, dann geht es über die LAN-Adresse. Von "außen" hingegen nicht.

Irgendwie seltsam das ganze.

lg scientific