Log fürs Hochfahren

[meister123]

Tach,

meine DS110j mit aktuellster Firmware fährt regelmässig um 23:19 hoch. Im normalen Logfile (wo die Anmeldung und so drinsteht) finde ich nichts, WARUM das Ding hochfährt. Wo könnte ich da noch nachschauen?

Danke für Tipps und eventuelle Stichwörter, um die Suche zu füttern.

 

[jahlives]

Danke für Tipps und eventuelle Stichwörter, um die Suche zu füttern.

Stichworte: root, telnet/ssh, Kommandokonsole, Logfiles und /var/log/messages

 

[goetz]

Hallo,
möglicherweise noch ein Stichwort
/etc/crontab

Gruß Götz

 

[meister123]

in der datei /var/log/messages hören die einträge gestern früh auf und fangen heute abend um 22:00 wieder an. irgendwie ist dazwischen goarnix. und um 20:00 ist mein backup gelaufen...

 

[meister123]

könnte mich jemand vielleicht an der hand nehmen. gestern um 23:40 ist die ds110j wieder mal kurz hochgefahren. es war sonst kein rechner mehr an. über more /var/log/messages ist nicht erkennbar, warum das gerät hochfährt. und über das webinterface bzw. die logdaten dort ist auch nichts erkennbar.

wie kann ich nachvollziehen, WARUM das ding hochfährt?

 

[meister123]

zu hülfe?!

 

[Trolli]

Stöpsel die DS doch einfach mal ab und schau dann nach, ob sie immer noch hochfährt.

Kann man von extern auf die DS zugreifen (z.B. Photo Station)?

 

[meister123]

Stöpsel die DS doch einfach mal ab und schau dann nach, ob sie immer noch hochfährt.

Kann man von extern auf die DS zugreifen (z.B. Photo Station)?

vom netzwerk abstöpseln... ok. leider ist das nächtliche hochfahren doch nicht so regelmässig, wie ich gedacht habe.

es sind keine sonstigen dienste aktiv. ich habe eine feste IP vergeben und sichere automatisch meine images daruf. der job läuft aber um 20:00 von ShadowProtect aus. das wars eigentlich. weder SSH noch telnet ist aktiv (mache ich nur bewusst an).

deswegen würd ich ja gerne anhand von logfiles nachvollziehen, warum das ding aktiv wird.

 

[Trolli]

Nicht jede Aktivität erzeugt halt einen Logeintrag. Besonders wenn es sich um eine Abfrage aus dem Netzwerk handeln würde, wird da wohl nichts zu finden sein. So etwas kann theoretisch auch vom Router ausgehen.

Man kann bei der DS einenDebug-Modus einschalten, so dass das Aufwachen im Log protokolliert wird. Vielleicht hilft Dir das schon weiter.
-> http://forum.synology.com/enu/viewtopic.php?f=83&t=23983

 

[meister123]

wirklich schlauer bin ich jetzt nicht. wobei ich gerade auf scemd aufmerksam geworden bin, mein hauptrechner ist noch an... da muss ich wohl erst warten, bis das ding hochfährt UND kein rechner an ist...

Nov 13 23:03:27 kernel: [1085302.400000] [/var/run/smbd.pid] opened by pid 20798 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 13 23:13:44 kernel: [1085918.750000] [/etc/localtime] opened by pid 1628 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 13 23:35:13 kernel: [1087208.420000] [] opened by pid 14900 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 13 23:35:19 kernel: [1087214.000000] drivers/scsi/sd.c[469]:sd_prep_fn(), sd
a: cmd 0x0 spin up by pid=406, comm=jbd2/sda1-8
Nov 13 23:35:19 kernel: [1087214.000000] [/etc/localtime] opened by pid 1628 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 13 23:35:19 scemd: SCEMD: disk 1 wake up from hibernation
Nov 13 23:35:27 kernel: [1087222.440000] [/var/run/smbd.pid] opened by pid 21962 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 13 23:45:59 kernel: [1087854.020000] [/etc/localtime] opened by pid 1628 [u:(/usr/syno/bin/scemd), comm:(scemd)]

 

[meister123]

so, heute vormittag ist das ding angelaufen und es war definitiv kein rechner an. den start um 19:11 kann ich nachvollziehen, da ist mein hauptrechner hochgefahren. aber warum das ding um 19:45 wieder gestartet ist, verstehe ich wiederum nicht. die üblichen sachen habe ich schon durchgeschaut (local master browser, usw.) es laufen keine weiteren anwendungen auf der DS. und langsam nervt es, nicht nachvollziehen zu können, warum das ding muckt...

Nov 15 11:42:22 kernel: [64489.000000] drivers/scsi/sd.c[469]:sd_prep_fn(), sda: cmd 0x0 spin up by pid=404, comm=jbd2/sda1-8
Nov 15 11:42:22 kernel: [64489.000000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 15 11:42:22 scemd: SCEMD: disk 1 wake up from hibernation
Nov 15 11:53:03 kernel: [65130.830000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 15 19:11:50 kernel: [91458.650000] [] opened by pid 3634 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 15 19:11:53 kernel: [91460.970000] [/etc/resolv.conf] opened by pid 21801 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 15 19:11:53 kernel: [91461.140000] drivers/scsi/sd.c[469]:sd_prep_fn(), sda: cmd 0x0 spin up by pid=21838, comm=synologset1
Nov 15 19:11:53 scemd: SCEMD: disk 1 wake up from hibernation
Nov 15 19:13:52 kernel: [91580.850000] [] opened by pid 21801 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 15 19:15:45 kernel: [91693.660000] [/etc/passwd] opened by pid 25878 [u:(/usr/sbin/inetd), comm:(inetd)]
Nov 15 19:15:52 kernel: [91700.630000] [/etc/nsswitch.conf] opened by pid 25881[u:(/bin/login), comm:(login)]
Nov 15 19:15:57 kernel: [91705.490000] [/etc/group] opened by pid 25881 [u:(/bin/login), comm:(login)]
Nov 15 19:16:03 kernel: [91712.000000] [/sbin/more] opened by pid 25881 [u:(-ash), comm:(ash)]
Nov 15 19:16:08 kernel: [91717.180000] [/var/log/messages] opened by pid 26283 [u:(more), comm:(more)]
Nov 15 19:16:33 kernel: [91741.950000] [/etc/passwd] opened by pid 25881 [u:(-ash), comm:(ash)]
Nov 15 19:26:59 kernel: [92366.680000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 15 19:45:54 kernel: [93502.200000] [] opened by pid 21801 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 15 19:46:00 kernel: [93508.000000] drivers/scsi/sd.c[469]:sd_prep_fn(), sda: cmd 0x0 spin up by pid=404, comm=jbd2/sda1-8
Nov 15 19:46:00 kernel: [93508.000000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 15 19:46:00 scemd: SCEMD: disk 1 wake up from hibernation
Nov 15 19:46:08 kernel: [93516.350000] [/var/log/messages] opened by pid 1055 [u:(/sbin/syslogd), comm:(syslogd)]
Nov 15 19:46:50 kernel: [93558.960000] [] opened by pid 21801 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 15 19:50:12 kernel: [93760.470000] [/etc/passwd] opened by pid 29605 [u:(/usr/sbin/inetd), comm:(inetd)]
Nov 15 19:50:14 kernel: [93762.630000] [/etc/nsswitch.conf] opened by pid 29608[u:(/bin/login), comm:(login)]
Nov 15 19:50:18 kernel: [93766.720000] [/etc/group] opened by pid 29608 [u:(/bin/login), comm:(login)]
Nov 15 19:50:27 kernel: [93775.610000] [/sbin/more] opened by pid 29608 [u:(-ash), comm:(ash)]
Nov 15 19:50:31 kernel: [93779.580000] [/var/log/messages] opened by pid 29938 [u:(more), comm:(more)]
Nov 15 19:50:40 kernel: [93789.050000] [/etc/passwd] opened by pid 29608 [u:(-ash), comm:(ash)]
Nov 15 19:59:15 kernel: [94303.390000] [.] opened by pid 29608 [u:(-ash), comm:(ash)]
Nov 15 19:59:17 kernel: [94305.280000] [.] opened by pid 29608 [u:(-ash), comm:(ash)]
Nov 15 20:00:02 kernel: [94350.870000] [] opened by pid 21801 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 15 20:00:05 kernel: [94353.220000] [/var/log/messages] opened by pid 7481 [u:(more), comm:(more)]
DS110j>

 

[Trolli]

Versuch doch jetzt mal, die DS vom Netzwerk zu trennen. Du kannst ja jetzt im Log erkennen, ob sie dann wieder aufwacht. Wenn nicht, muss irgendwas in Deinem Netzwerk noch die DS abfragen...

 

[meister123]

hi,

also wirklich weiter bin ich bislang nicht. wenn ich das netzwerkkabel abziehe, ist ruhe. vielleicht nochmal mein system:

W701V als Router
TP-Link TL-SG1005D als Switch
DS110j mit neuester Firmware
Windows 7 64-bit als OS
puresync für daten-backup
shadowprotect für images von c:

was ich bislang beobachtet habe:

1. wenn ich den rechner anmachen, fährt auch die ds110j hoch.
2. wenn ich den rechner ausmache und die ds110j dabei aus war, fährt sie nach ca. 10 min. nochmal hoch und nach 10 min. in hibernation.
3. wenn ich den rechner ausmache und die ds110j dabei an war, fährt sie nicht mehr hoch.
4. manchmal fahre ich den rechner hoch, die ds110j auch. und dann fährt sie runter und wieder hoch und wieder runter und wieder hoch. das manchmal 3-4 mal am stück, dann ist ruhe. gerade eben wieder:

Nov 25 22:24:10 kernel: [603570.250000] [/usr/syno/synoman/webman/modules/ControlPanel/modules/power.cgi] opened by pid 26663 [u:(/usr/syno/apache/bin/httpd), comm:(httpd)]
Nov 25 22:34:37 kernel: [604197.660000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 25 22:35:25 kernel: [604245.890000] [] opened by pid 29108 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 25 22:35:30 kernel: [604251.000000] drivers/scsi/sd.c[469]:sd_prep_fn(), sda: cmd 0x0 spin up by pid=404, comm=jbd2/sda1-8
Nov 25 22:35:30 kernel: [604251.000000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 25 22:35:30 scemd: SCEMD: disk 1 wake up from hibernation
Nov 25 22:35:39 kernel: [604259.370000] [/var/log/messages] opened by pid 1055 [u:(/sbin/syslogd), comm:(syslogd)]
Nov 25 22:35:59 kernel: [604279.570000] [/etc/passwd] opened by pid 20312 [u:(/usr/sbin/inetd), comm:(inetd)]
Nov 25 22:36:01 kernel: [604281.800000] [/etc/nsswitch.conf] opened by pid 20315 [u:(/bin/login), comm:(login)]
Nov 25 22:36:04 kernel: [604284.690000] [/etc/group] opened by pid 20315 [u:(/bin/login), comm:(login)]
Nov 25 22:36:13 kernel: [604293.330000] [/sbin/more] opened by pid 20315 [u:(-ash), comm:(ash)]

ich habe wireshark mal probiert, werde aus den vielen meldungen da aber nicht schlau. ich kriege nur mit, dass wenn die ds110j im ruhezustand ist, dennoch kommunikation stattfindet, diese aber nicht unbedingt ein aufwachen bedingt...

das aktuelle log von wireshark, leider habe ich das einschlafen und gleich wieder wecken nicht "mitgeschnitten". aber die DS110j ist IMHO um 22:50 wieder eingeschlafen und ruht auch jetzt:

No.     Time                       Source                Destination           Protocol Info
      8 2010-11-25 22:41:55.212130 192.168.2.114         192.168.2.255         BROWSER  Host Announcement DS110J, Workstation, Server, Print Queue Server, Xenix Server, NT Workstation, DFS server
     67 2010-11-25 22:44:35.528107 192.168.2.114         192.168.2.255         NBNS     Name query NB HEIMNETZWERK<1d>
     70 2010-11-25 22:44:35.528395 192.168.2.2           192.168.2.114         NBNS     Name query response NB 192.168.2.2
    390 2010-11-25 22:45:26.326459 192.168.2.114         192.168.2.2           NBSS     Session keep-alive
    402 2010-11-25 22:45:26.525865 192.168.2.2           192.168.2.114         TCP      49156 > microsoft-ds [ACK] Seq=1 Ack=5 Win=261 Len=0
    421 2010-11-25 22:45:26.666400 192.168.2.114         192.168.2.2           NBSS     Session keep-alive
    429 2010-11-25 22:45:26.866859 192.168.2.2           192.168.2.114         TCP      49158 > microsoft-ds [ACK] Seq=1 Ack=5 Win=259 Len=0
    601 2010-11-25 22:49:38.140311 192.168.2.114         192.168.2.255         NBNS     Name query NB HEIMNETZWERK<1d>
    604 2010-11-25 22:49:38.140617 192.168.2.2           192.168.2.114         NBNS     Name query response NB 192.168.2.2
   1274 2010-11-25 22:50:26.378737 192.168.2.114         192.168.2.2           NBSS     Session keep-alive
   1285 2010-11-25 22:50:26.580208 192.168.2.2           192.168.2.114         TCP      49156 > microsoft-ds [ACK] Seq=1 Ack=9 Win=261 Len=0
   1286 2010-11-25 22:50:26.718732 192.168.2.114         192.168.2.2           NBSS     Session keep-alive
   1289 2010-11-25 22:50:26.918221 192.168.2.2           192.168.2.114         TCP      49158 > microsoft-ds [ACK] Seq=1 Ack=9 Win=259 Len=0
   1362 2010-11-25 22:53:04.444973 192.168.2.114         192.168.2.255         BROWSER  Host Announcement DS110J, Workstation, Server, Print Queue Server, Xenix Server, NT Workstation, DFS server
   1436 2010-11-25 22:54:45.272458 192.168.2.114         192.168.2.255         NBNS     Name query NB HEIMNETZWERK<1d>
   1439 2010-11-25 22:54:45.272749 192.168.2.2           192.168.2.114         NBNS     Name query response NB 192.168.2.2
   1591 2010-11-25 22:55:26.311026 192.168.2.114         192.168.2.2           NBSS     Session keep-alive
   1611 2010-11-25 22:55:26.513578 192.168.2.2           192.168.2.114         TCP      49156 > microsoft-ds [ACK] Seq=1 Ack=13 Win=261 Len=0
   1638 2010-11-25 22:55:26.651007 192.168.2.114         192.168.2.2           NBSS     Session keep-alive
   1667 2010-11-25 22:55:26.853573 192.168.2.2           192.168.2.114         TCP      49158 > microsoft-ds [ACK] Seq=1 Ack=13 Win=259 Len=0
   1920 2010-11-25 22:59:45.824125 192.168.2.114         192.168.2.255         NBNS     Name query NB HEIMNETZWERK<1d>
   1923 2010-11-25 22:59:45.824436 192.168.2.2           192.168.2.114         NBNS     Name query response NB 192.168.2.2
   2108 2010-11-25 23:00:26.362590 192.168.2.114         192.168.2.2           NBSS     Session keep-alive
   2131 2010-11-25 23:00:26.561912 192.168.2.2           192.168.2.114         TCP      49156 > microsoft-ds [ACK] Seq=1 Ack=17 Win=261 Len=0
   2145 2010-11-25 23:00:26.702650 192.168.2.114         192.168.2.2           NBSS     Session keep-alive
   2161 2010-11-25 23:00:26.901947 192.168.2.2           192.168.2.114         TCP      49158 > microsoft-ds [ACK] Seq=1 Ack=17 Win=259 Len=0

ich habe auch schon mit netbios an/aus gespielt und krieg langsam wirklich die krise.

 

[meister123]

mein rechner wurde um 23:06 runtergefahren, mein rechner war vom netz und per steckdosennetzteil ausgeschaltet, keine weiteren rechner an. W701 und switch laufen weiter. prompt ist die ds110j nochmal hochgefahren:

Nov 25 22:41:07 kernel: [604587.400000] [/var/log/messages] opened by pid 25721[u:(more), comm:(more)]
Nov 25 22:51:49 kernel: [605228.970000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 25 23:25:52 kernel: [607272.920000] [/var/run/smbd.pid] opened by pid 29108[u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 25 23:25:58 kernel: [607278.000000] drivers/scsi/sd.c[469]:sd_prep_fn(), sda: cmd 0x0 spin up by pid=404, comm=jbd2/sda1-8
Nov 25 23:25:58 kernel: [607278.000000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 25 23:25:58 scemd: SCEMD: disk 1 wake up from hibernation
Nov 25 23:26:06 kernel: [607286.700000] [/var/log/messages] opened by pid 1055 [u:(/sbin/syslogd), comm:(syslogd)]
Nov 25 23:33:58 kernel: [607759.080000] [] opened by pid 16130 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 25 23:34:01 kernel: [607761.670000] [/etc/resolv.conf] opened by pid 16347 [u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 25 23:34:28 kernel: [607789.030000] [/etc/passwd] opened by pid 16868 [u:(/usr/sbin/inetd), comm:(inetd)]
Nov 25 23:34:31 kernel: [607791.570000] [/etc/nsswitch.conf] opened by pid 16907 [u:(/bin/login), comm:(login)]
Nov 25 23:34:34 kernel: [607794.800000] [/etc/group] opened by pid 16907 [u:(/bin/login), comm:(login)]
Nov 25 23:34:41 kernel: [607801.110000] [/sbin/more] opened by pid 16907 [u:(-ash), comm:(ash)]

 

[itari]

Im Protokoll sieht so aus, als wäre per SMB-Request die DS um 23:25 aufgeweckt worden. Manchmal werden ja die PCs über Nacht automatisch eingeschaltet, um MS-Software-Updates zu suchen und dann könnte sowas passieren ... das müsste man jetzt ausschließen. Also wenn die alle PCs von Stromnetz genommen werden und den Laptops der Akku entfernt wird ... und der Router stillgelegt wird (denn der hat eventuell die Fähigkeit eine USB-Platte/oder Drucker per eigenem SMB-Server zu nutzen), dann dürfte die DS nicht aufwachen ...

Itari

 

[meister123]

mein hauptrechner wird wie gesagt per steckdosenleiste ausgeschaltet. könnte die W701V das verursachen?

ich habe den beitrag gestern abend nochmal auf dem hauptrechner geschrieben. synology lief noch. rechner runtergefahren, synology geht in hibernation. knapp 10-15 min. später fährt sie wieder hoch, 10 min. später wieder runter und gut ist. ich werd erst heute abend das log auswerten können...

das mit den laptops muss ich dann wohl noch machen. wobei die alle immer ausgeschaltet und nicht im standby sind.

 

[meister123]

so, heute abend vor dem hochfahren das netzkabel am rechner gezogen, kein hochfahren der DS110j. wobei ich mit diesem hochfahren grundsätzlich kein problem hätte. ich werde heute mal den ganzen abend wireshark laufen lassen, vielleicht ergibt sich was.

das log von gestern abend. ich habe den beitrag um 23:38 geschrieben, habe den rechner runtergefahren und vom netz getrennt. trotzdem ist das ding dann nochmal hochgefahren. aber dann bis heute abend nicht mehr.

Nov 25 23:34:41 kernel: [607801.110000] [/sbin/more] opened by pid 16907 [u:(-ash), comm:(ash)]
Nov 25 23:34:46 kernel: [607806.230000] [/etc/passwd] opened by pid 16907 [u:(-ash), comm:(ash)]
Nov 25 23:45:40 kernel: [608460.140000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 25 23:59:28 kernel: [609289.080000] [/var/run/smbd.pid] opened by pid 16344[u:(/usr/syno/sbin/smbd), comm:(smbd)]
Nov 25 23:59:34 kernel: [609295.000000] drivers/scsi/sd.c[469]:sd_prep_fn(), sda: cmd 0x0 spin up by pid=404, comm=jbd2/sda1-8
Nov 25 23:59:34 kernel: [609295.000000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 25 23:59:34 scemd: SCEMD: disk 1 wake up from hibernation
Nov 25 23:59:43 kernel: [609303.690000] [/var/log/messages] opened by pid 1055 [u:(/sbin/syslogd), comm:(syslogd)]
Nov 26 00:10:16 kernel: [609936.290000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 26 19:48:01 kernel: [680602.210000] [/etc/passwd] opened by pid 2971 [u:(/usr/sbin/inetd), comm:(inetd)]
Nov 26 19:48:04 kernel: [680604.360000] [/etc/nsswitch.conf] opened by pid 2974[u:(/bin/login), comm:(login)]
Nov 26 19:48:07 kernel: [680608.000000] drivers/scsi/sd.c[469]:sd_prep_fn(), sda: cmd 0x0 spin up by pid=404, comm=jbd2/sda1-8
Nov 26 19:48:07 kernel: [680608.000000] [/etc/localtime] opened by pid 1626 [u:(/usr/syno/bin/scemd), comm:(scemd)]
Nov 26 19:48:07 scemd: SCEMD: disk 1 wake up from hibernation
Nov 26 19:48:14 kernel: [680616.730000] [/etc/group] opened by pid 2974 [u:(/bin/login), comm:(login)]
Nov 26 19:48:21 kernel: [680623.950000] [/sbin/more] opened by pid 2974 [u:(-ash), comm:(ash)]

 

[itari]

Du hast wahrscheinlich die Uhrzeit-Justierung um 24:00 Uhr. Davon ist deine DS aufgewacht. Schau in die /etc/crontab hinein - du kannst auch im DS_Manager den Zeitpunkt der Uhrzeitberichtigung definieren.

Itari

 

[meister123]

Du hast wahrscheinlich die Uhrzeit-Justierung um 24:00 Uhr. Davon ist deine DS aufgewacht. Schau in die /etc/crontab hinein - du kannst auch im DS_Manager den Zeitpunkt der Uhrzeitberichtigung definieren

hi,

meinst du die automatische uhrzeiteinstellung? das war eine der ersten dinge, wo ich geschaut habe. das steht auf manuell. und ich bin mir zu 99,5 % sicher, dass es daran lag, dass ich den rechner kurz vorher noch an hatte. normalerweise mache ich den rechner spätestens um 23:00 aus, daher auch meine meinung, dass das ding immer um 23:19 hochfährt, siehe erstes posting.