[RESOLVED] Customer error

[eayglon]

Hello everyone,

First of all, thank you for this software. I'm currently using it on 3 sites and I'm completely satisfied.
I'm encountering a problem on one of the sites with version 15 (1.5.1.21).

Following the client deployment, this error is logged on the machines (Windows 7 Pro):
2018-03-23 ​​09:20:44,516 WARNING Unable to update server status: com_error: (-2147024893, 'The specific access path could not be found.', None, None)

Do you have any suggestions for resolving this?

[dcardon]

Hello eayglon,

First of all, thank you for this software. I'm currently using it on 3 sites and I'm completely satisfied.
I'm encountering a problem on one of the sites with version 15 (1.5.1.21).

Following the client deployment, this error is logged on the machines (Windows 7 Pro):
2018-03-23 ​​09:20:44,516 WARNING Unable to update server status: com_error: (-2147024893, 'The specific access path could not be found.', None, None)

Do you have any suggestions for resolving this?

The Windows error code is quite generic and doesn't allow you to identify the problem on its own. You would need to restart the service in debug mode and provide a little more information:

Code: Select all

net stop waptservice
cd c:\program files (x86)\wapt
waptpython waptservice\waptservice.py -l debug

Just out of curiosity, could there possibly be two machines on the same LAN with the same NetBIOS name? Is the WMI/WBEM layer functioning correctly?

Sincerely,

Denis

[eayglon]

Thank you for the order. Both logs come from the same machine.

The first block corresponds to the logs following an installation via GPO.

Code: Select all

DEBUG Starting new HTTPS connection (1): xxxxxxxx
2018-03-23 14:29:52,808 DEBUG https://xxxxxxxx:443 "GET /socket.io/?login=%7B%22uuid%22%3A+%225C6EFB2C-
[.....]
3&transport=polling&t=1521811792744-0 HTTP/1.1" 401 12
2018-03-23 14:29:52,808 INFO Error in socket io connection ConnectionError(u'unexpected status code (401 Unauthorized)',)

The second one corresponds to a manual installation, everything works.

Code: Select all

2018-03-23 14:34:36,157 DEBUG Starting new HTTPS connection (1): xxxxxxxxx
2018-03-23 14:34:36,230 DEBUG https://xxxxxxx:443 "GET /socket.io/?login=%7B%22uuid%22%3A+%225C6EFB2C-16BF-11E5-BB6E-B0C873BC1400%22%2C+%22signature
[....]
C-16BF-11E5-BB6E-B0C873BC1400&EIO=3&transport=polling&t=1521812076147-0 HTTP/1.1" 200 119
2018-03-23 14:34:37,115 DEBUG Examining ThreadPool. 10 threads and 0 Q'd conxions

There are no duplicates, and the WMI layers are ok.

[dcardon]

Hello eayglon,

Thank you for the order. Both logs come from the same machine.

The first block corresponds to the logs following an installation via GPO.

Code: Select all

DEBUG Starting new HTTPS connection (1): xxxxxxxx
2018-03-23 14:29:52,808 DEBUG https://xxxxxxxx:443 "GET /socket.io/?login=%7B%22uuid%22%3A+%225C6EFB2C-
[.....]
3&transport=polling&t=1521811792744-0 HTTP/1.1" 401 12
2018-03-23 14:29:52,808 INFO Error in socket io connection ConnectionError(u'unexpected status code (401 Unauthorized)',)

What is the server-side configuration? Could you check the message at the server level during GPO installation by increasing the log level (loglevel=debug in waptserver.ini, then restarting the service)?

It may also be interesting to increase the nginx logging level by adding debug to the error_log line.

Sincerely,

Denis

[eayglon]

Hello,

I will do the tests as soon as I return to the site, thank you.

[eayglon]

Good morning,

With your advice, I isolated a period (connection refused for a client installed via a gpo)


nssm_waptserver.log

Code: Select all

2018-03-28 08:55:51,209 DEBUG (3252) accepted ('127.0.0.1', 49493)
2018-03-28 08:55:51,210 INFO 3e09c76c09594cc29128e8de87ca7f46: Sending packet OPEN data {'pingInterval': 25000, 'pingTimeout': 60000, 'upgrades': ['websocket'], 'sid': '3e09c76c09594cc29128e8de87ca7f46'}
2018-03-28 08:55:51,213 DEBUG ('SELECT "t1"."host_certificate" FROM "hosts" AS t1 WHERE ("t1"."uuid" = %s) LIMIT 1', [u'66F5E88C-F2E7-11E3-87DE-E4B425491200'])
2018-03-28 08:55:51,216 WARNING SocketIO connection refused for uuid 66F5E88C-F2E7-11E3-87DE-E4B425491200, sid 3e09c76c09594cc29128e8de87ca7f46: Host is not registered or no host certificate found in database.
2018-03-28 08:55:51,216 DEBUG Returning 67378496 to pool.
2018-03-28 08:55:51,217 DEBUG Returning 67378496 to pool.
2018-03-28 08:55:51,217 INFO 3e09c76c09594cc29128e8de87ca7f46: Sending packet MESSAGE data 4
2018-03-28 08:55:51,217 WARNING Application rejected connection
2018-03-28 08:55:51,219 INFO 127.0.0.1 - - [28/Mar/2018 08:55:51] "GET /socket.io/?login=%7B%22uuid%22%3A+%2266F5E88C-F2E7-11E3-87DE-E4B425491200%22%2C+%22signature[...]22signed_attributes%22%2C+%22signer%22%2C+%22signature_date%22%2C+%22signer_certificate%22%5D%2C+%22signer_certificate%22%3A+%22-----BEGIN+CERTIFICATE-----[....]-END+CERTIFICATE-----%5Cn%22%2C+%22signature_date%22%3A+%222018-03-28T06%3A55%3A51.229000%22%2C+%22signer%22%3A+%2266F5E88C-F2E7-11E3-87DE-E4B425491200%22%7D&uuid=66F5E88C-F2E7-11E3-87DE-E4B425491200&EIO=3&transport=polling&t=1522220151236-0 HTTP/1.1" 401 196 0.008000

error.log (nginx)

Code: Select all

2018/03/28 08:51:51 [debug] 5872#4968: *1135 http upstream request: "/socket.io/?login=%7B%22uuid%22%3A+%2266F5E88C-F2E7-11E3-87DE-E4B425491200%22%2C+%22signature[...]5sda%
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http upstream process header
2018/03/28 08:51:51 [debug] 5872#4968: *1135 malloc: 02AA4F28:4096
2018/03/28 08:51:51 [debug] 5872#4968: *1135 WSARecv: fd:808 rc:0 196 of 4096
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http proxy status 401 "401 UNAUTHORIZED"
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http proxy header: "Content-Type: text/plain"
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http proxy header: "Access-Control-Allow-Origin: *"
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http proxy header: "Access-Control-Allow-Credentials: true"
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http proxy header: "Content-Length: 12"
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http proxy header: "Date: Wed, 28 Mar 2018 06:51:51 GMT"
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http proxy header done
2018/03/28 08:51:51 [debug] 5872#4968: *1135 HTTP/1.1 401 UNAUTHORIZED
Server: nginx/1.13.5
Date: Wed, 28 Mar 2018 06:51:51 GMT
Content-Type: text/plain
Content-Length: 12
Connection: keep-alive
Access-Control-Allow-Origin: *
Access-Control-Allow-Credentials: true

2018/03/28 08:51:51 [debug] 5872#4968: *1135 write new buf t:1 f:0 02AAC8FC, pos 02AAC8FC, size: 230 file: 0, size: 0
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http write filter: l:0 f:0 s:230
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http proxy filter init s:401 h:0 c:0 l:12
2018/03/28 08:51:51 [debug] 5872#4968: *1135 tcp_nodelay
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http upstream process non buffered downstream
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http output filter "/socket.io/?login=%7B%22uuid%22%3A+%2266F5E88C-F2E7-11E3-87DE-E4B425491200%22%2C+%22signature[...]2By5
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http copy filter: "/socket.io/?login=%7B%22uuid%22%3A+%2266F5E88C-F2E7-11E3-87DE-E4B425491200%22%2C+%22signature[...]By5e
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http postpone filter "/socket.io/?login=%7B%22uuid%22%3A+%2266F5E88C-F2E7-11E3-87DE-E4B425491200%22%2C+%22signature[...]a%2B
2018/03/28 08:51:51 [debug] 5872#4968: *1135 write old buf t:1 f:0 02AAC8FC, pos 02AAC8FC, size: 230 file: 0, size: 0
2018/03/28 08:51:51 [debug] 5872#4968: *1135 write new buf t:0 f:0 00000000, pos 02AA4FE0, size: 12 file: 0, size: 0
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http write filter: l:0 f:1 s:242
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http write filter limit 0
2018/03/28 08:51:51 [debug] 5872#4968: *1135 WSASend: fd:792, s:242
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http write filter 00000000
2018/03/28 08:51:51 [debug] 5872#4968: *1135 http copy filter: 0 "/socket.io/?login=%7B%22uuid%22%3A+%2266F5E88C-F2E7-11E3-87DE-E4B425491200%22%2C+%22signature[....]%2By
2018/03/28 08:51:51 [debug] 5872#4968: *1135 finalize http upstream request: 0
2018/03/28 08:51:51 [debug] 5872#4968: *1135 finalize http proxy request

[sfonteneau]

On a machine that is having problems, can you:

Code: Select all

wapt-get register -ldebug

[eayglon]

Code: Select all

2018-03-28 10:07:47,615 DEBUG Default encoding : ascii 
2018-03-28 10:07:47,615 DEBUG Setting encoding for stdout and stderr to cp850 
2018-03-28 10:07:47,615 DEBUG Python path ['C:\\Program Files (x86)\\wapt', 'C:\\Program Files (x86)\\wapt\\python27.zip', 'C:\\Program Files (x86)\\wapt\\DLLs', 'C:\\Program Files (x86)\\wapt\\lib', 'C:\\Program Files (x86)\\wapt\\lib\\plat-win', 'C:\\Program Files (x86)\\wapt\\lib\\lib-tk', 'C:\\Program Files (x86)\\wapt', 'C:\\Program Files (x86)\\wapt\\lib\\site-packages', 'C:\\Program Files (x86)\\wapt\\lib\\site-packages\\pywin32-221-py2.7-win32.egg']
2018-03-28 10:07:47,615 INFO Using local waptservice configuration C:\Program Files (x86)\wapt\wapt-get.ini 
2018-03-28 10:07:47,615 DEBUG Config file: C:\Program Files (x86)\wapt\wapt-get.ini
2018-03-28 10:07:47,625 DEBUG Thread 6056 is connecting to wapt db
2018-03-28 10:07:47,631 INFO Unknown UUID or hostname has changed: reading host UUID
2018-03-28 10:07:47,631 INFO reading custom host UUID from WMI System Information.
2018-03-28 10:07:47,661 DEBUG Thread 6056 is connecting to wapt db
2018-03-28 10:07:47,664 DEBUG DB Start transaction
2018-03-28 10:07:47,665 DEBUG DB commit
2018-03-28 10:07:47,737 DEBUG DB Start transaction
2018-03-28 10:07:47,740 DEBUG DB commit
2018-03-28 10:07:47,788 INFO User Groups:[]
2018-03-28 10:07:47,788 DEBUG WAPT base directory : C:\Program Files (x86)\wapt
2018-03-28 10:07:47,788 DEBUG Package cache dir : C:\Program Files (x86)\wapt\cache
2018-03-28 10:07:47,788 DEBUG WAPT DB Structure version;: 20180303
2018-03-28 10:07:47,789 DEBUG DB Start transaction
2018-03-28 10:07:47,792 DEBUG DB commit
FATAL ERROR : com_error: (-2147024893, 'Le chemin d\x92acc\xe8s sp\xe9cifi\xe9 est introuvable.', None, None)
Traceback (most recent call last):
  File "<string>", line 1215, in <module>
  File "<string>", line 1004, in main
  File "C:\Program Files (x86)\wapt\common.py", line 4686, in register_computer
    inv = self.inventory()
  File "C:\Program Files (x86)\wapt\common.py", line 5024, in inventory
    inv['host_info'] = setuphelpers.host_info()
  File "C:\Program Files (x86)\wapt\setuphelpers.py", line 2642, in host_info
    info['windows_startup_items'] = win_startup_info()
  File "C:\Program Files (x86)\wapt\setuphelpers.py", line 2434, in win_startup_info
    for lnk in glob.glob(makepath(startup(1),'*.lnk')):
  File "C:\Program Files (x86)\wapt\lib\site-packages\winshell.py", line 186, in startup
    return get_path ((shellcon.CSIDL_STARTUP, shellcon.CSIDL_COMMON_STARTUP)[common])
  File "C:\Program Files (x86)\wapt\lib\site-packages\winshell.py", line 140, in get_path
    return shell.SHGetFolderPath (0, folder_id, None, 0)
pywintypes.com_error: (-2147024893, 'Le chemin d\x92acc\xe8s sp\xe9cifi\xe9 est introuvable.', None, None)
Exception at 0043EC7F: EPyException:
com_error: (-2147024893, 'Le chemin d\x92acc\xe8s sp\xe9cifi\xe9 est introuvable.', None, None).

[htouvet]

Strange.
It seems the .CSIDL_COMMON_STARTUP folder doesn't exist on the client.
I added a fallback in the win_startup_info() function:

https://github.com/tranquilit/WAPT/comm ... 8d7208d8c6

[eayglon]

Hello,

I'm taking the test tomorrow.