u1000
Anmeldungsdatum: 2. Oktober 2011
Beiträge: 1850
|
logfile2 sieht doch erstmal gut aus: Der cronjob wird also grundsätzlich schon gestartet. logfile1: Warum sind hier Null Ausgaben vom rsync enthalten? Da stimmt doch was nicht. Wenn du das Backup Script manuell startest hast du doch zig Ausgaben im Terminal? Ist da noch was besonderes im Script?
Ich habe deshalb 8x das pw eingegeben.
Das ist aber seltsam: Was denn für ein Passwort? Das sudo Passwort must du doch nur 1x Eingeben? Oder ist es das rsync Passwort für das NAS? Wie soll denn der cron Job automatisch funktionieren, wenn du da Passwort manuell eingeben must?
|
wired2051
(Themenstarter)
Anmeldungsdatum: 28. Februar 2007
Beiträge: 2736
|
u1000 schrieb: logfile2 sieht doch erstmal gut aus: Der cronjob wird also grundsätzlich schon gestartet.
Naja, ich finde ja entscheidender, was passiert, wenn cron das Script nicht startet, nämlich, wie ich am 25. April 2015 um 13:40 schrieb, nachdem 24h kein Backup gemacht wurde:
Laut rsync-Log war das letzte Backup am 2015/04/24 13:00:02. Genau genommen wurde die Datei seit dem letzten Backup nicht verändert: -rw-r--r-- 1 root root 80 Apr 24 13:01 cron-Test.log
u1000 schrieb: logfile1: Warum sind hier Null Ausgaben vom rsync enthalten? Da stimmt doch was nicht. Wenn du das Backup Script manuell startest hast du doch zig Ausgaben im Terminal? Ist da noch was besonderes im Script?
Wenn ich das Script manuell starte, habe ich nur die PW-Abfrage im Terminal. Alle Ausgaben von rsync gehen ins Log, egal ob manuell oder via cron gestartet. u1000 schrieb: Ich habe deshalb 8x das pw eingegeben.
Das ist aber seltsam: Was denn für ein Passwort? Das sudo Passwort must du doch nur 1x Eingeben? Oder ist es das rsync Passwort für das NAS? Wie soll denn der cron Job automatisch funktionieren, wenn du da Passwort manuell eingeben must?
Das sind die root-PWs der lokalen Kubuntu-Installation für rsync. Wenn das Script von cron gestartet wird, tut das auch root.
|
u1000
Anmeldungsdatum: 2. Oktober 2011
Beiträge: 1850
|
wired2051 schrieb: u1000 schrieb: logfile2 sieht doch erstmal gut aus: Der cronjob wird also grundsätzlich schon gestartet.
Naja, ich finde ja entscheidender, was passiert, wenn cron das Script nicht startet, nämlich, wie ich am 25. April 2015 um 13:40 schrieb, nachdem 24h kein Backup gemacht wurde:
Ist denn der Fehler jetzt nochmal aufgetreten, nachdem alle 3 Logfiles mitlaufen? Jetzt erst kann man nachvollziehen, was nicht geklappt hat. Wenn ich das Script manuell starte, habe ich nur die PW-Abfrage im Terminal. Alle Ausgaben von rsync gehen ins Log, egal ob manuell oder via cron gestartet.
Wie schon geschrieben: Hier stimmt was nicht: selbst wenn man die rsync Option --log-file=... verwendet landen einige Ausgaben im Terminal
Das sind die root-PWs der lokalen Kubuntu-Installation für rsync. Wenn das Script von cron gestartet wird, tut das auch root.
Hier reden wir aneinander vorbei: Du meinst das rsync-Passwort vom NAS für den dortigen root User? In einem vorherigen Post bat ich um einen manuellen Test:
sudo env -i /home/USER/Scripte/SicherungAufNAS
Das einzige was hier gefragt wird ist das sudo Passwort deines aktuellen Users, es darf weder ein root noch 8x ein rsync Passwort verlangt werden. Der Befehl simuliert den Aufruf im cron-job, daher sudo, env -i löscht temporär die Umgebungsvariabalen. Kann es sein, dass du das sudo vergessen hast? Viele Grüße u1000
|
wired2051
(Themenstarter)
Anmeldungsdatum: 28. Februar 2007
Beiträge: 2736
|
u1000 schrieb: Ist denn der Fehler jetzt nochmal aufgetreten, nachdem alle 3 Logfiles mitlaufen? Jetzt erst kann man nachvollziehen, was nicht geklappt hat.
Das Script wurde zuletzt am 2. Mai 2015 um 19:00 gestartet. -rw-r--r-- 1 root root 159 Mai 2 19:00 logfile1.log aufgerufen von /etc/crontab | Sa 2. Mai 19:00:02 CEST 2015
Password: Password: Password: Password: rsync error: received SIGINT, SIGTERM, or SIGHUP (code 20) at rsync.c(632) [sender=3.1.0]
|
-rw-r--r-- 1 root root 923 Mai 2 19:00 logfile2 aufgerufen vom Script 1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31 | Sat Apr 25 15:06:08 CEST 2015
Sa 25. Apr 16:00:01 CEST 2015
Sa 25. Apr 17:00:01 CEST 2015
Sa 25. Apr 18:00:01 CEST 2015
Sa 25. Apr 19:00:01 CEST 2015
Sa 25. Apr 20:00:01 CEST 2015
Mo 27. Apr 13:00:01 CEST 2015
Di 28. Apr 13:00:01 CEST 2015
Di 28. Apr 14:00:01 CEST 2015
Di 28. Apr 15:00:01 CEST 2015
Di 28. Apr 16:00:01 CEST 2015
Di 28. Apr 17:00:01 CEST 2015
Di 28. Apr 18:00:01 CEST 2015
Di 28. Apr 19:00:01 CEST 2015
Di 28. Apr 20:00:01 CEST 2015
Mi 29. Apr 13:00:01 CEST 2015
Do 30. Apr 13:00:01 CEST 2015
Do 30. Apr 14:00:01 CEST 2015
Do 30. Apr 15:00:01 CEST 2015
Do 30. Apr 16:00:01 CEST 2015
Do 30. Apr 17:00:01 CEST 2015
Do 30. Apr 18:00:01 CEST 2015
Do 30. Apr 19:00:01 CEST 2015
Do 30. Apr 20:00:01 CEST 2015
Fr 1. Mai 13:00:01 CEST 2015
Sa 2. Mai 14:00:01 CEST 2015
Sa 2. Mai 15:00:01 CEST 2015
Sa 2. Mai 16:00:01 CEST 2015
Sa 2. Mai 17:00:01 CEST 2015
Sa 2. Mai 18:00:02 CEST 2015
Sa 2. Mai 19:00:02 CEST 2015
|
-rw-r--r-- 1 root root 862576 Mai 2 19:00 PC-AufNAS.log erstellt von rsync | ---rsync 1/5 PC-etc gestartet---
2015/05/02 19:00:54 [8268] building file list
2015/05/02 19:00:55 [8268] .d..t...... cups/
2015/05/02 19:00:55 [8268] <f..t...... cups/subscriptions.conf
2015/05/02 19:00:55 [8268] <f..t...... cups/subscriptions.conf.O
2015/05/02 19:00:57 [8268] rsync error: received SIGINT, SIGTERM, or SIGHUP (code 20) at rsync.c(632) [sender=3.1.0]
|
Könnte das SIGHUP wegen Shutdown von Kubuntu gewesen sein? Und warum startet cron nicht, nachdem Kubuntu wieder gebootet wurde? Edit: Nachdem ich diesen Beitrag um 12:44 geschrieben habe, wurde um 13:00 wieder das Script gestartet. 😲 Warum? Ich habe /etc/crontab mit vi (nicht als root) geöffnet und die logs natürlich... Hier das von crontab gestartete Log: | So 3. Mai 13:00:01 CEST 2015
Password: Password: Password: Password: Password: Password: Password: Password:
|
Bei dem vom Script erstellten logfile2 wurde ein Eintrag für So 3. Mai 13:00:01 CEST 2015 hinzugefügt und das ist das rsync-log, gekürzt: 1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16 | ---rsync 1/5 PC-etc gestartet---
2015/05/03 13:02:23 [4667] building file list
2015/05/03 13:02:26 [4667] sent 104,897 bytes received 1,557 bytes total size 6,888,879
---rsync 2/5 PC-var gestartet---
2015/05/03 13:02:26 [4672] building file list
2015/05/03 13:03:32 [4672] sent 7,123,577 bytes received 522,978 bytes total size 1,759,557,805
---rsync 3/5 PC-local gestartet---
2015/05/03 13:03:33 [4678] building file list
2015/05/03 13:03:34 [4678] sent 852 bytes received 166 bytes total size 9
---rsync 4/5 PC-opt gestartet---
2015/05/03 13:03:34 [4683] building file list
2015/05/03 13:03:34 [4683] sent 38 bytes received 16 bytes total size 0
---rsync 5/5 PC-home gestartet---
2015/05/03 13:03:34 [4684] building file list
2015/05/03 13:03:56 [4684] sent 14,973,840 bytes received 310,901 bytes total size 882,604,803
---rsync PC beendet---
|
Für mich sieht alles gut aus, bleibt die Frage, warum cron wieder startete?
|
u1000
Anmeldungsdatum: 2. Oktober 2011
Beiträge: 1850
|
Hi, danke für die Logfiles. wired2051 schrieb: Nachdem ich diesen Beitrag um 12:44 geschrieben habe, wurde um 13:00 wieder das Script gestartet. 😲 Warum?
Die Frage verstehe ich nicht, das Verhalten ist doch richtig. Wenn du um 12:01 den Rechner anschaltest (nehme ich mal an, da du nicht geschrieben hast, wann der Rechner genau ein- ausgeschaltet wird), läuft der nächste cron genau um 13:00 das erste mal. Oder mal anders gefragt: Welcher Log Eintrag fehlt dir ? Viele Grüße u1000
|
wired2051
(Themenstarter)
Anmeldungsdatum: 28. Februar 2007
Beiträge: 2736
|
u1000 schrieb: Oder mal anders gefragt: Welcher Log Eintrag fehlt dir ?
Stimmt, die Info fehlt. Mindestens fehlen 11:00 und 12:00.
|
u1000
Anmeldungsdatum: 2. Oktober 2011
Beiträge: 1850
|
u1000 schrieb: Wenn du um 12:01 den Rechner anschaltest (nehme ich mal an, da du nicht geschrieben hast, wann der Rechner genau ein- ausgeschaltet wird),
Und wann hast du den Rechner nun genau eingeschaltet? War er dann im Leerlauf, oder hast du direkt damit gearbeitet?
Sorry, dass ich dich hier so Ausfragen muss, aber ohne ganz konkrete Zahlen und Fakten sehe ich hier noch kein Licht. wired2051 schrieb: Könnte das SIGHUP wegen Shutdown von Kubuntu gewesen sein?
Sehr wahrscheinlich, könnte man genauer sagen, wenn du sagen könntest, ob du hier den Rechner gerade Heruntergefahren hast ☺
Viele Grüße
u1000
|
wired2051
(Themenstarter)
Anmeldungsdatum: 28. Februar 2007
Beiträge: 2736
|
Du stellst Fragen... Nein, die Kubuntu-Installation ist nicht verschlüsselt. In den KDE-Energiespar-Einstellungen wird nach 20 Minuten der Bildschirm ausgeschaltet, das ist alles. Kein Ruhezustand, seit ich eine SDD habe, nutze ich Suspend to Disk nicht mehr. In den letzten Tagen habe ich mein Nutzungsverhalten genau beobachtet und immer erst den Rechner ausgeschaltet, wenn alle rsync-Aufrufe abgearbeitet waren und in den rsync-Logfiles "rsync beendet" stand, dennoch arbeitete cron nicht fehlerfrei: =23.5.2015=
11:15 eingeschaltet 12:00 nix
13:00 Backup
14:00 Backup
15:00 Backup
16:00 Backup
16:46 ausgeschaltet
=24.5.2015=
11:45 eingeschaltet 12:00 nix
13:00 nix
13:25 ausgeschaltet
=25.5.2015=
10:50 eingeschaltet 11:00 nix
12:00 nix
12:05 ausgeschaltet
=26.5.2015=
10:20 eingeschaltet 11:00 nix
12:00 nix
13:00 Backup
14:00 Backup
14:25 Beginn AFK 15:00 Backup
16:00 Backup
16:55 Ende AFK 17:00 Backup
18:00 Backup
19:00 nix
20:00 nix
21:15 ausgeschaltet Beginn AFK ist der Zeitpunkt, an dem ich den Arbeitsplatz für längere Zeit verlassen habe. Danach musste ich dann wieder die Bildschirmsperre deaktivieren. Meist wurde das erste Backup erst 12/13 Uhr gestartet, lief dann aber zuverlässig weiter. Dass heute um 19 und 20 Uhr kein Backup gemacht wurde, ist ungewöhnlich. /etc/crontab (gekürzt) | [...]
# m h dom mon dow user command
17 * * * * root cd / && run-parts --report /etc/cron.hourly
25 6 * * * root test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.daily )
47 6 * * 7 root test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.weekly )
52 6 1 * * root test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.monthly )
0 10-20 * * * root /home/USER/Scripte/SicherungAufNAS >/home/USER/Scripte/logs/logfile1.log 2>&1
#
|
-rw-r--r-- 1 root root 110 Mai 26 18:01 logfile1.log (von corn/crontab erstellt) | Di 26. Mai 18:00:01 CEST 2015
Password: Password: Password: Password: Password: Password: Password: Password:
|
-rw-r--r-- 1 root root 2818 Mai 26 18:00 logfile2 (von SicherungAufNAS-Script erstellt, gekürzt) | [...]
Sa 23. Mai 14:00:01 CEST 2015
Sa 23. Mai 15:00:01 CEST 2015
Sa 23. Mai 16:00:01 CEST 2015
Di 26. Mai 13:00:01 CEST 2015
Di 26. Mai 14:00:01 CEST 2015
Di 26. Mai 15:00:01 CEST 2015
Di 26. Mai 16:00:01 CEST 2015
Di 26. Mai 17:00:01 CEST 2015
Di 26. Mai 18:00:01 CEST 2015
|
-rw-r--r-- 1 root root 3109571 Mai 26 18:01 PC-SicherungAufNAS.log (von rsync erstellt, gekürzt) 1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111 | ---rsync 1/5 RECHNER etc gestartet---
2015/05/26 13:02:53 [8665] building file list
[...]2015/05/26 13:02:56 [8665] sent 105,733 bytes received 1,557 bytes total size 6,888,996
---rsync 2/5 RECHNER var gestartet---
2015/05/26 13:02:56 [8686] building file list
[...]
2015/05/26 13:04:31 [8686] sent 29,539,172 bytes received 695,786 bytes total size 1,853,109,485
---rsync 3/5 RECHNER local gestartet---
2015/05/26 13:04:32 [9031] building file list
2015/05/26 13:04:33 [9031] sent 852 bytes received 166 bytes total size 9
---rsync 4/5 RECHNER opt gestartet---
2015/05/26 13:04:33 [9034] building file list
2015/05/26 13:04:33 [9034] sent 38 bytes received 16 bytes total size 0
---rsync 5/5 RECHNER home gestartet---
2015/05/26 13:04:33 [9035] building file list
[...]
2015/05/26 13:05:28 [9035] sent 31,794,025 bytes received 343,257 bytes total size 905,893,635
---rsync RECHNER beendet---
---rsync 1/5 RECHNER etc gestartet---
2015/05/26 14:00:54 [10901] building file list
[...]
2015/05/26 14:00:56 [10901] sent 104,875 bytes received 1,515 bytes total size 6,888,996
---rsync 2/5 RECHNER var gestartet---
2015/05/26 14:00:57 [10906] building file list
[...]
2015/05/26 14:01:21 [10906] sent 1,001,013 bytes received 152,099 bytes total size 1,853,110,465
---rsync 3/5 RECHNER local gestartet---
2015/05/26 14:01:22 [10911] building file list
2015/05/26 14:01:22 [10911] sent 852 bytes received 166 bytes total size 9
---rsync 4/5 RECHNER opt gestartet---
2015/05/26 14:01:22 [10914] building file list
2015/05/26 14:01:22 [10914] sent 38 bytes received 16 bytes total size 0
---rsync 5/5 RECHNER home gestartet---
2015/05/26 14:01:22 [10915] building file list
[...]
2015/05/26 14:01:39 [10915] sent 4,153,312 bytes received 145,676 bytes total size 906,289,757
---rsync RECHNER beendet---
---rsync 1/5 RECHNER etc gestartet---
[...]
2015/05/26 15:00:56 [11095] sent 104,907 bytes received 1,515 bytes total size 6,888,996
---rsync 2/5 RECHNER var gestartet---
2015/05/26 15:00:56 [11100] building file list
[...]
2015/05/26 15:01:20 [11100] sent 866,000 bytes received 151,272 bytes total size 1,853,111,621
---rsync 3/5 RECHNER local gestartet---
2015/05/26 15:01:21 [11105] building file list
2015/05/26 15:01:21 [11105] sent 852 bytes received 166 bytes total size 9
---rsync 4/5 RECHNER opt gestartet---
2015/05/26 15:01:21 [11108] building file list
2015/05/26 15:01:21 [11108] sent 38 bytes received 16 bytes total size 0
---rsync 5/5 RECHNER home gestartet---
2015/05/26 15:01:21 [11109] building file list
[...]
2015/05/26 15:01:37 [11109] sent 1,872,176 bytes received 111,543 bytes total size 906,578,575
---rsync RECHNER beendet---
---rsync 1/5 RECHNER etc gestartet---
2015/05/26 16:00:53 [11240] building file list
[...]
2015/05/26 16:00:56 [11240] sent 104,923 bytes received 1,515 bytes total size 6,888,996
---rsync 2/5 RECHNER var gestartet---
2015/05/26 16:00:56 [11245] building file list
[...]
2015/05/26 16:01:13 [11245] sent 565,705 bytes received 86,872 bytes total size 1,853,112,332
---rsync 3/5 RECHNER local gestartet---
2015/05/26 16:01:13 [11250] building file list
2015/05/26 16:01:13 [11250] sent 852 bytes received 166 bytes total size 9
---rsync 4/5 RECHNER opt gestartet---
2015/05/26 16:01:14 [11255] building file list
2015/05/26 16:01:14 [11255] sent 38 bytes received 16 bytes total size 0
---rsync 5/5 RECHNER home gestartet---
2015/05/26 16:01:14 [11256] building file list
[...]
2015/05/26 16:01:28 [11256] sent 777,775 bytes received 69,727 bytes total size 906,255,933
---rsync RECHNER beendet---
---rsync 1/5 RECHNER etc gestartet---
2015/05/26 17:00:54 [11390] building file list
[...]2015/05/26 17:00:57 [11390] sent 104,899 bytes received 1,515 bytes total size 6,888,996
---rsync 2/5 RECHNER var gestartet---
2015/05/26 17:00:57 [11395] building file list
[...]
2015/05/26 17:01:13 [11395] sent 750,927 bytes received 86,872 bytes total size 1,853,113,386
---rsync 3/5 RECHNER local gestartet---
2015/05/26 17:01:13 [11401] building file list
2015/05/26 17:01:13 [11401] sent 852 bytes received 166 bytes total size 9
---rsync 4/5 RECHNER opt gestartet---
2015/05/26 17:01:14 [11406] building file list
2015/05/26 17:01:14 [11406] sent 38 bytes received 16 bytes total size 0
---rsync 5/5 RECHNER home gestartet---
2015/05/26 17:01:14 [11407] building file list
[...]
2015/05/26 17:01:26 [11407] sent 716,110 bytes received 52,184 bytes total size 906,425,933
---rsync RECHNER beendet---
---rsync 1/5 RECHNER etc gestartet---
2015/05/26 18:00:54 [11987] building file list
[...]
2015/05/26 18:00:56 [11987] sent 105,013 bytes received 1,557 bytes total size 6,888,996
---rsync 2/5 RECHNER var gestartet---
2015/05/26 18:00:57 [11992] building file list
[...]
2015/05/26 18:01:13 [11992] sent 1,088,164 bytes received 87,742 bytes total size 1,853,114,747
---rsync 3/5 RECHNER local gestartet---
2015/05/26 18:01:14 [11997] building file list
2015/05/26 18:01:14 [11997] sent 852 bytes received 166 bytes total size 9
---rsync 4/5 RECHNER opt gestartet---
2015/05/26 18:01:14 [12002] building file list
2015/05/26 18:01:14 [12002] sent 38 bytes received 16 bytes total size 0
---rsync 5/5 RECHNER home gestartet---
2015/05/26 18:01:14 [12003] building file list
[...]
2015/05/26 18:01:30 [12003] sent 1,905,360 bytes received 111,796 bytes total size 906,330,263
---rsync RECHNER beendet---
|
Für mich sind die Logs eindeutig: wenn cron das Backup startet, dann arbeitet alles fehlerfrei. 😎
|
u1000
Anmeldungsdatum: 2. Oktober 2011
Beiträge: 1850
|
Vielen Dank für die ausführlichen Infos! ok, jetzt sehe ich was du vermutest: es sieht wirklich so aus, als ob cron das Script gar nicht erst aufruft... Dann schauen wir mal genauer und vesuchen den Gegenbeweis: Editiere bitte die crontab, dupliziere deine Backup Zeile und ändere nur den hinterern Teil ab: 0 10-20 * * * root date >>/home/USER/Scripte/logs/logfile4.log Später ist dann ein Vergleich mit dem logfile2 möglich, wo ja ganz am Anfang vom Script ebenfalls eine "date >> ..." Zeile enthalten ist. Seltsam ist das ganze aber schon ... ☺ Viele Grüße u1000
|
wired2051
(Themenstarter)
Anmeldungsdatum: 28. Februar 2007
Beiträge: 2736
|
u1000 schrieb: Dann schauen wir mal genauer und vesuchen den Gegenbeweis:
Möööp. Gescheitert. 😢 Heute um 12:00 wurde kein Backup erstellt und /home/USER/Scripte/logs/logfile4.log auch nicht angelegt. Das letzte Backup und die letzte Log-Aktualisierung war am 27. um 13:00. Zur Kontrolle mein /etc/crontab: | # m h dom mon dow user command
17 * * * * root cd / && run-parts --report /etc/cron.hourly
25 6 * * * root test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.daily )
47 6 * * 7 root test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.weekly )
52 6 1 * * root test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.monthly )
0 10-20 * * * root date >>/home/USER/Scripte/logs/logfile4.log
0 10-20 * * * root /home/USER/Scripte/SicherungAufNAS >/home/USER/Scripte/logs/logfile1.log 2>&1
#
|
u1000 schrieb:
Später ist dann ein Vergleich mit dem logfile2 möglich, wo ja ganz am Anfang vom Script ebenfalls eine "date >> ..." Zeile enthalten ist.
Stimmt, ist vorhanden, das Log wurde aber nicht aktualisiert. | #!/bin/sh
#
date
date >> /home/USER/Scripte/logs/logfile2
|
Ich melde mich wieder, wenn das nächste Backup erstellt wurde...
|
wired2051
(Themenstarter)
Anmeldungsdatum: 28. Februar 2007
Beiträge: 2736
|
Um 13, 14 und 15 Uhr wurde nun ein Backup erstellt. In logfile2, erstellt vom Script, sind alle Starts protokolliert: | Do 28. Mai 13:00:01 CEST 2015
Do 28. Mai 14:00:01 CEST 2015
Do 28. Mai 15:00:01 CEST 2015
|
In logfile1-log, von cron aktualisiert, wenn Script gestartet, steht erwartungsgemäss der letzte Start: | Do 28. Mai 15:00:01 CEST 2015
Password: Password: Password: Password: Password: Password: Password: Password:
|
Im neuen logfile4.log, erstellt von cron bevor das Script gestartet wird, stehen auch alle Starts des Scripts. | Do 28. Mai 13:00:01 CEST 2015
Do 28. Mai 14:00:01 CEST 2015
Do 28. Mai 15:00:01 CEST 2015
|
Als 12:00 das Script hätte gestartet werden müssen aber nicht wurde, wurde logfile4.log also nicht aktualisiert. Der Gegenbeweis wurde demnach nicht erbracht. ☹ Anscheinend befinden wir uns in einer Sackgasse. Und da das Backup an sich ja funktioniert, könnte ich mir einen Workaround vorstellen, nämlich das Backup-Script manuell starten. Nur wie? Wenn ich in der Konsole ./SicherungAufNAS eingebe, muss ich für jeden Start von rsync das Passwort eingeben, das nervt. Und sudo ./SicherungAufNAS ändert daran auch nichts. 😠 Zur Erinnerung, mein Script besteht aus rsync-Aufrufen nach dem Muster: | rsync -au --log-file=/home/USER/Scripte/logs/PCAufNAS.log --delete --exclude=.cache/ --exclude=.local/share/Trash/ /home/ IP_DES_NAS::PC1-backupHome
|
Und auf dem NAS sieht die /etc/rsyncd.conf so aus: | [PC1-backupHome]
path = /volume1/PC1/home
comment = PC1_home
uid = root
gid = root
read only = no
list = yes
charset = utf-8
|
|
lubux
Anmeldungsdatum: 21. November 2012
Beiträge: 13892
|
wired2051 schrieb: Zur Erinnerung, mein Script besteht aus rsync-Aufrufen nach dem Muster:
Poste mal dein vollständiges Script.
|
u1000
Anmeldungsdatum: 2. Oktober 2011
Beiträge: 1850
|
Schau mal ob hier was zu finden ist, für 12:00 sollte da eigentlich was stehen...
sudo cat /var/log/syslog.1 /var/log/syslog | egrep -i cron Viele Grüße u1000
|
wired2051
(Themenstarter)
Anmeldungsdatum: 28. Februar 2007
Beiträge: 2736
|
lubux schrieb: Poste mal dein vollständiges Script.
Das sind wirklich nur rsync-Aufrufe, sonst nichts. u1000 schrieb: Schau mal ob hier was zu finden ist, für 12:00 sollte da eigentlich was stehen...
sudo cat /var/log/syslog.1 /var/log/syslog | egrep -i cron
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38 | May 28 11:06:16 RECHNER anacron[1020]: Job `cron.daily' terminated
May 28 11:06:16 RECHNER anacron[1020]: Normal exit (1 job run)
May 28 11:17:01 RECHNER CRON[3271]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 28 11:35:01 RECHNER cron[1024]: (*system*) RELOAD (/etc/crontab)
May 28 12:17:01 RECHNER CRON[3629]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 28 13:00:01 RECHNER CRON[17656]: (root) CMD (/home/USER/Scripte/SicherungAufNAS >/home/USER/Scripte/logs/logfile1.log 2>&1)
May 28 13:00:01 RECHNER CRON[17655]: (root) CMD ( date >>/home/USER/Scripte/logs/logfile4.log)
May 28 13:17:01 RECHNER CRON[17826]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 28 14:00:01 RECHNER CRON[18508]: (root) CMD ( date >>/home/USER/Scripte/logs/logfile4.log)
May 28 14:00:01 RECHNER CRON[18509]: (root) CMD (/home/USER/Scripte/SicherungAufNAS >/home/USER/Scripte/logs/logfile1.log 2>&1)
May 28 14:17:01 RECHNER CRON[18577]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 28 15:00:01 RECHNER CRON[18647]: (root) CMD (/home/USER/Scripte/SicherungAufNAS >/home/USER/Scripte/logs/logfile1.log 2>&1)
May 28 15:00:01 RECHNER CRON[18648]: (root) CMD ( date >>/home/USER/Scripte/logs/logfile4.log)
May 28 15:17:01 RECHNER CRON[18748]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 28 16:00:01 RECHNER CRON[20020]: (root) CMD (/home/USER/Scripte/SicherungAufNAS >/home/USER/Scripte/logs/logfile1.log 2>&1)
May 28 16:00:01 RECHNER CRON[20019]: (root) CMD ( date >>/home/USER/Scripte/logs/logfile4.log)
May 28 16:17:01 RECHNER CRON[22590]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 28 17:00:01 RECHNER CRON[25603]: (root) CMD ( date >>/home/USER/Scripte/logs/logfile4.log)
May 28 17:00:01 RECHNER CRON[25604]: (root) CMD (/home/USER/Scripte/SicherungAufNAS >/home/USER/Scripte/logs/logfile1.log 2>&1)
May 28 17:17:01 RECHNER CRON[26866]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 28 18:00:01 RECHNER CRON[27051]: (root) CMD ( date >>/home/USER/Scripte/logs/logfile4.log)
May 28 18:00:01 RECHNER CRON[27052]: (root) CMD (/home/USER/Scripte/SicherungAufNAS >/home/USER/Scripte/logs/logfile1.log 2>&1)
May 28 18:17:01 RECHNER CRON[27126]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 28 19:00:01 RECHNER CRON[27225]: (root) CMD (/home/USER/Scripte/SicherungAufNAS >/home/USER/Scripte/logs/logfile1.log 2>&1)
May 28 19:00:01 RECHNER CRON[27226]: (root) CMD ( date >>/home/USER/Scripte/logs/logfile4.log)
May 28 19:17:01 RECHNER CRON[27297]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 29 12:11:31 RECHNER anacron[1029]: Anacron 2.3 started on 2015-05-29
May 29 12:11:31 RECHNER cron[960]: (CRON) INFO (pidfile fd = 3)
May 29 12:11:31 RECHNER anacron[1029]: Will run job `cron.daily' in 5 min.
May 29 12:11:31 RECHNER anacron[1029]: Jobs will be executed sequentially
May 29 12:11:31 RECHNER cron[1039]: (CRON) STARTUP (fork ok)
May 29 12:11:31 RECHNER cron[1039]: (CRON) INFO (Running @reboot jobs)
May 29 10:16:31 RECHNER anacron[1029]: Job `cron.daily' started
May 29 10:16:31 RECHNER anacron[2805]: Updated timestamp for job `cron.daily' to 2015-05-29
May 29 10:17:01 RECHNER CRON[2872]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 29 10:23:25 RECHNER anacron[1029]: Job `cron.daily' terminated
May 29 10:23:25 RECHNER anacron[1029]: Normal exit (1 job run)
May 29 11:17:01 RECHNER CRON[5401]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
|
Interessant. Warum wurde nur 28. und 29. ausgegeben und nicht auch der 27.? Warum wurde am May 28 11:06:16 Job 'cron.daily' terminated ohne vorher gestartet worden zu sein? Und warum sind die Einträge vom 29. Mai nicht chronologisch? Anacron nutze ich übrigens nicht und habe es auch noch nie getan. Heute wurde übrigens noch kein Backup erstellt, obwohl um 11 und 12 Uhr eines hätte erstellt werden sollen. –-edit-– Um 13:00 sind die Backups nun durchgelaufen: 1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16 | [...]
May 29 12:11:31 RECHNER anacron[1029]: Anacron 2.3 started on 2015-05-29
May 29 12:11:31 RECHNER cron[960]: (CRON) INFO (pidfile fd = 3)
May 29 12:11:31 RECHNER anacron[1029]: Will run job `cron.daily' in 5 min.
May 29 12:11:31 RECHNER anacron[1029]: Jobs will be executed sequentially
May 29 12:11:31 RECHNER cron[1039]: (CRON) STARTUP (fork ok)
May 29 12:11:31 RECHNER cron[1039]: (CRON) INFO (Running @reboot jobs)
May 29 10:16:31 RECHNER anacron[1029]: Job `cron.daily' started
May 29 10:16:31 RECHNER anacron[2805]: Updated timestamp for job `cron.daily' to 2015-05-29
May 29 10:17:01 RECHNER CRON[2872]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 29 10:23:25 RECHNER anacron[1029]: Job `cron.daily' terminated
May 29 10:23:25 RECHNER anacron[1029]: Normal exit (1 job run)
May 29 11:17:01 RECHNER CRON[5401]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 29 12:17:01 RECHNER CRON[7536]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
May 29 13:00:01 RECHNER CRON[8350]: (root) CMD (/home/USER/Scripte/SicherungAufNAS >/home/USER/Scripte/logs/logfile1.log 2>&1)
May 29 13:00:01 RECHNER CRON[8351]: (root) CMD ( date >>/home/USER/Scripte/logs/logfile4.log)
|
|
lubux
Anmeldungsdatum: 21. November 2012
Beiträge: 13892
|
wired2051 schrieb: lubux schrieb: Poste mal dein vollständiges Script.
Das sind wirklich nur rsync-Aufrufe, sonst nichts.
Und genau diese rsync-Aufrufe interessieren mich nicht, sondern die shebang und evtl. Ergänzungen (Zeilen) an bestimmten Stellen in deinem Script, mit z. B. logger für die /var/log/syslog. Aber wer nicht will, der hat schon.
|