Extinction Inopinée Du Nas

[M@ttew]

Bonjour à tous,

 

J'utilise un QNAP TS-119P+ depuis juillet 2011.

Firmware 3.8.1 installé le 05/12/2012

Disque dur 2To Western Digital Red en interne et disque dur 2To externe connecté en eSATA.

De retour de vacances après 3 semaines, j'ai rallumé le NAS avec le HD externe.

Et depuis, comportement erratique du système.

Notamment, au premier démarrage, le HD connecté en eSATA a refusé de monter.

Finalement, suite à un ReBoot complet, j'ai récupéré le HD externe.

Mais depuis 6 jours (mon retour), j'ai eu 4 extinctions inexpliquées ("The system was not shut down properly last time.")

Du coup, j'ai jeté un oeil dans le fichier Log et j'ai remarqué que le disque externe ("eSATADisk1") s'était déconnecté 6 fois depuis le redémarrage à mon retour (le 05 juillet).

J'ai donc un doute sur l'intégrité du système.

 

Extrait du Log:

"Number","Type","Date","Time","Users","Source IP","Computer name","Content"

"1147","Information","2014-07-10","22:49:02","System","127.0.0.1","localhost","[Media Library] Media Library Server started."

"1146","Information","2014-07-10","22:47:25","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] Examination completed."

"1145","Information","2014-07-10","22:40:55","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] Start examination."

"1144","Information","2014-07-10","22:40:19","System","127.0.0.1","localhost","[Media Library] Media Library Server started."

"1143","Information","2014-07-10","21:31:06","System","127.0.0.1","localhost","[squid] Squid is enabled successfully."

"1142","Information","2014-07-10","21:07:08","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1141","Information","2014-07-10","21:06:27","System","127.0.0.1","localhost","[Media Library] Media Library Server started."

"1140","Warning","2014-07-10","21:05:37","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] The file system is not clean. It is suggested that you run "check disk"."

"1139","Information","2014-07-10","21:05:24","System","127.0.0.1","localhost","System started."

"1138","Warning","2014-07-10","21:05:22","System","127.0.0.1","localhost","The system was not shut down properly last time."

"1137","Information","2014-07-10","17:49:44","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1136","Information","2014-07-10","17:49:34","System","127.0.0.1","localhost","[eSATADisk1] Device removed."

"1135","Information","2014-07-10","17:32:19","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1134","Information","2014-07-10","16:13:47","System","127.0.0.1","localhost","[eSATADisk1] Device removed."

"1133","Information","2014-07-10","14:58:56","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1132","Information","2014-07-10","14:58:13","System","127.0.0.1","localhost","[Media Library] Media Library Server started."

"1131","Warning","2014-07-10","14:57:23","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] The file system is not clean. It is suggested that you run "check disk"."

"1130","Information","2014-07-10","14:57:13","System","127.0.0.1","localhost","System started."

"1129","Warning","2014-07-10","14:57:11","System","127.0.0.1","localhost","The system was not shut down properly last time."

"1128","Information","2014-07-10","14:55:06","System","127.0.0.1","localhost","[eSATADisk1] Device removed."

"1127","Information","2014-07-10","09:13:11","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1126","Information","2014-07-09","21:26:13","System","127.0.0.1","localhost","[Media Library] Media Library Server started."

"1125","Warning","2014-07-09","21:25:26","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] The file system is not clean. It is suggested that you run "check disk"."

"1124","Information","2014-07-09","21:25:13","System","127.0.0.1","localhost","System started."

"1123","Warning","2014-07-09","21:25:11","System","127.0.0.1","localhost","The system was not shut down properly last time."

"1122","Information","2014-07-08","23:51:32","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1121","Information","2014-07-08","23:49:44","System","127.0.0.1","localhost","[eSATADisk1] Device removed."

"1120","Information","2014-07-07","23:21:24","admin","192.168.1.129","---","[MySQL Server] Started successfully."

"1119","Information","2014-07-07","23:06:29","admin","192.168.1.129","---","[users] User [Keven]'s shared folder access rights changed."

"1118","Information","2014-07-07","23:06:25","admin","192.168.1.129","---","[users] User [Keven]'s shared folder access rights changed."

"1117","Information","2014-07-07","23:04:34","admin","192.168.1.129","---","[users] User [Keven]'s shared folder access rights changed."

"1116","Warning","2014-07-07","22:35:46","System","127.0.0.1","localhost","Re-launch process [apache]."

"1115","Information","2014-07-07","22:34:55","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1114","Information","2014-07-07","22:34:15","System","127.0.0.1","localhost","[Media Library] Media Library Server started."

"1113","Warning","2014-07-07","22:33:28","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] The file system is not clean. It is suggested that you run "check disk"."

"1112","Information","2014-07-07","22:33:14","System","127.0.0.1","localhost","System started."

"1111","Warning","2014-07-07","22:33:13","System","127.0.0.1","localhost","The system was not shut down properly last time."

"1110","Information","2014-07-06","17:37:41","System","127.0.0.1","localhost","[eSATADisk1] Device removed."

"1109","Information","2014-07-06","17:29:26","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1108","Information","2014-07-06","17:28:41","System","127.0.0.1","localhost","[Media Library] Media Library Server started."

"1107","Warning","2014-07-06","17:27:55","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] The file system is not clean. It is suggested that you run "check disk"."

"1106","Information","2014-07-06","17:27:42","System","127.0.0.1","localhost","System started."

"1105","Information","2014-07-06","09:28:26","System","127.0.0.1","localhost","System was shut down on Sun Jul  6 09:28:26 ADT 2014."

"1104","Information","2014-07-06","09:27:13","admin","192.168.1.11","---","[Power Management] System will be shutdown now."

"1103","Information","2014-07-06","09:12:39","System","127.0.0.1","localhost","[Media Library] Media Library Server started."

"1102","Warning","2014-07-06","09:11:52","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] The file system is not clean. It is suggested that you run "check disk"."

"1101","Information","2014-07-06","09:11:39","System","127.0.0.1","localhost","System started."

"1100","Information","2014-07-06","09:09:48","System","127.0.0.1","localhost","System was shut down on Sun Jul  6 09:09:48 ADT 2014."

"1099","Information","2014-07-06","09:08:29","admin","192.168.1.11","---","[Power Management] System will be restart now."

"1098","Information","2014-07-06","07:22:16","System","127.0.0.1","localhost","[eSATADisk1] Device removed."

"1097","Information","2014-07-06","00:42:29","System","127.0.0.1","localhost","Symform 4.14.1 has been installed in /share/HDA_DATA/.qpkg/Symform."

"1096","Information","2014-07-06","00:42:29","System","127.0.0.1","localhost","Symform enabled."

"1095","Warning","2014-07-05","20:54:22","System","127.0.0.1","localhost","Re-launch process [proftpd]."

"1094","Information","2014-07-05","20:53:31","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1093","Information","2014-07-05","20:53:19","System","127.0.0.1","localhost","[Media Library] Media Library Server started."

"1092","Warning","2014-07-05","20:52:31","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] The file system is not clean. It is suggested that you run "check disk"."

"1091","Information","2014-07-05","20:52:18","System","127.0.0.1","localhost","System started."

 

On remarque aussi la mention "[single Disk Volume: Drive 1] The file system is not clean. It is suggested that you run "check disk" à chaque redémarrage. Mais là, cette mention est présente depuis le 05/11/2013 (cf le log complet en pièce jointe…) et je doute donc du rapport avec mon problème à moins d'une corruption qui s'est amplifiée depuis 8 mois! De plus, l'état SMART du disque est "bon"...

 

Bref, j'ai pas trop d'idées sur ce coup-là, donc si cela vous évoque quelque chose, je suis preneur!

 

Merci,

 

@+, Onyxsmith

LOGQNAP.txt

[father_mande]

Bonjour,

 

Pas facile ...

 

1 vérifier la chaîne de liaison, démonter le disque externe (web admin) vérifiez que le câble est bien connecté (enlevez le, remettez le fermement sans forcer)

... reconnectez au QNAP ... et attendez pour voir si cela revient ...

 

2 il est toujours bon de suivre les conseils du log

... arrêtez les QPKG

... lancez la vérification du système de fichier, je ne sais plus dans quel menu c'est en 3.8 (ne pas confondre avec recherche des blocs défectueux)

... relancez un à un vos QPKG et vérifiez dans le log qu'il n'y a pas (entre chaque lancement) de corrélation visible ...

 

3 recherchez quel programme utilise le disque externe ... car si il ne fait rien, il y a peu de chance qu'il se démonte ...

 

4 moi, ce qui me parait le plus "inquiétant" c'est le "re-launch" de apache ... donc il s'est "planté" et le changement des droits ??? sur les partages de l'utilisateur "Keven" ... cela semble indiquer une incohérence dans la configuration ... peut-être qu'une update du firmware avec la même version ... pourrait aider ... 

 

Philippe.

Sinon faites appel au support ...

[M@ttew]

Bonjour,

 

Merci pour votre réponse.

Je reprends les points à la suite:

 

1 J'ai commencé par là, ce qui explique les évènements suivant:

"1099","Information","2014-07-06","09:08:29","admin","192.168.1.11","---","[Power Management] System will be restart now."

"1104","Information","2014-07-06","09:27:13","admin","192.168.1.11","---","[Power Management] System will be shutdown now."

 

2 Je n'ai pas encore testé l'arrêt des QPKG, mais cette nuit, j'ai fait 2 nouveaux tests:

"1156","Information","2014-07-11","04:38:35","System","127.0.0.1","localhost","[eSATADisk1] Device removed."

"1155","Warning","2014-07-11","04:27:56","System","127.0.0.1","localhost","[Drive 1] Bad Blocks Scan completed. 257 bad block(s) found."

"1154","Information","2014-07-11","01:06:42","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1153","Information","2014-07-11","01:04:55","System","127.0.0.1","localhost","[eSATADisk1] Device removed."

"1152","Information","2014-07-11","00:42:20","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1151","Information","2014-07-11","00:41:55","System","127.0.0.1","localhost","[eSATADisk1] Device removed."

"1150","Information","2014-07-11","00:39:34","System","127.0.0.1","localhost","[eSATADisk1] Device detected. The file system is ext4."

"1149","Information","2014-07-10","23:04:43","System","127.0.0.1","localhost","[eSATADisk1] Device removed."

"1148","Information","2014-07-10","22:52:57","System","127.0.0.1","localhost","[Drive 1] Start scanning bad blocks."

"1147","Information","2014-07-10","22:49:02","System","127.0.0.1","localhost","[Media Library] Media Library Server started."

"1146","Information","2014-07-10","22:47:25","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] Examination completed."

"1145","Information","2014-07-10","22:40:55","System","127.0.0.1","localhost","[single Disk Volume: Drive 1] Start examination."

Le système de fichier semble ok, mais grosse mauvaise surprise pour les "bad blocks"!

Et pendant le scan, alors que le disque eSATA n'était pas sollicité (la nuit, hébergeant une vidéothèque sans personne connecté dessus…), il s'est déconnecté 4 fois! Et d'ailleurs, ce matin, il est toujours dans les choux!

 

3 Comme dit précédemment, les accès ne se font que par NFS, AFP ou WebDAV depuis mon réseau local via une interface XBMC sur Mac, iPad ou Androïd afin de visionner des films. Donc, usage très restreint du disque externe.

 

4 Concernant Apache, je faisais une démonstration à l'utilisateur "Keven" d'un site Web hébergé sur le NAS dans le volume /Web du Lecteur 1 depuis son Mac, et là, la page html s'affichait, mais le code PHP/MySQL ne s'interprétait pas (les requêtes restaient vides!)

J'ai donc cherché à comprendre pourquoi, et en fouillant dans l'interface admin (avant d'avoir vérifié que ça plantait aussi sur mes machines), j'ai donc essayé de changer les droits d'accès de l'utilisateur Keven _ sans succès bien sûr. Finalement, je réalise que mes machines échouent aussi et je percute en relançant le server MySQL planté pour une raison inconnue?!...

Maintenant, la relance d'Apache par System et l'hôte local, je ne comprends pas non plus?!...

 

"1120","Information","2014-07-07","23:21:24","admin","192.168.1.129","---","[MySQL Server] Started successfully."

"1119","Information","2014-07-07","23:06:29","admin","192.168.1.129","---","[users] User [Keven]'s shared folder access rights changed."

"1118","Information","2014-07-07","23:06:25","admin","192.168.1.129","---","[users] User [Keven]'s shared folder access rights changed."

"1117","Information","2014-07-07","23:04:34","admin","192.168.1.129","---","[users] User [Keven]'s shared folder access rights changed."

"1116","Warning","2014-07-07","22:35:46","System","127.0.0.1","localhost","Re-launch process [apache]."

 

Pour l'update du firmware avec la même version, que se passe-t'il au niveau des disques? Sont-ils effacés? Et pour les QPKG installés?

J'ai Optware et phpMyAdmin installés dessus.

En gros, je fais tourner 3 sites web hébergeant des bdd personnelles dont l'une d'entre elles est un peu particulière puisqu'elle a des droits "admin" sur le système de fichier, cf ce post:

La mise en place de la configuration ayant été plus que laborieuse aux vues de mes faibles connaissances, j'ai donc depuis évité tout upgrade du firmware, le système étant stable jusqu'à la semaine dernière...

Dois-je tout re-configurer en cas d'upgrade même si je restaure la même version?

 

Concernant le support, existe-t'il en français, car cela risque d'être tendu de m'expliquer en anglais?!...

 

Cordialement,

 

@+, Onyxsmith

 

[M@ttew]

Bonjour,

 

Il y a 3 jours (donc le 12), j'ai noté un fonctionnement anormal du disque externe (eSATADisk1) comme s'il tournait dans le vide, se mettait en pause, puis se relançait sans raison.

Mes doutes furent confirmés lorsque j'ai reçu cette notification:

[eSATADisk1] Device removed.

 

Du coup, j'ai décidé d'arrêter le disque externe.

Laissant alors fonctionner le NAS seul pendant 48h, je n'ai plus eu d'extinction inopinée.

J'ai donc décidé de démonter le disque externe et de le tester dans un autre boitier réputé fiable.

Etant sous OSX, j'ai dû en passer par l'installation des drivers compatibles ext2,3 et 4 pour accéder à la partition du DD, mais après ces quelques manipulations, j'ai constaté que le disque semblait en état. J'ai entrepris un stress test en lisant une vidéo .mkv de 16Go sous VLC et en copiant un dossier de 58Go en même temps sur le disque. Pas de problème, pas de déconnexion intempestive, j'en conclu donc que le boitier est défectueux!

Il s'agit d'un boitier acheté en septembre 2010 chez Macway (pourtant réputé d'habitude) et qui a rapidement présenté des signes d'instabilité en eSATA et en USB2 dans les mois qui suivirent l'achat, ainsi que le second boitier mentionné sur l'image...

 

J'ai donc demandé une réparation des 2 boitiers, et depuis, tout semblait fonctionner correctement jusqu'à la semaine dernière...

Ainsi, le boitier est de nouveau instable, et aux vues de ces presque 4 années, il va rejoindre le cimetière des objets polluants obsolètes!!!

 

J'ai donc re-connecté le disque dur dans son nouveau boitier au NAS, et depuis 24h, tout fonctionne correctement, avec divers stress tests du HD.

 

Bilan: Le NAS n'a pas dû apprécier les déconnexions-reconnexions du HD en eSata et a fini par planter en forçant l'extinction du système...

Le disque externe était donc la source du problème.

En revanche, le log du NAS indique à chaque redémarrage :

[single Disk Volume: Drive 1] The file system is not clean. It is suggested that you run "check disk".

 

Or le test du disque m'a donné:

[Drive 1] Bad Blocks Scan completed. 257 bad block(s) found.

 

Le disque interne du NAS est un WD Red 2 To (pourtant recommandé pour ce genre de machine…) de 2 ans à peine avec peu de sollicitations au demeurant.

Je m'étonne d'un si grand nombre de secteurs défectueux.

Que faut-il faire? Changer le disque en préventif? Est-ce réparable au sein du système? Faut-il ré-installer le firmware, effacer le disque puis tout recopier?

 

Je pense faire l'acquisition d'un second NAS identique (modèle TS-121) avec un HD de 3To. Que penser du WD Red 3To? Y a-t'il un modèle concurrent réputé?

 

Merci,

 

@+, Onyxsmith