sabnzbd stopt met downloaden [NTPD / time-change?]

[frankskeb]

sabnzbd stopt met downloaden als ntpd veranderd.
in freenas komt dan een code in de console "freenas ntpd(1828):kernel time sync status change 2001".
is dit een probleem in freenas of van sabnzbd.
deze service kan uitgeschakeld worden, maar dit kan weer niet , als je active directories gebruikt.
heb deze vraag ook in het freenas forum gesteld.
om sabnzbd weer te laten werken, moet ik de freenas rebooten.

gr

[sander]

Wat raar. Ik neem namelijk aan dat NTPD de tijd een klein beetje (een seconde of zo?) bijstelt. En zelfs als het een bijstelling van een uur was, dan zou ik het niet kunnen verklaren (aangenomen dat je in SABnzbd geen time-schedule wel/niet downloaden hebt ingesteld).

Ik zou dit doen: in SABnzbd de Logging naar +Debug zetten en kijken wat de log zegt

EDIT:

Het lijkt erop dat ik iets kan reproduceren: als ik de tijd ver vooruit zet (een uur), dan krijgen de SAB-NNTP-sessies onmiddellijk een time-out. Dat is verklaakbaar: SABnzbd kijkt blijkbaar naar de tijd zoals het systeem die rapporteert, en ontdekt dat de NNTP-sessie al langer dan een uur duurt, en dat triggert de time-out.
Maar op mijn systeem start SAB na een paar minuten weer en downloadt hij succesvol.

Dus: wat doet jouw SABnzbd?

Code: Select all

sander@flappie:~/kul$ sudo date 10120830
za okt 12 08:30:00 CEST 2013
sander@flappie:~/kul$ date
za okt 12 08:30:01 CEST 2013
sander@flappie:~/kul$ sudo date 10120930
za okt 12 09:30:00 CEST 2013
sander@flappie:~/kul$ 


2013-10-12 08:30:18,118::INFO::[downloader:612] Thread 2@weathergirl-ipv6.tele2.net: Article FYlVtKeFy2FYp3GWNY95_1o1@JBinUp.local missing (error=430)
2013-10-12 08:30:18,606::INFO::[downloader:612] Thread 2@weathergirl-ipv6.tele2.net: Article suoxuxCbFegiTcziRlW4_1o1@JBinUp.local missing (error=430)
2013-10-12 08:30:18,608::INFO::[downloader:612] Thread 1@weathergirl-ipv6.tele2.net: Article ci2KTAMMUJpvgVh6LBh3_1o1@JBinUp.local missing (error=430)
2013-10-12 09:30:00,006::INFO::[downloader:672] Thread 4@weathergirl-ipv6.tele2.net: timed out
2013-10-12 09:30:00,007::INFO::[downloader:672] Thread 3@weathergirl-ipv6.tele2.net: timed out
2013-10-12 09:30:00,007::INFO::[downloader:672] Thread 2@weathergirl-ipv6.tele2.net: timed out
2013-10-12 09:30:00,008::INFO::[downloader:672] Thread 1@weathergirl-ipv6.tele2.net: timed out
2013-10-12 09:30:00,009::INFO::[downloader:672] Thread 4@91.223.220.236: timed out
2013-10-12 09:30:00,010::INFO::[downloader:672] Thread 2@91.223.220.236: timed out
2013-10-12 09:30:00,011::INFO::[downloader:672] Thread 1@91.223.220.236: timed out
2013-10-12 09:30:00,014::INFO::[downloader:672] Thread 3@91.223.220.236: timed out
2013-10-12 09:30:00,015::INFO::[downloader:672] Thread 1@newsreader3.eweka.nl: timed out
2013-10-12 09:30:00,016::INFO::[downloader:672] Thread 2@newsreader3.eweka.nl: timed out
2013-10-12 09:30:00,018::INFO::[downloader:672] Thread 4@newsreader3.eweka.nl: timed out
2013-10-12 09:30:00,019::INFO::[downloader:672] Thread 5@newsreader3.eweka.nl: timed out
2013-10-12 09:30:00,026::INFO::[downloader:672] Thread 3@newsreader3.eweka.nl: timed out
2013-10-12 09:30:00,026::INFO::[downloader:672] Thread 6@newsreader3.eweka.nl: timed out
2013-10-12 09:30:00,027::INFO::[downloader:672] Thread 2@reader.i-telligent.com: timed out
2013-10-12 09:30:00,027::INFO::[downloader:672] Thread 1@reader.i-telligent.com: timed out
2013-10-12 09:30:00,028::INFO::[downloader:672] Thread 3@reader.i-telligent.com: timed out
2013-10-12 09:30:00,029::INFO::[downloader:672] Thread 4@reader.i-telligent.com: timed out
2013-10-12 09:30:00,029::INFO::[downloader:672] Thread 6@reader.i-telligent.com: timed out
2013-10-12 09:30:00,030::INFO::[downloader:672] Thread 5@reader.i-telligent.com: timed out
2013-10-12 09:30:00,030::INFO::[downloader:672] Thread 4@newszilla6.xs4all.nl: timed out
2013-10-12 09:30:00,047::INFO::[downloader:672] Thread 5@newszilla6.xs4all.nl: timed out
2013-10-12 09:30:00,048::INFO::[downloader:672] Thread 7@newszilla6.xs4all.nl: timed out
2013-10-12 09:30:00,049::INFO::[downloader:672] Thread 2@newszilla6.xs4all.nl: timed out
2013-10-12 09:30:00,050::INFO::[downloader:672] Thread 8@newszilla6.xs4all.nl: timed out
2013-10-12 09:30:00,051::INFO::[downloader:672] Thread 6@newszilla6.xs4all.nl: timed out
2013-10-12 09:30:00,052::INFO::[downloader:672] Thread 3@newszilla6.xs4all.nl: timed out

2013-10-12 09:32:00,063::INFO::[downloader:394] 4@weathergirl-ipv6.tele2.net: Initiating connection
2013-10-12 09:32:00,064::INFO::[downloader:394] 3@weathergirl-ipv6.tele2.net: Initiating connection
2013-10-12 09:32:00,065::INFO::[downloader:394] 2@weathergirl-ipv6.tele2.net: Initiating connection
2013-10-12 09:32:00,065::INFO::[downloader:394] 1@weathergirl-ipv6.tele2.net: Initiating connection
2013-10-12 09:32:00,066::INFO::[downloader:394] 4@91.223.220.236: Initiating connection
2013-10-12 09:32:00,067::INFO::[downloader:394] 2@91.223.220.236: Initiating connection
2013-10-12 09:32:00,069::INFO::[downloader:394] 1@91.223.220.236: Initiating connection
2013-10-12 09:32:00,070::INFO::[downloader:394] 3@91.223.220.236: Initiating connection
2013-10-12 09:32:00,071::INFO::[downloader:394] 1@newsreader3.eweka.nl: Initiating connection
2013-10-12 09:32:00,072::INFO::[downloader:394] 2@newsreader3.eweka.nl: Initiating connection
2013-10-12 09:32:00,073::INFO::[downloader:394] 4@newsreader3.eweka.nl: Initiating connection
2013-10-12 09:32:00,074::INFO::[downloader:394] 5@newsreader3.eweka.nl: Initiating connection
2013-10-12 09:32:00,075::INFO::[downloader:394] 3@newsreader3.eweka.nl: Initiating connection
2013-10-12 09:32:00,077::INFO::[downloader:394] 6@newsreader3.eweka.nl: Initiating connection
2013-10-12 09:32:00,078::INFO::[downloader:394] 2@reader.i-telligent.com: Initiating connection
2013-10-12 09:32:00,079::INFO::[downloader:394] 1@reader.i-telligent.com: Initiating connection
2013-10-12 09:32:00,080::INFO::[downloader:394] 3@reader.i-telligent.com: Initiating connection
2013-10-12 09:32:00,082::INFO::[downloader:394] 4@reader.i-telligent.com: Initiating connection
2013-10-12 09:32:00,084::INFO::[downloader:394] 6@reader.i-telligent.com: Initiating connection
2013-10-12 09:32:00,085::INFO::[downloader:394] 5@reader.i-telligent.com: Initiating connection
2013-10-12 09:32:00,086::INFO::[downloader:394] 4@newszilla6.xs4all.nl: Initiating connection
2013-10-12 09:32:00,087::INFO::[downloader:394] 5@newszilla6.xs4all.nl: Initiating connection
2013-10-12 09:32:00,089::INFO::[downloader:394] 7@newszilla6.xs4all.nl: Initiating connection
2013-10-12 09:32:00,090::INFO::[downloader:394] 2@newszilla6.xs4all.nl: Initiating connection
2013-10-12 09:32:00,091::INFO::[downloader:394] 8@newszilla6.xs4all.nl: Initiating connection
2013-10-12 09:32:00,092::INFO::[downloader:394] 6@newszilla6.xs4all.nl: Initiating connection
2013-10-12 09:32:00,094::INFO::[downloader:394] 3@newszilla6.xs4all.nl: Initiating connection
2013-10-12 09:32:00,106::INFO::[downloader:588] Connecting 4@91.223.220.236 finished
2013-10-12 09:32:00,110::INFO::[downloader:588] Connecting 2@91.223.220.236 finished
2013-10-12 09:32:00,111::INFO::[downloader:588] Connecting 1@91.223.220.236 finished
2013-10-12 09:32:00,112::INFO::[downloader:588] Connecting 3@91.223.220.236 finished
2013-10-12 09:32:00,129::INFO::[downloader:588] Connecting 1@newsreader3.eweka.nl finished
2013-10-12 09:32:00,130::INFO::[downloader:588] Connecting 2@newsreader3.eweka.nl finished
2013-10-12 09:32:00,131::INFO::[downloader:588] Connecting 4@newsreader3.eweka.nl finished
2013-10-12 09:32:00,132::INFO::[downloader:588] Connecting 5@newsreader3.eweka.nl finished
2013-10-12 09:32:00,133::INFO::[downloader:588] Connecting 3@newsreader3.eweka.nl finished
2013-10-12 09:32:00,134::INFO::[downloader:588] Connecting 6@newsreader3.eweka.nl finished
2013-10-12 09:32:00,209::INFO::[downloader:588] Connecting 6@newszilla6.xs4all.nl finished
2013-10-12 09:32:00,210::INFO::[downloader:588] Connecting 5@newszilla6.xs4all.nl finished
2013-10-12 09:32:00,211::INFO::[downloader:588] Connecting 8@newszilla6.xs4all.nl finished
2013-10-12 09:32:00,212::INFO::[downloader:588] Connecting 3@newszilla6.xs4all.nl finished
2013-10-12 09:32:00,212::INFO::[downloader:588] Connecting 7@newszilla6.xs4all.nl finished
2013-10-12 09:32:00,213::INFO::[downloader:588] Connecting 2@newszilla6.xs4all.nl finished
2013-10-12 09:32:00,219::INFO::[downloader:588] Connecting 4@newszilla6.xs4all.nl finished
2013-10-12 09:32:00,220::INFO::[downloader:588] Connecting 3@reader.i-telligent.com finished
2013-10-12 09:32:00,228::INFO::[downloader:588] Connecting 2@reader.i-telligent.com finished
2013-10-12 09:32:00,229::INFO::[downloader:588] Connecting 1@reader.i-telligent.com finished
2013-10-12 09:32:00,267::INFO::[downloader:588] Connecting 4@reader.i-telligent.com finished
2013-10-12 09:32:00,268::INFO::[downloader:588] Connecting 6@reader.i-telligent.com finished
2013-10-12 09:32:00,269::INFO::[downloader:588] Connecting 5@reader.i-telligent.com finished
2013-10-12 09:32:15,087::INFO::[newswrapper:233] Failed to connect: (110, '[Errno 113] No route to host') 2@weathergirl-ipv6.tele2.net:119
2013-10-12 09:32:15,087::INFO::[newswrapper:233] Failed to connect: (110, '[Errno 113] No route to host') 3@weathergirl-ipv6.tele2.net:119
2013-10-12 09:32:15,103::INFO::[newswrapper:233] Failed to connect: (110, '[Errno 113] No route to host') 1@weathergirl-ipv6.tele2.net:119
2013-10-12 09:32:15,723::INFO::[downloader:588] Connecting 4@weathergirl-ipv6.tele2.net finished
2013-10-12 09:32:15,853::INFO::[downloader:612] Thread 4@weathergirl-ipv6.tele2.net: Article qxKYQSvHWOv9gxc3ebIB_1o1@JBinUp.local missing (error=430)
2013-10-12 09:32:16,094::INFO::[downloader:612] Thread 4@weathergirl-ipv6.tele2.net: Article gcbjas5lihNtLNEFFJka_1o1@JBinUp.local missing (error=430)
2013-10-12 09:32:16,242::INFO::[downloader:612] Thread 4@weathergirl-ipv6.tele2.net: Article ueiIqNyDH8kXKjAd4dvC_1o1@JBinUp.local missing (error=430)

EDIT2:

Hoeveel stelt jouw ntpd de tijd bij? Want ik begrijp jouw verschijnsel dus alleen als dat meer dan 30 seconde of zo is. Op mijn systeem zijn de bijstelling veel kleiner dan dat:

Code: Select all

sander@flappie:~$ grep -i ntp /var/log/syslog
Oct 11 13:45:52 flappie ntpdate[4962]: adjust time server 91.189.94.4 offset 0.061789 sec
Oct 11 14:47:41 flappie ntpdate[6394]: adjust time server 91.189.89.199 offset 0.031551 sec
Oct 11 15:13:33 flappie ntpdate[7801]: step time server 91.189.89.199 offset 0.516289 sec
Oct 11 15:16:00 flappie ntpdate[8354]: adjust time server 91.189.94.4 offset -0.008843 sec
Oct 11 22:47:29 flappie ntpdate[10375]: adjust time server 91.189.94.4 offset 0.021244 sec
Oct 11 23:01:54 flappie ntpdate[11257]: adjust time server 91.189.94.4 offset 0.156263 sec
Oct 12 08:04:12 flappie ntpdate[12440]: adjust time server 91.189.94.4 offset -0.218774 sec
Oct 12 08:48:56 flappie ntpdate[13233]: adjust time server 91.189.94.4 offset -0.337634 sec

[frankskeb]

ik ga dit instellen en zodra ik dit verschijnsel weer heb, zal ik het posten.

zover bedankt

[bettinocraxi]

Bedankt voor het delen van nuttige informatie.

[frankskeb]

heb een nieuwe map toegewezen voor de downloads in de root van de plugin en nu gaat het downloaden wel door.
er schijnen meer mensen te zijn, die dit probleem hebben in freenas.
want na de ntpd /time change, worden de rechten veranderd en heeft sabnzbd geen rechten meer en stopt.

gr