GNOME Bugzilla – Bug 641334
Retrieved messages are added twice to the chat in msn
Last modified: 2011-03-19 18:14:06 UTC
Every now and then messages are added twice and when it happens all messages retrieved are added twice in that chat. It can look like this: Me: Hello! Other: Hi! HI! Me: How are you? Other: fine fine and you? and you? ... I'm using Ubuntu 10.10. I'm sad to say that I don't have the slightest idea of how to reproduce this bug.
Does it happen only with MSN? If yes, that's probably a telepathy-butterfly bug that should be reported on bugs.freedesktop.org. Anyway, when it happends, could you please grab logs from the help - debug menu and attach the Empathy and Butterfly ones?
Created attachment 180320 [details] Log of telepathy-butterfly This is the log from telepathy-butterfly from one instance when the bug occurred. From what I can tell it started at 06/02/11 20:14:20 in the log but I can't be sure. The only thing I've changed in the file is the names of people.
Created attachment 180321 [details] mission-control debug log This is the log from the mission-control process which was recorded at the same time as the log I've uploaded from telepathy-butterfly. I get the impression that this bug occurs when I open new chat windows or when people sign in although it is very intermittent. In addition I've only seen it mentioned on the internet in relation to msn accounts.
mission-control logs are not so useful, but the Empathy one is. Also, could you please tell us which messages have been displayed twice?
I'm not sure how to get the Empathy debug log. In the debug window the only logs I can see are for 'mission-control', butterfly, 'Empathy.FileTransfer', 'Logger' and 'Vino'. The messages that were displayed twice were all those from 'contact1' from 06/02/11 20:14:30 onwards. I would attach logs of the conversation from the logger but it doesn't seem to have saved them. Anyway, here's the section of the butterfly debug log where it first appears: "butterfly/textchannel-INFO: 06/02/11 20:14:30.691133: User <ButterflyContactHandle id=15 name='contact1@hotmail.co.uk'> is typing butterfly/textchannel-INFO: 06/02/11 20:14:30.691560: User <ButterflyContactHandle id=15 name='contact1@hotmail.co.uk'> is typing papyon/papyon.transport-DEBUG: 06/02/11 20:14:31.113099: <<< MSG contact1@hotmail.co.uk contact1 175 X-MMS-IM-Format: 'FN=Cambria; EF=B; CO=d06abc; CS=0; PF=12'\r\n Content-Type: 'text/plain; charset=UTF-8'\r\n MIME-Version: '1.0'\r\n \r\n wooah, you had an actual dream! butterfly/textchannel-INFO: 06/02/11 20:14:31.113974: User <ButterflyContactHandle id=15 name='contact1@hotmail.co.uk'> sent a message butterfly/textchannel-INFO: 06/02/11 20:14:31.114682: User <ButterflyContactHandle id=15 name='contact1@hotmail.co.uk'> sent a message" So the duplicate messages don't seem to be to do with papyon which only shows the message once but the repeated 'butterfly/textchannel' sections which presumably are communicated to empathy and cause the message to be repeated. In addition the conversation I was having with 'contact2' wasn't affected.
Empathy.FileTransfer is the one containing the empathy logs (yeah it's a bit miss named).
Created attachment 182408 [details] Log of telepathy-butterfly I don't think this contains logs from the relevant time, even though I saved it after the bug occurred and it contains logs from before...
Created attachment 182409 [details] Mission-control debug log
Created attachment 182410 [details] empathy debug log
Created attachment 182411 [details] Conversation log
Sorry for the delay, I haven't had much of a chance to save any logs of the bug occurring until recently - it's very intermittent. The conversation log is a copy of the messages from the empathy window and contains the times the messages were sent, and were the only ones which the bug affected.
Hum the butterfly log doesn't match the other one. I don't see any of the messages exchanged in it.
Yeah, although it was saved at the same time as the other logs and something was logged right at the end of the file after the previous messages had been sent. Presumably this is due to a separate bug, since in the butterfly log I uploaded before, the messages were logged. I'll save and upload the logs when the message duplication happens again and hopefully it'll work properly then, although I've had this problem a couple of times now.
Created attachment 183099 [details] telepathy-butterfly log
Created attachment 183100 [details] telepathy-butterfly log
Created attachment 183101 [details] Conversation log
Created attachment 183102 [details] empathy debug log
Created attachment 183103 [details] Mission-control debug log
OK, hopefully this new set of logs will be suitable. I've uploaded a new set of logs with most the telepathy-butterfly stuff present. It seems it wasn't recording any of the conversation I had with the contact until I opened the debug window, although it has recorded things from before that. Anyway, there is still a large section of the conversation present after it started recording properly.
Humm I still don't find anything helping in the logs :( Next time the bug happen, can you please start "dbus-monitor", receive a (duplicate) message and attach the output please?
Created attachment 183223 [details] Output from dbus-monitor part 1
Created attachment 183224 [details] Output from dbus-monitor part 2 Here's the dbus-monitor output. The original file was too big apparently so I've split it into two sections. I've also saved the other logs but again the telepathy-butterfly one isn't complete. I can upload them if necessary though.
Ok, so it seems that the messages are duplicated by Butterfly. Can you please open telepathy-butterfly bug on bugs.freedesktop.org, attach the D-Bus logs and link this bug?
https://bugs.freedesktop.org/show_bug.cgi?id=35337