Equipement Proxmox

echo · Juillet 10, 2024, 10:23

Bonjour

Je supervise Proxmox avec le plugin.

Suite à des alarmes sur le daemon sshd dans le journal system de Proxmox :

Jul 10 10:00:05 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:00:10 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:00:15 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:00:20 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:00:25 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:00:31 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:00:36 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:00:41 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:00:46 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:00:51 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:00:56 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:01 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:06 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:11 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:16 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:21 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:26 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:31 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:36 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:41 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:46 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:51 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice
Jul 10 10:01:56 pve2 sshd[1980964]: error: channel 0: protocol error: close rcvd twice

J’ai fait des recherches :
Sur htop : plusieurs dizaines de PID :

La commande df -h renvoie l’espace disque libre sur les disques.

J’ai désactivé l’équipement Proxmox dans le plugin monitoring au bout de quelques minutes les erreurs dans le journal systemd ont disparu.

Les process sont en état D en sommeil non interruptible.

Cela fait la 2ème fois sur Proxmox.

Avez vous déjà rencontré ce problème?

Cordialement

echo · Juillet 10, 2024, 11:23

Bonjour

J’ai une piste :

Jul 09 13:15:21 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 09 13:15:21 pve2 pvestatd[1075]: status update time (6.423 seconds)
Jul 09 13:15:30 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 09 13:15:30 pve2 pvestatd[1075]: status update time (6.093 seconds)
Jul 09 13:15:39 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 09 13:15:39 pve2 pvestatd[1075]: status update time (5.072 seconds)
Jul 09 13:15:48 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 09 13:15:51 pve2 sshd[1690099]: error: channel 0: protocol error: close rcvd twice
Jul 09 13:15:56 pve2 sshd[1690099]: error: channel 0: protocol error: close rcvd twice
Jul 09 13:16:00 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 09 13:16:01 pve2 pvestatd[1075]: status update time (6.487 seconds)
Jul 09 13:16:01 pve2 sshd[1690099]: error: channel 0: protocol error: close rcvd twice
Jul 09 13:16:06 pve2 sshd[1690099]: error: channel 0: protocol error: close rcvd twice

Dès que j’entend le bruit du tonnerre m’annonçant un orage, j’arrête mon NAS et je débranche le câble réseau et l’alimentation ou lorsque je quitte la maison plusieurs jours, le NAS est mis en sécurité. Mieux prévenir que guérir.

J’ai débranché le NAS à 13H11, à 13H15 les premiers messages d’erreurs sshd arrivent à 13H15.

Mon NAS est déclaré en SMB dans Proxmox pour les backup.

Je vais faire des essais mais si c’est ça c’est problématique.

Cordialement

TiTidom · Juillet 10, 2024, 11:41

Bonjour,

J’ai du mal à suivre, quel est le rapport avec le plugin-monitoring ?

C’est un problème proxmox ou bien côté plugin monitoring ?

TiTidom.

echo · Juillet 10, 2024, 12:19

Bonjour

Le rapport est lié, puisque lorsque j’arrête l’équipement « Proxmox » du pluging monitoring dans Jeedom je n 'est plus d’erreurs dans le journal systemd de Proxmox.

Et lorsque l’on regarde les erreurs, elles apparaissent toutes les 5 secondes, je vais donc regarder dans le cas où le pluging monitoring n’a pas de réponse à sa requête, si il réémet une nouvelle requête toutes les 5 secondes.

Je ne mets pas en cause le pluging monitoring il fonctionne je l’utilise pour mes VM. J’essaye simplement de comprendre et trouver une solution de contournement, mais à la base c’est le pluging qui prend la main en ssh sur Proxmox comme un opérateur qui travaille sur le système.

Cordialement

TiTidom · Juillet 10, 2024, 12:46

Pas de soucis Le sujet est intéressant.

Je voulais dire, que si proxmox commence à râler lorsque le NAS est indispo, c’est que le soucis est côté Proxmox quand il ne voit plus son NAS, et comme le plugin monitoring n’interroge que Proxmox et pas la baie NAS, c’est là où j’avais buggé

Mais le sujet est intéressant et mérite dans tous les cas d’être creusé.

Peux tu passer les logs du plugin Monitoring en débug, et envoyer les logs correspondantes aux plages horaires où le soucis survient ?

Cela permettra d’avoir les deux bouts de la connexion, et voir comment le plugin réagit s’il y a un soucis lorsqu’il requête le proxmox.

TiTidom.

echo · Juillet 10, 2024, 1:18

Bonjour TiTidom

J’ai désactivé tous les équipements du plugin, j’ai réactivé l’équipement Proxmox.

A 15H00, j’ai débranché le cable réseau du NAS.
Les erreurs sont réapparues et j’ai rebranché le réseau à vers 15H04.

Journalctl :

root@pve2:~# journalctl -f
Jul 10 15:01:20 pve2 systemd-logind[743]: New session 4529 of user root.
Jul 10 15:01:20 pve2 systemd[1]: Started session-4529.scope - Session 4529 of User root.
Jul 10 15:01:20 pve2 sshd[2053884]: pam_env(sshd:session): deprecated reading of user environment enabled
Jul 10 15:01:24 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:01:25 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:01:25 pve2 pvestatd[1075]: status update time (6.440 seconds)
Jul 10 15:01:29 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:01:34 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:01:34 pve2 pvedaemon[1977772]: storage 'NAS' is not online
Jul 10 15:01:34 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:01:39 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:01:44 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:01:45 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:01:45 pve2 pvestatd[1075]: status update time (6.460 seconds)
Jul 10 15:01:49 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:01:54 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:01:55 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:01:55 pve2 pvedaemon[1790881]: storage 'NAS' is not online
Jul 10 15:01:55 pve2 pvestatd[1075]: status update time (5.469 seconds)
Jul 10 15:01:59 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:04 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:05 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:02:05 pve2 pvestatd[1075]: status update time (6.378 seconds)
Jul 10 15:02:09 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:14 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:15 pve2 pvedaemon[1854497]: storage 'NAS' is not online
Jul 10 15:02:15 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:02:16 pve2 pvestatd[1075]: status update time (6.421 seconds)
Jul 10 15:02:19 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:24 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:25 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:02:25 pve2 pvestatd[1075]: status update time (6.397 seconds)
Jul 10 15:02:29 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:34 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:35 pve2 pvedaemon[1854497]: storage 'NAS' is not online
Jul 10 15:02:35 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:02:35 pve2 pvestatd[1075]: status update time (6.399 seconds)
Jul 10 15:02:39 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:44 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:46 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:02:46 pve2 pvestatd[1075]: status update time (6.478 seconds)
Jul 10 15:02:49 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:54 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:02:55 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:02:55 pve2 pvedaemon[1790881]: storage 'NAS' is not online
Jul 10 15:02:55 pve2 pvestatd[1075]: status update time (5.941 seconds)
Jul 10 15:02:59 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:04 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:05 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:03:05 pve2 pvestatd[1075]: status update time (6.391 seconds)
Jul 10 15:03:06 pve2 kernel: nfs: server 192.168.1.234 not responding, still trying
Jul 10 15:03:09 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:14 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:16 pve2 pvedaemon[1854497]: storage 'NAS' is not online
Jul 10 15:03:16 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:03:16 pve2 pvestatd[1075]: status update time (6.484 seconds)
Jul 10 15:03:19 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:24 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:25 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:03:25 pve2 pvestatd[1075]: status update time (6.163 seconds)
Jul 10 15:03:29 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:34 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:34 pve2 pvedaemon[1854497]: storage 'NAS' is not online
Jul 10 15:03:34 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:03:34 pve2 pvestatd[1075]: status update time (5.250 seconds)
Jul 10 15:03:39 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:43 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:03:44 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:49 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:50 pve2 pvedaemon[1790881]: storage 'NAS' is not online
Jul 10 15:03:53 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:03:54 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:03:59 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:04:04 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:04:05 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:04:05 pve2 pvestatd[1075]: status update time (6.429 seconds)
Jul 10 15:04:08 pve2 pvedaemon[1977772]: storage 'NAS' is not online
Jul 10 15:04:09 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:04:14 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:04:16 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:04:16 pve2 pvestatd[1075]: status update time (6.368 seconds)
Jul 10 15:04:19 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:04:24 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:04:24 pve2 pvedaemon[1854497]: storage 'NAS' is not online
Jul 10 15:04:24 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:04:24 pve2 pvestatd[1075]: status update time (5.192 seconds)
Jul 10 15:04:29 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:04:33 pve2 pvestatd[1075]: storage 'NAS' is not online
Jul 10 15:04:34 pve2 sshd[2053605]: error: channel 0: protocol error: close rcvd twice
Jul 10 15:04:36 pve2 kernel: nfs: server 192.168.1.234 OK
Jul 10 15:04:36 pve2 sshd[2053605]: Received disconnect from 192.168.1.174 port 50704:11:
Jul 10 15:04:36 pve2 sshd[2053605]: Disconnected from user root 192.168.1.174 port 50704
Jul 10 15:04:36 pve2 sshd[2053605]: pam_unix(sshd:session): session closed for user root

Log plugin :

htop:

Je vois rienn dans le journal du pluging.

Cordialement

MaGoo · Juillet 10, 2024, 1:45

Ton NAS est monté comment dans Proxmox ?
Si c’est du NFS ou similaire, il faut désactiver le stockage NAS dans Proxmox avant de le couper, sinon, Proxmox tente de s’y connecter et c’est l’avalanche d’erreurs …
D’ailleurs, tu dois avoir des erreurs à l’écran de ton Proxmox

echo · Juillet 10, 2024, 2:47

Bonjour MaGoo

Ha! il est monté en NFS :

Dans Proxmox dans le journal système, les erreurs de déconnections du NAS remontent.

La semaine prochaine je vais faire des tests plus poussés.

J’ai une solution de contournement provisoire c’est de pinguer le NAS avec le pluging Network et en cas d’échec de désactiver l’équipement Proxmox dans le pluging Monitoring en scénario.

Cordialement

echo · Juillet 10, 2024, 3:37

Bonjour

J’ai installé et testé pour l’instant la solution de contournement, les messages remontent dans le journal système de Proxmox mais je n’ai plus les PID :

qui saturent le Proxmox au risque d’avoir un gros planté, le nombre de tache à 467 qui augmente je ne sais pas où ça mène.

Cordialement

echo · Juillet 19, 2024, 9:57

Bonjour

Pour clôturé ce post :

Si on retire le partage NFS ou on désactive le partage dans la console Proxmox, le partage NFS est toujours monté, bug dans Proxmox je ne sais pas.

Je vais faire un message sur le forum Proxmox.

Donc pour démonter le partage NFS la solution est de faire le démontage du partage sous CLI avant d’intervenir sur le NAS:

root@pve2:~# umount /mnt/pve/NAS

Cordialement

Ajout :

Après recherche sur le forum Proxmox, le bug a été formulé et confirme que les montages NFS restent actifs après désactivation dans l’interface Proxmox.
https://bugzilla.proxmox.com/show_bug.cgi?id=1016#c9

La commande recommandée:

umount -l -f /path/to/mount

system · Juillet 20, 2024, 9:58

Ce sujet a été automatiquement fermé après 24 heures suivant le dernier commentaire. Aucune réponse n’est permise dorénavant.