Adium

Ticket #4457 (closed defect: invalid)

Opened 3 years ago

Last modified 2 years ago

AIM connection drops after minimal usage.

Reported by: anonymous Assigned to: durin42
Priority: highest Milestone:
Component: adium-joscar Version: 1.0b8
Severity: critical Keywords: aim, connection, log in, log out
Cc: leak Patch:
Pending:

Description (Last modified by tick)

We know about this issue, you do not need to say you have the problem as well. You can however add yourself to the cc list if you would like to be updated on this issue

upon upgrading to adium 1.0b2 from .89, AIM cannot maintain a connection and continues to log in and log out until the server blocks connection from too many attempts. when it does finally log in, buddy info and away status is not available. i am using an ibook g4 with 10.4.7, no internet connection changes have been made, i am hardwired to a router via ethernet cable--cxn is shared between two computers. have been forced to revert to .89 for the program to be usable. ichat is able to maintain connection.

Attachments

2006-07-12.log (55.1 kB) - added by mgarrison@alienz.net on 07/12/2006 12:50:12 PM.
Adium Debug
2006-07-12.2.log (205.7 kB) - added by mgarrison@alienz.net on 07/12/2006 12:54:11 PM.
Joscar Debug
adiumdebug.rtf (5.0 kB) - added by jdelsman@voxxit.com on 07/23/2006 10:02:19 PM.
Adium Debug Log for 1.0b7

Change History

(follow-up: ↓ 52 ) 07/08/2006 02:48:57 PM changed by anonymous

is this not very important? are other people not having this problem? i honestly can connect for 2 or 3 minutes then the connection is irreparable. i really want to use this, but cant! and now am forced to use ichat for the next couple of minutes until im able to log in again! :(

07/08/2006 07:01:37 PM changed by evands

Post your debug log of being disconnected. Both Adium Debug Log and joscar Debug Log.

07/10/2006 10:56:34 AM changed by anonymous

I'm still having this problem with b4, I will post logs next time it happens

07/10/2006 03:32:55 PM changed by anonymous

Adium debug log: 

15:26:30: <RAFjoscarAIMAccount: 0xb9a100>:12:cpk0: Updating status for Online
15:26:30: +++ Connecting <RAFjoscarAIMAccount: 0xb9a100>:12:cpk0 via <ESjoscarCocoaAdapter: 0xb67130>
15:26:30: *** net.kano.joustsim.oscar.DefaultAppSession@2af081 connecting cpk0 with Java bridge net.adium.joscarBridge.joscarBridge@93c0cf ***
15:26:30: <RAFjoscarAIMAccount: 0xb9a100>:12:cpk0: State changed to CONNECTINGAUTH ((null) - (null))
15:26:31: <RAFjoscarAIMAccount: 0xb9a100>:12:cpk0: State changed to AUTHORIZING ((null) - (null))
15:26:31: <RAFjoscarAIMAccount: 0xb9a100>:12:cpk0: State changed to CONNECTING ((null) - (null))
15:26:31: <RAFjoscarAIMAccount: 0xb9a100>:12:cpk0: State changed to SIGNINGON ((null) - (null))
15:26:31: <RAFjoscarAIMAccount: 0xb9a100>:12:cpk0: State changed to DISCONNECTED (ConnectionLost - (null))
.
.
.
15:26:31: AIMetaContact: Remote grouping of <AIListContact: 0xb343a90>:AIM.every_screenname_online_in_buddy_list changed to (null)
.
.
.
15:26:31: Error message short is ConnectionLost; code (null)
15:26:35: <RAFjoscarAIMAccount: 0xb9a100>:12:cpk0: Updating status for Enabled
15:26:35: <RAFjoscarAIMAccount: 0xb9a100>:12:cpk0: Updating status for Online
15:26:35: <RAFjoscarAIMAccount: 0xb9a100>:12:cpk0: Updating status for FullNameAttr

Joscar debug: 

[3:26:26 PM EDT] ConnectionManager: New state: DISCONNECTED - net.kano.joustsim.oscar.DisconnectedStateInfo@fb6e9b
[3:26:27 PM EDT] BuddyInfoTracker$TrackingThread: Shutting down buddy tracker thread for net.kano.joustsim.oscar.AimConnection@f1c25
[3:26:30 PM EDT] AimConnection: Warning: this AIM connection's certificate and signer managers will not be set because the trust manager is null
[3:26:30 PM EDT] TrustedCertificatesTracker: Warning: Trusted certificates tracker will not track explicitly trusted certificates since the certificate trust manager is null
[3:26:30 PM EDT] TrustedCertificatesTracker: Warning: Trusted certificates tracker will not track signer-trusted certificates since the signer trust manager is null
[3:26:30 PM EDT] SecurityEnabledHandler: Warning: Key manager for SecurityEnabledHandler will not be set because the AIM session's trust preferences are null
[3:26:30 PM EDT] ExternalServiceManager: Creating arbiter for service 13
[3:26:30 PM EDT] ExternalServiceManager: Created arbiter for service 13: net.kano.joustsim.oscar.oscar.service.chatrooms.RoomFinderServiceArbiter@85ee39
[3:26:30 PM EDT] AbstractService: Created new net.kano.joustsim.oscar.oscar.service.login.LoginService
[3:26:30 PM EDT] AbstractService: LoginService is ready
[3:26:30 PM EDT] LoginService: Using SecurID provider net.adium.joscarBridge.joscarBridge@93c0cf
[3:26:30 PM EDT] OscarConnection: OscarConnection to login.oscar.aol.com trying to connect...
[3:26:30 PM EDT] ConnectionManager: New state: CONNECTINGAUTH - net.kano.joustsim.oscar.AuthorizingStateInfo@ad5c84
[3:26:31 PM EDT] OscarConnection: Connected to login.oscar.aol.com
[3:26:31 PM EDT] LoginService: Sending key request on net.kano.joustsim.oscar.oscar.service.login.LoginService@7b1ee2
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=1, seq=1): 10 total bytes
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #1: KeyRequest for screenname cpk0
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for KeyRequest for screenname cpk0: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=2): 32 total bytes
[3:26:31 PM EDT] ConnectionManager: New state: AUTHORIZING - net.kano.joustsim.oscar.AuthorizingStateInfo@9bfe76
[3:26:31 PM EDT] FlapProcessor: FlapProcessor received packet: FlapPacket (channel=1, seq=5398)
[3:26:31 PM EDT] FlapProcessor: Flap command for FlapPacket (channel=1, seq=5398): LoginFlapCmd: version=1, cookie=null
[3:26:31 PM EDT] FlapProcessor: FlapProcessor received packet: FlapPacket (channel=2, seq=5399)
[3:26:31 PM EDT] FlapProcessor: Flap command for FlapPacket (channel=2, seq=5399): SnacFlapCmd: packet=SnacPacket type 0x17/0x7: 12 bytes (id=1)
[3:26:31 PM EDT] AbstractSnacProcessor: Converted Snac packet SnacPacket type 0x17/0x7: 12 bytes (id=1) to KeyResponse: key=4139646568
[3:26:31 PM EDT] LoginService: Sending authorization request
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #2: AuthRequest: sn='cpk0', version='ClientVersionInfo: , versionString='Apple iChat', clientid=12570, major=1, minor=0, point=0, build=388, distCode=198', locale=en_US
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for AuthRequest: sn='cpk0', version='ClientVersionInfo: , versionString='Apple iChat', clientid=12570, major=1, minor=0, point=0, build=388, distCode=198', locale=en_US: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=3): 118 total bytes
[3:26:31 PM EDT] FlapProcessor: FlapProcessor received packet: FlapPacket (channel=2, seq=5400)
[3:26:31 PM EDT] FlapProcessor: Flap command for FlapPacket (channel=2, seq=5400): SnacFlapCmd: packet=SnacPacket type 0x17/0x3: 403 bytes (id=2)
[3:26:31 PM EDT] AbstractSnacProcessor: Converted Snac packet SnacPacket type 0x17/0x3: 403 bytes (id=2) to AuthResponse: sn='cpk0', server='64.12.162.85', port=5190, regStatus=0x3 (REGSTATUS_FULL), email='cpk0@mac.com'
[3:26:31 PM EDT] LoginService: Login process succeeded: net.kano.joustsim.oscar.oscar.loginstatus.LoginSuccessInfo@65922d
[3:26:31 PM EDT] AbstractService: LoginService is finished
[3:26:31 PM EDT] OscarConnection: Disconnected from login.oscar.aol.com
[3:26:31 PM EDT] AbstractService: LoginService is finished
[3:26:31 PM EDT] OscarConnection: OscarConnection to 64.12.162.85 trying to connect...
[3:26:31 PM EDT] ConnectionManager: New state: CONNECTING - net.kano.joustsim.oscar.ConnectingStateInfo@2e3591
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=1, seq=1): 270 total bytes
[3:26:31 PM EDT] OscarConnection: Connected to 64.12.162.85
[3:26:31 PM EDT] ConnectionManager: New state: SIGNINGON - net.kano.joustsim.oscar.SigningOnStateInfo@a639f
[3:26:31 PM EDT] FlapProcessor: FlapProcessor received packet: FlapPacket (channel=1, seq=8585)
[3:26:31 PM EDT] FlapProcessor: Flap command for FlapPacket (channel=1, seq=8585): LoginFlapCmd: version=1, cookie=null
[3:26:31 PM EDT] FlapProcessor: FlapProcessor received packet: FlapPacket (channel=2, seq=8586)
[3:26:31 PM EDT] FlapProcessor: Flap command for FlapPacket (channel=2, seq=8586): SnacFlapCmd: packet=SnacPacket type 0x1/0x3: 26 bytes (id=3166111618)
[3:26:31 PM EDT] AbstractSnacProcessor: Converted Snac packet SnacPacket type 0x1/0x3: 26 bytes (id=3166111618) to ServerReadyCmd: snac families: 1, 2, 3, 4, 6, 8, 9, a, b, c, 13, 15, 22
[3:26:31 PM EDT] AbstractService: Created new net.kano.joustsim.oscar.oscar.service.bos.MainBosServiceImpl
[3:26:31 PM EDT] AbstractService: Created new net.kano.joustsim.oscar.oscar.service.info.InfoServiceImpl
[3:26:31 PM EDT] AbstractService: Created new net.kano.joustsim.oscar.oscar.service.buddy.BuddyServiceImpl
[3:26:31 PM EDT] AbstractService: Created new net.kano.joustsim.oscar.oscar.service.icbm.IcbmServiceImpl
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #1: SetInfoCmd: info=InfoData:  capabilities: 5
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for SetInfoCmd: info=InfoData:  capabilities: 5: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=2): 100 total bytes
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #2: SetInfoCmd: info=InfoData:  capabilities: 6
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for SetInfoCmd: info=InfoData:  capabilities: 6: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=3): 116 total bytes
[3:26:31 PM EDT] ConnectionManager$BasicServiceFactory: No service for family 0x6
[3:26:31 PM EDT] OscarConnection: No service for family 0x6
[3:26:31 PM EDT] ConnectionManager$BasicServiceFactory: No service for family 0x8
[3:26:31 PM EDT] OscarConnection: No service for family 0x8
[3:26:31 PM EDT] ConnectionManager$BasicServiceFactory: No service for family 0x9
[3:26:31 PM EDT] OscarConnection: No service for family 0x9
[3:26:31 PM EDT] ConnectionManager$BasicServiceFactory: No service for family 0xa
[3:26:31 PM EDT] OscarConnection: No service for family 0xa
[3:26:31 PM EDT] ConnectionManager$BasicServiceFactory: No service for family 0xb
[3:26:31 PM EDT] OscarConnection: No service for family 0xb
[3:26:31 PM EDT] ConnectionManager$BasicServiceFactory: No service for family 0xc
[3:26:31 PM EDT] OscarConnection: No service for family 0xc
[3:26:31 PM EDT] AbstractService: Created new net.kano.joustsim.oscar.oscar.service.ssi.SsiServiceImpl
[3:26:31 PM EDT] ConnectionManager$BasicServiceFactory: No service for family 0x15
[3:26:31 PM EDT] OscarConnection: No service for family 0x15
[3:26:31 PM EDT] ConnectionManager$BasicServiceFactory: No service for family 0x22
[3:26:31 PM EDT] OscarConnection: No service for family 0x22
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #3: SetInfoCmd: info=InfoData:  capabilities: 6
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for SetInfoCmd: info=InfoData:  capabilities: 6: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=4): 116 total bytes
[3:26:31 PM EDT] AbstractService: InfoServiceImpl is ready
[3:26:31 PM EDT] OscarConnection: net.kano.joustsim.oscar.oscar.service.info.InfoServiceImpl is ready, waiting for 4: [net.kano.joustsim.oscar.oscar.service.ssi.SsiServiceImpl@a515c3, net.kano.joustsim.oscar.oscar.service.bos.MainBosServiceImpl@eae7d9, net.kano.joustsim.oscar.oscar.service.icbm.IcbmServiceImpl@3e00c7, net.kano.joustsim.oscar.oscar.service.buddy.BuddyServiceImpl@e5aee7]
[3:26:31 PM EDT] AbstractService: BuddyServiceImpl is ready
[3:26:31 PM EDT] OscarConnection: net.kano.joustsim.oscar.oscar.service.buddy.BuddyServiceImpl is ready, waiting for 3: [net.kano.joustsim.oscar.oscar.service.ssi.SsiServiceImpl@a515c3, net.kano.joustsim.oscar.oscar.service.bos.MainBosServiceImpl@eae7d9, net.kano.joustsim.oscar.oscar.service.icbm.IcbmServiceImpl@3e00c7]
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #4: ParamInfoRequest
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for ParamInfoRequest: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=5): 16 total bytes
[3:26:31 PM EDT] AimConnection: Could not find service handler for family 0x6
[3:26:31 PM EDT] AimConnection: Could not find service handler for family 0x8
[3:26:31 PM EDT] AimConnection: Could not find service handler for family 0x9
[3:26:31 PM EDT] AimConnection: Could not find service handler for family 0xa
[3:26:31 PM EDT] AimConnection: Could not find service handler for family 0xb
[3:26:31 PM EDT] AimConnection: Could not find service handler for family 0xc
[3:26:31 PM EDT] AimConnection: Could not find service handler for family 0x15
[3:26:31 PM EDT] AimConnection: Could not find service handler for family 0x22
[3:26:31 PM EDT] AbstractBosService: Server is ready
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #5: ClientVersionsCmd: family versions: 1 (v=4), 2 (v=1), 3 (v=1), 4 (v=1), 13 (v=3), 
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for ClientVersionsCmd: family versions: 1 (v=4), 2 (v=1), 3 (v=1), 4 (v=1), 13 (v=3), : listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=6): 36 total bytes
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #6: RateInfoRequest
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for RateInfoRequest: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=7): 16 total bytes
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #7: SsiRightsRequest
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for SsiRightsRequest: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=8): 16 total bytes
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #8: SsiDataRequest
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for SsiDataRequest: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=9): 16 total bytes
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #9: SetEncryptionInfoCmd: blocks=[ExtraInfoBlock: type=0x402 (TYPE_CERTINFO_HASHA), extraData=<ExtraInfoData: flags=0x1 (FLAG_HASH_PRESENT), data=d4 1d 8c d9 8f 00 b2 04 e9 80 09 98 ec f8 42 7e>, ExtraInfoBlock: type=0x403 (TYPE_CERTINFO_HASHB), extraData=<ExtraInfoData: flags=0x1 (FLAG_HASH_PRESENT), data=d4 1d 8c d9 8f 00 b2 04 e9 80 09 98 ec f8 42 7e>]
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for SetEncryptionInfoCmd: blocks=[ExtraInfoBlock: type=0x402 (TYPE_CERTINFO_HASHA), extraData=<ExtraInfoData: flags=0x1 (FLAG_HASH_PRESENT), data=d4 1d 8c d9 8f 00 b2 04 e9 80 09 98 ec f8 42 7e>, ExtraInfoBlock: type=0x403 (TYPE_CERTINFO_HASHB), extraData=<ExtraInfoData: flags=0x1 (FLAG_HASH_PRESENT), data=d4 1d 8c d9 8f 00 b2 04 e9 80 09 98 ec f8 42 7e>]: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=10): 56 total bytes
[3:26:31 PM EDT] ClientSnacProcessor: Queueing Snac request #10: MyInfoRequest
[3:26:31 PM EDT] ClientSnacProcessor: Sending SNAC request SnacRequest for MyInfoRequest: listeners: null, responses: null
[3:26:31 PM EDT] FlapProcessor: Sending Flap packet FlapPacket (channel=2, seq=11): 16 total bytes
[3:26:31 PM EDT] FlapProcessor: FlapProcessor received packet: FlapPacket (channel=2, seq=8587)
[3:26:31 PM EDT] FlapProcessor: Flap command for FlapPacket (channel=2, seq=8587): SnacFlapCmd: packet=SnacPacket type 0x1/0xa, flag1=0x80: 42 bytes (id=3166111647)
[3:26:31 PM EDT] AbstractSnacProcessor: Converted Snac packet SnacPacket type 0x1/0xa, flag1=0x80: 32 bytes (id=3166111647) to RateChange (code=3): null
[3:26:31 PM EDT] OscarConnection: Disconnected from 64.12.162.85
[3:26:31 PM EDT] AbstractService: MainBosServiceImpl is finished
[3:26:31 PM EDT] AbstractService: InfoServiceImpl is finished
[3:26:31 PM EDT] AbstractService: BuddyServiceImpl is finished
[3:26:31 PM EDT] AbstractService: IcbmServiceImpl is finished
[3:26:31 PM EDT] AbstractService: SsiServiceImpl is finished

07/10/2006 04:05:25 PM changed by BenGrimsbo@mac.com

I am also getting a spontaneous disconnect using beta 4. It worked fine for a couple of hours.

07/11/2006 10:22:36 AM changed by alien88

I also have the same problem with being disconnected then not being able to log in because AIM says i'm logging in too fast. In addition, when disconnected the client still tries to request the buddy icons for all of my AIM contacts.

(follow-up: ↓ 9 ) 07/12/2006 10:12:37 AM changed by blufire

Also having the disconnection bug, though it's not frequent enough to cause AIM to block me. It's quite annoying though, because I'll be talking with someone, and then they won't respond for a long time (maybe 10 minutes) before the contact list disappears and then reappears, indicating the reconnection. Good to see it's in "critical" and "highest priority" state. :)

07/12/2006 12:50:12 PM changed by mgarrison@alienz.net

  • attachment 2006-07-12.log added.

Adium Debug

07/12/2006 12:54:11 PM changed by mgarrison@alienz.net

  • attachment 2006-07-12.2.log added.

Joscar Debug

07/12/2006 12:55:23 PM changed by alien88 / mgarrison@alienz.net

I've attached my joscar and adium debug logs. The joscar one has been trimmed to meet the file length, but if you need the full log or more logs feel free to e-mail me and I can provide them. This happens fairley frequently on my account.

(in reply to: ↑ 7 ) 07/12/2006 05:06:08 PM changed by plucas

Replying to blufire:

Also having the disconnection bug, though it's not frequent enough to cause AIM to block me. It's quite annoying though, because I'll be talking with someone, and then they won't respond for a long time (maybe 10 minutes) before the contact list disappears and then reappears, indicating the reconnection. Good to see it's in "critical" and "highest priority" state. :)

I have the exact same problem. I had Adium and iChat open (on different SN's). After around 20 minutes of Adium being open, my Adium SN signs off (I can see it from iChat), but Adium doesn't recognize the disconnect for some time later. Everything in Adium just stays in its current state... and then eventually it realizes it's not connect around 20 minutes later.

07/17/2006 07:29:13 PM changed by alien88 / mgarrison@alienz.net

Still occurs in beta 5.

07/19/2006 10:17:13 PM changed by churst5841@gmail.com

I was having the same problem in the previous 1.0 betas. With beta 5 I have not noticed it at all.

07/19/2006 11:42:41 PM changed by jdelsman@voxxit.com

I am experiencing the same issue. At first, I thought it was an Intel mac issue, until I saw that someone with a iBook G4 had trouble as well. I have it working flawlessly on my Powermac G5, so I don't know. I have attached my logs as well.

07/20/2006 09:43:53 AM changed by churst5841@gmail.com

In reply to jdelsman@voxxit.com's comment, if it makes any difference, I'm using a PowerBook G4.

07/20/2006 01:03:28 PM changed by mgarrison@alienz.net

Still occurs in beta 6. If you need more information so that I can help to get this bug fixed, please let me know.

(follow-up: ↓ 16 ) 07/20/2006 08:23:10 PM changed by anonymous

Having the same issue except that when im logged out it still appears as if im logged in with the buddy list still showing the buddies that are online, the list is then no longer refreshed everytime a contact logs in or out and i appear as offline to my friends

(in reply to: ↑ 15 ) 07/21/2006 11:28:45 AM changed by anonymous

Replying to anonymous:

Having the same issue except that when im logged out it still appears as if im logged in with the buddy list still showing the buddies that are online, the list is then no longer refreshed everytime a contact logs in or out and i appear as offline to my friends

I have that same issue of a disconnect not immediately closing my buddy list. Using the last stable adium build .89.1 . Note that this is only an unexpected disconnect, not if I purposefully log out.

(in reply to: ↑ description ) 07/22/2006 10:08:58 PM changed by cpk0@mac.com

Just happened almost immediately with b7. if I knew how to attatch files to I would attatch the logs, but I don't. I have them saved if anyone wants them, though.

(in reply to: ↑ description ) 07/23/2006 06:29:23 PM changed by noktulo (aim)

This is also happening to me only with the beta. (Never happened pre-1.0.) It happens at seemingly random times. Updated to b7 and it happened immediately.

07/23/2006 09:45:53 PM changed by evands

  • component changed from AIM to adium-joscar.

07/23/2006 10:02:19 PM changed by jdelsman@voxxit.com

  • attachment adiumdebug.rtf added.

Adium Debug Log for 1.0b7

07/23/2006 10:03:28 PM changed by anonymous

Added my debug log after updating to 1.0b7 on my MacBook Pro. This time, it updated all of the buddies one by one it seemed, and then disconnected when it got to mine. Works like a charm on my Powermac, still.

07/29/2006 08:42:07 PM changed by tickswifey

  • milestone changed from Adium 1.0 - After beta 1 to Adium X 1.0.

08/04/2006 02:13:28 AM changed by blufire

Still occurring in 1.0b8. :(

08/05/2006 11:10:06 PM changed by a88laptp

Yeah, still occuring for me in 1.0b8 *sigh*

08/05/2006 11:25:28 PM changed by mlf

Can anybody who is experiencing this and who can duplicate on demand please submit an ethereal trace.

08/06/2006 09:20:08 PM changed by anonymous

It has also been happening to me since 1.0b3. I can not recreate it on demand sadly. I reverted back to .89 and didn't have the problem again until reinstalling 1.0b8.

08/07/2006 04:22:16 AM changed by a88laptop

is a tcpdump okay? if not, can you provide me with more details as to what you mean by an ethereal trace? i havent used ethereal that much.

08/07/2006 07:18:55 AM changed by mlf

tcpdump is great, thank you!

08/07/2006 02:13:25 PM changed by a88laptop

You don't appear to be a developer or contributor. I'm somewhat hesitant to tcpdump my AIM connection and send it to someone who is not a developer..

08/07/2006 04:12:04 PM changed by evands

  • reporter changed from adium to evands.

It's worth noting that anyone can put any name they want in the name field. If you'll send your tcpdump to the email listed on ContactUs we'll make sure it only goes to appropriate parties.

08/07/2006 04:12:46 PM changed by evands

  • reporter changed from evands to anonymous.

(that said, mlf is trusted :) )

08/07/2006 04:22:17 PM changed by a88laptop

True. Anywho, I've sent a tcpdump to the contact address.

08/08/2006 08:27:44 AM changed by steven

Wow, I was worried that someone had somehow gotten into my account and was hacking it or something...but AIM wasn't telling me I was online in two places.

Still getting this issue with 1.0b8

08/08/2006 11:52:53 AM changed by mlf

a88 - it would be interesting to compare and see the difference with a dump of a "normal" connection (using adium .89.1 or ichat or AIM) and your problem with the latest beta. I'm wondering if certain SNACs are getting sent up inappropriately, or the responses aren't being correctly handled. thanks.

Also, all reporters of this problem, how many buddies are on your buddy list?

08/08/2006 11:54:05 AM changed by anonymous

I have over 200-250 on mine.

08/08/2006 11:55:57 AM changed by chris@farski.com

I have 203

08/08/2006 12:25:52 PM changed by landminehare@mac.com

205 added buddies, 86 in the recent buddies list :P

(in reply to: ↑ description ) 08/08/2006 12:45:46 PM changed by CHurst5841@gmail.com

@mif:

I have 194 in my Buddies and 52 in my Recent Buddies. Just to reiterate, for me, the problem stopped past beta 5. I am noticing that Adium 8 only signs on to my first listed screen name and not my 2nd and 3rd (as the previous versions did).

08/08/2006 02:41:19 PM changed by a88laptop

I have like 250 buddies. I will try to get a capture from 0.89.1 shortly...

08/08/2006 02:58:53 PM changed by mlf

Thanks a88. :-) what i'm _suspecting_ is: clients are supposed to get info on every buddy in the list, to show status and messages. there may be a difference in timing or aggregation in how joscar is doing this versus how libgaim or official clients do it. and since all the reports are for people with large buddy lists (even though the limit is 500), this would get aggrevated by size. I'd be interested in seeing how libgaim (.89.1) or an official client (AIM, iChat) does this get info query with the same problematic list.

08/08/2006 03:59:11 PM changed by mlf

Also, if joscar isn't pulling messages from bos off the TCP stack fast enough, this will result in a zero window situation. Adium will still send requests up, but bos can't send anything down until the window size opens up. So a bunch of requests can't be answered, and then they'll all get answered at once. Once your rate limit counter hits a certain level, bos will RST you. Adium then makes another connection to bucp to try and log on. Eventually, bucp says "go away" every time the autoreconnect happens. :-/

again, it would be useful to see what libgaim/official is doing on getting info for large buddy lists vs joscar, that older works why newer doesn't. thanks.

08/08/2006 05:51:43 PM changed by A88Laptop / Alien88

Okay.. made a dump from iChat and from 0.89.1 and sent it to the list.. they're awaiting moderator approval. Let me know if there's anything else I can do.

08/08/2006 08:43:53 PM changed by leak

  • cc set to leak.

08/08/2006 11:23:12 PM changed by durin42

  • owner changed from nobody to durin42.

Assigning to me to reduce noise. There's some ongoing discussion about this amongst involved devs.

08/15/2006 05:17:44 PM changed by Maki

Just started getting this error today. It's been working fine up until this morning. Using 1.0b8. I tried logging on to a blank user account and it lets me connect to aim with that one fine. Zero buddies on that one.

08/17/2006 06:39:50 AM changed by xie

Just got this myself for the first time. Using 1.0b8 on 10.4.7 PPC.

(follow-up: ↓ 49 ) 08/17/2006 07:49:48 AM changed by anonymous

1.0b8, 10.4.7 PPC. Confirmed. Went to sleep at 11pm EDT with solid connection. Woke up 7am EDT disconnected. Upon trying to reconnect, I get essentailly a rate-limiting error.

08/17/2006 09:49:31 AM changed by anonymous

been getting the same rate limiting error since this morning.

08/17/2006 10:52:04 AM changed by anonymous

Same here, except no error.. it connects, loads the contact list, then immediately disconnects, contact list disappears, and repeats the cycle over and over again.

(in reply to: ↑ 46 ) 08/17/2006 12:13:16 PM changed by mlf

Replying to anonymous:

1.0b8, 10.4.7 PPC. Confirmed. Went to sleep at 11pm EDT with solid connection. Woke up 7am EDT disconnected. Upon trying to reconnect, I get essentailly a rate-limiting error.

You were probably disconnected by some server side maintenance work being done overnight. The rate limiting is what this bug is about. I'm betting you have a decently large AIM/ICQ buddy list.

(follow-up: ↓ 51 ) 08/17/2006 12:36:36 PM changed by adium

I have managed to login for about a second before being punted right back off and unable to log in due to the rate-limiting error. I've been eyeing the joscar debug log and watching fascinated as joscar continues to attempt to retrieve buddy icons for people on the list for the account that can't connect. Even after disabling the account it continues. Bizarre behavior.

(in reply to: ↑ 50 ) 08/17/2006 12:37:48 PM changed by adium

Replying to adium:

by the by, Tiger 10.4.7 PPC, Adium 1.0b8

(in reply to: ↑ 1 ) 08/17/2006 12:44:16 PM changed by anonymous

Replying to anonymous:

is this not very important? are other people not having this problem? i honestly can connect for 2 or 3 minutes then the connection is irreparable. i really want to use this, but cant! and now am forced to use ichat for the next couple of minutes until im able to log in again! :(

Yeah, I'm having the same issue, with 1.0b8 Tiger Intel.

08/17/2006 01:11:06 PM changed by anonymous

I start Adium 1.0b8, AIM logs in, is immediately disconnected, if left on, will continue to connect and disconnect until it can't anymore. This wasn't a problem until an AIM service outage last night.

08/17/2006 01:27:24 PM changed by rakaur@malkier.net

Same problem here, but my buddy list is only 38.

08/17/2006 01:56:15 PM changed by anonymous

I'm having the same problem and it happened overnight too. I left Adium on last night. When I woke up, I was disconnected. Upon reconnecting, I would connect and disconnect within a second, eventually banning me. My buddylist isn't too large, only 150 or so. iChat seems to work perfectly fine.

08/17/2006 01:59:48 PM changed by wd@hpgx.net

I left my comment on #5182 before I saw this one.

1.0b8 worked fine until this morning, tiger g4. I'm not sure how many contacts I have .. as I can't login - but I'm sure it's less than 100. A second account logs in fine, but has only 7 contacts.

08/17/2006 02:06:34 PM changed by daustin627@mac.com

Same problem, only on my PBG4 though. My PMG4 has no problem. Any thoughts on that? About 120 on my list.

08/17/2006 02:19:40 PM changed by tankrocks@gmail.com

1.0b8 worked fine since I downloaded it (a week or so ago) until now - connects and disconnects rapidly until banned. iChat and AOL IM work. Using Tiger on a PowerBook G4. My buddylist has a little more than 100 people. A second account (with about 10 or so buddies) works just fine.

08/17/2006 02:40:36 PM changed by ephemeron0@gmail.com

Same problem here, about 80 buddies. Tiger 10.4.7 on PPC. Adium 1.0b8. It started happening yesterday with no changes on my part in usage.

08/17/2006 05:55:59 PM changed by toastido@gmal.com

Same here, but I was working fine on 1.0b2 since "release" and it was fine. This issue started today, exactly as others described. I updated to 1.0b8, same issue. My buddy list is slightly over 150, but i can also use a second account that has ~25 contacts just fine. The odd thing is, watching the oscar debug, adium still attempts to download buddy icons even after it has been booted off the server. Related?

08/17/2006 06:40:45 PM changed by dcclark

I am experiencing the same problem. Connecting accounts with 30+ buddies seems to fail, but connecting an account with very few (< 10) works just fine. I too get a huge number of rerequests for buddy icons in my joscar debug window.

08/17/2006 06:54:23 PM changed by talikan

Ditto. Tiger 10.4.7 on PPC. Same debug stuff.

08/17/2006 06:55:04 PM changed by talikan

Yahoo and gmail stay logged in.

08/17/2006 07:01:34 PM changed by adam@thepeacock.net

I'm having the same issues on an iMac G5/Tiger using Adium 1.0b8, including joscar continuing to request buddy icons. iChat works fine now, everything was fine until last night. If needed I can help troubleshoot with more debug info, contact me at the listed email address if needed.

08/17/2006 07:33:47 PM changed by cbarrett

Alright guys, thanks very much for chiming in and confirming this issue, but we know about it now, and we're working on fixing it.

08/17/2006 07:36:45 PM changed by tick

  • description changed.

08/17/2006 07:37:24 PM changed by tick

  • summary changed from losing connection in adium 1.0b2 to AIM connection drops after minimal usage..

08/17/2006 07:38:10 PM changed by tick

  • version changed from 1.0b2 to 1.0b8.

(in reply to: ↑ description ) 08/17/2006 10:36:15 PM changed by maya@elevatorium.org

I have just started experiencing this all day today on 1.0b8 as well.. ::cries::

08/17/2006 11:07:10 PM changed by AskedRelic

Just another addition, iBook G4 PPC/Tiger 10.4.7, Adium 1.0b8, same issues as everyone else since last night.

08/18/2006 03:08:51 AM changed by anonymous

Same as everyone else, dual core mac mini on tiger, around 75 buddies on list.

08/18/2006 09:10:05 PM changed by anonymous

Same, 12" Pbook.

08/18/2006 09:22:19 PM changed by anonymous

1.0b8, Macbook 10.4.7. Same as everyone else.

08/18/2006 09:58:13 PM changed by justindonnaruma@mac.com

Latest version, Macbook 2.0ghz 10.4.7, same issue

08/18/2006 10:43:10 PM changed by SimDan

I to am expirenceing this issue (iBook G3 900mhz, 10.4.7, 1.0b if anyone really cares at this point)

I would like to volunteer to attempt to do an eathereal capture. Could you tell me what ports would be of interest?

08/19/2006 12:42:24 AM changed by stillman@post.harvard.edu

i've been having this problem for a day or two now, but when i just tried to log on a minute ago, it worked without any issues. has the problem fixed itself? or did i just get lucky....

08/20/2006 10:43:16 AM changed by evands

  • status changed from new to closed.
  • resolution set to fixed.

This has been fixed with the 1.0b9 joscar changes

11/21/2006 11:31:54 AM changed by zztzed

  • status changed from closed to reopened.
  • resolution deleted.

I'm having almost exactly the same problem in 1.0b15:

I can connect to AIM and stay connected for anywhere from a few minutes to several hours (the amount of time varies but like comment:7 it's always long enough that I don't get rate-limited when trying to reconnect). When the connection drops, the error "Error message short is ConnectionLost; code (null)" appears in console.log. Again, like comment:7, Adium usually doesn't immediately recognize when it has been disconnected; contacts don't disappear from the contact list (I have 'show offline contacts' disabled) until after it's reconnected and begins repopulating the list of available contacts.

I had initially believed this to be a network problem, or a problem with the AIM servers specifically, but I wasn't having problems with any other services, and I've had no problems since switching back to 0.89.1.

I'm not wizardly enough to divine from the debug logs whether it might be related to the number of AIM contacts I have (around 150). I'll post those if need be, however. (I imagine it probably will be needed, but I don't want to post them unless a dev actually tells me to.)

This is happening on a G4 Cube running 10.4.8 if that makes any difference.

12/01/2006 02:52:36 PM changed by tick

  • milestone changed from Adium X 1.0 to Adium X 1.0.1.

We've not had a mass of reports on this as far as I've seen (my feedback subscription is horked) so moving to 1.0.1 for now.

(in reply to: ↑ description ) 12/05/2006 06:25:39 AM changed by jpfeffer

the same thing has been happening to me and i'm running on a g4 titanium powerbook. will this be fixed soon? i am using adium version .89.1

12/06/2006 03:06:06 PM changed by zztzed

For whatever it might be worth, I'm still having this problem with b15 despite having trimmed my AIM contact list to roughly a third of its prior size.

12/06/2006 10:54:23 PM changed by zztzed

I've even gone so far as to reduce my AIM contact list to 8 people, and it's still happening.

12/06/2006 11:29:52 PM changed by mlf

zzted, it's not a matter of the size of your buddy list, it's a matter if you have a router in the middle that drops your connection if the client hasn't sent a message up (like a keep alive) the the AIM server in a few minutes. Actively using other protocols won't help you, the router keep track of each connection and kills those that it thinks are idle (because it doesn't see "recent" client->server activity).

jpfeffer, I don't think your instance of this problem is the same cause as what is being tracked in this ticket, because you say are using .89.1, which has the older libraries for connecting to AIM (libgaim vs joscar).

Also folks, as the description says, the developers are aware of this. If you feel you really do have something to add, please describe your network connectivity (eg, wireless to XXXXX brand router to cable modem) and definitely attach a tcpdump file (see UsingTcpdump ), as that is the only way to actually see what is happening. Thanks!

12/26/2006 12:45:39 PM changed by mlf

Is this ticket still valid, since joscar is no longer the AIM conectivity method?

12/26/2006 06:58:49 PM changed by cbarrett

  • milestone changed from Adium X 1.0.1 to Needs feedback from users.

Marking this as needs feedback from users, since we switched to libgaim-oscar (it's possible it exists in libgaim? who knows)

12/26/2006 07:18:55 PM changed by mlf

If anyone encounters this bug (AIM connection drops after you haven't been using AIM for a while), please duplicate the issue with tcpdump running (see UsingTcpdump ) and please attach the tcpdump file when you add to the ticket. anecdotal evidence is tough to debug without seeing what's on the wire. Also please don't forget to describe your network configuration (ie, wireless to XXXX brand router to cable modem). Thanks!

02/19/2007 04:41:44 PM changed by evands

  • status changed from reopened to closed.
  • resolution set to invalid.
  • milestone deleted.

Invalid with switch back to libgaim.