Discussion:
WinHttp 15 second delay sending my data caused by windowsupdate
(too old to reply)
Dan Wanderer
2013-06-11 14:53:57 UTC
Permalink
I have a winhttp https client with no internet connection. Client connects to Tomcat server with a self signed SLL Cert. It appears to my customers that my app is very slow. In reality, windows update is trying to download authrootseq.txt and timing out. How do I stop this? I don't care if windows update runs, just don't do it on my thread.

Here's a trimmed down log.

08:47:37.531 ::*Session* :: >>>> WinHttp Version 5.1 Build 5.1.2600 Nov 16 2011 17:40:13>>>>Process TestCamDialogsDll.exe [3860 (0xf14)] started at 08:47:37.531 06/11/2013
08:47:41.546 ::*Session* :: WinHttpCrackUrl("https://192.168.2.9:2243/CamServer/CamService", 0x0, 0x0, 0xc5f8e0)
08:47:41.546 ::*Session* :: WinHttpCrackUrlA("https://192.168.2.9:2243/CamServer/CamService", 0x2d, 0x0, 0xc5f558)
08:47:41.546 ::*Session* :: WinHttpOpen("CAMClient", (1), "", "", 0x0)
08:47:41.546 ::*Session* :: WinHttpOpen() returning handle 0xce4000
08:47:41.546 ::*Session* :: WinHttpSetOption(0xce4000, (84), 0xc5f9f8 [0x80], 4)
08:47:41.546 ::*Session* :: WinHttpSetOption() returning TRUE
08:47:41.546 ::*Session* :: WinHttpConnect(0xce4000, "192.168.2.9", 2243, 0x0)
08:47:41.546 ::*Session* :: WinHttpConnect() returning handle 0xce4100
08:47:41.546 ::*Session* :: WinHttpOpenRequest(0xce4100, "POST", "/CamServer/CamService", "", "", 0x0, 0x00800000)
08:47:41.546 ::*Session* :: WinHttpCreateUrlA(0xc5f438, 0x0, 0xe60000, 0xc5f474)
08:47:41.546 ::*Session* :: WinHttpCreateUrlA() returning TRUE
08:47:41.546 ::*0000001* :: WinHttpOpenRequest() returning handle 0xd50000
08:47:41.546 ::*0000001* :: WinHttpSetOption(0xd50000, (31), 0xc5f9f8 [0x3100], 4)
08:47:41.546 ::*0000001* :: WinHttpSetOption() returning TRUE
08:47:41.546 ::*0000001* :: WinHttpSetOption(0xd50000, (3), 0xc5fa14 [0x1388], 4)
08:47:41.546 ::*0000001* :: WinHttpSetOption() returning TRUE
08:47:41.546 ::*Session* :: WinHttpQueryOption(0xd50000, (4), 0xc5fa14, 0xc5fa10 [4])
08:47:41.546 ::*Session* :: WinHttpQueryOption() returning TRUE
08:47:41.546 ::*0000001* :: WinHttpQueryOption(0xd50000, (3), 0xc5fa14, 0xc5fa10 [4])
08:47:41.546 ::*0000001* :: WinHttpQueryOption() returning TRUE
08:47:41.546 ::*0000001* :: WinHttpSendRequest(0xd50000, "", 0, 0x0, 0, 195, 0)
08:47:41.562 ::*0000001* :: Winsock/RPC/SSL/Transport error: 0x90312 [SEC_I_CONTINUE_NEEDED]
08:47:41.562 ::*0000001* :: sending data:
08:47:41.562 ::*0000001* :: 77 (0x4d) bytes
-------------- cut some hand shake
08:47:41.593 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>

------------------ suddenly they stop my SendRequest to check for updates??

08:47:41.796 ::*Session* :: WinHttpOpen("Microsoft-CryptoAPI/5.131.2600.5512", (0), "", "", 0x0)
08:47:41.796 ::*Session* :: WinHttpOpen() returning handle 0x15f1000
08:47:41.796 ::*Session* :: WinHttpSetTimeouts(0x15f1000, 0, 60000, 60000, 60000)
08:47:41.796 ::*Session* :: WinHttpSetTimeouts() returning TRUE
08:47:41.796 ::*Session* :: WinHttpSetOption(0x15f1000, (83), 0x128fe28 [0x0], 4)
08:47:41.796 ::*Session* :: WinHttpSetOption() returning TRUE
08:47:41.796 ::*Session* :: WinHttpCrackUrl("http://www.download.windowsupdate.com/msdownload/update/v3/static/trustedr/en/authrootseq.txt", 0x0, 0x0, 0x128fe34)
08:47:41.796 ::*Session* :: WinHttpCrackUrlA("http://www.download.windowsupdate.com/msdownload/update/v3/static/trustedr/en/authrootseq.txt", 0x5d, 0x0, 0x128fd44)
08:47:41.796 ::*Session* :: WinHttpCrackUrlA() returning TRUE
08:47:41.796 ::*Session* :: WinHttpCrackUrl() returning TRUE
08:47:41.796 ::*Session* :: WinHttpConnect(0x15f1000, "www.download.windowsupdate.com", 80, 0x0)
08:47:41.796 ::*Session* :: WinHttpConnect() returning handle 0x15f1100
08:47:41.796 ::*Session* :: WinHttpOpenRequest(0x15f1100, "", "/msdownload/update/v3/static/trustedr/en/authrootseq.txt", "", "", 0x128fe80, 0x00000100)
08:47:41.796 ::*Session* :: WinHttpCreateUrlA(0x128fc28, 0x0, 0x1630000, 0x128fc64)
08:47:41.796 ::*Session* :: WinHttpCreateUrlA() returning TRUE
08:47:41.796 ::*0000002* :: WinHttpOpenRequest() returning handle 0x1610000
08:47:41.796 ::*Session* :: DoConnectoidsExist()
08:47:41.796 ::*Session* :: IsRasInstalled()
08:47:41.796 ::*Session* :: IsRasInstalled() returning TRUE
08:47:41.796 ::*Session* :: DoConnectoidsExist() returning TRUE
08:47:41.812 ::*0000002* :: WinHttpSendRequest(0x1610000, "", 0, 0x0, 0, 0, 0)
--
---------- after a 15 second delay....
--
08:47:56.593 ::*0000002* :: "www.download.windowsupdate.com" NOT resolved
08:47:56.593 ::*0000002* :: WinHttpSendRequest: error 12007 [ERROR_WINHTTP_NAME_NOT_RESOLVED]
08:47:56.593 ::*0000002* :: WinHttpSendRequest() returning FALSE
08:47:56.593 ::*0000002* :: WinHttpCloseHandle(0x1610000)
08:47:56.593 ::*0000002* :: WinHttpCloseHandle() returning TRUE
08:47:56.593 ::*Session* :: WinHttpCloseHandle(0x15f1100)
08:47:56.593 ::*Session* :: WinHttpCloseHandle() returning TRUE
08:47:56.593 ::*Session* :: WinHttpCloseHandle(0x15f1000)
08:47:56.593 ::*Session* :: WinHttpCloseHandle() returning TRUE

--
------------ .... my data is sent
--
08:47:56.593 ::*0000001* :: sending data:
08:47:56.593 ::*0000001* :: 139 (0x8b) bytes
08:47:56.593 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.593 ::*0000001* :: POST /CamServer/CamService HTTP/1.1
08:47:56.593 ::*0000001* :: User-Agent: CAM-Client
08:47:56.593 ::*0000001* :: Host: 192.168.2.9:2243
08:47:56.593 ::*0000001* :: Content-Length: 195
08:47:56.593 ::*0000001* :: Connection: Keep-Alive
08:47:56.593 ::*0000001* ::
08:47:56.593 ::*0000001* ::
08:47:56.593 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.593 ::*0000001* :: WinHttpSendRequest() returning TRUE
08:47:56.593 ::*0000001* :: WinHttpSetOption(0xd50000, (3), 0xc5fa14 [0x15f90], 4)
08:47:56.593 ::*0000001* :: WinHttpSetOption() returning TRUE
08:47:56.593 ::*0000001* :: WinHttpWriteData(0xd50000, 0x9f81d8, 195, 0xc5f8d0)
08:47:56.593 ::*0000001* :: sending data:
08:47:56.593 ::*0000001* :: 195 (0xc3) bytes
08:47:56.593 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.593 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.593 ::*0000001* :: WinHttpWriteData() returning TRUE
08:47:56.593 ::*0000001* :: WinHttpReceiveResponse(0xd50000, 0x0)
08:47:56.609 ::*0000001* :: received data:
08:47:56.609 ::*0000001* :: 333 (0x14d) bytes
08:47:56.609 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>

08:47:56.609 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: received data:
08:47:56.609 ::*0000001* :: 302 (0x12e) bytes
08:47:56.609 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: HTTP/1.1 200 OK
08:47:56.609 ::*0000001* :: Server: Apache-Coyote/1.1
08:47:56.609 ::*0000001* :: Transfer-Encoding: chunked
08:47:56.609 ::*0000001* :: Date: Tue, 11 Jun 2013 12:47:57 GMT
08:47:56.609 ::*0000001* ::
08:47:56.609 ::*0000001* ::
08:47:56.609 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: WinHttpReceiveResponse() returning TRUE
08:47:56.609 ::*0000001* :: WinHttpQueryHeaders(0xd50000, (0x20000013), "<null>", 0xc5fa04, 0xc5f9c8 [4], 0x0 [0])
08:47:56.609 ::*0000001* :: WinHttpQueryHeaders() returning TRUE
08:47:56.609 ::*0000001* :: WinHttpQueryDataAvailable(0xd50000, 0xc5f61c, 0x0)
08:47:56.609 ::*0000001* :: WinHttpQueryDataAvailable() returning TRUE
08:47:56.609 ::*0000001* :: WinHttpReadData(0xd50000, 0x9ffd70, 185, 0xc5f618)
08:47:56.609 ::*0000001* :: WinHttpReadData() returning TRUE
08:47:56.609 ::*0000001* :: WinHttpQueryDataAvailable(0xd50000, 0xc5f61c, 0x0)
08:47:56.609 ::*0000001* :: received data:
08:47:56.609 ::*0000001* :: 37 (0x25) bytes
08:47:56.609 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: .... ..D....^x)
.W."G.......7k...^*.v
08:47:56.609 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: received data:
08:47:56.609 ::*0000001* :: 5 (0x5) bytes
08:47:56.609 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: 0
08:47:56.609 ::*0000001* ::
08:47:56.609 ::*0000001* ::
08:47:56.609 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: WinHttpQueryDataAvailable() returning TRUE
08:47:56.609 ::*0000001* :: WinHttpCloseHandle(0xd50000)
08:47:56.609 ::*0000001* :: WinHttpCloseHandle() returning TRUE
08:47:56.609 ::*Session* :: WinHttpCloseHandle(0xce4100)
08:47:56.609 ::*Session* :: WinHttpCloseHandle() returning TRUE
08:47:56.609 ::*Session* :: WinHttpCloseHandle(0xce4000)
08:47:56.609 ::*Session* :: WinHttpCloseHandle() returning TRUE
s***@gmail.com
2018-01-31 15:24:13 UTC
Permalink
Post by Dan Wanderer
I have a winhttp https client with no internet connection. Client connects to Tomcat server with a self signed SLL Cert. It appears to my customers that my app is very slow. In reality, windows update is trying to download authrootseq.txt and timing out. How do I stop this? I don't care if windows update runs, just don't do it on my thread.
Here's a trimmed down log.
08:47:37.531 ::*Session* :: >>>> WinHttp Version 5.1 Build 5.1.2600 Nov 16 2011 17:40:13>>>>Process TestCamDialogsDll.exe [3860 (0xf14)] started at 08:47:37.531 06/11/2013
08:47:41.546 ::*Session* :: WinHttpCrackUrl("https://192.168.2.9:2243/CamServer/CamService", 0x0, 0x0, 0xc5f8e0)
08:47:41.546 ::*Session* :: WinHttpCrackUrlA("https://192.168.2.9:2243/CamServer/CamService", 0x2d, 0x0, 0xc5f558)
08:47:41.546 ::*Session* :: WinHttpOpen("CAMClient", (1), "", "", 0x0)
08:47:41.546 ::*Session* :: WinHttpOpen() returning handle 0xce4000
08:47:41.546 ::*Session* :: WinHttpSetOption(0xce4000, (84), 0xc5f9f8 [0x80], 4)
08:47:41.546 ::*Session* :: WinHttpSetOption() returning TRUE
08:47:41.546 ::*Session* :: WinHttpConnect(0xce4000, "192.168.2.9", 2243, 0x0)
08:47:41.546 ::*Session* :: WinHttpConnect() returning handle 0xce4100
08:47:41.546 ::*Session* :: WinHttpOpenRequest(0xce4100, "POST", "/CamServer/CamService", "", "", 0x0, 0x00800000)
08:47:41.546 ::*Session* :: WinHttpCreateUrlA(0xc5f438, 0x0, 0xe60000, 0xc5f474)
08:47:41.546 ::*Session* :: WinHttpCreateUrlA() returning TRUE
08:47:41.546 ::*0000001* :: WinHttpOpenRequest() returning handle 0xd50000
08:47:41.546 ::*0000001* :: WinHttpSetOption(0xd50000, (31), 0xc5f9f8 [0x3100], 4)
08:47:41.546 ::*0000001* :: WinHttpSetOption() returning TRUE
08:47:41.546 ::*0000001* :: WinHttpSetOption(0xd50000, (3), 0xc5fa14 [0x1388], 4)
08:47:41.546 ::*0000001* :: WinHttpSetOption() returning TRUE
08:47:41.546 ::*Session* :: WinHttpQueryOption(0xd50000, (4), 0xc5fa14, 0xc5fa10 [4])
08:47:41.546 ::*Session* :: WinHttpQueryOption() returning TRUE
08:47:41.546 ::*0000001* :: WinHttpQueryOption(0xd50000, (3), 0xc5fa14, 0xc5fa10 [4])
08:47:41.546 ::*0000001* :: WinHttpQueryOption() returning TRUE
08:47:41.546 ::*0000001* :: WinHttpSendRequest(0xd50000, "", 0, 0x0, 0, 195, 0)
08:47:41.562 ::*0000001* :: Winsock/RPC/SSL/Transport error: 0x90312 [SEC_I_CONTINUE_NEEDED]
08:47:41.562 ::*0000001* :: 77 (0x4d) bytes
-------------- cut some hand shake
08:47:41.593 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
------------------ suddenly they stop my SendRequest to check for updates??
08:47:41.796 ::*Session* :: WinHttpOpen("Microsoft-CryptoAPI/5.131.2600.5512", (0), "", "", 0x0)
08:47:41.796 ::*Session* :: WinHttpOpen() returning handle 0x15f1000
08:47:41.796 ::*Session* :: WinHttpSetTimeouts(0x15f1000, 0, 60000, 60000, 60000)
08:47:41.796 ::*Session* :: WinHttpSetTimeouts() returning TRUE
08:47:41.796 ::*Session* :: WinHttpSetOption(0x15f1000, (83), 0x128fe28 [0x0], 4)
08:47:41.796 ::*Session* :: WinHttpSetOption() returning TRUE
08:47:41.796 ::*Session* :: WinHttpCrackUrl("http://www.download.windowsupdate.com/msdownload/update/v3/static/trustedr/en/authrootseq.txt", 0x0, 0x0, 0x128fe34)
08:47:41.796 ::*Session* :: WinHttpCrackUrlA("http://www.download.windowsupdate.com/msdownload/update/v3/static/trustedr/en/authrootseq.txt", 0x5d, 0x0, 0x128fd44)
08:47:41.796 ::*Session* :: WinHttpCrackUrlA() returning TRUE
08:47:41.796 ::*Session* :: WinHttpCrackUrl() returning TRUE
08:47:41.796 ::*Session* :: WinHttpConnect(0x15f1000, "www.download.windowsupdate.com", 80, 0x0)
08:47:41.796 ::*Session* :: WinHttpConnect() returning handle 0x15f1100
08:47:41.796 ::*Session* :: WinHttpOpenRequest(0x15f1100, "", "/msdownload/update/v3/static/trustedr/en/authrootseq.txt", "", "", 0x128fe80, 0x00000100)
08:47:41.796 ::*Session* :: WinHttpCreateUrlA(0x128fc28, 0x0, 0x1630000, 0x128fc64)
08:47:41.796 ::*Session* :: WinHttpCreateUrlA() returning TRUE
08:47:41.796 ::*0000002* :: WinHttpOpenRequest() returning handle 0x1610000
08:47:41.796 ::*Session* :: DoConnectoidsExist()
08:47:41.796 ::*Session* :: IsRasInstalled()
08:47:41.796 ::*Session* :: IsRasInstalled() returning TRUE
08:47:41.796 ::*Session* :: DoConnectoidsExist() returning TRUE
08:47:41.812 ::*0000002* :: WinHttpSendRequest(0x1610000, "", 0, 0x0, 0, 0, 0)
--
---------- after a 15 second delay....
--
08:47:56.593 ::*0000002* :: "www.download.windowsupdate.com" NOT resolved
08:47:56.593 ::*0000002* :: WinHttpSendRequest: error 12007 [ERROR_WINHTTP_NAME_NOT_RESOLVED]
08:47:56.593 ::*0000002* :: WinHttpSendRequest() returning FALSE
08:47:56.593 ::*0000002* :: WinHttpCloseHandle(0x1610000)
08:47:56.593 ::*0000002* :: WinHttpCloseHandle() returning TRUE
08:47:56.593 ::*Session* :: WinHttpCloseHandle(0x15f1100)
08:47:56.593 ::*Session* :: WinHttpCloseHandle() returning TRUE
08:47:56.593 ::*Session* :: WinHttpCloseHandle(0x15f1000)
08:47:56.593 ::*Session* :: WinHttpCloseHandle() returning TRUE
--
------------ .... my data is sent
--
08:47:56.593 ::*0000001* :: 139 (0x8b) bytes
08:47:56.593 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.593 ::*0000001* :: POST /CamServer/CamService HTTP/1.1
08:47:56.593 ::*0000001* :: User-Agent: CAM-Client
08:47:56.593 ::*0000001* :: Host: 192.168.2.9:2243
08:47:56.593 ::*0000001* :: Content-Length: 195
08:47:56.593 ::*0000001* :: Connection: Keep-Alive
08:47:56.593 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.593 ::*0000001* :: WinHttpSendRequest() returning TRUE
08:47:56.593 ::*0000001* :: WinHttpSetOption(0xd50000, (3), 0xc5fa14 [0x15f90], 4)
08:47:56.593 ::*0000001* :: WinHttpSetOption() returning TRUE
08:47:56.593 ::*0000001* :: WinHttpWriteData(0xd50000, 0x9f81d8, 195, 0xc5f8d0)
08:47:56.593 ::*0000001* :: 195 (0xc3) bytes
08:47:56.593 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.593 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.593 ::*0000001* :: WinHttpWriteData() returning TRUE
08:47:56.593 ::*0000001* :: WinHttpReceiveResponse(0xd50000, 0x0)
08:47:56.609 ::*0000001* :: 333 (0x14d) bytes
08:47:56.609 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: 302 (0x12e) bytes
08:47:56.609 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: HTTP/1.1 200 OK
08:47:56.609 ::*0000001* :: Server: Apache-Coyote/1.1
08:47:56.609 ::*0000001* :: Transfer-Encoding: chunked
08:47:56.609 ::*0000001* :: Date: Tue, 11 Jun 2013 12:47:57 GMT
08:47:56.609 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: WinHttpReceiveResponse() returning TRUE
08:47:56.609 ::*0000001* :: WinHttpQueryHeaders(0xd50000, (0x20000013), "<null>", 0xc5fa04, 0xc5f9c8 [4], 0x0 [0])
08:47:56.609 ::*0000001* :: WinHttpQueryHeaders() returning TRUE
08:47:56.609 ::*0000001* :: WinHttpQueryDataAvailable(0xd50000, 0xc5f61c, 0x0)
08:47:56.609 ::*0000001* :: WinHttpQueryDataAvailable() returning TRUE
08:47:56.609 ::*0000001* :: WinHttpReadData(0xd50000, 0x9ffd70, 185, 0xc5f618)
08:47:56.609 ::*0000001* :: WinHttpReadData() returning TRUE
08:47:56.609 ::*0000001* :: WinHttpQueryDataAvailable(0xd50000, 0xc5f61c, 0x0)
08:47:56.609 ::*0000001* :: 37 (0x25) bytes
08:47:56.609 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: .... ..D....^x)
.W."G.......7k...^*.v
08:47:56.609 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: 5 (0x5) bytes
08:47:56.609 ::*0000001* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: 0
08:47:56.609 ::*0000001* :: <<<<-------- End ----------------------------------------------->>>>
08:47:56.609 ::*0000001* :: WinHttpQueryDataAvailable() returning TRUE
08:47:56.609 ::*0000001* :: WinHttpCloseHandle(0xd50000)
08:47:56.609 ::*0000001* :: WinHttpCloseHandle() returning TRUE
08:47:56.609 ::*Session* :: WinHttpCloseHandle(0xce4100)
08:47:56.609 ::*Session* :: WinHttpCloseHandle() returning TRUE
08:47:56.609 ::*Session* :: WinHttpCloseHandle(0xce4000)
08:47:56.609 ::*Session* :: WinHttpCloseHandle() returning TRUE
Hi - Did you ever discover the problem? I'm having the same issue!
Loading...