[RISOLTO] L'agente WAPT si disconnette

alain17 · 15 luglio 2022 - 10:25

Buongiorno,

Al momento utilizzo un server WAPT Enterprise versione 2.2 su Ubuntu 20.04 LTS e ho un problema con i miei client Windows. Alcuni funzionano perfettamente (rimangono connessi), mentre altri si disconnettono dal server molto rapidamente (sembrano disconnessi anche se è possibile effettuare il ping). Ho seguito la procedura.. argomento sull'antivirus e quello di questo utente che sta riscontrando problemi di disconnessione Anche.

Ho eseguito il seguente comando su una macchina client che si disconnette, come utente non privilegiato:

Codice: Seleziona tutto

"c:\Program Files (x86)\wapt\runwaptservice.bat" -ldebug

Il risultato emerso è stato il seguente:

Codice: Seleziona tutto

2022-07-15 09:19:57,091 [waptcore MainThread 16644] DEBUG Thread 16644 is connecting to wapt db
2022-07-15 09:19:57,094 [waptcore MainThread 16644] DEBUG DB Start transaction
2022-07-15 09:19:57,094 [waptcore MainThread 16644] DEBUG DB commit
2022-07-15 09:19:57,127 [waptservice MainThread 16644] INFO Setting hiberboot_enabled to 0
2022-07-15 09:19:57,129 [waptcore MainThread 16644] DEBUG Thread 16644 is connecting to wapt db
2022-07-15 09:19:57,129 [waptcore MainThread 16644] DEBUG DB Start transaction
2022-07-15 09:19:57,130 [waptcore MainThread 16644] DEBUG DB commit
Disabling Windows auto update service, using WaptWUA instead
2022-07-15 09:19:57,159 [waptwua MainThread 16644] CRITICAL Unable to set waptwua policies : __enter__
2022-07-15 09:19:57,159 [wapttasks MainThread 16644] INFO Service Configuration: {'config_filename': 'c:\\Program Files (x86)\\wapt\\wapt-get.ini', 'waptservice_user': None, 'waptservice_password': None, 'MAX_HISTORY': 30, 'waptservice_port': 8088, 'dbpath': 'c:\\Program Files (x86)\\wapt\\db\\waptdb.sqlite', 'loglevel': 'debug', 'log_directory': 'c:\\Program Files (x86)\\wapt\\log', 'waptserver': <WaptServer https://waptserver.domaine.local>, 'hiberboot_enabled': 0, 'max_gpo_script_wait': 180, 'pre_shutdown_timeout': 180, 'log_to_windows_events': False, 'allow_user_service_restart': False, 'signature_clockskew': 21600, 'notify_user': False, 'waptservice_admin_filter': False, 'enable_remote_repo': False, 'local_repo_path': 'c:\\Program Files (x86)\\wapt\\repository', 'local_repo_sync_task_period': '2h', 'local_repo_time_for_sync_start': None, 'local_repo_time_for_sync_end': None, 'local_repo_limit_bandwidth': None, 'wol_port': '7,9', 'wol_relay': False, 'service_auth_type': 'system', 'verify_cert_ldap': False, 'download_after_update_with_waptupdate_task_period': True, 'forced_installs_task_period': '2m', 'reconfig_on_network_change': False, 'loglevel_waptcore': None, 'loglevel_waptservice': None, 'loglevel_waptws': None, 'loglevel_waptdb': None, 'loglevel_websocket': None, 'loglevel_waitress': None, 'loglevel_wapttasks': None}
2022-07-15 09:19:57,159 [wapttasks MainThread 16644] INFO Starting task queue
2022-07-15 09:19:57,160 [wapttasks MainThread 16644] INFO Tasks queue running
2022-07-15 09:19:57,160 [wapttasks WaptTaskManager 7028] INFO Wapt tasks management initialized with c:\Program Files (x86)\wapt\wapt-get.ini configuration
2022-07-15 09:19:57,161 [wapttasks MainThread 16644] INFO Starting waitress waptservice on port 8088
2022-07-15 09:19:57,162 [waptservice SocketIOClient 20008] INFO No password set for local waptservice, using local computer security
2022-07-15 09:19:57,163 [waptcore WaptTaskManager 7028] DEBUG Thread 7028 is connecting to wapt db
2022-07-15 09:19:57,165 [waptcore SocketIOClient 20008] DEBUG Thread 20008 is connecting to wapt db
2022-07-15 09:19:57,165 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:19:57,165 [waptcore WaptTaskManager 7028] DEBUG DB commit
2022-07-15 09:19:57,166 [waptcore SocketIOClient 20008] DEBUG DB Start transaction
2022-07-15 09:19:57,167 [waptcore SocketIOClient 20008] DEBUG DB commit
2022-07-15 09:19:57,168 [waitress MainThread 16644] INFO Serving on http://127.0.0.1:8088
2022-07-15 09:19:57,249 [wapttasks SocketIOClient 20008] INFO Starting socketio on "https://waptserver.domaine.local:443" Thread id 20008
2022-07-15 09:19:57,249 [waptws SocketIOClient 20008] DEBUG Certificate checking : False
2022-07-15 09:19:57,250 [waptcore WaptTaskManager 7028] DEBUG Host certificate c:\Program Files (x86)\wapt\private\4C4C4544-0035-4710-804A-B3C04F434233.crt not found, not using it for auth on repo global
2022-07-15 09:19:57,252 [waptcore WaptTaskManager 7028] DEBUG Host certificate c:\Program Files (x86)\wapt\private\4C4C4544-0035-4710-804A-B3C04F434233.crt not found, not using it for auth on repo wapt
2022-07-15 09:19:57,252 [waptcore WaptTaskManager 7028] INFO Main repository: https://waptserver.domaine.local/wapt
2022-07-15 09:19:57,253 [waptcore WaptTaskManager 7028] DEBUG Host certificate c:\Program Files (x86)\wapt\private\4C4C4544-0035-4710-804A-B3C04F434233.crt not found, not using it for auth on repo wapt-host
2022-07-15 09:19:57,254 [wapttasks WaptTaskManager 7028] INFO Core Configuration: {'wapt_base_dir': 'c:\\Program Files (x86)\\wapt', 'waptserver': <WaptServer https://waptserver.domaine.local>, 'config_filename': 'c:\\Program Files (x86)\\wapt\\wapt-get.ini', 'proxies': {'http': '', 'https': ''}, 'repositories': [<WaptRepo https://waptserver.domaine.local/wapt>, <WaptHostRepo https://waptserver.domaine.local/wapt-host for host_id 4C4C4544-0035-4710-804A-B3C04F434233 >], 'personal_certificate_path': '', 'public_certs_dir': 'c:\\Program Files (x86)\\wapt\\ssl', 'package_cache_dir': 'c:\\Program Files (x86)\\wapt\\cache', 'dbpath': 'c:\\Program Files (x86)\\wapt\\db\\waptdb.sqlite', 'use_http_proxy_for_repo': False, 'use_http_proxy_for_server': False, 'limit_bandwidth': None, 'locales': ['fr'], 'custom_tags': [], 'packages_whitelist': None, 'packages_blacklist': None, 'maturities': ['PROD'], 'host_uuid': '4C4C4544-0035-4710-804A-B3C04F434233', 'use_fqdn_as_uuid': False, 'use_hostpackages': True, 'use_ad_groups': False, 'use_repo_rules': False, 'host_profiles': [], 'host_organizational_unit_dn': 'OU=WAPT Test,OU=Ordinateurs,DC=Domaine,DC=local', 'host_ad_site': None, 'waptaudit_task_period': '2h', 'hiberboot_enabled': '0', 'max_gpo_script_wait': 180.0, 'pre_shutdown_timeout': 180.0, 'uninstallkey_timeout': 120, 'check_certificates_validity': True, 'token_lifetime': 86400}
2022-07-15 09:19:57,254 [wapttasks WaptTaskManager 7028] INFO Wapt tasks queue started
2022-07-15 09:19:57,850 [waptws SocketIOClient 20008] WARNING Exception PermissionError(13, 'Permission denied'), waiting 1.3s before retrying
2022-07-15 09:19:59,426 [waptws SocketIOClient 20008] WARNING Exception PermissionError(13, 'Permission denied'), waiting 2.2750000000000004s before retrying
2022-07-15 09:20:02,026 [waptws SocketIOClient 20008] WARNING Exception PermissionError(13, 'Permission denied'), waiting 3.9812500000000006s before retrying
2022-07-15 09:20:06,093 [waptws SocketIOClient 20008] WARNING Exception PermissionError(13, 'Permission denied'), waiting 6.967187500000001s before retrying
2022-07-15 09:20:07,320 [urllib3.connectionpool WaptTaskManager 7028] DEBUG Starting new HTTPS connection (1): waptserver.domaine.local:443
2022-07-15 09:20:07,426 [urllib3.connectionpool WaptTaskManager 7028] DEBUG https://waptserver.domaine.local:443 "GET /licences.json HTTP/1.1" 200 7952
2022-07-15 09:20:07,428 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:20:07,428 [waptcore WaptTaskManager 7028] DEBUG DB rollback
2022-07-15 09:20:07,432 [waptcore WaptTaskManager 7028] DEBUG Error at DB exit attempt to write a readonly database, rollbacking
['  File "c:\\Program Files (x86)\\wapt\\common.py", line 347, in set_param\n    self.execute(\'insert or replace into wapt_params(name,value,create_date,ptype) values (?,?,?,?)\', (name, value, datetime2isodate(), ptype))\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 397, in execute\n    return self.db.execute(query, args)\n']
2022-07-15 09:20:07,435 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:20:07,435 [waptcore WaptTaskManager 7028] DEBUG Remove unknown repositories from packages table and params ("wapt","wapt-host")
2022-07-15 09:20:07,436 [waptcore WaptTaskManager 7028] DEBUG Check last-modified header for wapt to avoid unecessary update
2022-07-15 09:20:07,436 [waptcore WaptTaskManager 7028] DEBUG Checking availability of https://waptserver.domaine.local/wapt/Packages
2022-07-15 09:20:07,437 [urllib3.connectionpool WaptTaskManager 7028] DEBUG Starting new HTTPS connection (1): waptserver.domaine.local:443
2022-07-15 09:20:07,532 [urllib3.connectionpool WaptTaskManager 7028] DEBUG https://waptserver.domaine.local:443 "HEAD /wapt/Packages HTTP/1.1" 200 0
2022-07-15 09:20:07,533 [waptcore WaptTaskManager 7028] INFO Index from https://waptserver.domaine.local/wapt has not been updated (last update 2022-07-11T07:09:54), skipping update
2022-07-15 09:20:07,533 [waptcore WaptTaskManager 7028] DEBUG Check last-modified header for wapt-host to avoid unecessary update
2022-07-15 09:20:07,534 [waptcore WaptTaskManager 7028] DEBUG Checking availability of wapt-host
2022-07-15 09:20:07,534 [waptcore WaptTaskManager 7028] DEBUG Trying to get  host package for 4C4C4544-0035-4710-804A-B3C04F434233 at https://waptserver.domaine.local/wapt-host/4C4C4544-0035-4710-804A-B3C04F434233.wapt
2022-07-15 09:20:07,535 [urllib3.connectionpool WaptTaskManager 7028] DEBUG Starting new HTTPS connection (1): waptserver.domaine.local:443
2022-07-15 09:20:07,624 [urllib3.connectionpool WaptTaskManager 7028] DEBUG https://waptserver.domaine.local:443 "HEAD /wapt-host/4C4C4544-0035-4710-804A-B3C04F434233.wapt HTTP/1.1" 200 0
2022-07-15 09:20:07,625 [waptcore WaptTaskManager 7028] INFO Index from https://waptserver.domaine.local/wapt-host has not been updated (last update 2022-07-08T09:23:28), skipping update
2022-07-15 09:20:07,626 [waptcore WaptTaskManager 7028] DEBUG Error at DB exit attempt to write a readonly database, rollbacking
['  File "c:\\Program Files (x86)\\wapt\\common.py", line 347, in set_param\n    self.execute(\'insert or replace into wapt_params(name,value,create_date,ptype) values (?,?,?,?)\', (name, value, datetime2isodate(), ptype))\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 397, in execute\n    return self.db.execute(query, args)\n']
2022-07-15 09:20:07,626 [waptcore WaptTaskManager 7028] DEBUG DB rollback
2022-07-15 09:20:07,626 [waptcore WaptTaskManager 7028] DEBUG Error at DB exit attempt to write a readonly database, rollbacking
['  File "c:\\Program Files (x86)\\wapt\\common.py", line 4567, in _update_repos_list\n    self.write_param(\'host_capabilities_fingerprint\', new_capa)\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 8018, in write_param\n    self.waptdb.set_param(name, value)\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 347, in set_param\n    self.execute(\'insert or replace into wapt_params(name,value,create_date,ptype) values (?,?,?,?)\', (name, value, datetime2isodate(), ptype))\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 397, in execute\n    return self.db.execute(query, args)\n']
2022-07-15 09:20:07,627 [wapttasks WaptTaskManager 7028] CRITICAL Error for update in check_scheduled_tasks: attempt to write a readonly database
2022-07-15 09:20:07,627 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:20:07,628 [waptcore WaptTaskManager 7028] DEBUG DB commit
2022-07-15 09:20:13,222 [waptws SocketIOClient 20008] WARNING Exception PermissionError(13, 'Permission denied'), waiting 12.192578125000002s before retrying
2022-07-15 09:20:17,637 [urllib3.connectionpool WaptTaskManager 7028] DEBUG Starting new HTTPS connection (1): waptserver.domaine.local:443
2022-07-15 09:20:17,725 [urllib3.connectionpool WaptTaskManager 7028] DEBUG https://waptserver.domaine.local:443 "GET /licences.json HTTP/1.1" 200 7952
2022-07-15 09:20:17,726 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:20:17,727 [waptcore WaptTaskManager 7028] DEBUG DB rollback
2022-07-15 09:20:17,727 [waptcore WaptTaskManager 7028] DEBUG Error at DB exit attempt to write a readonly database, rollbacking
['  File "c:\\Program Files (x86)\\wapt\\common.py", line 347, in set_param\n    self.execute(\'insert or replace into wapt_params(name,value,create_date,ptype) values (?,?,?,?)\', (name, value, datetime2isodate(), ptype))\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 397, in execute\n    return self.db.execute(query, args)\n']
2022-07-15 09:20:17,729 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:20:17,730 [waptcore WaptTaskManager 7028] DEBUG Remove unknown repositories from packages table and params ("wapt","wapt-host")
2022-07-15 09:20:17,730 [waptcore WaptTaskManager 7028] DEBUG Check last-modified header for wapt to avoid unecessary update
2022-07-15 09:20:17,730 [waptcore WaptTaskManager 7028] DEBUG Checking availability of https://waptserver.domaine.local/wapt/Packages
2022-07-15 09:20:17,731 [urllib3.connectionpool WaptTaskManager 7028] DEBUG Starting new HTTPS connection (1): waptserver.domaine.local:443
2022-07-15 09:20:17,821 [urllib3.connectionpool WaptTaskManager 7028] DEBUG https://waptserver.domaine.local:443 "HEAD /wapt/Packages HTTP/1.1" 200 0
2022-07-15 09:20:17,822 [waptcore WaptTaskManager 7028] INFO Index from https://waptserver.domaine.local/wapt has not been updated (last update 2022-07-11T07:09:54), skipping update
2022-07-15 09:20:17,823 [waptcore WaptTaskManager 7028] DEBUG Check last-modified header for wapt-host to avoid unecessary update
2022-07-15 09:20:17,826 [waptcore WaptTaskManager 7028] DEBUG Checking availability of wapt-host
2022-07-15 09:20:17,826 [waptcore WaptTaskManager 7028] DEBUG Trying to get  host package for 4C4C4544-0035-4710-804A-B3C04F434233 at https://waptserver.domaine.local/wapt-host/4C4C4544-0035-4710-804A-B3C04F434233.wapt
2022-07-15 09:20:17,826 [urllib3.connectionpool WaptTaskManager 7028] DEBUG Starting new HTTPS connection (1): waptserver.domaine.local:443
2022-07-15 09:20:17,895 [urllib3.connectionpool WaptTaskManager 7028] DEBUG https://waptserver.domaine.local:443 "HEAD /wapt-host/4C4C4544-0035-4710-804A-B3C04F434233.wapt HTTP/1.1" 200 0
2022-07-15 09:20:17,896 [waptcore WaptTaskManager 7028] INFO Index from https://waptserver.domaine.local/wapt-host has not been updated (last update 2022-07-08T09:23:28), skipping update
2022-07-15 09:20:17,897 [waptcore WaptTaskManager 7028] DEBUG Error at DB exit attempt to write a readonly database, rollbacking
['  File "c:\\Program Files (x86)\\wapt\\common.py", line 347, in set_param\n    self.execute(\'insert or replace into wapt_params(name,value,create_date,ptype) values (?,?,?,?)\', (name, value, datetime2isodate(), ptype))\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 397, in execute\n    return self.db.execute(query, args)\n']
2022-07-15 09:20:17,897 [waptcore WaptTaskManager 7028] DEBUG DB rollback
2022-07-15 09:20:17,898 [waptcore WaptTaskManager 7028] DEBUG Error at DB exit attempt to write a readonly database, rollbacking
['  File "c:\\Program Files (x86)\\wapt\\common.py", line 4567, in _update_repos_list\n    self.write_param(\'host_capabilities_fingerprint\', new_capa)\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 8018, in write_param\n    self.waptdb.set_param(name, value)\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 347, in set_param\n    self.execute(\'insert or replace into wapt_params(name,value,create_date,ptype) values (?,?,?,?)\', (name, value, datetime2isodate(), ptype))\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 397, in execute\n    return self.db.execute(query, args)\n']
2022-07-15 09:20:17,898 [wapttasks WaptTaskManager 7028] CRITICAL Error for update in check_scheduled_tasks: attempt to write a readonly database
2022-07-15 09:20:17,898 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:20:17,898 [waptcore WaptTaskManager 7028] DEBUG DB commit
2022-07-15 09:20:23,435 [wapttasks MainThread 16644] INFO Waptservice stopped
2022-07-15 09:20:25,848 [waptws SocketIOClient 20008] WARNING Exception PermissionError(13, 'Permission denied'), waiting 21.337011718750006s before retrying
2022-07-15 09:20:27,919 [urllib3.connectionpool WaptTaskManager 7028] DEBUG Starting new HTTPS connection (1): waptserver.domaine.local:443
2022-07-15 09:20:27,994 [urllib3.connectionpool WaptTaskManager 7028] DEBUG https://waptserver.domaine.local:443 "GET /licences.json HTTP/1.1" 200 7952
2022-07-15 09:20:27,998 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:20:27,999 [waptcore WaptTaskManager 7028] DEBUG DB rollback
2022-07-15 09:20:28,000 [waptcore WaptTaskManager 7028] DEBUG Error at DB exit attempt to write a readonly database, rollbacking
['  File "c:\\Program Files (x86)\\wapt\\common.py", line 347, in set_param\n    self.execute(\'insert or replace into wapt_params(name,value,create_date,ptype) values (?,?,?,?)\', (name, value, datetime2isodate(), ptype))\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 397, in execute\n    return self.db.execute(query, args)\n']
2022-07-15 09:20:28,011 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:20:28,012 [waptcore WaptTaskManager 7028] DEBUG Remove unknown repositories from packages table and params ("wapt","wapt-host")
2022-07-15 09:20:28,013 [waptcore WaptTaskManager 7028] DEBUG Check last-modified header for wapt to avoid unecessary update
2022-07-15 09:20:28,014 [waptcore WaptTaskManager 7028] DEBUG Checking availability of https://waptserver.domaine.local/wapt/Packages
2022-07-15 09:20:28,015 [urllib3.connectionpool WaptTaskManager 7028] DEBUG Starting new HTTPS connection (1): waptserver.domaine.local:443
2022-07-15 09:20:28,085 [urllib3.connectionpool WaptTaskManager 7028] DEBUG https://waptserver.domaine.local:443 "HEAD /wapt/Packages HTTP/1.1" 200 0
2022-07-15 09:20:28,086 [waptcore WaptTaskManager 7028] INFO Index from https://waptserver.domaine.local/wapt has not been updated (last update 2022-07-11T07:09:54), skipping update
2022-07-15 09:20:28,087 [waptcore WaptTaskManager 7028] DEBUG Check last-modified header for wapt-host to avoid unecessary update
2022-07-15 09:20:28,087 [waptcore WaptTaskManager 7028] DEBUG Checking availability of wapt-host
2022-07-15 09:20:28,087 [waptcore WaptTaskManager 7028] DEBUG Trying to get  host package for 4C4C4544-0035-4710-804A-B3C04F434233 at https://waptserver.domaine.local/wapt-host/4C4C4544-0035-4710-804A-B3C04F434233.wapt
2022-07-15 09:20:28,088 [urllib3.connectionpool WaptTaskManager 7028] DEBUG Starting new HTTPS connection (1): waptserver.domaine.local:443
2022-07-15 09:20:28,156 [urllib3.connectionpool WaptTaskManager 7028] DEBUG https://waptserver.domaine.local:443 "HEAD /wapt-host/4C4C4544-0035-4710-804A-B3C04F434233.wapt HTTP/1.1" 200 0
2022-07-15 09:20:28,157 [waptcore WaptTaskManager 7028] INFO Index from https://waptserver.domaine.local/wapt-host has not been updated (last update 2022-07-08T09:23:28), skipping update
2022-07-15 09:20:28,158 [waptcore WaptTaskManager 7028] DEBUG Error at DB exit attempt to write a readonly database, rollbacking
['  File "c:\\Program Files (x86)\\wapt\\common.py", line 347, in set_param\n    self.execute(\'insert or replace into wapt_params(name,value,create_date,ptype) values (?,?,?,?)\', (name, value, datetime2isodate(), ptype))\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 397, in execute\n    return self.db.execute(query, args)\n']
2022-07-15 09:20:28,158 [waptcore WaptTaskManager 7028] DEBUG DB rollback
2022-07-15 09:20:28,158 [waptcore WaptTaskManager 7028] DEBUG Error at DB exit attempt to write a readonly database, rollbacking
['  File "c:\\Program Files (x86)\\wapt\\common.py", line 4567, in _update_repos_list\n    self.write_param(\'host_capabilities_fingerprint\', new_capa)\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 8018, in write_param\n    self.waptdb.set_param(name, value)\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 347, in set_param\n    self.execute(\'insert or replace into wapt_params(name,value,create_date,ptype) values (?,?,?,?)\', (name, value, datetime2isodate(), ptype))\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 397, in execute\n    return self.db.execute(query, args)\n']
2022-07-15 09:20:28,159 [wapttasks WaptTaskManager 7028] CRITICAL Error for update in check_scheduled_tasks: attempt to write a readonly database
2022-07-15 09:20:28,159 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:20:28,159 [waptcore WaptTaskManager 7028] DEBUG DB commit
2022-07-15 09:20:38,177 [urllib3.connectionpool WaptTaskManager 7028] DEBUG Starting new HTTPS connection (1): waptserver.domaine.local:443
2022-07-15 09:20:38,250 [urllib3.connectionpool WaptTaskManager 7028] DEBUG https://waptserver.domaine.local:443 "GET /licences.json HTTP/1.1" 200 7952
2022-07-15 09:20:38,251 [waptcore WaptTaskManager 7028] DEBUG DB Start transaction
2022-07-15 09:20:38,252 [waptcore WaptTaskManager 7028] DEBUG DB rollback
2022-07-15 09:20:38,252 [waptcore WaptTaskManager 7028] DEBUG Error at DB exit attempt to write a readonly database, rollbacking
['  File "c:\\Program Files (x86)\\wapt\\common.py", line 347, in set_param\n    self.execute(\'insert or replace into wapt_params(name,value,create_date,ptype) values (?,?,?,?)\', (name, value, datetime2isodate(), ptype))\n', '  File "c:\\Program Files (x86)\\wapt\\common.py", line 397, in execute\n    return self.db.execute(query, args)\n']

Ho notato numerosi errori "CRITICI" nel registro, che l'agente ha problemi ad applicare la policy WUA e che anche le attività pianificate sembrano non funzionare correttamente. Ciò che mi preoccupa un po' è che questi client di prova sono stati installati solo di recente (massimo 2 settimane). Ho forse configurato male il mio agente? Devo ancora aggiornarlo prima di distribuire WAPT alle nostre circa cento postazioni di lavoro.

Il nostro software antivirus non segnala alcun blocco dell'utilità WAPT, quindi mi chiedo cosa potrebbe essere successo... avete qualche idea? Tutti gli utenti eseguono il sistema senza privilegi. Ho provato a eseguire il comando debug come amministratore e ha funzionato senza problemi.

Grazie in anticipo per le vostre idee.

alain17 · 18 luglio 2022 - 15:59

Buongiorno,

Dopo aver effettuato alcune ricerche, ho capito che l'eseguibile runwaptservice.bat dovrebbe essere sempre eseguito come amministratore e che quindi non c'era nulla di sbagliato nel mio test senza privilegi di amministratore.

Questo, tuttavia, non risolve il problema: alcune workstation hanno il loro agente che si blocca regolarmente. A quanto pare, è possibile riavviare WAPT utilizzando il comando:

Codice: Seleziona tutto

wapt-get restart-waptservice

C'è un modo per automatizzare questa operazione, ad esempio con un'attività pianificata? Non riesco a trovare la documentazione di setuphelpers come menzionato qui e chi potrebbe aiutarmi.

18 luglio 2022 - 18:37

Ciao,

l'agente non dovrebbe bloccarsi in questo modo.

Ci sono messaggi nel Visualizzatore eventi relativi a questo blocco? Vedi qualcosa di insolito in c:\program files (x86)\wapt\log?

Cordiali saluti,

Denis

alain17 · 12 agosto 2022 - 08:23

Buongiorno,

Stamattina ho controllato i log e il registro eventi di una macchina interessata da queste disconnessioni per recuperare il più possibile. Nei log WAPT ho trovato quanto segue:

Codice: Seleziona tutto

Disabling Windows auto update service, using WaptWUA instead
2022-08-12 07:19:32,315 [wapttasks MainThread 7640] INFO Service Configuration: {'config_filename': 'C:\\Program Files (x86)\\wapt\\wapt-get.ini', 'waptservice_user': None, 'waptservice_password': None, 'MAX_HISTORY': 30, 'waptservice_port': 8088, 'dbpath': 'C:\\Program Files (x86)\\wapt\\db\\waptdb.sqlite', 'loglevel': 'warning', 'log_directory': 'C:\\Program Files (x86)\\wapt\\log', 'waptserver': <WaptServer https://waptserver.domaine.local>, 'hiberboot_enabled': 0, 'max_gpo_script_wait': 180, 'pre_shutdown_timeout': 180, 'log_to_windows_events': False, 'allow_user_service_restart': False, 'signature_clockskew': 21600, 'notify_user': False, 'waptservice_admin_filter': False, 'enable_remote_repo': False, 'local_repo_path': 'C:\\Program Files (x86)\\wapt\\repository', 'local_repo_sync_task_period': '2h', 'local_repo_time_for_sync_start': None, 'local_repo_time_for_sync_end': None, 'local_repo_limit_bandwidth': None, 'wol_port': '7,9', 'wol_relay': False, 'service_auth_type': 'system', 'verify_cert_ldap': False, 'download_after_update_with_waptupdate_task_period': True, 'forced_installs_task_period': '2m', 'reconfig_on_network_change': False, 'loglevel_waptcore': None, 'loglevel_waptservice': None, 'loglevel_waptws': None, 'loglevel_waptdb': None, 'loglevel_websocket': None, 'loglevel_waitress': None, 'loglevel_wapttasks': None}
2022-08-12 07:19:32,316 [wapttasks MainThread 7640] INFO Starting task queue
2022-08-12 07:19:32,317 [wapttasks MainThread 7640] INFO Tasks queue running
2022-08-12 07:19:32,317 [wapttasks WaptTaskManager 6372] INFO Wapt tasks management initialized with C:\Program Files (x86)\wapt\wapt-get.ini configuration
2022-08-12 07:19:32,319 [wapttasks MainThread 7640] INFO Starting waitress waptservice on port 8088
2022-08-12 07:19:32,421 [wapttasks SocketIOClient 6556] INFO Starting socketio on "https://waptserver.domaine.local:443" Thread id 6556
2022-08-12 07:19:32,466 [wapttasks WaptTaskManager 6372] INFO Core Configuration: {'wapt_base_dir': 'C:\\Program Files (x86)\\wapt', 'waptserver': <WaptServer https://waptserver.domaine.local>, 'config_filename': 'C:\\Program Files (x86)\\wapt\\wapt-get.ini', 'proxies': {'http': '', 'https': ''}, 'repositories': [<WaptRepo https://waptserver.domaine.local/wapt>, <WaptHostRepo https://waptserver.domaine.local/wapt-host for host_id 4C4C4544-0035-4710-804A-B3C04F434233 >], 'personal_certificate_path': '', 'public_certs_dir': 'C:\\Program Files (x86)\\wapt\\ssl', 'package_cache_dir': 'C:\\Program Files (x86)\\wapt\\cache', 'dbpath': 'C:\\Program Files (x86)\\wapt\\db\\waptdb.sqlite', 'use_http_proxy_for_repo': False, 'use_http_proxy_for_server': False, 'limit_bandwidth': None, 'locales': ['fr'], 'custom_tags': [], 'packages_whitelist': None, 'packages_blacklist': None, 'maturities': ['PROD'], 'host_uuid': '4C4C4544-0035-4710-804A-B3C04F434233', 'use_fqdn_as_uuid': False, 'use_hostpackages': True, 'use_ad_groups': False, 'use_repo_rules': False, 'host_profiles': [], 'host_organizational_unit_dn': 'OU=WAPT Test,OU=Ordinateurs,DC=Domaine,DC=local', 'host_ad_site': None, 'waptaudit_task_period': '2h', 'hiberboot_enabled': '0', 'max_gpo_script_wait': 180.0, 'pre_shutdown_timeout': 180.0, 'uninstallkey_timeout': 120, 'check_certificates_validity': True, 'token_lifetime': 86400}
2022-08-12 07:19:32,466 [wapttasks WaptTaskManager 6372] INFO Wapt tasks queue started
2022-08-12 07:19:32,523 [waptws SocketIOClient 6556] WARNING Exception ConnectionError(MaxRetryError("HTTPSConnectionPool(host='waptserver.domaine.local', port=443): Max retries exceeded with url: /get_websocket_auth_token (Caused by NewConnectionError('<urllib3.connection.HTTPSConnection object at 0x079AEEF8>: Failed to establish a new connection: [Errno 11001] getaddrinfo failed'))")), waiting 2.6s before retrying
2022-08-12 07:19:35,272 [wapttasks SocketIOClient 6556] INFO Socketio connection params have changed. Socketio needs reconnect
2022-08-12 07:19:35,273 [wapttasks SocketIOClient 6556] INFO Creating socketio client: https://waptserver.domaine.local:443 client auth cert: ('C:\\Program Files (x86)\\wapt\\private\\4C4C4544-0035-4710-804A-B3C04F434233.crt', 'C:\\Program Files (x86)\\wapt\\private\\4C4C4544-0035-4710-804A-B3C04F434233.pem') proxies: None verify_cert: False
2022-08-12 07:19:35,273 [wapttasks SocketIOClient 6556] INFO Connecting Socketio to https://waptserver.domaine.local:443
2022-08-12 07:19:35,342 [wapttasks SocketIOClient 6556] INFO WS read loop for 120s
2022-08-12 07:19:42,847 [wapttasks WaptTaskManager 6372] INFO Add task Mise à jour de la liste des paquets disponibles to queue
2022-08-12 07:19:42,849 [wapttasks WaptTaskManager 6372] INFO Add task Audit de 8 packages (tâche #-1) to queue
2022-08-12 07:19:42,849 [wapttasks WaptTaskManager 6372] INFO Add task Informer le serveur du statut de cette machine to queue
2022-08-12 07:19:42,856 [wapttasks WaptTaskManager 6372] INFO Running task Mise à jour de la liste des paquets disponibles created by SCHEDULER
Get packages index from [<WaptRepo https://waptserver.domaine.local/wapt>, <WaptHostRepo https://waptserver.domaine.local/wapt-host for host_id 4C4C4544-0035-4710-804A-B3C04F434233 >]
2022-08-12 07:19:44,070 [wapttasks WaptTaskManager 6372] INFO update_server_status successful (data size 62647)
2022-08-12 07:19:44,098 [wapttasks WaptTaskManager 6372] INFO Running task Audit de 8 packages (tâche #2) created by SCHEDULER
Auditing pla-waptwua
Auditing pla-vcredist2015-2022
Auditing DC=Domaine=local
OK: No setup.py
Auditing pla-vcredist
Auditing pla-pyscripter4
 OK: Uninstall Key PyScripter_is1 in Windows Registry.
Auditing 4C4C4544-0035-4710-804A-B3C04F434233
OK: No setup.py
Auditing pla-fork
Auditing pla-waptupgrade
The installed version of the WAPT agent and the version of the package are corresponding.
2022-08-12 07:19:44,728 [wapttasks WaptTaskManager 6372] INFO Running task Informer le serveur du statut de cette machine created by SCHEDULER
2022-08-12 07:19:45,466 [wapttasks WaptTaskManager 6372] INFO update_server_status successful (data size 15541)
Done.
2022-08-12 07:20:42,297 [wapttasks waitress-0 5136] INFO Add task Mise à jour de la liste des paquets disponibles to queue
2022-08-12 07:20:42,297 [wapttasks WaptTaskManager 6372] INFO Running task Mise à jour de la liste des paquets disponibles created by None
Get packages index from [<WaptRepo https://waptserver.domaine.local/wapt>, <WaptHostRepo https://waptserver.domaine.local/wapt-host for host_id 4C4C4544-0035-4710-804A-B3C04F434233 >]
2022-08-12 07:21:19,375 [waptws SocketIOClient 6556] WARNING Exception ConnectionError(MaxRetryError("HTTPSConnectionPool(host='waptserver.domaine.local', port=443): Max retries exceeded with url: /get_websocket_auth_token (Caused by NewConnectionError('<urllib3.connection.HTTPSConnection object at 0x08490D18>: Failed to establish a new connection: [Errno 11001] getaddrinfo failed'))")), waiting 5.0s before retrying
2022-08-12 07:21:24,508 [wapttasks SocketIOClient 6556] INFO Socketio connection params have changed. Socketio needs reconnect
2022-08-12 07:21:24,509 [wapttasks SocketIOClient 6556] INFO Connecting Socketio to https://waptserver.domaine.local:443
2022-08-12 07:21:24,558 [wapttasks SocketIOClient 6556] INFO WS read loop for 120s
2022-08-12 07:23:25,518 [wapttasks SocketIOClient 6556] INFO WS read loop for 120s
2022-08-12 07:25:26,489 [wapttasks SocketIOClient 6556] INFO WS read loop for 120s
2022-08-12 07:27:27,489 [wapttasks SocketIOClient 6556] INFO WS read loop for 120s

Chiaramente, la connessione HTTPS non funziona correttamente, anche se il server è raggiungibile, può essere risolto (nslookup) e il computer può effettuare il ping. Il server è funzionante e il computer si trova sulla stessa rete cablata (e persino sullo stesso sito) di un altro computer funzionante che rimane connesso. Per dovere di cronaca, vorrei sottolineare che:

Questo comportamento si osserva sia sulle workstation degli utenti sia sulle workstation dotate della console WAPT
I certificati sono distribuiti correttamente (posso vederli nella scheda "Certificati" di ogni macchina interessata)
Sembra che si sia stabilita una breve connessione, poiché vedo che le postazioni di lavoro si sono connesse almeno una volta nello stesso giorno
Sto utilizzando un certificato SSL autofirmato e non ho abilitato la verifica del certificato nella configurazione dell'agente
Ho aggiunto la configurazione dell'agente agli allegati

Per quanto riguarda gli eventi, ho fatto alcuni screenshot allegati, ma traducono abbastanza bene ciò che vediamo nel registro: c'è un avvio, un crash alle 07:19 e un riavvio alle 07:21.

alain17 · 12 agosto 2022 - 09:28

La configurazione dell'agente non è stata caricata per la modifica sul forum, quindi la sto caricando qui

12 agosto 2022 - 15:17

Codice: Seleziona tutto

2022-08-12 07:21:19,375 [waptws SocketIOClient 6556] WARNING Exception ConnectionError(MaxRetryError("HTTPSConnectionPool(host='waptserver.domaine.local', port=443): Max retries exceeded with url: /get_websocket_auth_token (Caused by NewConnectionError('<urllib3.connection.HTTPSConnection object at 0x08490D18>: Failed to establish a new connection: [Errno 11001] getaddrinfo failed'))")), waiting 5.0s before retrying

Codice: Seleziona tutto

getaddrinfo failed

Strano, sembra un problema DNS. Potresti avere due server DNS inviati alle macchine, uno dei quali non riesce a risolvere l'indirizzo del server WAPT?

Altrimenti, potrebbe apparentemente provenire da una variabile http_proxy locale impostata sulla macchina?

https://stackoverflow-com.translate.goo ... r_pto=wapp

alain17 · 15 agosto 2022 - 09:32

Buongiorno

sfonteneau ha scritto: ↑12 agosto 2022 - 15:17
Codice: Seleziona tutto
2022-08-12 07:21:19,375 [waptws SocketIOClient 6556] WARNING Exception ConnectionError(MaxRetryError("HTTPSConnectionPool(host='waptserver.domaine.local', port=443): Max retries exceeded with url: /get_websocket_auth_token (Caused by NewConnectionError('<urllib3.connection.HTTPSConnection object at 0x08490D18>: Failed to establish a new connection: [Errno 11001] getaddrinfo failed'))")), waiting 5.0s before retrying
Codice: Seleziona tutto
getaddrinfo failed
Strano, sembra un problema DNS. Potresti avere due server DNS inviati alle macchine, uno dei quali non riesce a risolvere l'indirizzo del server WAPT?

Anche questo mi sembra strano. Stiamo utilizzando due controller di dominio (repliche) che fungono anche da server DNS (i loro indirizzi IP sono elencati correttamente in ipconfig). Ho eseguito i seguenti comandi per verificare la risoluzione dei nomi su entrambi i server e ho ottenuto la stessa risposta (corretta):

Codice: Seleziona tutto

nslookup waptserver.domaine.local dc01.domaine.local
nslookup waptserver.domaine.local dc02.domaine.local

sfonteneau ha scritto: ↑12 agosto 2022 - 15:17 Altrimenti, potrebbe apparentemente derivare da una variabile http_proxy impostata localmente sulla macchina?

Ho controllato, non ho quel tipo di variabile d'ambiente sui miei computer e non usiamo un proxy. Stranamente, stamattina sono apparse più macchine connesse, ma non la mia. Ho colto l'occasione per eseguire un test in Python e sembra funzionare correttamente.

: wapt_addrinfo.png (11,98 KB) Visualizzato 7514 volte

Detto questo, ho una pista interessante: il mio computer risulta "disconnesso" quando passo dalla rete cablata alla rete Wi-Fi aziendale. Un comportamento simile è stato osservato con altri laptop. Ho replicato i miei test NSLOOKUP e Python su entrambe le reti e i risultati sono identici. Quando il computer torna alla rete cablata, la connessione viene ripristinata.

Per qualche motivo che non riesco a capire, WAPT sembra avere problemi a passare da una rete cablata a una wireless. Hai notato anche tu questo comportamento?

17 agosto 2022 - 10:20

Nella configurazione di rete, a livello DNS, non è previsto un fallback a 8.8.8.8, ad esempio?

Che si tratti di Wi-Fi o cavo, non dovrebbe essere un problema; stiamo semplicemente ristabilendo una connessione.

La riga `/get_websocket_auth_token` contatta il server per riautenticarsi per il WebSocket,

ma è proprio qui che apparentemente si presenta un problema DNS.

alain17 · 19 agosto 2022 - 11:33

sfonteneau ha scritto: ↑17 agosto 2022 - 10:20 Nella configurazione di rete, a livello DNS, non è previsto un fallback a 8.8.8.8, ad esempio?

Ho un fallback su un server DNS esterno, ma non per gli indirizzi ".local", che vengono risolti internamente. Detto questo, questa settimana sono passato alla versione 2.2.2 e l'implementazione dei nuovi agenti sembra aver portato maggiore stabilità. Questo potrebbe essere dovuto a una delle tante correzioni introdotte in questa versione. Terrò d'occhio la situazione e aprirò un nuovo thread se necessario.

Grazie per il tuo follow-up

19 agosto 2022 - 12:11

alain17 ha scritto: ↑19 agosto 2022 - 11:33 Ho un fallback su un DNS esterno, ma non per gli indirizzi in ".local", che vengono risolti internamente.

In generale, ricorrere a un DNS esterno non è una buona idea (e non solo per Wapt)

Infatti, se per qualche motivo il server DNS che risolve .local non risponde, la richiesta verrà inoltrata al server DNS esterno, se questo risponde. (E il server DNS esterno risponderà, non lo so, e Windows la memorizzerà nella cache.)

Questo problema è già stato riscontrato nel supporto qui; la rimozione del DNS esterno risolve i problemi.