+-Serie Shell 'time' und 'ntpdate' laufen extrem auseinander

[schuetze]

Hallo alle zusammen,
ich hoffe, dass ich im richtigen Bereich bin.
Ich bin ein halbwegs glücklicher Besitzer von einer DS213+ . Zur Zeit benutze ich sie nur als "Datenspeicher" sowie die Audio-Station und Photo-Station.
Mir ist aufgefallen, dass beim Kommando time eine andere Uhrzeit + Datum kommt als bei ntpdate.

Aktuelle Zeit: 17:48 Uhr
Ausgabe ntpdate:

29 Apr 17:48:16 ntpdate[8739]: no servers can be used, exiting

Ausgabe time:

BusyBox v1.16.1 (2013-03-01 01:11:15 CST) multi-call binary.

Usage: time [OPTIONS] PROG ARGS

Run PROG, display resource usage when it exits

Options:
-v Verbose

Den NTP-Dienst habe ich absichtlich deaktiviert, damit dieser nicht immer die DS aus dem Tiefschlaf holt.

Wie kann ich die Zeit von der BussyBox abgleichen? Warum wird das nicht automatisch gemacht? Fragen über Fragen

Danke und viele Grüße

 

[Benares]

Du verwechselst da was.

ntpdate synchronisert die Zeit mit dem angegebenen Zeitserver. "ntpdate -b servername|ip", also z.B. "ntpdate -b 192.168.0.1" wäre richtig.

"time" misst die Laufzeit eines Kommandos, also z.B. "time ls" gibt aus, wie lange "ls" dauert. Was du meinst ist "date", das gibt die aktuelle Systemzeit aus.

Gruß Benares

 

[schuetze]

Hi Benares,
vielen Dank für die superschnelle Antwort.
Trotzdem laufen bei mir auf dem System irgendwie zwei unterschiedliche Zeiten. Ich bin auf dieses "Problem" gestoßen, als ich mir die datei /var/log/messages angeschaut habe.
Dort finde ich diesen Eintrag:

crond[5172]: crond: time disparity of 2440 minutes detected

 

[Benares]

Die Meldung sagt mir im Moment auch nichts, google mal nach "cron disparity". Ich glaube, das gibt der laufende crond aus, wenn er feststellt, dass die Zeit springt.

Zeigt "date" die aktuelle Uhrzeit?

Gruß Benares

 

[schuetze]

Hi Benares,
naja, so kleine "Sprünchen" von ca 1,5 Tagen finde ich persönlich schon seltsam ;-)
mit 'date' wird bei mir die aktuelle Zeit ausgegeben.
Welche Zeiten werden denn bei Dir ausgegeben, wenn Du "meine" anderen beiden Befehle eingibst?

 

[Ap0phis]

Wenn man "deine anderen beiden" Befehl (ntpdate,time) eingibt, dann bekommt jeder die gleiche Anzeige.
Sie haben einfach "beide" nicht direkt mit der Anzeige der aktuellen Zeit zu tun!

Was steht denn in der /var/log/messages vor oder hinter der crond-Meldung?
Evtl. kann man da mehr erkennen?

 

[jahlives]

@schuetze
die Ausgabe bei deinem Time-Bsp ist das Build-Datum des Time-Binaries ;-)

 

[schuetze]

N'abend zusammen,
nochmal Danke für Eure Hilfe

@Ap0phis
hier mal so ein Log-Eintrag:

Apr 18 23:45:46 hotplugd: ##### ACTION:add
Apr 18 23:45:46 hotplugd: DEVICE:/proc/bus/usb/002/001
Apr 18 23:45:46 hotplugd: DEVPATH:2-0:1.0
Apr 18 23:45:46 hotplugd: SUBSYSTEM:usb
Apr 18 23:45:46 hotplugd: INTERFACE:9/0/0
Apr 18 23:45:50 scemd: modules/rtc.c:238 RTC and system time diff are too large. rtc_year:[113] rtc_mon:[3] rtc_mday:[19] rtc_hour:[4] sys_year:[113] sys_mon:[3] sys_md
Apr 18 23:45:50 scemd: modules/rtc.c:256 RTC looks like fine, maybe system error let it update
Apr 19 06:55:45 scemd: modules/fan_check_common.c:601 Start fan full speed to avoid fan fail
Apr 19 06:55:51 scemd: modules/fan_check_common.c:605 Stop fan full speed to avoid fan fail
Apr 19 06:55:55 crond[5172]: crond: time disparity of 430 minutes detected
Apr 19 07:10:16 kernel: [66994.958559] Freezing user space processes ... (elapsed 0.00 seconds) done.
Apr 19 07:10:16 kernel: [66994.974240] Freezing remaining freezable tasks ... (elapsed 0.03 seconds) done.
Apr 19 07:10:16 kernel: [66995.014962] Suspending console(s) (use no_console_suspend to debug)
Apr 19 07:10:16 kernel: [66995.022410] ata4: send port disabled event
Apr 19 07:10:16 kernel: [66995.047003] ata3: send port disabled event
Apr 19 07:10:16 kernel: [66995.254475] ata1: send port disabled event
Apr 19 07:10:16 kernel: [66995.254519] ata2: send port disabled event
Apr 19 07:10:16 kernel: [66995.282442] Disabling non-boot CPUs ...
Apr 19 07:10:16 kernel: [66995.794661] Enabling non-boot CPUs ...
Apr 19 07:10:16 kernel: [66995.794689] setting frequency for cpu 0 to 1066666 kHz, PLL ratio is 4/2
Apr 19 07:10:16 kernel: [66995.794697] PMJCR request 04043c00 at CPU 0
Apr 19 07:10:16 kernel: [66995.794703] PORPLLSR core freq 1066MHz at CPU 0
Apr 19 07:10:16 kernel: [66995.928222] Processor 1 found.
Apr 19 07:10:16 kernel: [66996.037765] 0: 533333kHz
Apr 19 07:10:16 kernel: [66996.037769] 1: 799999kHz
Apr 19 07:10:16 kernel: [66996.037773] 2: 1066666kHz
Apr 19 07:10:16 kernel: [66996.037776] 3: 1333332kHz
Apr 19 07:10:16 kernel: [66996.037780] 4: 1599999kHz
Apr 19 07:10:16 kernel: [66996.037783] 5: 1866665kHz
Apr 19 07:10:16 kernel: [66996.037787] 6: 2133332kHz
Apr 19 07:10:16 kernel: [66996.037836] setting frequency for cpu 1 to 1066666 kHz, PLL ratio is 4/2
Apr 19 07:10:16 kernel: [66996.037836] setting frequency for cpu 1 to 1066666 kHz, PLL ratio is 4/2
Apr 19 07:10:16 kernel: [66996.037842] PMJCR request 04043c00 at CPU 1
Apr 19 07:10:16 kernel: [66996.037847] PORPLLSR core freq 1066MHz at CPU 1
Apr 19 07:10:16 kernel: [66996.037881] setting frequency for cpu 1 to 1066666 kHz, PLL ratio is 4/2
Apr 19 07:10:16 kernel: [66996.037887] PMJCR request 04043c00 at CPU 1
Apr 19 07:10:16 kernel: [66996.037892] PORPLLSR core freq 1066MHz at CPU 1
Apr 19 07:10:16 kernel: [66996.037896] CPU1 is up
Apr 19 07:10:16 kernel: [66999.049721] ata1: wake up from deepsleep, reset link now
Apr 19 07:10:16 kernel: [67000.566694] ata3: No Device OR PHYRDY change,Hstatus = 0xa0000000
Apr 19 07:10:16 kernel: [67000.566733] ata4: No Device OR PHYRDY change,Hstatus = 0xa0000000
Apr 19 07:10:16 kernel: [67006.057660] ata2: wake up from deepsleep, reset link now
Apr 19 07:10:16 kernel: [67012.033664] ata1: softreset failed (timeout)
Apr 19 07:10:16 kernel: [67012.033669] ata1: softreset failed, set srst fail flag
Apr 19 07:10:16 kernel: [67014.361696] ata1: link reset sucessfully clear error flags
Apr 19 07:10:16 kernel: [67014.403766] ata1.00: Disable disk write cache in EH
Apr 19 07:10:16 kernel: [67019.037663] ata2: softreset failed (timeout)
Apr 19 07:10:16 kernel: [67019.037668] ata2: softreset failed, set srst fail flag
Apr 19 07:10:16 kernel: [67021.349695] ata2: link reset sucessfully clear error flags
Apr 19 07:10:16 kernel: [67021.394718] ata2.00: Disable disk write cache in EH
Apr 19 07:10:16 kernel: [67021.678809] Restarting tasks ... done.
Apr 19 07:10:16 kernel: [67022.223019] Got empty serial number. Generate serial number from product.
Apr 19 07:10:17 kernel: [67022.872474] usb usb2: No SuperSpeed endpoint companion for config 1 interface 0 altsetting 0 ep 129: using minimum values
Apr 19 07:10:17 scemd: modules/disk_hibernation.c:2349 Diskstation wakes up from sys sleep.
Apr 19 07:10:18 scemd: modules/rtc.c:238 RTC and system time diff are too large. rtc_year:[113] rtc_mon:[3] rtc_mday:[22] rtc_hour:[21] sys_year:[113] sys_mon:[3] sys_m
Apr 19 07:10:18 hotplugd: hotplugd.c:1110 failed get usb status from usb config, DEVICE=/proc/bus/usb/002/001, usbid=1d6b:0003:0002:05:00.0
Apr 19 07:10:18 hotplugd: ##### ACTION:add
Apr 19 07:10:18 hotplugd: DEVICE:/proc/bus/usb/002/001
Apr 19 07:10:18 hotplugd: DEVPATH:usb2
Apr 19 07:10:18 hotplugd: SUBSYSTEM:usb
Apr 19 07:10:18 hotplugd: hotplugd.c:884 Fail to recognize usb DEVNAME=, DEVPATH=usb2
Apr 19 07:10:18 hotplugd: hotplugd.c:1409 failed to setup usb config, (DEVPATH, DEVICE, ACTION, DEVNAME, INTERFACE)=(usb2, /proc/bus/usb/002/001, add, , )
Apr 19 07:10:18 hotplugd: hotplugd.c:1110 failed get usb status from usb config, DEVICE=/proc/bus/usb/002/001, usbid=1d6b:0003:0002:05:00.0
Apr 19 07:10:18 hotplugd: ##### ACTION:add
Apr 19 07:10:18 hotplugd: DEVICE:/proc/bus/usb/002/001
Apr 19 07:10:18 hotplugd: DEVPATH:2-0:1.0
Apr 19 07:10:18 hotplugd: SUBSYSTEM:usb
Apr 19 07:10:18 hotplugd: INTERFACE:9/0/0
Apr 19 07:10:22 scemd: modules/rtc.c:238 RTC and system time diff are too large. rtc_year:[113] rtc_mon:[3] rtc_mday:[22] rtc_hour:[21] sys_year:[113] sys_mon:[3] sys_m
Apr 19 07:10:22 scemd: modules/rtc.c:256 RTC looks like fine, maybe system error let it update
Apr 22 23:04:33 scemd: modules/fan_check_common.c:601 Start fan full speed to avoid fan fail
Apr 22 23:04:39 scemd: modules/fan_check_common.c:605 Stop fan full speed to avoid fan fail
Apr 22 23:05:11 crond[5172]: crond: time disparity of 5274 minutes detected

könnt Ihr damit was anfangen?
EDIT: zur Info, ich habe nix am USB-Port angeschlossen.

 

[Benares]

könnt Ihr damit was anfangen?

Nicht genau, aber ich kann's mir denken.

Bei dir klappt ausnahmsweise der DeepSleep. Dabei wird auch die CPU runter getaktet und auch die Systemzeit (Softwareuhr) bleibt stehen. Beim Aufwachen wird sie aus der Hardwareuhr (RTC) neu geladen.

Ich interpretieren die Zeilen

Apr 19 07:10:22 scemd: modules/rtc.c:238 RTC and system time diff are too large. rtc_year:[113] rtc_mon:[3] rtc_mday:[22] rtc_hour:[21] sys_year:[113] sys_mon:[3] sys_m
 Apr 19 07:10:22 scemd: modules/rtc.c:256 RTC looks like fine, maybe system error let it update
 Apr 22 23:04:33 scemd: modules/fan_check_common.c:601 Start fan full speed to avoid fan fail

mal so: Die DS ist am 19.4. 07:10 schlafen gegangen. Alle 3 Zeilen stammen vom Aufwachvorgang am 22.4. 23:04. Zunächst ist die Systemzeit noch auf der alten Zeit (Zeilenanfang), ab der 3. Zeile wurde sie aus der Hardwareuhr neu geladen. Das ein bereits laufender crond das anmeckert, ist auch verständlich. Ob die Zeit dann auch stimmt, hängt von der Genauigkeit der Hardwareuhr ab. Den Feinabgleich übernehmen dann Dienste wie ntpdate oder ntpd.

Gruß Benares