Adium

Ticket #10350 (closed defect: fixed)

Opened 5 months ago

Last modified 4 months ago

Delay sending message (as long as 10 minutes)

Reported by: Michael Kruck Assigned to: evands
Priority: high Milestone: Adium 1.3
Component: ICQ Version: 1.3b7
Severity: regression Keywords: message delay since icq6
Cc: Patch: None
Pending: 0

Description (Last modified by evands)

Hello,

since the last update to Adium version 1.2.6 I have a big message delay to all other instant messanger clients e.g. Miranda, ICQ6, Trillian.

I'm using Mac OS X 10.5.4 on a MacBook Pro. Many thanks in advance.

Kindest regards Michael

Attachments

2008-07-06.log (26.4 kB) - added by Stussi on 07/06/2008 10:02:23 AM.
2008-07-04.log (94.3 kB) - added by Michael Kruck on 07/07/2008 06:11:48 AM.
2008-07-19 2.log (261.0 kB) - added by Michael Kruck on 07/19/2008 11:20:42 AM.
2008-07-20.log (77.4 kB) - added by Stussi on 07/20/2008 07:46:10 AM.
2008-07-20.2.log (500.2 kB) - added by Michael Kruck on 07/20/2008 07:55:54 AM.
2008-07-20 2.log (0.5 MB) - added by Michael Kruck on 07/20/2008 12:06:26 PM.
2008-07-20 1.log (335.0 kB) - added by Stussi on 07/20/2008 01:56:35 PM.
2008-07-20.3.log (464.3 kB) - added by Michael Kruck on 07/20/2008 06:04:14 PM.
2008-07-21.log (212.6 kB) - added by Stussi on 07/20/2008 06:59:57 PM.
2008-07-21 1.log (107.2 kB) - added by Stussi on 07/21/2008 05:41:23 AM.

Change History

07/03/2008 09:42:07 PM changed by jas8522

  • pending set to 1.
  • milestone set to Needs feedback from users.

We need a debug log: see CurrentAdiumDebug. It would be best to get the latest 1.3 AdiumBeta and retrieve it with that.

(follow-up: ↓ 3 ) 07/04/2008 03:37:44 AM changed by Michael Kruck

  • pending deleted.

(in reply to: ↑ 2 ) 07/04/2008 06:42:20 AM changed by Michael Kruck

furthermore i noticed that approximately after 20 minutes of running adium the queue goes downwards again, and everything works!!!

07/05/2008 07:23:03 AM changed by Stussi

Hello,

i've got the same Problem but it occured first in 1.2.5.

Messages will arrive aprox. 5-10 minutes later....

1.2.3 worked just fine, 1.2.4 reconnected itself often....

Now in 1.2.6 (right now i test 1.2.7) the delay problem is there again.... this realls sucks :-/

Hope you'll find the error!

Thanks in advance!

07/05/2008 07:16:52 PM changed by jas8522

  • pending set to 1.

Please attach the debug log - do not paste it in the window. Otherwise it is unreadable!

07/06/2008 10:02:23 AM changed by Stussi

  • attachment 2008-07-06.log added.

07/07/2008 06:11:48 AM changed by Michael Kruck

  • attachment 2008-07-04.log added.

07/16/2008 08:21:09 PM changed by Robby

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

07/16/2008 11:09:18 PM changed by jas8522

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

Is this still a problem with 1.3b7 AdiumBeta? I would have expected to have seen more reports of this...

07/17/2008 02:25:56 AM changed by Michael Kruck

  • pending deleted.

yes, this is still a problem! in the forum there is another user who noticed it...

07/17/2008 07:15:11 AM changed by jas8522

  • summary changed from big message delay since icq v6 (adium 1.2.6) to Delay sending message (as long as 10 minutes).
  • component changed from Adium Core to ICQ.
  • version changed from 1.2.6 to 1.3b7.
  • milestone changed from Needs feedback from users to Adium X 1.3.

07/17/2008 04:10:35 PM changed by evands

This is caused by the ICQ Status Note support in libpurple

07/17/2008 04:40:35 PM changed by evands

(In [24405]) libpurple.framework which hopefully fixes the long ICQ delay for people with lengthy ICQ buddy lists. If it works, I'll commit it upstream. Refs #10350

(follow-up: ↓ 13 ) 07/17/2008 05:23:38 PM changed by evands

Please wait at least 10 minutes after experiencing this bug to completion (with messages no longer having a delay). Then try Adium_1.3b720080717 and let us know here if it fixes the problem.

(in reply to: ↑ 12 ) 07/19/2008 07:27:48 AM changed by Michael Kruck

The Problem still exists in Adium_1.3b720080717 :(

07/19/2008 10:49:51 AM changed by evands

Please post the complete debug log of connecting just your ICQ account. and finding the delay to be present using Adium_1.3b720080717

07/19/2008 11:20:42 AM changed by Michael Kruck

  • attachment 2008-07-19 2.log added.

07/19/2008 04:19:28 PM changed by evands

(In [24430]) Another attempted fix for handling the ICQ status notes request flood. Refs #10350

07/19/2008 04:54:32 PM changed by Stussi

My attachment is 1.3b7 too....

The Problem still exists in 1.3b7!

07/19/2008 05:17:55 PM changed by evands

Please try Adium_1.3b720080719 and post the debug log.

07/19/2008 06:52:00 PM changed by jas8522

  • owner changed from nobody to evands.
  • priority changed from normal to high.
  • severity changed from normal to regression.

07/20/2008 07:46:10 AM changed by Stussi

  • attachment 2008-07-20.log added.

07/20/2008 07:46:45 AM changed by Stussi

Attached a log with the Adium Version you posted....

07/20/2008 07:55:54 AM changed by Michael Kruck

  • attachment 2008-07-20.2.log added.

07/20/2008 07:57:44 AM changed by Michael Kruck

me too, still the same problem

07/20/2008 09:18:05 AM changed by Stussi

Just to let you know: some friends tell me that "i" am reading their Away Messages over and over again.. i think that was with 1.2.5 too. Maybe there is the error...

07/20/2008 09:19:49 AM changed by evands

Well, yes, that's the source of the problem - but the solution is to fix the rate limiting, not stop reading away messages, I think.

07/20/2008 09:53:44 AM changed by evands

(In [24438]) Iterating on trying to fix ICQ rate limiting problems. Refs #10350

(follow-up: ↓ 25 ) 07/20/2008 10:05:06 AM changed by Michael Kruck

doesn't help :(

(in reply to: ↑ 24 ) 07/20/2008 10:13:30 AM changed by evands

Replying to Michael Kruck:

doesn't help :(

Are you building from source?

07/20/2008 10:14:47 AM changed by evands

  • description changed.

Note: I removed the parts of this ticket involving offline messages and file transfers from the description. Please make new tickets for separate issues.

(follow-up: ↓ 33 ) 07/20/2008 11:03:24 AM changed by evands

Please wait 10 minutes to let your account completely clear, then try Adium_1.3b720080720, posting the entire debug log from initial connection to message sending attempt. I don't believe anyone has posted a complete log so far - hold shift while Adium loads to prevent your account from automatically connecting, then open the debug window before connecting.

07/20/2008 12:06:26 PM changed by Michael Kruck

  • attachment 2008-07-20 2.log added.

07/20/2008 12:07:28 PM changed by Michael Kruck

so here is a complete log now (holding shift on start up)

07/20/2008 12:23:56 PM changed by evands

Thanks, Michael. I can see that we're making improvement - your messages started off sending immediately and then ended up with a brief delay, rather than the 10 minutes or so you were seeing previously. Does that understanding of the logs match your experience using 1.3b720080720?

07/20/2008 12:32:08 PM changed by Michael Kruck

yes, as soon as the queued for the low-priority snacs are 0 the message arrives immediately... before the delay is aprox. 5 minutes at peak.

07/20/2008 12:34:46 PM changed by evands

Messages are sent at high priority; it should be a second or two at most when you send a message so long as there aren't high priority snacs waiting. Are you sure that it's not until the low priority ones reach 0 that you're seeing good message send times?

07/20/2008 01:09:56 PM changed by evands

(In [24440]) Improved debug logging for SNAC queueing, and made buddy icon requests group into the low priority queue. Refs #10350

(in reply to: ↑ 27 ) 07/20/2008 01:37:45 PM changed by evands

Same story, but now with Adium_1.3b720080720-2. Entire debug log again please :)

07/20/2008 01:56:35 PM changed by Stussi

  • attachment 2008-07-20 1.log added.

07/20/2008 01:57:58 PM changed by Stussi

It seems to be better.... sometimes the error occurs after a sudden reconnect (don't know why...)

07/20/2008 02:30:26 PM changed by evands

(In [24441]) Once more toward good ICQ rate control. 'bout ready to just cardiovert the sucker the old fashioned way... Refs #10350

(follow-up: ↓ 40 ) 07/20/2008 03:46:03 PM changed by evands

Next try: Adium_1.3b72008720-3.

07/20/2008 06:04:14 PM changed by Michael Kruck

  • attachment 2008-07-20.3.log added.

07/20/2008 06:04:47 PM changed by Michael Kruck

and here is the next logfile :)

07/20/2008 06:28:07 PM changed by evands

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

(In [24443]) Make ICQ alias requests low priority to avoid interfering with messaging, and clean up debug logging. Fixes #10350 if I'm not mistaken :)

07/20/2008 06:59:57 PM changed by Stussi

  • attachment 2008-07-21.log added.

07/20/2008 07:02:23 PM changed by Stussi

The number of the queued SNACs still grows... now: 01:01:10: (Libpurple: oscar) Attempting to send 31 queued SNACs and 14 queued low-priority SNACs for 0x195bf680

I think the delay is still there... (i used the last version you posted)

(in reply to: ↑ 36 ) 07/20/2008 07:03:15 PM changed by evands

I believe this should fix the issue. Please try Adium_1.3b72008720-4 and let us know :)

07/21/2008 05:41:23 AM changed by Stussi

  • attachment 2008-07-21 1.log added.

07/21/2008 05:41:48 AM changed by Stussi

Yes, seems to work :) Thank you! :)

(follow-up: ↓ 43 ) 07/21/2008 03:53:54 PM changed by Michael Kruck

yes it works! would it be implemented now in a non-beta version?

(in reply to: ↑ 42 ) 07/21/2008 09:00:55 PM changed by jas8522

Replying to Michael Kruck:

yes it works! would it be implemented now in a non-beta version?

Of course... when 1.3 is released.