Ruhiges IT-Forum

- Installed WAPT version: 1.5.1.23
- Server OS: Debian 9
- Administration/package creation machine OS: Windows 10

Hello,

For some time now, our WAPT server has stopped reporting the availability of certain machines (actually, most of them). What's incomprehensible is that some of them have no problems at all.

I just upgraded the server from version 1.5.1.21 to 1.5.1.23, and I get the same message when I try to upload the new agent.

Beim Starten des Servers im Debug-Modus erhalte ich Folgendes:

2018-04-17 15:20:18,691 INFO d573fcb8089e434bb28238dda1e624d3: Paket MESSAGE data 2["wapt_pong"] empfangen
2018-04-17 15:20:18,691 INFO Ereignis "wapt_pong" von d573fcb8089e434bb28238dda1e624d3 empfangen [/]
2018-04-17 15:20:18,693 DEBUG Socket.IO pong vom wapt-Client mit der 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 Returning 139838076834184 to pool.
2018-04-17 15:20:18,717 INFO d573fcb8089e434bb28238dda1e624d3: Empfangenes Paket PING Daten None
2018-04-17 15:20:18,717 INFO d573fcb8089e434bb28238dda1e624d3: Sendendes Paket PONG Daten None
2018-04-17 15:20:18,923 DEBUG (1865) akzeptiert ('127.0.0.1', 36760)
2018-04-17 15:20:18,926 INFO f520a971bf9c4b7e962a6f97292ee0a7: Sendendes Paket OPEN data {'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 SocketIO connection refused for uuid 1E442148-659D-11E4-B810-0F11F5150E00, sid f520a971bf9c4b7e962a6f97292ee0a7: Daten zu alt oder in der Zukunft (Alter: 514s)...
2018-04-17 15:20:18,932 DEBUG Rückgabe von 139838076834184 an den Pool.
2018-04-17 15:20:18,934 DEBUG Rückgabe von 139838076834184 an den Pool.
2018-04-17 15:20:18,935 INFO f520a971bf9c4b7e962a6f97292ee0a7: Sende Paket MESSAGE data 4
2018-04-17 15:20:18,935 WARNUNG Anwendung hat Verbindung abgelehnt
2018-04-17 15:20:18,936 INFO 127.0.0.1 - - [17/Apr/2018 15:20:18] "GET /socket.io/?login=%7B%22uuid%22%3A+%221E442148-659D-11E4-B810-0F11F5150E00%22%2C+ %22signature%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 [...]

Hallo seb-aprolis,

seb-aprolis schrieb: ↑17. Apr. 2018 - 15:27 Uhr ...
Seit einiger Zeit meldet unser WAPT-Server die Verfügbarkeit bestimmter Maschinen (eigentlich der meisten) nicht mehr. Unverständlich ist, dass einige von ihnen überhaupt keine Probleme haben.

Ich habe den Server gerade von Version 1.5.1.21 auf 1.5.1.23 aktualisiert und erhalte dieselbe Fehlermeldung beim Hochladen des neuen Agenten.

Im Debug-Modus erhalte ich folgende Meldung:
B810-0F11F5150E00, sid f520a971bf9c4b7e962a6f97292ee0a7: Daten zu alt oder in der Zukunft: 514s
...

Könnten Sie bitte die Uhrzeit auf dem Server und den Client-Rechnern überprüfen? Standardmäßig akzeptiert WAPT eine maximale Zeitdifferenz von 5 Minuten zwischen Server und Client. Diese Toleranz kann mit dem Parameter `signature_clockskew` in der Datei `waptserver.ini` erhöht werden

https://www.wapt.fr/en/doc-1.5/Configur ... tie-option

Aufrichtig,

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: Sende Paket PONG Daten Keine
2018-04-18 09:26:08,998 INFO 46271366851944f8aa9c2bf7a03549fa: Empfangen Paket PING Daten Keine
2018-04-18 09:26:08,999 INFO 46271366851944f8aa9c2bf7a03549fa: Sende Paket PONG Daten Keine
2018-04-18 09:26:09,176 INFO 16d467efc7384eefb02d5be7283d6b8f: Empfangen Paket PING Daten Keine
2018-04-18 09:26:09,177 INFO 16d467efc7384eefb02d5be7283d6b8f: Sende Paket PONG Daten Keine
2018-04-18 09:26:09,873 INFO 59494df967ab428fa7c668f3cef3a805: Empfangenes Paket PING Daten Keine
2018-04-18 09:26:09,874 INFO 59494df967ab428fa7c668f3cef3a805: Sende Paket PONG Daten Keine
2018-04-18 09:26:11,227 INFO 8d1463c48dce43588d62eb62658f9077: Empfangenes Paket PING Daten Keine
2018-04-18 09:26:11,228 INFO 8d1463c48dce43588d62eb62658f9077: Sendendes Paket PONG Daten Keine
2018-04-18 09:26:13,243 INFO ccec047c606943efb23b004f2f3a4eed: Empfangenes Paket PING Daten Keine
2018-04-18 09:26:13,243 INFO ccec047c606943efb23b004f2f3a4eed: Sendendes Paket PONG Daten Keine
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: Senden von Paket PONG-Daten Keine
2018-04-18 09:26:16,791 INFO 314b59759c4344119b33773a5fded716: Empfangenes Paket PING-Daten Keine
2018-04-18 09:26:16,791 INFO 314b59759c4344119b33773a5fded716: Senden von Paket PONG-Daten Keine
2018-04-18 09:26:18,345 INFO e1158c58a76643fdb58d749ae5067186: Empfangenes Paket PING-Daten Keine
2018-04-18 09:26:18,346 INFO e1158c58a76643fdb58d749ae5067186: Sending packet PONG data None
2018-04-18 09:26:19,745 INFO 1d93e6ed3ba249ee853515e4cf3ab09b: Received packet PING data None
2018-04-18 09:26:19,746 INFO 1d93e6ed3ba249ee853515e4cf3ab09b: Sending packet PONG data None
2018-04-18 09:26:21,657 INFO 337ee328bde54513b049f980c36111ea: Received packet PING data None
2018-04-18 09:26:21,657 INFO 337ee328bde54513b049f980c36111ea: Sende Paket PONG Daten Keine
2018-04-18 09:26:22,377 INFO 070ccfd6869a4e1db4a237427cc36087: Empfangen Paket PING Daten Keine
2018-04-18 09:26:22,377 INFO 070ccfd6869a4e1db4a237427cc36087: Sende Paket PONG Daten Keine
2018-04-18 09:26:22,406 INFO 7cd8939bfcce47c2a91fcc1803439780: Empfangen Paket PING-Daten Keine
2018-04-18 09:26:22,407 INFO 7cd8939bfcce47c2a91fcc1803439780: Paket wird gesendet PONG-Daten Keine

Hallo seb-aprolis,

seb-aprolis schrieb: ↑18. Apr. 2018 - 09:37 Ich habe den entsprechenden Parameter geändert, aber es ändert sich nicht viel. Ich habe jedoch eine Frage: Verwendet SocketIO die Zeitangabe in CEST oder UTC?

Meine Clients senden CEST-Zeit (in meinem Fall also die Pariser Zeitzone). Obwohl ich den Wert für `signature_clockskew` auf 72.000 (also 20 Minuten) gesetzt habe, erhalte ich selbst bei kleineren Abweichungen (713 Sekunden) noch eine Meldung.

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: Sende Paket PONG Daten None
2018-04-18 09:26:08,998 INFO 46271366851944f8aa9c2bf7a03549fa: Empfangenes Paket PING Daten None
....
2018-04-18 09:26:22,407 INFO 7cd8939bfcce47c2a91fcc1803439780: Sende Paket PONG Daten None

Wir hatten in der Vergangenheit Probleme damit, dass der Socket.io-Client seine Verbindung nicht wiederherstellt, wenn sie unterbrochen wird. Eine Möglichkeit, den Socket.io-Client zum Zurücksetzen zu zwingen, ist ein Neustart von Nginx. Könnten Sie bitte versuchen, Nginx zu stoppen, einige Sekunden zu warten und es dann neu zu starten?

Aufrichtig,

Denis