Yahoo/msn transport issue

I have a fresh install of openfire 3.5.2 with the im gateway 1.2.3. On my contact list one users status message appears on the wrong yahoo user. Also I can’t contact one user through my spark client but it works fine when using the yahoo webmail for im. Other users also can’t establish a two way connection. We are evaluating this and it would basically block our ability to roll out this system in a few weeks.

My MSN transport issue is that I can’t see my other msn contact who is also logged into msn through spark and this openfire server, he also can’t see me. Our aim transport seems to be working just fine though.

I get the following when I turned on debugging in the log, looks like the @ in the username is set to \40.

2008.07.02 13:37:55

Received message packet: <message id=“5ThrP-82”

           to="to_username\40sbcglobal.net@yahoo.im.foo.com"

           from="from_username@im.foo.com/spark"

type=“chat”><body>test2</body><thread>MPj430 </thread><x

xmlns=“jabber:x:event”><offline/><composing/></ x></message>

2008.07.08 08:42:47

Received message packet: <message id=“oNbzo-84”

           to="user1\40sbcglobal.net@yahoo.im.foo.com"

           from="user3@im.foo.com/spark"

type=“chat”><body>testing</body><thread>C1fS TY</thread><x

xmlns=“jabber:x:event”><offline/><composing/></ x></message>2008.07.08 08:42:47 Failed to send message to yahoo user.2008.07.08 08:43:29

Received iq packet: <iq id=“oNbzo-85”

           to="user2@yahoo.im.foo.com" type="get"

           from="user3@im.foo.com/spark"&gt;&lt;query

           xmlns="http://jabber.org/protocol/disco#info"/&gt;&lt;/iq&gt;<span class="date">2008.07.08 08:43:36

Received message packet: <message id=“oNbzo-86”

           to="user2@yahoo.im.foo.com" from="user3@im.foo.com/spark"

type=“chat”><body>test for yahoo

plugin</body><thread>0wBNYc</thread><x

xmlns=“jabber:x:event”><offline/><composing/></ x></message>2008.07.08 08:43:36 Failed to send message to yahoo user.2008.07.08 08:44:18

Received message packet: <message id=“oNbzo-87”

           to="user1\40sbcglobal.net@yahoo.im.foo.com"

           from="user3@im.foo.com/spark" type="chat"&gt;&lt;body&gt;testing

yahoo plugin</body><thread>C1fSTY</thread><x

xmlns=“jabber:x:event”><offline/><composing/></ x></message>2008.07.08 08:44:18 Failed to send message to yahoo user.

Does anyone have any input on this issue? We really want to deploy this

at the end of the month and would pay for support if we knew where to

get it.

Here is another log of just a user loggin in. The status message assigned to user 7 actually belongs to user5. Also user9 isn’t logged in even though the name shows up in the list. After a while I can log off my other user running on pidgin, user7, and my instance on jabber, yahoo_user, doesn’t reflect the change. Also after a period of time the yahoo_user is shown to be logged out on my other client, user7. I’ve made some timeout changes in the firewall so connections to and

from the im server don’t timeout for 24 hours, but this doesn’t affect

this issue. It seems to be about an hour from when the last message was sent until the yahoo_user disappears on my other client.

2008.07.11 08:50:36 Received iq packet: <iq id=“C25tY-113” to=“yahoo.im.foo.com” type=“get” from="jabber_user@im.foo.com/spark"><query xmlns=“http://jabber.org/protocol/disco#info”/></iq>

           2008.07.11 08:50:36 yahoo: Sending packet: &lt;iq type="result" id="C25tY-113" from="yahoo.im.foo.com" to="jabber_user@im.foo.com/spark"&gt;&lt;query xmlns="http://jabber.org/protocol/disco#info"&gt;&lt;identity category="gateway" type="yahoo" name="Yahoo! Transport"/&gt;&lt;feature var="http://jabber.org/protocol/disco#info"/&gt;&lt;feature var="http://jabber.org/protocol/disco#items"/&gt;&lt;feature var="jabber:iq:gateway"/&gt;&lt;feature var="jabber:iq:register"/&gt;&lt;feature var="jabber:iq:version"/&gt;&lt;feature var="vcard-temp"/&gt;&lt;feature var="jabber:iq:registered"/&gt;&lt;/query&gt;&lt;/iq&gt;

           2008.07.11 08:50:37 Received presence packet: &lt;presence id="C25tY-114" to="yahoo.im.foo.com" from="jabber_user@im.foo.com/spark"&gt;&lt;x xmlns="vcard-temp:x:update"&gt;&lt;photo&gt;9d2a90a1afee6a87a3c25761c2c39fe3e69 5ceb8&lt;/photo&gt;&lt;/x&gt;&lt;x xmlns="jabber:x:avatar"&gt;&lt;hash&gt;9d2a90a1afee6a87a3c25761c2c39fe3e695ceb8 &lt;/hash&gt;&lt;/x&gt;&lt;/presence&gt;

           2008.07.11 08:50:37 A new session has come online: jabber_user@im.foo.com/spark

           2008.07.11 08:50:37 Loaded avatar for jabber_user@im.foo.com of hash 9d2a90a1afee6a87a3c25761c2c39fe3e695ceb8

           2008.07.11 08:50:37 Created yahoo session for jabber_user@im.foo.com/spark as 'yahoo_user'

2008.07.11 08:50:37 Magic:YMSG Version:12 Length:102 Service:AUTH Status:1 SessionId:0x9f0fbc e&w-3%(x)*o+5%u\

2008.07.11 08:50:37 Magic:YMSG Version:12 Length:893 Service:LIST Status:0 SessionId:0x9f0fbc [Buddies:user1,user2@sbcglobal.net,user3,user4,user5,user6

Family:user7

Friends:user8,user9

Match:user10

] [] [] [] [] [] [] http://o0hZLeVgqYQ.a5ZdlDrFZQ-- http://vwyw.jzajjRP0IhqxGgLIA-- http://118H.JlMdAmezjYTObX8xQ–

2008.07.11 08:50:37 add new group from list YahooGroup

2008.07.11 08:50:37 add new group from list YahooGroup

2008.07.11 08:50:37 add new group from list YahooGroup

2008.07.11 08:50:37 add new group from list YahooGroup

2008.07.11 08:50:37 Magic:YMSG Version:12 Length:96 Service:LOGON Status:1 SessionId:0x82cb6e

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Storing new buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Storing new buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Storing new buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Storing new buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Storing new buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Storing new buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Storing new buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Storing new buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Storing new buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Storing new buddy:

2008.07.11 08:50:37 Syncing Legacy Roster: [, , , , , , , , , ]

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user10@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user6@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user3@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user5@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user9@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user8@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user2\40sbcglobal.net@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user1@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user4@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user7@yahoo.im.foo.com

2008.07.11 08:50:37 Updating status for

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="user4_jabber_id@im.foo.com" from="yahoo_user@yahoo.im.foo.com"/&gt;

2008.07.11 08:50:37 Magic:YMSG Version:12 Length:881 Service:LOGON Status:0 SessionId:0x9f0fbc [] [] [] [] [] [] [] [] [] [] [] []

2008.07.11 08:50:37 Magic:YMSG Version:12 Length:56 Service:UNKNOWN002 Status:1 SessionId:0x9f0fbc

2008.07.11 08:50:37 Don’t know how to handle service type ‘UNKNOWN002’. The original packet was: Magic:YMSG Version:12 Length:56 Service:UNKNOWN002 Status:1 SessionId:0x9f0fbc

2008.07.11 08:50:37 Magic:YMSG Version:12 Length:17 Service:PING Status:1 SessionId:0x9f0fbc

2008.07.11 08:50:37 Don’t know how to handle service type ‘PING’. The original packet was: Magic:YMSG Version:12 Length:17 Service:PING Status:1 SessionId:0x9f0fbc

2008.07.11 08:50:37 Magic:YMSG Version:12 Length:7 Service:NEWPERSONMAIL Status:1 SessionId:0x9f0fbc

2008.07.11 08:50:37 Don’t know how to handle service type ‘NEWPERSONMAIL’. The original packet was: Magic:YMSG Version:12 Length:7 Service:NEWPERSONMAIL Status:1 SessionId:0x9f0fbc

2008.07.11 08:50:37 Magic:YMSG Version:12 Length:6 Service:NEWMAIL Status:1 SessionId:0x9f0fbc

2008.07.11 08:50:37 Updating status for TransportSession[jabber_user@im.foo.com]

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="yahoo.im.foo.com"/&gt;

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Replacing buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Replacing buddy:

2008.07.11 08:50:37 Updating status for

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user4@yahoo.im.foo.com"/&gt;

2008.07.11 08:50:37 (YAHOO) Replacing buddy:

2008.07.11 08:50:37 Updating status for

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user7@yahoo.im.foo.com"&gt;&lt;status&gt;Gone all day @ hospice training & work @ the chiro, but text&lt;/status&gt;&lt;/presence&gt;

2008.07.11 08:50:37 (YAHOO) Replacing buddy:

2008.07.11 08:50:37 Updating status for

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user5@yahoo.im.foo.com"/&gt;

2008.07.11 08:50:37 (YAHOO) Replacing buddy:

2008.07.11 08:50:37 Updating status for

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user3@yahoo.im.foo.com"/&gt;

2008.07.11 08:50:37 (YAHOO) Replacing buddy:

2008.07.11 08:50:37 Updating status for

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user1@yahoo.im.foo.com"/&gt;

2008.07.11 08:50:37 (YAHOO) Replacing buddy:

2008.07.11 08:50:37 Updating status for

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user10@yahoo.im.foo.com"/&gt;

2008.07.11 08:50:37 (YAHOO) Replacing buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Replacing buddy:

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 (YAHOO) Replacing buddy:

2008.07.11 08:50:37 Syncing Legacy Roster: [, , , , , , , , , ]

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user10@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user6@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user3@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user5@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user9@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user8@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user2\40sbcglobal.net@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user1@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user4@yahoo.im.foo.com

           2008.07.11 08:50:37 ROSTERSYNC: We found, updating user7@yahoo.im.foo.com

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user10@yahoo.im.foo.com"/&gt;

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user3@yahoo.im.foo.com"/&gt;

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user5@yahoo.im.foo.com"/&gt;

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user1@yahoo.im.foo.com"/&gt;

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user4@yahoo.im.foo.com"/&gt;

           2008.07.11 08:50:37 yahoo: Sending packet: &lt;presence to="jabber_user@im.foo.com" from="user7@yahoo.im.foo.com"&gt;&lt;status&gt;Gone all day @ hospice training & work @ the chiro, but text&lt;/status&gt;&lt;/presence&gt;

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 Magic:YMSG Version:12 Length:40 Service:Y6_STATUS_UPDATE Status:1 SessionId:0x82cb6e

2008.07.11 08:50:37 Updating status for

2008.07.11 08:50:37 Magic:YMSG Version:12 Length:40 Service:Y6_STATUS_UPDATE Status:1 SessionId:0x82cb6e

2008.07.11 08:50:37 Updating status for

Good lord. Yeah I can clearly see where it’s saying it’s going to replace something, and updating something else. The OpenYMSG guys have been kinda awol lately unfortunately though. =/

So in other words it’s unknown when/if it’ll be fixed? This is holding up our ability to deploy our im server which in turn will help lock down our network.

Correct, this entire plugin is done in folk’s free time, so generally no timeframes can be guaranteed. If you or anyone else would have time to work on a fix though I’d be more than pleased with a patch!

I’d love to be able to help but I have no programming experience and wouldn’t even know where to begin. Hopefully someone else helps out or the developers find some time to work on it.

Yeah. =/ Sadly I have no direct experience with OpenYMSG or the protocol itself. There’s an OpenYMSG support forum here at ignite though, you might have some luck posting there and seeing if you can get their attention. =)