ICQ contacts without nickname and group

Sometimes my contacts appear groupless and without nicknames. I have to re-logon to get the contact list right. Debug log with the problem follows. Suspicious messages include “Handling AIM-style auth.” (this is ICQ) and “AIM got buddy item BuddyItem for 256995594 (buddy 0x1798 in group 0x1): alias=Zuzka, comment=“null””, this is correct but again, this is ICQ not AIM (otherwise, the contact details are correct). Then, there is a log message including some binary data which is hard to copy&paste here…(see end of log):

Cheers,

Filip

2006.11.15 08:41:29 icq: rosterLoaded fkonvick

2006.11.15 08:41:29 Received presence packet:

2006.11.15 08:41:30 OSCAR login service state change from CONNECTING to CONNECTED

2006.11.15 08:41:30 Handling AIM-style auth.

2006.11.15 08:41:30 Got auth response!

2006.11.15 08:41:30 Got something else?

2006.11.15 08:41:30 OSCAR bos service state change from NOT_CONNECTED to INITING

2006.11.15 08:41:30 OSCAR bos service state change from INITING to RESOLVING

2006.11.15 08:41:30 OSCAR bos service state change from RESOLVING to CONNECTING

2006.11.15 08:41:30 OSCAR login service state change from CONNECTED to NOT_CONNECTED

2006.11.15 08:41:30 OSCAR bos service state change from CONNECTING to CONNECTED

2006.11.15 08:41:30 OSCAR bps flap packet received: FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=0, flapCommand=LoginFlapCmd: version=1, cookie=null, flapPacket=FlapPacket (channel=1, seq=35792)

2006.11.15 08:41:30 OSCAR flap packet received: FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=0, flapCommand=LoginFlapCmd: version=1, cookie=null, flapPacket=FlapPacket (channel=1, seq=35792)

2006.11.15 08:41:31 OSCAR bos snac packet received: SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=0, requests: 0, paused=false, snacPacket=SnacPacket type 0x1/0x3: 26 bytes (id=2599426237), snacCommand=ServerReadyCmd: snac families: 1, 2, 3, 4, 6, 8, 9, a, b, c, 13, 15, 22 - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=1, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x1/0x3: 26 bytes (id=2599426237), flapPacket=FlapPacket (channel=2, seq=35793)

2006.11.15 08:41:31 OSCAR snac packet received: SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=0, requests: 0, paused=false, snacPacket=SnacPacket type 0x1/0x3: 26 bytes (id=2599426237), snacCommand=ServerReadyCmd: snac families: 1, 2, 3, 4, 6, 8, 9, a, b, c, 13, 15, 22 - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=1, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x1/0x3: 26 bytes (id=2599426237), flapPacket=FlapPacket (channel=2, seq=35793)

2006.11.15 08:41:31 OSCAR bos snac packet received: SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=6, requests: 6, paused=false, snacPacket=SnacPacket type 0x1/0x18: 52 bytes (id=2599426507), snacCommand=ServerVersionsCmd: family versions: 1 (v=4), 2 (v=1), 3 (v=1), 4 (v=1), 6 (v=1), 8 (v=1), 9 (v=1), a (v=1), b (v=1), c (v=1), 13 (v=5), 15 (v=2), 22 (v=1), - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=7, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x1/0x18: 52 bytes (id=2599426507), flapPacket=FlapPacket (channel=2, seq=35794)

2006.11.15 08:41:31 OSCAR snac packet received: SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=6, requests: 6, paused=false, snacPacket=SnacPacket type 0x1/0x18: 52 bytes (id=2599426507), snacCommand=ServerVersionsCmd: family versions: 1 (v=4), 2 (v=1), 3 (v=1), 4 (v=1), 6 (v=1), 8 (v=1), 9 (v=1), a (v=1), b (v=1), c (v=1), 13 (v=5), 15 (v=2), 22 (v=1), - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=7, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x1/0x18: 52 bytes (id=2599426507), flapPacket=FlapPacket (channel=2, seq=35794)

2006.11.15 08:41:31 OSCAR bos snac packet received: SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=6, requests: 6, paused=false, snacPacket=SnacPacket type 0x1/0x13: 14 bytes (id=2599426508), snacCommand=UpdateAdvisory: type=5, message=null - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=7, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x1/0x13: 14 bytes (id=2599426508), flapPacket=FlapPacket (channel=2, seq=35795)

2006.11.15 08:41:31 OSCAR snac packet received: SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=6, requests: 6, paused=false, snacPacket=SnacPacket type 0x1/0x13: 14 bytes (id=2599426508), snacCommand=UpdateAdvisory: type=5, message=null - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=7, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x1/0x13: 14 bytes (id=2599426508), flapPacket=FlapPacket (channel=2, seq=35795)

2006.11.15 08:41:31 OSCAR bos snac response received: SnacResponseEvent: request=SnacRequest for RateInfoRequest: listeners: , responses: null - SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=7, requests: 7, paused=false, snacPacket=SnacPacket type 0x1/0x7: 1017 bytes (id=2), snacCommand=RateInfoCmd: RateClassInfo for class 1, currentAvg=5859, windowSize=80, clearAvg=2500, warnAvg=2000, limitedAvg=1500, disconnectAvg=800, max=6000, timeSinceLastCommand=0, currentState=0, families: 194 - RateClassInfo for class 2, currentAvg=6000, windowSize=80, clearAvg=3000, warnAvg=2000, limitedAvg=1500, disconnectAvg=1000, max=6000, timeSinceLastCommand=707, currentState=0, families: 6 - RateClassInfo for class 3, currentAvg=3500, windowSize=20, clearAvg=3100, warnAvg=2500, limitedAvg=2000, disconnectAvg=1500, max=4500, timeSinceLastCommand=707, currentState=0, families: 3 - RateClassInfo for class 4, currentAvg=6000, windowSize=20, clearAvg=5500, warnAvg=5300, limitedAvg=4200, disconnectAvg=3000, max=8000, timeSinceLastCommand=707, currentState=0, families: 2 - RateClassInfo for class 5, currentAvg=6000, windowSize=10, clearAvg=5500, warnAvg=5300, limitedAvg=4200, disconnectAvg=3000, max=8000, timeSinceLastCommand=707, currentState=0, families: 0 - - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=8, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x1/0x7: 1017 bytes (id=2), flapPacket=FlapPacket (channel=2, seq=35796)

2006.11.15 08:41:31 OSCAR bos snac response received: SnacResponseEvent: request=SnacRequest for ParamInfoRequest: listeners: , responses: null - SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=7, requests: 7, paused=false, snacPacket=SnacPacket type 0x4/0x5: 16 bytes (id=3), snacCommand=ParamInfoCmd: paramInfo=<ParamInfo: maxChannel=4, flags=0x3 (FLAG_MISSEDCALLS_ALLOWED | FLAG_CHANMSGS_ALLOWED), maxMsgLen=512, maxSenderWarning=90.0%, maxReceiverWarning=100.9%, minMsgInterval=1000> - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=8, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x4/0x5: 16 bytes (id=3), flapPacket=FlapPacket (channel=2, seq=35797)

2006.11.15 08:41:31 OSCAR bos snac response received: SnacResponseEvent: request=SnacRequest for LocRightsRequest: listeners: , responses: null - SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=8, requests: 8, paused=false, snacPacket=SnacPacket type 0x2/0x3: 30 bytes (id=4), snacCommand=LocRightsCmd: max info length=4096 - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=9, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x2/0x3: 30 bytes (id=4), flapPacket=FlapPacket (channel=2, seq=35798)

2006.11.15 08:41:31 OSCAR bos snac response received: SnacResponseEvent: request=SnacRequest for SsiRightsRequest: listeners: , responses: null - SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=10, requests: 10, paused=false, snacPacket=SnacPacket type 0x13/0x3: 152 bytes (id=5), snacCommand=SsiRightsCmd: maxima: 0x0: 2600, 0x1: 51, 0x2: 128, 0x3: 128, 0x4: 1, 0x5: 1, 0x6: 50, 0x7: 0, 0x8: 0, 0x9: 3, 0xa: 0, 0xb: 0, 0xc: 0, 0xd: 128, 0xe: 128, 0xf: 20, 0x10: 200, 0x11: 1, 0x12: 0, 0x13: 1, 0x14: 15, 0x15: 1, 0x16: 40, 0x17: 0, 0x18: 0, 0x19: 200, 0x1a: 1, 0x1b: 20, 0x1c: 200, 0x1d: 1, 0x1e: 8, 0x1f: 20, 0x20: 1, 0x21: 0, 0x22: 0, 0x23: 0, - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=11, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x13/0x3: 152 bytes (id=5), flapPacket=FlapPacket (channel=2, seq=35799)

2006.11.15 08:41:32 OSCAR bos snac response received: SnacResponseEvent: request=SnacRequest for SsiDataRequest: listeners: , responses: null - SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=10, requests: 10, paused=false, snacPacket=SnacPacket type 0x13/0x6: 1925 bytes (id=6), snacCommand=SsiDataCmd (ssi version=0): 43 items, modified Tue Nov 14 16:49:40 CET 2006 - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=11, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x13/0x6: 1925 bytes (id=6), flapPacket=FlapPacket (channel=2, seq=35800)

2006.11.15 08:41:32 AIM got group item GroupItem for Rodina, groupid=0x1, buddies: 0x7249, 0x724b, 0x41c0, 0x24e0, 0x1798, 0x28ef, 0x6d7a, 0x509b,

2006.11.15 08:41:32 Found group item: GroupItem for Rodina, groupid=0x1, buddies: 0x7249, 0x724b, 0x41c0, 0x24e0, 0x1798, 0x28ef, 0x6d7a, 0x509b, at id 1

2006.11.15 08:41:32 AIM got buddy item BuddyItem for 256995594 (buddy 0x1798 in group 0x1): alias=Zuzka, comment=“null”, alerts:

2006.11.15 08:41:32 Found buddy item: BuddyItem for 256995594 (buddy 0x1798 in group 0x1): alias=Zuzka, comment=“null”, alerts: at id 6040

2006.11.15 08:41:32 AIM got buddy item BuddyItem for 286206671 (buddy 0x24e0 in group 0x1): alias=Eva, comment=“null”, alerts:

2006.11.15 08:41:32 Found buddy item: BuddyItem for 286206671 (buddy 0x24e0 in group 0x1): alias=Eva, comment=“null”, alerts: at id 9440

2006.11.15 08:41:32 AIM got buddy item BuddyItem for 223100190 (buddy 0x28ef in group 0x1): alias=David, comment=“null”, alerts:

2006.11.15 08:41:32 Found buddy item: BuddyItem for 223100190 (buddy 0x28ef in group 0x1): alias=David, comment=“null”, alerts: at id 10479

(…a lot more of these…)

2006.11.15 08:41:32 AIM got group item GroupItem for Transport Buddies, groupid=0x430d, buddies: none

2006.11.15 08:41:32 Found group item: GroupItem for Transport Buddies, groupid=0x430d, buddies: none at id 17165

2006.11.15 08:41:32 icq: Sending packet:

2006.11.15 08:41:32 icq: Sending packet:

2006.11.15 08:41:32 OSCAR bos snac response received: SnacResponseEvent: request=SnacRequest for MyInfoRequest: listeners: , responses: null - SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=13, requests: 13, paused=false, snacPacket=SnacPacket type 0x1/0xf: 240 bytes (id=10), snacCommand=YourInfoCmd: UserInfo for 154380033: flags=0x51 (MASK_FREE | MASK_UNCONFIRMED | 0x40), memberSince=Thu Mar 21 14:33:47 CET 2002, sessLenAim=0min, onSince=Wed Nov 15 08:41:31 CET 2006, extraTlvs=[TLV: type=0xc, length=37, ascii value="…

I could not copy&paste the last log message here because it contains some binary data, the message is something like (this is another, similar line):

2006.11.15 09:38:35 OSCAR bos snac response received: SnacResponseEvent: request=SnacRequest for MyInfoRequest: listeners: , responses: null - SnacPacketEvent: snacProcessor=ClientSnacProcessor: lastreqid=13, requests: 13, paused=false, snacPacket=SnacPacket type 0x1/0xf: 240 bytes (id=10), snacCommand=YourInfoCmd: UserInfo for 154380033: flags=0x51 (MASK_FREE | MASK_UNCONFIRMED | 0x40), memberSince=Thu Mar 21 14:33:47 CET 2002, sessLenAim=0min, onSince=Wed Nov 15 09:38:34 CET 2006, extraTlvs=[TLV: type=0xc, length=37, ascii value=" (binary data here) “: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00, TLV: type=0xa, length=4, uint value=3243510804: c1 54 0c 14, TLV: type=0xa, length=4, uint value=3243510804: c1 54 0c 14, TLV: type=0x22, length=2, ushort value=43451: a9 bb, TLV: type=0x1e, length=4, ascii value=” (binary data here) “, uint value=0: 00 00 00 00, TLV: type=0x14, length=1, ascii value=” ": 15] - FlapPacketEvent: flapProcessor=FlapProcessor: seqNum=SeqNum: min=0, max=65535, last(current)=14, flapCommand=SnacFlapCmd: packet=SnacPacket type 0x1/0xf: 240 bytes (id=10), flapPacket=FlapPacket (channel=2, seq=22021)

Then there were some more messages that I did not copy&paste here (I did not notice at first that the binary data cut off the rest of the copy&paste operation).

When I log out and back on, the contact list is ok.

Cheers,

Filip

Ignore the AIM messages, they don’‘t mean that I’'m treating an ICQ account as AIM. They are just debug messages that were poorly labeled. =)

Any chance you could also get an XML dump between your client and wildfire?

jadestorm wrote:

Ignore the AIM messages, they don’‘t mean that I’'m treating an ICQ account as AIM. They are just debug messages that were poorly labeled. =)

Yeah, I thought that, because when I looked at the “correct” log the messages were nearly the same…

Any chance you could also get an XML dump between your client and wildfire?

How do I do that? Is there some commonly used tool/method of doing this?

fkonvick wrote:
jadestorm wrote:

Any chance you could also get an XML dump between your client and wildfire?

How do I do that? Is there some commonly used tool/method of doing this?

From Psi, you can just open up the XML Console under tools, enable it, and then it’'ll let you save to file. Not sure about other clients. (many offer this functionality)