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) -- - Antti Mäkelä - http://www.cs.tut.fi/~zarhan - zarhan(a)cs.tut.fi - There is a theory which states that if ever anyone discovers exactly what the Universe is for and why it is here,it will instantly disappear and be replaced by something even more bizarre and inexplicable.