Help debugging Fotowire

Antti Mäkelä zarhan at cs.tut.fi
Sat Jan 15 06:34:30 CST 2005


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 at 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.




More information about the wine-devel mailing list