Neues Thema starten

STB lässt sich nur nach Neustart mit Fernbedienung starten

>Wir haben hier einen seltsamen Fall, wo wir nicht weiterkommen. Kunde kann seine STB erst nach einem Neustart (Netzteil raus- und >rein) mit der Fernbedienung starten (1x). Danach muss er wieder Neustart machen.

 

In den Logfiles habe ich diese Meldung gesehen  und die gefällt mir gar nicht!

  

.. Error HandleEvent: Failed to get a suitable reply from NTP server 

   

Siehe auch URL http://support.tvfactory.ch/support/discussions/topics/5000029740


Ich muss annehmen, dass die Default NTP Server aus dem LAN des kunden nicht erreichbar sind, bzw. die Fritzbox im DHCP Lease das FELD ntpsrv=192.168.178.1; überschreibt. Könnt ihr schauen, dass ihr da einen Null-Wert übergebt?  

  

...domain=fritz.box;dns=192.168.178.1;ntpsrv=192.168.178.1;...

 

Hier folgt eine Logsequenz, die das Fehlverhalten aufzeigt. 

 

 

2015-02-03T16:30:34+02:00 00-02-9b-aa-12-ba sysman[536] seq=485 msec=253 Note Platform started 
2015-02-03T16:30:34+02:00 00-02-9b-aa-12-ba timeman[539] seq=500 msec=500 Note ntpd is running 
2015-02-03T16:30:34+02:00 00-02-9b-aa-12-ba timeman[539] seq=501 msec=503 Note SpawnScript: /usr/bin/ntpreset.sh spawned. 
2015-02-03T16:30:35+02:00 00-02-9b-aa-12-ba timeman[539] seq=517 msec=439 Trace StartNtpdateProcess: Calling /usr/sbin/ntpdate -q 192.168.178.1 
2015-02-03T16:30:36+02:00 00-02-9b-aa-12-ba ntp_logger.sh[552] seq=521 msec=976 None 3 Feb 16:30:36 ntpd[481]: Listen normally on 3 eth0 192.168.178.37 UDP 123 
2015-02-03T16:30:36+02:00 00-02-9b-aa-12-ba ntp_logger.sh[552] seq=522 msec=976 None 3 Feb 16:30:36 ntpd[481]: Deleting interface #2 eth0, 169.254.44.44#123, interface stats: received=0, sent=0, dropped=0, active_time=6 secs 
2015-02-03T16:30:36+02:00 00-02-9b-aa-12-ba ntp_logger.sh[552] seq=523 msec=976 None 3 Feb 16:30:36 ntpd[481]: peers refreshed 
2015-02-03T16:30:43+02:00 00-02-9b-aa-12-ba timeman[539] seq=524 msec=569 None 3 Feb 16:30:43 ntpdate[614]: no server suitable for synchronization found 
2015-02-03T16:30:43+02:00 00-02-9b-aa-12-ba timeman[539] seq=525 msec=570 Error HandleEvent: Failed to get a suitable reply from NTP server 
2015-02-03T17:00:43+02:00 00-02-9b-aa-12-ba timeman[539] seq=638 msec=643 Trace StartNtpdateProcess: Calling /usr/sbin/ntpdate -q 192.168.178.1 
2015-05-07T16:03:59+02:00 00-02-9b-aa-12-ba timeman[539] seq=707 msec=90 Trace StartNtpdateProcess: Calling /usr/sbin/ntpdate -q 192.168.178.1 
2015-05-07T16:34:06+02:00 00-02-9b-aa-12-ba timeman[539] seq=773 msec=190 Trace StartNtpdateProcess: Calling /usr/sbin/ntpdate -q 192.168.178.1 
2015-05-07T17:04:12+02:00 00-02-9b-aa-12-ba timeman[539] seq=839 msec=498 Trace StartNtpdateProcess: Calling /usr/sbin/ntpdate -q 192.168.178.1 
2015-05-07T17:34:18+02:00 00-02-9b-aa-12-ba timeman[539] seq=905 msec=699 Trace StartNtpdateProcess: Calling /usr/sbin/ntpdate -q 192.168.178.1 
2015-05-07T18:04:24+02:00 00-02-9b-aa-12-ba timeman[539] seq=971 msec=908 Trace StartNtpdateProcess: Calling /usr/sbin/ntpdate -q 192.168.178.1 
2015-05-07T18:34:31+02:00 00-02-9b-aa-12-ba timeman[539] seq=1037 msec=100 Trace StartNtpdateProcess: Calling /usr/sbin/ntpdate -q 192.168.178.1 
2015-05-07T19:04:37+02:00 00-02-9b-aa-12-ba timeman[539] seq=1103 msec=313 Trace StartNtpdateProcess: Calling /usr/sbin/ntpdate -q 192.168.178.1 
2015-02-03T16:30:22+02:00 00-02-9b-aa-12-ba kernel[0] seq=352 msec=968 Trace frontpanel: Normal probing is used 
2015-02-03T16:30:22+02:00 00-02-9b-aa-12-ba kernel[0] seq=353 msec=968 Trace frontpanel: VIP11x3 without optional leds. 
2015-02-03T16:30:28+02:00 00-02-9b-aa-12-ba udhcpc[541] seq=468 msec=482 None action=leasefail;interface=eth0;ip=;broadcast=;router=;subnet=;domain=;dns=;ntpsrv=;tftp=;bootfile=;dhcp_t1=;dhcp_t2=;userclass=;tz_ieee=;tz_file=;sroutes=;infocast=;lease=;vi_vendor=;vsi=
2015-02-03T16:30:30+02:00 00-02-9b-aa-12-ba ntp_logger.sh[552] seq=472 msec=959 None 3 Feb 16:30:30 ntpd[481]: proto: precision = 1.080 usec 
2015-02-03T16:30:30+02:00 00-02-9b-aa-12-ba ntp_logger.sh[552] seq=473 msec=959 None 3 Feb 16:30:30 ntpd[481]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 
2015-02-03T16:30:30+02:00 00-02-9b-aa-12-ba ntp_logger.sh[552] seq=474 msec=959 None 3 Feb 16:30:30 ntpd[481]: Listen normally on 1 lo 127.0.0.1 UDP 123 
2015-02-03T16:30:30+02:00 00-02-9b-aa-12-ba ntp_logger.sh[552] seq=475 msec=959 None 3 Feb 16:30:30 ntpd[481]: Listen normally on 2 eth0 169.254.44.44 UDP 123 
2015-02-03T16:30:30+02:00 00-02-9b-aa-12-ba ntp_logger.sh[552] seq=476 msec=959 None 3 Feb 16:30:30 ntpd[481]: peers refreshed 
2015-02-03T16:30:30+02:00 00-02-9b-aa-12-ba ntp_logger.sh[552] seq=477 msec=959 None 3 Feb 16:30:30 ntpd[481]: Listening on routing socket on fd #19 for interface updates 
2015-02-03T16:30:34+02:00 00-02-9b-aa-12-ba udhcpc[541] seq=478 msec=242 None action=bound;interface=eth0;ip=192.168.178.37;broadcast=192.168.178.255;router=192.168.178.1;subnet=255.255.255.0;domain=fritz.box;dns=192.168.178.1;ntpsrv=192.168.178.1;tftp=;bootfile=;dhcp_t1=;dhcp_t2=;userclass=;tz_ieee=;tz_file=;sroutes=;infocast=;lease=864000;vi_vendor=00000de9210406303030343045050c3038393644374141363342360609465249545a21426f78;vsi=
2015-02-03T16:30:34+02:00 00-02-9b-aa-12-ba sysman[536] seq=485 msec=253 Note Platform started   

 

Bei diesem Kunden hat sich gezeigt, dass eine Race-Condition beim Starten des Routers und der STB entsteht. Dabei erhält die Set-Top-Box über den DHCP Lease, den Fritzbox Router als NTP Server, der davon nichts wissen will. Wenn die STB neu gestartet wird, funktioniiert die STB. 

Kunden die Ihren Router und STB über eine Stromschiene ein-/ausschalten sollten dies mitberücksichtigen. 




Anmelden oder Registrieren um einen Kommentar zu veröffentlichen