[Pidgin] #8324: Buddies are missing on Startup

Pidgin trac at pidgin.im
Mon Feb 2 11:43:35 EST 2009


#8324: Buddies are missing on Startup
----------------------------------------+-----------------------------------
 Reporter:  tazenman                    |           Owner:  lschiere
     Type:  defect                      |          Status:  pending 
Milestone:                              |       Component:  AIM     
  Version:  2.5.4                       |      Resolution:          
 Keywords:  Missing Buddies upon start  |   Launchpad_bug:          
----------------------------------------+-----------------------------------
Changes (by datallah):

  * status:  new => pending
  * component:  unclassified => AIM


Old description:

> I am running Pidgin 2.5.4 on an Acer laptop running XP Pro SP3. When I
> start Pidgin all my Buddies are missing and my groups are empty. I have
> to Disabel my account and Enable it for my Buddies to show up in the
> list.
>
> I have the debug window open and started Pidgin to create a debug.log
> file. I started Pidgin, Buddies are missing, disabled my account, enabled
> my account, and my Buddies list appeared. I will paste the debug window
> contents here and attach the log file because they seem to have different
> information.
>
> Begin Paste:
> (07:32:51) account: Network not connected; skipping reconnect
> (07:32:51) prefs: /pidgin/blist/width changed, scheduling save.
> (07:32:51) prefs: /pidgin/blist/height changed, scheduling save.
> (07:32:51) prefs: /pidgin/blist/width changed, scheduling save.
> (07:32:51) prefs: /pidgin/blist/height changed, scheduling save.
> (07:32:52) util: requested to fetch
> (http://192.168.10.1:80/devdescr.xml), full=1, user_agent=((null)),
> http11=1
> (07:32:52) dnsquery: Performing DNS lookup for 192.168.10.1
> (07:32:52) dnsquery: IP resolved for 192.168.10.1
> (07:32:52) proxy: Attempting connection to 192.168.10.1
> (07:32:52) proxy: Connecting to 192.168.10.1:80 with no proxy
> (07:32:52) proxy: Connection in progress
> (07:32:52) proxy: Connecting to 192.168.10.1:80.
> (07:32:52) util: Request: 'GET /devdescr.xml HTTP/1.1
>
> Connection: close
>
> Host: 192.168.10.1:80
>

>
> '
> (07:32:52) util: Response headers: 'HTTP/1.1 200 OK
>
> Content-Type: text/xml
>
> Cache-Control: no-cache
>
> Expires: Thu, 26 Oct 1995 00:00:00 GMT
>
> Content-Length: 5785
>
> Server: Allegro-Software-RomPager/4.03
>
> Connection: close
>

>
> '
> (07:32:52) util: parsed 5785
> (07:32:52) util: requested to fetch (http://192.168.10.1:80/wpppc_cont),
> full=0, user_agent=((null)), http11=1
> (07:32:52) dnsquery: Performing DNS lookup for 192.168.10.1
> (07:32:52) dnsquery: Performing DNS lookup for 192.168.10.1
> (07:32:52) dnsquery: IP resolved for 192.168.10.1
> (07:32:52) proxy: Attempting connection to 192.168.10.1
> (07:32:52) proxy: Connecting to 192.168.10.1:80 with no proxy
> (07:32:52) proxy: Connection in progress
> (07:32:52) dnsquery: IP resolved for 192.168.10.1
> (07:32:52) proxy: Attempting connection to 192.168.10.1
> (07:32:52) proxy: Connecting to 192.168.10.1:80 with no proxy
> (07:32:52) proxy: Connection in progress
> (07:32:52) proxy: Connecting to 192.168.10.1:80.
> (07:32:52) util: Request: 'POST /wpppc_cont HTTP/1.1
>
> HOST: 192.168.10.1:80
>
> SOAPACTION: "urn:schemas-upnp-
> org:service:WANPPPConnection:1#GetExternalIPAddress"
>
> CONTENT-TYPE: text/xml ; charset="utf-8"
>
> CONTENT-LENGTH: 311
>

>
> <?xml version="1.0" encoding="utf-8"?>
>
> <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"
> s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
>
> <s:Body>
>
> <u:GetExternalIPAddress xmlns:u="urn:schemas-upnp-
> org:service:WANPPPConnection:1">
>
> </u:GetExternalIPAddress>
>
> </s:Body>
>
> </s:Envelope>'
> (07:32:52) proxy: Connecting to 192.168.10.1:80.
> (07:32:52) upnp: Local IP: 192.168.10.90
> (07:32:52) util: Response headers: 'HTTP/1.1 500 Internal Server Error
>
> Content-Length: 612
>
> Content-Type: text/xml
>
> Date: Tue, 08 Jan 2002 23:53:01 GMT
>
> EXT:
>
> Server:NetopiaOS/1.0 UPnP/1.0 4686XL/8.6.1r4
>

>
> '
> (07:32:52) util: parsed 612
> (07:32:56) util: Writing file prefs.xml to directory C:\Documents and
> Settings\James F Whalen II\Application Data\.purple
> (07:32:56) util: Writing file C:\Documents and Settings\James F Whalen
> II\Application Data\.purple\prefs.xml
> (07:32:56) util: Writing file accounts.xml to directory C:\Documents and
> Settings\James F Whalen II\Application Data\.purple
> (07:32:56) util: Writing file C:\Documents and Settings\James F Whalen
> II\Application Data\.purple\accounts.xml
> (07:32:56) util: Writing file blist.xml to directory C:\Documents and
> Settings\James F Whalen II\Application Data\.purple
> (07:32:56) util: Writing file C:\Documents and Settings\James F Whalen
> II\Application Data\.purple\blist.xml
> (07:41:57) util: Writing file accounts.xml to directory C:\Documents and
> Settings\James F Whalen II\Application Data\.purple
> (07:41:57) util: Writing file C:\Documents and Settings\James F Whalen
> II\Application Data\.purple\accounts.xml
> (07:42:03) account: Connecting to account jimwhalen1926
> (07:42:03) connection: Connecting. gc = 01824A28
> (07:42:03) oscar: registered module misc (family 0xffff, version =
> 0x0000, tool 0x0000, tool version 0x0000)
> (07:42:03) oscar: registered module oservice (family 0x0001, version =
> 0x0003, tool 0x0110, tool version 0x0629)
> (07:42:03) oscar: registered module locate (family 0x0002, version =
> 0x0001, tool 0x0110, tool version 0x0629)
> (07:42:03) oscar: registered module buddy (family 0x0003, version =
> 0x0001, tool 0x0110, tool version 0x0629)
> (07:42:03) oscar: registered module messaging (family 0x0004, version =
> 0x0001, tool 0x0110, tool version 0x0629)
> (07:42:03) oscar: registered module admin (family 0x0007, version =
> 0x0001, tool 0x0010, tool version 0x0629)
> (07:42:03) oscar: registered module popup (family 0x0008, version =
> 0x0001, tool 0x0104, tool version 0x0001)
> (07:42:03) oscar: registered module bos (family 0x0009, version = 0x0001,
> tool 0x0110, tool version 0x0629)
> (07:42:03) oscar: registered module userlookup (family 0x000a, version =
> 0x0001, tool 0x0110, tool version 0x0629)
> (07:42:03) oscar: registered module stats (family 0x000b, version =
> 0x0001, tool 0x0104, tool version 0x0001)
> (07:42:03) oscar: registered module chatnav (family 0x000d, version =
> 0x0001, tool 0x0010, tool version 0x0629)
> (07:42:03) oscar: registered module chat (family 0x000e, version =
> 0x0001, tool 0x0010, tool version 0x0629)
> (07:42:03) oscar: registered module odir (family 0x000f, version =
> 0x0001, tool 0x0010, tool version 0x0629)
> (07:42:03) oscar: registered module bart (family 0x0010, version =
> 0x0001, tool 0x0010, tool version 0x0629)
> (07:42:03) oscar: registered module feedbag (family 0x0013, version =
> 0x0004, tool 0x0110, tool version 0x0629)
> (07:42:03) oscar: registered module icq (family 0x0015, version = 0x0001,
> tool 0x0110, tool version 0x047c)
> (07:42:03) oscar: registered module auth (family 0x0017, version =
> 0x0000, tool 0x0000, tool version 0x0000)
> (07:42:03) oscar: registered module alert (family 0x0018, version =
> 0x0001, tool 0x0010, tool version 0x0629)
> (07:42:03) oscar: Adding handler for ffff/0003
> (07:42:03) oscar: Adding handler for ffff/0006
> (07:42:03) oscar: Adding handler for 0007/0003
> (07:42:03) oscar: Adding handler for 0007/0005
> (07:42:03) oscar: Adding handler for 0007/0007
> (07:42:03) oscar: Adding handler for 0018/0001
> (07:42:03) oscar: Adding handler for 0018/0007
> (07:42:03) oscar: Adding handler for 0017/0003
> (07:42:03) oscar: Adding handler for 0017/0007
> (07:42:03) oscar: Adding handler for 0017/000a
> (07:42:03) oscar: Adding handler for 0010/0005
> (07:42:03) oscar: Adding handler for 0009/0001
> (07:42:03) oscar: Adding handler for 0009/0003
> (07:42:03) oscar: Adding handler for 0003/0001
> (07:42:03) oscar: Adding handler for 0003/0003
> (07:42:03) oscar: Adding handler for 0003/000b
> (07:42:03) oscar: Adding handler for 0003/000c
> (07:42:03) oscar: Adding handler for 000e/0001
> (07:42:03) oscar: Adding handler for 000e/0003
> (07:42:03) oscar: Adding handler for 000e/0004
> (07:42:03) oscar: Adding handler for 000e/0002
> (07:42:03) oscar: Adding handler for 000e/0006
> (07:42:03) oscar: Adding handler for 000d/0001
> (07:42:03) oscar: Adding handler for 000d/0009
> (07:42:03) oscar: Adding handler for 0013/0001
> (07:42:03) oscar: Adding handler for 0013/0003
> (07:42:03) oscar: Adding handler for 0013/0006
> (07:42:03) oscar: Adding handler for 0013/000e
> (07:42:03) oscar: Adding handler for 0013/0008
> (07:42:03) oscar: Adding handler for 0013/0009
> (07:42:03) oscar: Adding handler for 0013/0015
> (07:42:03) oscar: Adding handler for 0013/0019
> (07:42:03) oscar: Adding handler for 0013/001b
> (07:42:03) oscar: Adding handler for 0013/001c
> (07:42:03) oscar: Adding handler for 0004/0007
> (07:42:03) oscar: Adding handler for 0004/000a
> (07:42:03) oscar: Adding handler for 0004/000b
> (07:42:03) oscar: Adding handler for 0004/0001
> (07:42:03) oscar: Adding handler for 0004/0014
> (07:42:03) oscar: Adding handler for 0004/000c
> (07:42:03) oscar: Adding handler for 0015/00f3
> (07:42:03) oscar: Adding handler for 0015/00f2
> (07:42:03) oscar: Adding handler for 0002/0003
> (07:42:03) oscar: Adding handler for 0002/0006
> (07:42:03) oscar: Adding handler for 0002/0001
> (07:42:03) oscar: Adding handler for 0002/fffd
> (07:42:03) oscar: Adding handler for 0001/0001
> (07:42:03) oscar: Adding handler for 0001/000f
> (07:42:03) oscar: Adding handler for 0001/001f
> (07:42:03) oscar: Adding handler for 0001/0021
> (07:42:03) oscar: Adding handler for 0001/000a
> (07:42:03) oscar: Adding handler for 0001/0005
> (07:42:03) oscar: Adding handler for 0001/0013
> (07:42:03) oscar: Adding handler for 0001/0010
> (07:42:03) oscar: Adding handler for 0008/0002
> (07:42:03) oscar: Adding handler for 000a/0001
> (07:42:03) oscar: Adding handler for 000a/0003
> (07:42:03) oscar: oscar_login: gc = 01824A28
> (07:42:03) wpurple: winhttp.dll not already loaded; loading it...
> (07:42:03) wpurple: This version of winhttp.dll contains
> WinHttpGetIEProxyConfigForCurrentUser
> (07:42:03) proxy: No Windows proxy set.
> (07:42:03) dnsquery: Performing DNS lookup for login.messaging.aol.com
> (07:42:03) dnsquery: IP resolved for login.messaging.aol.com
> (07:42:03) proxy: Attempting connection to 64.12.200.89
> (07:42:03) proxy: Connecting to login.messaging.aol.com:5190 with no
> proxy
> (07:42:03) proxy: Connection in progress
> (07:42:03) proxy: Connecting to login.messaging.aol.com:5190.
> (07:42:03) oscar: connected to FLAP server of type 0x0017
> (07:42:03) oscar: Username sent, waiting for response
> (07:42:03) oscar: inside auth_resp (Username: jimwhalen1926)
> (07:42:03) oscar: Reg status: 3
> Email: sales at parrishent.net
> BOSIP: 205.188.8.113:5190
> (07:42:03) oscar: Closing auth connection...
> (07:42:03) oscar: Scheduling destruction of FLAP connection of type
> 0x0017
> (07:42:03) proxy: No Windows proxy set.
> (07:42:03) dnsquery: Performing DNS lookup for 205.188.8.113
> (07:42:03) oscar: Destroying oscar connection of type 0x0017.  Disconnect
> reason is 0
> (07:42:03) oscar: Disconnected.  Code is 0x0000 and msg is
> (07:42:03) dnsquery: IP resolved for 205.188.8.113
> (07:42:03) proxy: Attempting connection to 205.188.8.113
> (07:42:03) proxy: Connecting to 205.188.8.113:5190 with no proxy
> (07:42:03) proxy: Connection in progress
> (07:42:03) proxy: Connecting to 205.188.8.113:5190.
> (07:42:03) oscar: connected to FLAP server of type 0x0002
> (07:42:03) oscar: MOTD: Unknown (5)
> (07:42:03) oscar: FLAP connection of type 0x0002 is now fully connected
> (07:42:03) oscar: ssi: requesting rights and list
> (07:42:03) oscar: locate rights: max sig len = 4096
> (07:42:03) oscar: buddy list rights: Max buddies = 1000 / Max watchers =
> 2000
> (07:42:03) oscar: BOS rights: Max permit = 1000 / Max deny = 1000
> (07:42:03) connection: Activating keepalive.
> (07:42:03) oscar: buddy list loaded
> (07:42:03) oscar: ssi rights: max type 0x0000=3000, max type 0x0001=61,
> max type 0x0002=1000, max type 0x0003=1000, max type 0x0004=1, max type
> 0x0005=1, max type 0x0006=150, max type 0x0007=12, max type 0x0008=12,
> max type 0x0009=3, max type 0x000a=50, max type 0x000b=50, max type
> 0x000c=0, max type 0x000d=0, max type 0x000e=0, max type 0x000f=0, max
> type 0x0010=0, max type 0x0011=1, max type 0x0012=0, max type 0x0013=0,
> max type 0x0014=15, max type 0x0015=1, max type 0x0016=40, max type
> 0x0017=1, max type 0x0018=10, max type 0x0019=200, max type 0x001a=1, max
> type 0x001b=0, max type 0x001c=200, max type 0x001d=1, max type 0x001e=8,
> max type 0x001f=20, max type 0x0020=0, max type 0x0021=10000, max type
> 0x0022=1000, max type 0x0023=1000, max type 0x0024=50,
> (07:42:03) oscar: ssi: syncing local list and server list
> (07:42:03) oscar: ssi: activating server-stored buddy list
> (07:42:03) blist: Updating buddy status for rhill53 (AIM)
> (07:42:03) blist: Updating buddy status for CasaDL (AIM)
> (07:42:03) blist: Updating buddy status for CasaDL (AIM)
> (07:42:03) blist: Updating buddy status for fepelayo (AIM)
> (07:42:03) blist: Updating buddy status for ianpsi (AIM)
> (07:42:03) blist: Updating buddy status for MegNalisMOM (AIM)
> (07:42:03) blist: Updating buddy status for MegNalisMOM (AIM)
> (07:42:03) oscar: Connecting to FLAP server 64.12.165.68:5190 of type
> 0x0018
>
> (07:42:03) proxy: No Windows proxy set.
> (07:42:03) dnsquery: Performing DNS lookup for 64.12.165.68
> (07:42:03) oscar: Connecting to FLAP server 64.12.200.200:5190 of type
> 0x000d
> (07:42:03) proxy: No Windows proxy set.
> (07:42:03) dnsquery: Performing DNS lookup for 64.12.200.200
> (07:42:03) dnsquery: IP resolved for 64.12.165.68
> (07:42:03) proxy: Attempting connection to 64.12.165.68
> (07:42:03) proxy: Connecting to 64.12.165.68:5190 with no proxy
> (07:42:03) proxy: Connection in progress
> (07:42:03) dnsquery: IP resolved for 64.12.200.200
> (07:42:03) proxy: Attempting connection to 64.12.200.200
> (07:42:03) proxy: Connecting to 64.12.200.200:5190 with no proxy
> (07:42:03) proxy: Connection in progress
> (07:42:04) proxy: Connecting to 64.12.165.68:5190.
> (07:42:04) oscar: connected to FLAP server of type 0x0018
> (07:42:04) proxy: Connecting to 64.12.200.200:5190.
> (07:42:04) oscar: connected to FLAP server of type 0x000d
> (07:42:04) oscar: Connecting to FLAP server 205.188.13.20:5190 of type
> 0x0010
> (07:42:04) proxy: No Windows proxy set.
> (07:42:04) dnsquery: Performing DNS lookup for 205.188.13.20
> (07:42:04) dnsquery: IP resolved for 205.188.13.20
> (07:42:04) proxy: Attempting connection to 205.188.13.20
> (07:42:04) proxy: Connecting to 205.188.13.20:5190 with no proxy
> (07:42:04) proxy: Connection in progress
> (07:42:04) proxy: Connecting to 205.188.13.20:5190.
> (07:42:04) oscar: connected to FLAP server of type 0x0010
> (07:42:04) blist: Updating buddy status for rhill53 (AIM)
> (07:42:04) blist: Updating buddy status for ianpsi (AIM)
> (07:42:04) blist: Updating buddy status for MegNalisMOM (AIM)
> (07:42:04) blist: Updating buddy status for MegNalisMOM (AIM)
> (07:42:04) blist: Updating buddy status for fepelayo (AIM)
> (07:42:04) blist: Updating buddy status for CasaDL (AIM)
> (07:42:04) blist: Updating buddy status for CasaDL (AIM)
> (07:42:04) oscar: FLAP connection of type 0x0018 is now fully connected
> (07:42:04) oscar: FLAP connection of type 0x000d is now fully connected
> (07:42:04) oscar: chat info: Chat Rights:
> (07:42:04) oscar: chat info:    Max Concurrent Rooms: 17
> (07:42:04) oscar: chat info:    Exchange List: (15 total)
> (07:42:04) oscar: chat info:            2
> (07:42:04) oscar: chat info:            4
> (07:42:04) oscar: chat info:            5
> (07:42:04) oscar: chat info:            6
> (07:42:04) oscar: chat info:            7
> (07:42:04) oscar: chat info:            8
> (07:42:04) oscar: chat info:            9
> (07:42:04) oscar: chat info:            10
> (07:42:04) oscar: chat info:            11
> (07:42:04) oscar: chat info:            12
> (07:42:04) oscar: chat info:            13
> (07:42:04) oscar: chat info:            14
> (07:42:04) oscar: chat info:            15
> (07:42:04) oscar: chat info:            16
> (07:42:04) oscar: chat info:            20
> (07:42:04) oscar: FLAP connection of type 0x0010 is now fully connected
> (07:42:04) oscar: no more icons to request
> (07:42:08) util: Writing file accounts.xml to directory C:\Documents and
> Settings\James F Whalen II\Application Data\.purple
> (07:42:08) util: Writing file C:\Documents and Settings\James F Whalen
> II\Application Data\.purple\accounts.xml
> (07:42:08) util: Writing file blist.xml to directory C:\Documents and
> Settings\James F Whalen II\Application Data\.purple
> (07:42:08) util: Writing file C:\Documents and Settings\James F Whalen
> II\Application Data\.purple\blist.xml
> (07:42:49) oscar: rate limit cleared (param ID 0x0003): curavg = 4500,
> maxavg = 4500, alert at 2500, clear warning at 3100, limit at 2000,
> disconnect at 1500 (window size = 20)
>
> End Paste:

New description:

 I am running Pidgin 2.5.4 on an Acer laptop running XP Pro SP3. When I
 start Pidgin all my Buddies are missing and my groups are empty. I have to
 Disabel my account and Enable it for my Buddies to show up in the list.

 I have the debug window open and started Pidgin to create a debug.log
 file. I started Pidgin, Buddies are missing, disabled my account, enabled
 my account, and my Buddies list appeared. I will paste the debug window
 contents here and attach the log file because they seem to have different
 information.

 <snip>Removed debug log as it is the same as the attached log</snip

--

Comment:

 From the attached debug log, there are several AIM buddies that are on
 your buddy list.

 Are they offline?  Are you using the "Show when Offline" functionality to
 make the appear in this situation (there is a known bug with that)?

-- 
Ticket URL: <http://developer.pidgin.im/ticket/8324#comment:4>
Pidgin <http://pidgin.im>
Pidgin


More information about the Tracker mailing list