Foro de TI tranquilo

- Versión de WAPT instalada: 1.5.1.23
- SO del servidor: Debian 9
- SO de la máquina de administración/creación de paquetes: Windows 10

Hola,

desde hace algún tiempo, nuestro servidor WAPT ha dejado de informar la disponibilidad de ciertas máquinas (en realidad, la mayoría de ellas). Lo incomprensible es que algunas de ellas no tienen ningún problema.

Acabo de actualizar el servidor de la versión 1.5.1.21 a la 1.5.1.23, y recibo el mismo mensaje cuando intento cargar el nuevo agente.

Al iniciar el servidor en modo de depuración, esto es lo que obtengo:

2018-04-17 15:20:18,691 INFO d573fcb8089e434bb28238dda1e624d3: Received packet MESSAGE data 2["wapt_pong"]
2018-04-17 15:20:18,691 INFO received event "wapt_pong" from d573fcb8089e434bb28238dda1e624d3 [/]
2018-04-17 15:20:18,693 DEBUG Socket.IO pong from wapt client sid d573fcb8089e434bb28238dda1e624d3 (uuid: 7578a06b-0dfb-4631-bd3f-5ad668abff9c)
2018-04-17 15:20:18,695 DEBUG ('UPDATE "hosts" SET "reachable" = %s, "server_uuid" = %s, "listening_protocol" = %s, "listening_address" = %s, "listening_timestamp" = %s WHERE ("hosts".."uuid" = %s)', [u'OK', u'ccbe3738-7c13-11e7-9353-001422b37596', u'websockets', u'd573fcb8089e434bb28238dda1e624d3', u'2018-04-17T15:20:18.693571', u'7578a06b-0dfb-4631-bd3f-5ad668abff9c'])
2018-04-17 15:20:18,705 DEBUG Devolviendo 139838076834184 al pool.
2018-04-17 15:20:18,717 INFO d573fcb8089e434bb28238dda1e624d3: Paquete PING recibido datos Ninguno
2018-04-17 15:20:18,717 INFO d573fcb8089e434bb28238dda1e624d3: Enviando paquete PONG datos Ninguno
2018-04-17 15:20:18,923 DEBUG (1865) aceptado ('127.0.0.1', 36760)
2018-04-17 15:20:18,926 INFO f520a971bf9c4b7e962a6f97292ee0a7: Enviando paquete OPEN datos {'pingInterval': 25000, 'pingTimeout': 60000, 'upgrades': ['websocket'], 'sid': 'f520a971bf9c4b7e962a6f97292ee0a7'}
2018-04-17 15:20:18,928 DEBUG ('SELECT "t1".."host_certificate" FROM "hosts" AS t1 WHERE ("t1".."uuid" = %s) LIMIT 1', [u'1E442148-659D-11E4-B810-0F11F5150E00'])
2018-04-17 15:20:18,931 WARNING Conexión SocketIO rechazada para uuid 1E442148-659D-11E4-B810-0F11F5150E00, sid f520a971bf9c4b7e962a6f97292ee0a7: Datos demasiado antiguos o en la edad futura: 514s...
2018-04-17 15:20:18,932 DEBUG Devolviendo 139838076834184 al pool.
2018-04-17 15:20:18,934 DEBUG Devolviendo 139838076834184 al pool.
2018-04-17 15:20:18,935 INFO f520a971bf9c4b7e962a6f97292ee0a7: Enviando paquete MESSAGE datos 4
2018-04-17 15:20:18,935 ADVERTENCIA La aplicación rechazó la conexión
2018-04-17 15:20:18,936 INFO 127.0.0.1 - - [17/Abr/2018 15:20:18] "GET /socket.io/?login=%7B%22uuid%22%3A+%221E442148-659D-11E4-B810-0F11F5150E00%22%2C+ %22firma%22%3A+%22iWhnZYSYdNxwP7Xx4FzZUglasWtXEY6NqW4pn%2BCVdMm2H2NM8OG5z1UB2 Fq8AEjIQ55jTWlw%2FBG7W5rGaNT%2Bks5%2BN647M3E0LApRYSXmvW7x85%2BPz%2B%2BJnyRfqcKf4P ujofC8QejzuXYaijA01F2TDzA%2BDiAuIR0SjlOOPl3xlr8a%2Bs8nw%2FcQ%2BJb0A9%2Ft3Assgfse6s qUTbN2PaYxFBKcjrxc3%2FyzdaNUtVenNFVfIbP0LdlgnxRTwgGRPGKLJ5haY3UzMuJaajusB2CAaOHr6 YQ%2BzT6yLlEz%2Bn3jivvoQyw4UQFPmXEo9FXS95y09nqUpmCREsa6cFRkkXjmMLP3oQ%3D%3D%22%2C+ %22signed_attributes%22%3A+%5B%22uuid%22%2C+%22signed_attributes%22%2C+%22signer%22%2C+%22signature_date%22%2C+%22signer_certificate%22%5D%2C+%22signer_certificate [...]

Hola seb-aprolis,

seb-aprolis escribió: ↑17 de abril de 2018 - 15:27 ...
Desde hace algún tiempo, nuestro servidor WAPT ha dejado de informar sobre la disponibilidad de ciertas máquinas (de hecho, de la mayoría). Lo incomprensible es que algunas no presentan ningún problema.

Acabo de actualizar el servidor de la versión 1.5.1.21 a la 1.5.1.23 y recibo el mismo mensaje cuando intento cargar el nuevo agente.

Cuando ejecuto el servidor en modo de depuración, esto es lo que obtengo:
B810-0F11F5150E00, sid f520a971bf9c4b7e962a6f97292ee0a7: Datos demasiado antiguos o con una antigüedad futura: 514s
...

¿Podrías comprobar la hora en el servidor y en los equipos cliente? Por defecto, WAPT acepta una diferencia horaria máxima de 5 minutos entre el servidor y el equipo cliente. Esta tolerancia se puede aumentar con el parámetro `signature_clockskew` en el archivo `waptserver.ini`:

https://www.wapt.fr/es/doc-1.5/Configurar ... opción de corbata

Atentamente,

Denis

Thanks for your reply, Denis.

I changed the relevant setting, but it doesn't make much difference. However, I have a question: does SocketIO use CEST or UTC time?

As far as I know, clients are sending CEST time (so, in my case, the Paris time zone). Even though I've set the `signature_clockskew` value to 72,000 (20 minutes), I'm still getting an error message for smaller discrepancies (713 seconds).

Here is an excerpt from the log:
2018-04-18 09:26:05,075 WARNING SocketIO connection refused for uuid 5E56B0BC-659C-11E4-B666-D54C01160E00, sid 9bbed422a96647b09e7012ac3ba53bb3: Data too old or in the future age: 713s...


However, on the PING/PONG requests, I observe that the clients are not sending back anything...

Excerpt from the log:
2018-04-18 09:26:07,944 INFO a4363b4915254ffeb9b8bb2985b6dfc4: Received packet PING data None
2018-04-18 09:26:07,945 INFO a4363b4915254ffeb9b8bb2985b6dfc4: Enviando datos del paquete PONG Ninguno
2018-04-18 09:26:08,998 INFO 46271366851944f8aa9c2bf7a03549fa: Datos del paquete PING recibidos Ninguno
2018-04-18 09:26:08,999 INFO 46271366851944f8aa9c2bf7a03549fa: Enviando datos del paquete PONG Ninguno
2018-04-18 09:26:09,176 INFO 16d467efc7384eefb02d5be7283d6b8f: Datos del paquete PING recibidos Ninguno
2018-04-18 09:26:09,177 INFO 16d467efc7384eefb02d5be7283d6b8f: Sending packet PONG data None
2018-04-18 09:26:09,873 INFO 59494df967ab428fa7c668f3cef3a805: Received packet PING data None
2018-04-18 09:26:09,874 INFO 59494df967ab428fa7c668f3cef3a805: Sending packet PONG data None
2018-04-18 09:26:11,227 INFO 8d1463c48dce43588d62eb62658f9077: Received packet PING data None
2018-04-18 09:26:11,228 INFO 8d1463c48dce43588d62eb62658f9077: Enviando datos del paquete PONG Ninguno
2018-04-18 09:26:13,243 INFO ccec047c606943efb23b004f2f3a4eed: Datos del paquete PING recibidos Ninguno
2018-04-18 09:26:13,243 INFO ccec047c606943efb23b004f2f3a4eed: Enviando datos del paquete PONG Ninguno
2018-04-18 09:26:15,097 INFO 20a2baf55f5d4892a9939b223e090e37: Received packet PING data None
2018-04-18 09:26:15,097 INFO 20a2baf55f5d4892a9939b223e090e37: Sending packet PONG data None
2018-04-18 09:26:15,700 INFO 6906fbbacc504f3eb7d71a3091128b79: Received packet PING data None
2018-04-18 09:26:15,700 INFO 6906fbbacc504f3eb7d71a3091128b79: Sending packet PONG data None
2018-04-18 09:26:16,791 INFO 314b59759c4344119b33773a5fded716: Received packet PING data None
2018-04-18 09:26:16,791 INFO 314b59759c4344119b33773a5fded716: Sending packet PONG data None
2018-04-18 09:26:18,345 INFO e1158c58a76643fdb58d749ae5067186: Received packet PING data None
2018-04-18 09:26:18,346 INFO e1158c58a76643fdb58d749ae5067186: Sending packet PONG data Ninguno
2018-04-18 09:26:19,745 INFO 1d93e6ed3ba249ee853515e4cf3ab09b: Paquete PING recibido datos Ninguno
2018-04-18 09:26:19,746 INFO 1d93e6ed3ba249ee853515e4cf3ab09b: Enviando paquete PONG datos Ninguno
2018-04-18 09:26:21,657 INFO 337ee328bde54513b049f980c36111ea: Paquete PING recibido datos Ninguno
2018-04-18 09:26:21,657 INFO 337ee328bde54513b049f980c36111ea: Enviando datos de paquete PONG Ninguno
2018-04-18 09:26:22,377 INFO 070ccfd6869a4e1db4a237427cc36087: Recibido datos de paquete PING Ninguno
2018-04-18 09:26:22,377 INFO 070ccfd6869a4e1db4a237427cc36087: Enviando datos de paquete PONG Ninguno
2018-04-18 09:26:22,406 INFO 7cd8939bfcce47c2a91fcc1803439780: Recibido datos de paquete PING Ninguno
2018-04-18 09:26:22,407 INFO 7cd8939bfcce47c2a91fcc1803439780: Enviando datos del paquete PONG Ninguno

Hola seb-aprolis,

seb-aprolis escribió: ↑18 de abril de 2018 - 09:37 Modifiqué el parámetro correspondiente, pero no cambia mucho. Sin embargo, tengo una pregunta: ¿SocketIO usa la hora CEST o UTC?

En mi caso, los clientes envían la hora CEST (es decir, la zona horaria de París). A pesar de establecer el valor de signature_clockskew en 72,000 (es decir, 20 minutos), sigo recibiendo un mensaje por discrepancias menores (713 segundos).

Aquí hay un extracto del registro:
2018-04-18 09:26:05,075 ADVERTENCIA Conexión SocketIO rechazada para uuid 5E56B0BC-659C-11E4-B666-D54C01160E00, sid 9bbed422a96647b09e7012ac3ba53bb3: Datos demasiado antiguos o en la era futura: 713s...

Sin embargo, en las solicitudes PING/PONG, observo que los clientes no envían nada de vuelta...

Extracto del registro:
2018-04-18 09:26:07,944 INFO a4363b4915254ffeb9b8bb2985b6dfc4: Paquete recibido PING datos Ninguno
2018-04-18 09:26:07,945 INFO a4363b4915254ffeb9b8bb2985b6dfc4: Enviando datos del paquete PONG Ninguno
2018-04-18 09:26:08,998 INFO 46271366851944f8aa9c2bf7a03549fa: Recibido paquete PING datos Ninguno
....
2018-04-18 09:26:22,407 INFO 7cd8939bfcce47c2a91fcc1803439780: Enviando datos del paquete PONG Ninguno

Hemos visto problemas con el cliente Socket.io que no restablece su conexión al perderla. Una forma de forzar el reinicio es reiniciar Nginx. ¿Podrías intentar detener Nginx, esperar unos segundos y reiniciarlo?

Atentamente,

Denis