Freebox Server (Delta V7 / Revolution V6 / Server Mini 4K)

  • État Close
  • Type de tâche Anomalie
  • Catégorie Téléchargements → News
  • Assignée à Romain FLIEDEL (rfliedel)
  • Système d'exploitation Freebox Server V6 (Révolution)
  • Sévérité Haute
  • Priorité Normale
  • Basée sur la version 2.1.0
  • Due pour la version Non décidé
  • Date d'échéance Non décidé
Concerne le projet: Freebox Server (Delta V7 / Revolution V6 / Server Mini 4K)
Ouverte par Franck (Franck.75) - 02/10/2013
Dernière édition par Freebox Robot (Freebox Robot) - 19/11/2013

FS#13282 - Le téléchargement via newsgroups s'arrête sans raison au bout de 6 heures

Le téléchargement via newsgroups (service de Freebox OS) s’arrête sans raison au bout de 6 heures.
Tous les téléchargements partent alors en erreur avec le message “Segments manquants”.
Il suffit de faire “Reprendre” manuellement pour que cela reparte.
Au final, après avoir fait des relances manuelles toutes les 6 heures, le téléchargement est complet.

Testé avec le newsgroup gratuit free.xsusenet.com avec 2 connexions simultanées et un débit maximal de 120 ko/s.
L’erreur a été reproduite systématiquement sur une durée supérieure à 1 semaine.
S’applique à tous les téléchargements. C’est visiblement la durée qui est la source du bug.

Malheureusement, je n’ai pas accès à des logs qui permettrait d’en savoir plus...

Close par  Freebox Robot (Freebox Robot)
Tuesday 19 November, 2013 22:29:26
Raison de clôture :  Résolu
Commentaires supplémentaires de clôture :  En 2.0.7

Cette tache ne dépend pas d'autre tache

Romain FLIEDEL (rfliedel)
Thursday 3 October, 2013 14:56:15

dans l'interface freebox os il y a un onglet 'journal' avec des logs, vous pouvez postez votre log ici la prochaine fois ?

rom7 (rom7)
Monday 7 October, 2013 18:49:10

Je viens de constater exactement le même problème, et voici quelques log:
Il y avait 4 nzb dans la file d'attente.

Le 1er NZB s'est déroule sans problème (le téléchargement à commencé à 18:23:23):

2013-10-06 18:23:23 new nzb task, downloading to /Disque dur/Téléchargements/.......
2013-10-06 18:23:23 starting download
.....
2013-10-06 21:08:03 [extract] done
2013-10-06 21:08:03 [extract] extract succeeded

Le 2eme nzb a été téléchargé à moitié. A partir de 00:23:33, Le log est rempli de ligne comme celle-ci:
2013-10-07 00:23:33 segment finished part684of820.o1bCtd1ttUN4xqiz0Awc@powerpost2000AA.local in state: no group
2013-10-07 00:23:33 segment finished part685of820.o1bCtd1ttUN4xqiz0Awc@powerpost2000AA.local in state: no group

Les 3eme et 4eme nzb ne sont pas téléchargés. Il sont remplis d'erreurs similaires.

Comme le dit Franck,on voit que la différence entre le début du téléchargement, et les log en erreur est d'exactement 6 heures ( 10sec prés) !!!

Romain FLIEDEL (rfliedel)
Monday 7 October, 2013 18:58:18

c'est surement une limitation du serveur de news qui coupe au bout de 6H. vous utilisez quelle fournisseur ?

Franck (Franck.75)
Wednesday 9 October, 2013 06:50:06

Bonjour,
comme indiqué dans mon message initial c'est le newsgroup gratuit free.xsusenet.com avec 2 connexions simultanées et un débit maximal de 120 ko/s
Vous pouvez facilement ouvrir un compte et tester avec n'importe quel fichier qui a moins de 10 jours.

Je viens de refaire le test sur PC avec NewsbinPro et je ne rencontre pas le problème. Aucun log d'erreur après 9 heures.
Le problème provient donc de l'application embarquée dans la Freebox.

Les logs ne sont pas conservés dans la durée, je dois donc relancer une sessions de 6 heures pour en avoir mais peut-être que les indications de rom7 vous suffiront.

Je ne sais pas si c'est lié, mais je viens de lancer un nouveau fichier pour le test de 6 heures et j'ai eu immédiatement une erreur qui ne s'est pas reproduite en relançant.
Voila les logs en espérant ne pas vous induire en erreur. Je vous donne mes logs après 6 heures d'ici ce soir.

2013-10-09 08:43:55 new nzb task, downloading to /Disque dur/Téléchargements/...
2013-10-09 08:43:55 starting download
2013-10-09 08:43:55 init connection to free.xsusenet.com on port 119
2013-10-09 08:43:55 resolved free.xsusenet.com
2013-10-09 08:43:55 connecting to server
2013-10-09 08:43:55 connected, checking service status
2013-10-09 08:43:55 server status ok
2013-10-09 08:43:55 getting server capabilities
2013-10-09 08:43:55 server does not provide its capabilities
2013-10-09 08:43:55 [00] connection established
2013-10-09 08:43:55 start authentication with server
2013-10-09 08:43:57 [00] failed to authenticate
2013-10-09 08:43:57 [00] connection closed
2013-10-09 08:43:57 task finished with state authentication failed
2013-10-09 08:44:41 retry download
2013-10-09 08:44:41 starting download
2013-10-09 08:44:41 init connection to free.xsusenet.com on port 119
2013-10-09 08:44:41 resolved free.xsusenet.com
2013-10-09 08:44:41 connecting to server
2013-10-09 08:44:41 connected, checking service status
2013-10-09 08:44:41 server status ok
2013-10-09 08:44:41 getting server capabilities
2013-10-09 08:44:41 server does not provide its capabilities
2013-10-09 08:44:41 [00] connection established
2013-10-09 08:44:41 start authentication with server
2013-10-09 08:44:41 server auth success
2013-10-09 08:44:41 getting server capabilities
2013-10-09 08:44:41 server does not provide its capabilities
2013-10-09 08:44:41 [00] connection authenticated
2013-10-09 08:44:41 [00] setting group to alt...

Franck (Franck.75)
Wednesday 9 October, 2013 22:30:28

Voila les logs 6 heures et 12 secondes après :
2013-10-09 14:44:53 segment finished 1381055119.974580.46@eu.news.astraweb.com in state: no group
2013-10-09 14:44:53 segment finished 1381055119.988180.47@eu.news.astraweb.com in state: no group
2013-10-09 14:44:53 segment finished 1381055120.007110.48@eu.news.astraweb.com in state: no group
2013-10-09 14:44:53 segment finished 1381055120.02463.49@eu.news.astraweb.com in state: no group

Il y a 154 lignes sur la même seconde.

suivi de (dans la même seconde) :

2013-10-09 14:44:53 [00] connection closed
2013-10-09 14:44:54 task has errors
2013-10-09 14:44:54 task finished with state missing data
2013-10-09 14:44:54 [repair] trying to repair download
2013-10-09 14:44:54 [repair] looking for par2 files

On a donc un plantage systématique reproductible sur plusieurs Freebox au bout de 21612 secondes.
Et cela s'arrête net dans la même seconde.
Je ne connais pas le code, mais ça sent un dépassement dans une variable pas assez dimensionnée.
Bonne chance pour trouver le bug. Il est reproductible systématiquement. C'est déjà ça.
Reste plus qu'à trouver la bonne ligne...

Romain FLIEDEL (rfliedel)
Monday 14 October, 2013 10:16:18

ok, j'ai pu identifier le problème, ça sera corrigé dans la prochaine mise à jour

Franck (Franck.75)
Saturday 19 October, 2013 16:29:21

Super Romain. Bonne nouvelle. Merci.
Et pour les geeks curieux, c'était quoi ?

Chargement...