On Sat, 14 Jan 2005, Vincent Béron wrote:
Le ven 14/01/2005 à 19:38, Robert Shearman a écrit :
If you can get debug messages working, I would makes sure you are running builtin wininet and then get a +wininet log.
With the Gentoo ebuild, if you don't have "debug" in your USE flags, it'll pass --disable-debug and/or --disable-trace to ./configure, so you don't get any messages in your terminal.
Thanks for the tip. I didn't notice that in this new ebuild the debug USE-flag had appeared. Anyway, now I have some debugs.
First of all, the connection to Internet works fine. So I don't think wininet as such might be the culprit. With Ethereal, I can see that following come through the wire (basically XML over HTTP)
Content-Type: text/xml; charset=utf-8 Content-Length: 416 <?xml version="1.0"?>
<update><validity>2005/1/21</validity><package><title>Lab Logo 1</title><date>1997/11/26</date><url>/labs/logos/1100/2.jpg< (rest of the stuff snipped). Basically, I think the program checks whether some license or something has expired.
If I debug the +wininet I can see the connection working just fine - HTTP methods get called and I can see stuff like
trace:wininet:HTTP_GetResponseHeaders got line "Server: Microsoft-IIS/6.0", nowinterpretting
going by. Apparently the content gets passed out of wininet, so the xml parsing part somehow is messed up.
Since I have no real idea where to start, I tried with WINEDEBUG=warn+all and this is what I get for the part where internet connection is activated and the error message appears:
warn:gdi:GDI_GetObjPtr Invalid handle (nil) warn:x11drv:SWP_DoOwnedPopups (0x50046) hInsertAfter = (nil) warn:gdi:GDI_GetObjPtr Invalid handle (nil) warn:gdi:GDI_GetObjPtr Invalid handle (nil) warn:x11drv:SWP_DoOwnedPopups (0x6004a) hInsertAfter = (nil) warn:event:EVENT_ProcessEvent Got event UnmapNotify for unknown Window 028000ba warn:event:EVENT_ProcessEvent Got event FocusOut for unknown Window 028000ba warn:event:EVENT_ProcessEvent Got event ConfigureNotify for unknown Window 028000ba warn:event:EVENT_ProcessEvent Got event ConfigureNotify for unknown Window 028000ba warn:x11drv:SWP_DoOwnedPopups (0x6004a) hInsertAfter = 0x10022 warn:file:wine_nt_to_unix_file_name L"imm32.dll" not found in /home/zarhan/.wine/dosdevices/c:/Program Files/IFI/OnlineFoto warn:file:wine_nt_to_unix_file_name L"imm32.dll" not found in /home/zarhan/.wine/dosdevices/c:/Program Files/IFI/OnlineFoto warn:file:wine_nt_to_unix_file_name L"imm32.dll" not found in /home/zarhan/.wine/dosdevices/c:/windows/system warn:file:wine_nt_to_unix_file_name L"imm32.dll" not found in /home/zarhan/.wine/dosdevices/c:/windows warn:file:wine_nt_to_unix_file_name L"imm32.dll" not found in /home/zarhan/.wine/dosdevices/c:/windows/system warn:file:wine_nt_to_unix_file_name L"imm32.dll" not found in /home/zarhan/.wine/dosdevices/c:/windows warn:imm:ImmAssociateContext ((nil), 0x77cd59e8): semi-stub warn:gdi:GDI_GetObjPtr Invalid handle (nil) warn:x11drv:SWP_DoOwnedPopups (0x6004e) hInsertAfter = (nil) warn:x11drv:SWP_DoOwnedPopups (0x6004e) hInsertAfter = 0x10022 warn:gdi:GDI_GetObjPtr Invalid handle (nil) warn:x11drv:SWP_DoOwnedPopups (0x6004e) hInsertAfter = (nil) warn:x11drv:SWP_DoOwnedPopups (0x6004e) hInsertAfter = 0x10022
Using native imm32.dll (From win2k SP4) does not help.
Anyway, here is also the +wininet log. What would be the next step?
fixme:ole:StdMarshalImpl_MarshalInterface table marshalling unimplemented trace:wininet:DllMain 0x7f750000,1,(nil) trace:wininet:DllMain 0x7f750000,2,(nil) trace:wininet:DllMain 0x7f750000,2,(nil) trace:wininet:InternetOpenA ("fwPrint", 0x00000001, (null), (null), 0x00000000) trace:wininet:InternetOpenW (L"fwPrint", 1, (null), (null), 0) trace:wininet:InternetOpenW access type : INTERNET_OPEN_TYPE_DIRECT trace:wininet:InternetOpenW flags : trace:wininet:WININET_AddRef 0x77cdc768 -> refcount = 2 trace:wininet:WININET_Release object 0x77cdc768 refcount = 1 trace:wininet:InternetOpenW returning 0x77cdc768 trace:wininet:InternetSetStatusCallbackA 0x00000001 trace:wininet:WININET_AddRef 0x77cdc768 -> refcount = 2 trace:wininet:WININET_GetObject handle 1 -> 0x77cdc768 trace:wininet:WININET_Release object 0x77cdc768 refcount = 1 trace:wininet:InternetConnectW (0x1, L"master.fotowire.com", 80, L"", (null), 3, 0, 1) trace:wininet:WININET_AddRef 0x77cdc768 -> refcount = 2 trace:wininet:WININET_GetObject handle 1 -> 0x77cdc768 trace:wininet:HTTP_Connect --> trace:wininet:WININET_AddRef 0x77cdc768 -> refcount = 3 trace:wininet:WININET_AddRef 0x77cdc838 -> refcount = 2 trace:wininet:SendAsyncCallback (0x77cdc838, 00000001, 60 (INTERNET_STATUS_HANDLE_CREATED), 0x7c67e338, 8): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc838 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000002 (0x77cdc838), 00000001, 60 (INTERNET_STATUS_HANDLE_CREATED), 0x7c67e338, 8) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc838 refcount = 2 trace:wininet:WININET_Release object 0x77cdc838 refcount = 1 trace:wininet:HTTP_Connect 0x77cdc768 --> 0x2 (0x77cdc838) trace:wininet:WININET_Release object 0x77cdc768 refcount = 2 trace:wininet:InternetConnectW returning 0x2 trace:wininet:HttpOpenRequestA (0x2, "GET", "/fworder/fwver.asp?LID=1100&OEM=1100&UPID=1100&VER=3.1.139.1&RD=cbc", "HTTP/1.0", (null), (nil), 84600000, 00000001) trace:wininet:HttpOpenRequestW (0x2, L"GET", L"/fworder/fwver.asp?LID=1100&OEM=1100&UPID=1100&VER=3.1.139.1&RD=cbc", L"HTTP/1.0", (null), (nil), 84600000, 00000001) trace:wininet:WININET_AddRef 0x77cdc838 -> refcount = 2 trace:wininet:WININET_GetObject handle 2 -> 0x77cdc838 trace:wininet:HTTP_HttpOpenRequestW --> trace:wininet:WININET_AddRef 0x77cdc838 -> refcount = 3 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 2 trace:wininet:HTTP_ProcessHeader --> L"Host": L"master.fotowire.com" - 0xa2000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:HTTP_HttpAddRequestHeadersW copying header: L"User-Agent: fwPrint\r\n" trace:wininet:HTTP_HttpAddRequestHeadersW interpreting header L"User-Agent: fwPrint" trace:wininet:HTTP_InterpretHttpHeader field(L"User-Agent") Value(L"fwPrint") trace:wininet:HTTP_ProcessHeader --> L"User-Agent": L"fwPrint" - 0x22000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:InternetGetCookieW (L"http://master.fotowire.com", (null), (nil), 0x7c67e020) trace:wininet:InternetCrackUrlW (L"http://master.fotowire.com" 26 0 0x7c67bf38) trace:wininet:GetInternetSchemeW trace:wininet:SetUrlComponentValueW L"http"... (4) trace:wininet:SetUrlComponentValueW (null) (0) trace:wininet:SetUrlComponentValueW (null) (0) trace:wininet:SetUrlComponentValueW L"master.fotowire.com" (19) trace:wininet:InternetCrackUrlW L"http://master.fotowire.com": host(L"master.fotowire.com") path(L"") extra((null)) trace:wininet:InternetGetCookieW returning trace:wininet:InternetGetCookieW (L"http://master.fotowire.com", (null), 0x77cdcec0, 0x7c67e020) trace:wininet:InternetCrackUrlW (L"http://master.fotowire.com" 26 0 0x7c67bf38) trace:wininet:GetInternetSchemeW trace:wininet:SetUrlComponentValueW L"http"... (4) trace:wininet:SetUrlComponentValueW (null) (0) trace:wininet:SetUrlComponentValueW (null) (0) trace:wininet:SetUrlComponentValueW L"master.fotowire.com" (19) trace:wininet:InternetCrackUrlW L"http://master.fotowire.com": host(L"master.fotowire.com") path(L"") extra((null)) trace:wininet:HTTP_HttpAddRequestHeadersW copying header: L"Cookie: \r\n" trace:wininet:HTTP_HttpAddRequestHeadersW interpreting header L"Cookie: " trace:wininet:HTTP_InterpretHttpHeader field(L"Cookie") Value(L"") trace:wininet:HTTP_ProcessHeader --> L"Cookie": L"" - 0x22000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:SendAsyncCallback (0x77cdc838, 00000001, 60 (INTERNET_STATUS_HANDLE_CREATED), 0x7c67e018, 8): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc838 -> refcount = 4 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000002 (0x77cdc838), 00000001, 60 (INTERNET_STATUS_HANDLE_CREATED), 0x7c67e018, 8) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc838 refcount = 3 trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 10 (INTERNET_STATUS_RESOLVING_NAME), 0x77cdc888, 20): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000003 (0x77cdc968), 00000001, 10 (INTERNET_STATUS_RESOLVING_NAME), 0x77cdcf80, 20) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc968 refcount = 2 trace:wininet:GetAddress L"master.fotowire.com" trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 11 (INTERNET_STATUS_NAME_RESOLVED), 0x77cdc868, 16): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000003 (0x77cdc968), 00000001, 11 (INTERNET_STATUS_NAME_RESOLVED), 0x77cdc868, 16) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc968 refcount = 2 trace:wininet:WININET_Release object 0x77cdc968 refcount = 1 trace:wininet:HTTP_HttpOpenRequestW <-- 0x3 (0x77cdc968) trace:wininet:WININET_Release object 0x77cdc838 refcount = 2 trace:wininet:HttpOpenRequestW returning 0x3 trace:wininet:HttpAddRequestHeadersA 0x3, "Accept: text/*\r\nUser-Agent: fwClient(3.1.139.1;FIN;1100) OS(4.0.950;ENU;Win9x) WinInet(5.0.0.0) Browser(0.0.0.0;;Unknown)\r\nAccept-Language: fi\r\n", 144, 268435456 trace:wininet:HttpAddRequestHeadersW 0x3, L"Accept: text/*\r\nUser-Agent: fwClient(3.1.139.1;FIN;1100) OS(4.0.950;ENU;Win9x) WinInet(5.0.0.0) Browser(0.0.0.0;;Unknown)\r\nAccept-Language: fi\r\n\2aa1\0008\5246\45458\77c5t\77c5\5455\4f48\5352\6620\726f\6120\6320\6d6f\6c70\7465\2065\696c\7473)$\000c\724f\6769\6e69\6c61\6946\656c\616e\656d", 144, 268435456 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 2 trace:wininet:WININET_GetObject handle 3 -> 0x77cdc968 trace:wininet:HTTP_HttpAddRequestHeadersW copying header: L"Accept: text/*\r\nUser-Agent: fwClient(3.1.139.1;FIN;1100) OS(4.0.950;ENU;Win9x) WinInet(5.0.0.0) Browser(0.0.0.0;;Unknown)\r\nAccept-Language: fi\r\n\2aa1\0008\5246\45458\77c5t\77c5\5455\4f48\5352\6620\726f\6120\6320\6d6f\6c70\7465\2065\696c\7473)$\000c\724f\6769\6e69\6c61\6946\656c\616e\656d" trace:wininet:HTTP_HttpAddRequestHeadersW interpreting header L"Accept: text/*" trace:wininet:HTTP_InterpretHttpHeader field(L"Accept") Value(L"text/*") trace:wininet:HTTP_ProcessHeader --> L"Accept": L"text/*" - 0x12000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:HTTP_HttpAddRequestHeadersW interpreting header L"User-Agent: fwClient(3.1.139.1;FIN;1100) OS(4.0.950;ENU;Win9x) WinInet(5.0.0.0) Browser(0.0.0.0;;Unknown)" trace:wininet:HTTP_InterpretHttpHeader field(L"User-Agent") Value(L"fwClient(3.1.139.1;FIN;1100) OS(4.0.950;ENU;Win9x) WinInet(5.0.0.0) Browser(0.0.0.0;;Unknown)") trace:wininet:HTTP_ProcessHeader --> L"User-Agent": L"fwClient(3.1.139.1;FIN;1100) OS(4.0.950;ENU;Win9x) WinInet(5.0.0.0) Browser(0.0.0.0;;Unknown)" - 0x12000000 trace:wininet:HTTP_ProcessHeader <-- 0 trace:wininet:WININET_Release object 0x77cdc968 refcount = 1 trace:wininet:HttpSendRequestW 0x3, (nil) ((null)), 0, (nil), 0) trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 2 trace:wininet:WININET_GetObject handle 3 -> 0x77cdc968 trace:wininet:HTTP_HttpSendRequestW --> 0x77cdc968 trace:wininet:HTTP_HttpSendRequestW Going to url L"master.fotowire.com" L"/fworder/fwver.asp?LID=1100&OEM=1100&UPID=1100&VER=3.1.139.1&RD=cbc" trace:wininet:HTTP_HttpSendRequestW Adding header L"Accept" (L"text/*") trace:wininet:HTTP_HttpSendRequestW Adding header L"User-Agent" (L"fwPrint") trace:wininet:HTTP_HttpSendRequestW Adding header L"Cookie" (L"") trace:wininet:HTTP_HttpSendRequestW Adding header L"Host" (L"master.fotowire.com") trace:wininet:HTTP_HttpSendRequestW Request header -> L"GET /fworder/fwver.asp?LID=1100&OEM=1100&UPID=1100&VER=3.1.139.1&RD=cbc HTTP/1.0\r\nAccept: text/*\r\nUser-Agent: fwPrint\r\nCookie: \r\nHost: master.fotowire.com\r\n\r\n" trace:wininet:HTTP_OpenConnection --> trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 20 (INTERNET_STATUS_CONNECTING_TO_SERVER), 0x77cdc868, 16): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000003 (0x77cdc968), 00000001, 20 (INTERNET_STATUS_CONNECTING_TO_SERVER), 0x77cdc868, 16) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc968 refcount = 2 trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 21 (INTERNET_STATUS_CONNECTED_TO_SERVER), 0x77cdc868, 16): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000003 (0x77cdc968), 00000001, 21 (INTERNET_STATUS_CONNECTED_TO_SERVER), 0x77cdc868, 16) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc968 refcount = 2 trace:wininet:HTTP_OpenConnection 1 <-- trace:wininet:HTTP_HttpSendRequestW full request -> GET /fworder/fwver.asp?LID=1100&OEM=1100&UPID=1100&VER=3.1.139.1&RD=cbc HTTP/1.0 Accept: text/* User-Agent: fwPrint Cookie: Host: master.fotowire.com
trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 30 (INTERNET_STATUS_SENDING_REQUEST), (nil), 0): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000003 (0x77cdc968), 00000001, 30 (INTERNET_STATUS_SENDING_REQUEST), (nil), 0) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc968 refcount = 2 trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 31 (INTERNET_STATUS_REQUEST_SENT), 0x7c67a9ec, 4): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000003 (0x77cdc968), 00000001, 31 (INTERNET_STATUS_REQUEST_SENT), 0x7c67a9ec, 4) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc968 refcount = 2 trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 40 (INTERNET_STATUS_RECEIVING_RESPONSE), (nil), 0): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000003 (0x77cdc968), 00000001, 40 (INTERNET_STATUS_RECEIVING_RESPONSE), (nil), 0) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc968 refcount = 2 trace:wininet:HTTP_GetResponseHeaders --> trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :16 HTTP/1.1 200 OK trace:wininet:HTTP_GetResponseHeaders version [L"HTTP/1.1"] status code [L"200"] status text [L"OK"] trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :18 Connection: close trace:wininet:HTTP_GetResponseHeaders got line "Connection: close", now interpretting trace:wininet:HTTP_InterpretHttpHeader field(L"Connection") Value(L"close") trace:wininet:HTTP_ProcessHeader --> L"Connection": L"close" - 0xa0000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :36 Date: Sat, 15 Jan 2005 12:31:57 GMT trace:wininet:HTTP_GetResponseHeaders got line "Date: Sat, 15 Jan 2005 12:31:57 GMT", now interpretting trace:wininet:HTTP_InterpretHttpHeader field(L"Date") Value(L"Sat, 15 Jan 2005 12:31:57 GMT") trace:wininet:HTTP_ProcessHeader --> L"Date": L"Sat, 15 Jan 2005 12:31:57 GMT" - 0xa0000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :26 Server: Microsoft-IIS/6.0 trace:wininet:HTTP_GetResponseHeaders got line "Server: Microsoft-IIS/6.0", now interpretting trace:wininet:HTTP_InterpretHttpHeader field(L"Server") Value(L"Microsoft-IIS/6.0") trace:wininet:HTTP_ProcessHeader --> L"Server": L"Microsoft-IIS/6.0" - 0xa0000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :22 X-Powered-By: ASP.NET trace:wininet:HTTP_GetResponseHeaders got line "X-Powered-By: ASP.NET", now interpretting trace:wininet:HTTP_InterpretHttpHeader field(L"X-Powered-By") Value(L"ASP.NET") trace:wininet:HTTP_ProcessHeader --> L"X-Powered-By": L"ASP.NET" - 0xa0000000 trace:wininet:HTTP_GetStdHeaderIndex Couldn't find L"X-Powered-By" in standard header table trace:wininet:HTTP_GetCustomHeaderIndex L"X-Powered-By" trace:wininet:HTTP_GetCustomHeaderIndex Return: -1 trace:wininet:HTTP_InsertCustomHeader --> L"X-Powered-By": L"ASP.NET" trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :27 X-AspNet-Version: 1.1.4322 trace:wininet:HTTP_GetResponseHeaders got line "X-AspNet-Version: 1.1.4322", now interpretting trace:wininet:HTTP_InterpretHttpHeader field(L"X-AspNet-Version") Value(L"1.1.4322") trace:wininet:HTTP_ProcessHeader --> L"X-AspNet-Version": L"1.1.4322" - 0xa0000000 trace:wininet:HTTP_GetStdHeaderIndex Couldn't find L"X-AspNet-Version" in standard header table trace:wininet:HTTP_GetCustomHeaderIndex L"X-AspNet-Version" trace:wininet:HTTP_GetCustomHeaderIndex Return: -1 trace:wininet:HTTP_InsertCustomHeader --> L"X-AspNet-Version": L"1.1.4322" trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :24 Cache-Control: no-cache trace:wininet:HTTP_GetResponseHeaders got line "Cache-Control: no-cache", now interpretting trace:wininet:HTTP_InterpretHttpHeader field(L"Cache-Control") Value(L"no-cache") trace:wininet:HTTP_ProcessHeader --> L"Cache-Control": L"no-cache" - 0xa0000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :17 Pragma: no-cache trace:wininet:HTTP_GetResponseHeaders got line "Pragma: no-cache", now interpretting trace:wininet:HTTP_InterpretHttpHeader field(L"Pragma") Value(L"no-cache") trace:wininet:HTTP_ProcessHeader --> L"Pragma": L"no-cache" - 0xa0000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :12 Expires: -1 trace:wininet:HTTP_GetResponseHeaders got line "Expires: -1", now interpretting trace:wininet:HTTP_InterpretHttpHeader field(L"Expires") Value(L"-1") trace:wininet:HTTP_ProcessHeader --> L"Expires": L"-1" - 0xa0000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :38 Content-Type: text/xml; charset=utf-8 trace:wininet:HTTP_GetResponseHeaders got line "Content-Type: text/xml; charset=utf-8", now interpretting trace:wininet:HTTP_InterpretHttpHeader field(L"Content-Type") Value(L"text/xml; charset=utf-8") trace:wininet:HTTP_ProcessHeader --> L"Content-Type": L"text/xml; charset=utf-8" - 0xa0000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :20 Content-Length: 416 trace:wininet:HTTP_GetResponseHeaders got line "Content-Length: 416", now interpretting trace:wininet:HTTP_InterpretHttpHeader field(L"Content-Length") Value(L"416") trace:wininet:HTTP_ProcessHeader --> L"Content-Length": L"416" - 0xa0000000 trace:wininet:HTTP_ProcessHeader <-- 1 trace:wininet:NETCON_getNextLine trace:wininet:NETCON_getNextLine :1 trace:wininet:HTTP_GetResponseHeaders got line "", now interpretting trace:wininet:HTTP_GetResponseHeaders raw headers: L"HTTP/1.1 200 OK\r\nConnection: close\r\nDate: Sat, 15 Jan 2005 12:31:57 GMT\r\nServer: Microsoft-IIS/6.0\r\nX-Powered-By: ASP.NET\r\nX-AspNet-Version: 1.1.4322\r\nCache-Control: no-cache\r\nPragma: no-cache\r\nExpires: -1\r\nContent-Type: text/xml; charset=utf-8\r\nContent-Length: 416\r\n\r\n" trace:wininet:HTTP_GetResponseHeaders <-- trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 41 (INTERNET_STATUS_RESPONSE_RECEIVED), 0x7c67a9e8, 4): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000003 (0x77cdc968), 00000001, 41 (INTERNET_STATUS_RESPONSE_RECEIVED), 0x7c67a9e8, 4) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc968 refcount = 2 trace:wininet:HTTP_GetCustomHeaderIndex L"Set-Cookie" trace:wininet:HTTP_GetCustomHeaderIndex Return: -1 trace:wininet:HTTP_HttpQueryInfoW returning number : 200 trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 100 (INTERNET_STATUS_REQUEST_COMPLETE), 0x7c67a9cc, 8): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000003 (0x77cdc968), 00000001, 100 (INTERNET_STATUS_REQUEST_COMPLETE), 0x7c67a9cc, 8) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc968 refcount = 2 trace:wininet:HTTP_HttpSendRequestW <-- trace:wininet:WININET_Release object 0x77cdc968 refcount = 1 trace:wininet:HttpQueryInfoW (0x3, 0x00000013)--> 19 trace:wininet:HttpQueryInfoW Attribute: HTTP_QUERY_STATUS_CODE Modifier: trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 2 trace:wininet:WININET_GetObject handle 3 -> 0x77cdc968 trace:wininet:HTTP_HttpQueryInfoW returning string : 'L"200"' trace:wininet:WININET_Release object 0x77cdc968 refcount = 1 trace:wininet:HttpQueryInfoW 1 <-- trace:wininet:HttpQueryInfoA lpBuffer: "200" trace:wininet:InternetReadFile 0x3 0x7c67e890 1024 0x7c67e470 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 2 trace:wininet:WININET_GetObject handle 3 -> 0x77cdc968 trace:wininet:WININET_Release object 0x77cdc968 refcount = 1 trace:wininet:InternetReadFile -- TRUE (bytes read: 416) trace:wininet:InternetReadFile 0x3 0x7c67e890 1024 0x7c67e470 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 2 trace:wininet:WININET_GetObject handle 3 -> 0x77cdc968 trace:wininet:WININET_Release object 0x77cdc968 refcount = 1 trace:wininet:InternetReadFile -- TRUE (bytes read: 0) trace:wininet:InternetCloseHandle 0x3 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 2 trace:wininet:WININET_GetObject handle 3 -> 0x77cdc968 trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 70 (INTERNET_STATUS_HANDLE_CLOSING), 0x7c67e478, 4): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc968 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000003 (0x77cdc968), 00000001, 70 (INTERNET_STATUS_HANDLE_CLOSING), 0x7c67e478, 4) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc968 refcount = 2 trace:wininet:WININET_Release object 0x77cdc838 refcount = 1 trace:wininet:WININET_FreeHandle destroying handle 3 for object 0x77cdc968 trace:wininet:WININET_Release object 0x77cdc968 refcount = 1 trace:wininet:WININET_Release object 0x77cdc968 refcount = 0 trace:wininet:WININET_Release destroying object 0x77cdc968 trace:wininet:HTTP_CloseHTTPRequestHandle trace:wininet:HTTP_CloseConnection 0x77cdc968 trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 50 (INTERNET_STATUS_CLOSING_CONNECTION), (nil), 0): non async call with callback 0x67f931a7 trace:wininet:SendSyncCallback Could not convert header '0x77cdc968' into a handle ! trace:wininet:SendAsyncCallback (0x77cdc968, 00000001, 51 (INTERNET_STATUS_CONNECTION_CLOSED), (nil), 0): non async call with callback 0x67f931a7 trace:wininet:SendSyncCallback Could not convert header '0x77cdc968' into a handle ! trace:wininet:InternetCloseHandle 0x2 trace:wininet:WININET_AddRef 0x77cdc838 -> refcount = 2 trace:wininet:WININET_GetObject handle 2 -> 0x77cdc838 trace:wininet:SendAsyncCallback (0x77cdc838, 00000001, 70 (INTERNET_STATUS_HANDLE_CLOSING), 0x7c67f0c4, 4): non async call with callback 0x67f931a7 trace:wininet:WININET_AddRef 0x77cdc838 -> refcount = 3 trace:wininet:SendSyncCallback callback(0x67f931a7) (00000002 (0x77cdc838), 00000001, 70 (INTERNET_STATUS_HANDLE_CLOSING), 0x7c67f0c4, 4) trace:wininet:SendSyncCallback end callback(). trace:wininet:WININET_Release object 0x77cdc838 refcount = 2 trace:wininet:WININET_Release object 0x77cdc768 refcount = 1 trace:wininet:WININET_FreeHandle destroying handle 2 for object 0x77cdc838 trace:wininet:WININET_Release object 0x77cdc838 refcount = 1 trace:wininet:WININET_Release object 0x77cdc838 refcount = 0 trace:wininet:WININET_Release destroying object 0x77cdc838 trace:wininet:HTTP_CloseHTTPSessionHandle 0x77cdc838 trace:wininet:InternetSetStatusCallbackA 0x00000001 trace:wininet:WININET_AddRef 0x77cdc768 -> refcount = 2 trace:wininet:WININET_GetObject handle 1 -> 0x77cdc768 trace:wininet:WININET_Release object 0x77cdc768 refcount = 1 trace:wininet:InternetCloseHandle 0x1 trace:wininet:WININET_AddRef 0x77cdc768 -> refcount = 2 trace:wininet:WININET_GetObject handle 1 -> 0x77cdc768 trace:wininet:SendAsyncCallback (0x77cdc768, 00000001, 70 (INTERNET_STATUS_HANDLE_CLOSING), 0x7c67f0ac, 4): non async call with callback (nil) trace:wininet:WININET_FreeHandle destroying handle 1 for object 0x77cdc768 trace:wininet:WININET_Release object 0x77cdc768 refcount = 1 trace:wininet:WININET_Release object 0x77cdc768 refcount = 0 trace:wininet:WININET_Release destroying object 0x77cdc768 trace:wininet:INTERNET_CloseHandle 0x77cdc768 trace:wininet:DllMain 0x7f750000,3,(nil)