| Issue 24: | connection lost immediately after connection | |
| Back to list |
Posted by guest at 2006-04-26 07:22:47
i had a lot of problems in last days but today this one is new
i restart the service try to connect and it dies. every time, reproducable with
this message and always such strange packets before the closing, i use svn
version 191 :
[2006-04-26 09:20:16] {5:
'\x00\x1a\x00+\xa8y\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\
x00\x00\x00\x00\x00\x00\x00'}
[2006-04-26 09:20:16] hmm, weird tlvs for <legacy.icqt.OA instance at
0xb705f06c> cookie packet
[2006-04-26 09:20:16] {5:
'\x00\x1a\x00+\xa8y\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\
x00\x00\x00\x00\x00\x00\x00'}
[2006-04-26 09:20:16] snac
[2006-04-26 09:20:16] [23, 1, 0, 0, 0L,
'\x00\x05\x00!\x00\x1a\x00+\xa8y\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00
\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00']
[2006-04-26 09:20:16] Connection Lost! <legacy.icqt.OA instance at
0xb705f06c>
[2006-04-26 09:20:16] Connection Lost! <legacy.icqt.OA instance at
0xb705f06c>
[2006-04-26 09:20:16] Stopping factory
<twisted.internet.protocol._InstanceFactory instance at 0xb705f0ac>
Posted by guest at 2006-04-26 07:28:10
im using psi client with it maybe it is a problem with psi?
[2006-04-26 09:26:12] INFO :: hackmett@jabber.cheatpoint.de :: Session ::
gotCapabilities :: {'var':
u'http://jabber.org/protocol/si/profile/file-transfer', 'el':
<tlib.domish.Element object at 0xb702bfac>, 'e': <tlib.domish.Element
object at 0xb702bcac>, 'item': <tlib.domish.Element object at
0xb702bdec>, 'fro': u'hackmett@jabber.cheatpoint.de/Psi'} :: Capabilities of
u'hackmett@jabber.cheatpoint.de/Psi':
u'http://jabber.org/protocol/bytestreams\n\thttp://jabber.org/protocol/si\n\thtt
p://jabber.org/protocol/si/profile/file-transfer'
[2006-04-26 09:26:17] {5:
'\x00\x1a\x02\x92r\xd6\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x
00\x00\x00\x00\x00\x00\x00\x00'}
[2006-04-26 09:26:17] hmm, weird tlvs for <legacy.icqt.OA instance at
0xb702104c> cookie packet
[2006-04-26 09:26:17] {5:
'\x00\x1a\x02\x92r\xd6\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x
00\x00\x00\x00\x00\x00\x00\x00'}
[2006-04-26 09:26:17] snac
[2006-04-26 09:26:17] [23, 1, 0, 0, 0L,
'\x00\x05\x00!\x00\x1a\x02\x92r\xd6\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\
x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00']
[2006-04-26 09:26:17] Connection Lost! <legacy.icqt.OA instance at
0xb702104c>
[2006-04-26 09:26:17] Connection Lost! <legacy.icqt.OA instance at
0xb702104c>
[2006-04-26 09:26:17] Stopping factory
<twisted.internet.protocol._InstanceFactory instance at 0xb70210ac>
Posted by guest at 2006-04-27 13:55:21
after switching now to svn 192 no such thing happened so far (thats a few hours
already) if it occurs again i will post
Posted by guest at 2006-04-28 07:41:48
unfortunately it happened again, same problem
it seems to happen randomly at connecting to the pyicq-t service with psi
client
[2006-04-28 09:03:45] INFO :: :: IqHandler :: onIq :: {'el':
<tlib.domish.Element object at 0xb702098c>, 'froj':
<tlib.jabber.jid.JID instance at 0xb6fcd16c>, 'fro':
u'admin@jabber.cheatpoint.de/Notebook', 'iqType': u'result', 'ulang': None,
'to': u'icq.cheatpoint.de', 'ID': u'379'} :: Doing callback
[2006-04-28 09:03:45] INFO :: admin@jabber.cheatpoint.de :: Session ::
gotCapabilities :: {'var':
u'http://jabber.org/protocol/si/profile/file-transfer', 'el':
<tlib.domish.Element object at 0xb702098c>, 'e': <tlib.domish.Element
object at 0xb70209ac>, 'item': <tlib.domish.Element object at
0xb6fcd1cc>, 'fro': u'admin@jabber.cheatpoint.de/Notebook'} :: Capabilities
of u'admin@jabber.cheatpoint.de/Notebook':
u'http://jabber.org/protocol/bytestreams\n\thttp://jabber.org/protocol/si\n\thtt
p://jabber.org/protocol/si/profile/file-transfer'
[2006-04-28 09:03:51] {5:
'\x00\x1a\x00+\xa8y\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\
x00\x00\x00\x00\x00\x00\x00'}
[2006-04-28 09:03:51] hmm, weird tlvs for <legacy.icqt.OA instance at
0xb6fd2eec> cookie packet
[2006-04-28 09:03:51] {5:
'\x00\x1a\x00+\xa8y\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\
x00\x00\x00\x00\x00\x00\x00'}
[2006-04-28 09:03:51] snac
[2006-04-28 09:03:51] [23, 1, 0, 0, 0L,
'\x00\x05\x00!\x00\x1a\x00+\xa8y\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00
\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00']
[2006-04-28 09:03:51] Connection Lost! <legacy.icqt.OA instance at
0xb6fd2eec>
[2006-04-28 09:03:51] Connection Lost! <legacy.icqt.OA instance at
0xb6fd2eec>
[2006-04-28 09:03:51] Stopping factory
<twisted.internet.protocol._InstanceFactory instance at 0xb6fd2f4c>
after this i cant go online in the service any more and have to restart it
Posted by jadestorm at 2006-04-29 02:21:59
Do you see an -attempt- to log in before restarting? Or does PyICQ not respond
at all?
Posted by jadestorm at 2006-04-30 02:52:26
Sounds like this has nothing to do with what version you are running. Sounds
more like a timing issue . . . like one day you can log in and one day you
can't. That sort of thing.
Posted by Z_God at 2006-05-10 12:58:16
I seem to have the same problem.
I too need to restart PyICQ-t after a while to be able to log in again.
Posted by Z_God at 2006-05-10 13:06:24
Here is a full debug log of a log-in attempt, when PyICQ-t is in such a
'need to
restart first, before logging in is possible again' state. (Also contains MySQL
error, don't know if this is related.)
http://haar.student.utwente.nl/~julius/pyicq-t-nologonanymore.debug.gz
Posted by micressor at 2006-06-15 08:57:22
[2006-06-15 10:55:12] Connection Lost! <legacy.icqt.OA instance at
0xb4e086ec>
[2006-06-15 10:55:12] Connection Lost! <legacy.icqt.OA instance at
0xb4e086ec>
[2006-06-15 10:55:12] Stopping factory
<twisted.internet.protocol._InstanceFactory instance at 0xb4e08b2c>
Same here, any news about that?
Posted by Z_God at 2006-11-02 10:25:37
Here is the log of a complete log-in attempt (right-click->login with Psi).
It seems PyICQt doesn't always attempt a complete log-in but expects an
connection to be already made. This is with latest SVN & Twisted 1.3.
[2006-11-02 11:15:23] INFO :: julius@haar.student.utwente.nl :: Session ::
onPresence :: {'el': <twisted.words.xish.domish.Element object at
0x4097d1ac>} ::
[2006-11-02 11:15:23] INFO :: julius@haar.student.utwente.nl :: Session ::
onPresence :: {'status': None, 'el': <twisted.words.xish.domish.Element
object at 0x4097d1ac>, 'froj': <twisted.words.protocols.jabber.jid.JID
instance at 0x40916cec>, 'show': None, 'url': None, 'fro':
u'julius@haar.student.utwente.nl/campus (middelvinger)', 'ptype': None,
'avatarType': '', 'avatarHash': '', 'toj':
<twisted.words.protocols.jabber.jid.JID instance at 0x4091682c>, 'to':
u'icq', 'child': <twisted.words.xish.domish.Element object at 0x4097586c>,
'nickname': '', 'priority': u'5'} :: Parsed presence packet
[2006-11-02 11:15:23] INFO :: julius@haar.student.utwente.nl :: Session ::
handleResourcePresence :: {'status': None, 'resource': u'campus
(middelvinger)',
'show': None, 'url': None, 'ptype': None, 'existing': True, 'priority': u'5',
'to': u'icq', 'tor': None, 'source': u'julius@haar.student.utwente.nl'} ::
Setting status
[2006-11-02 11:15:23] INFO :: julius@haar.student.utwente.nl :: Session ::
handleResourcePresence :: {'status': None, 'resource': u'campus
(middelvinger)',
'show': None, 'url': None, 'ptype': None, 'existing': True, 'priority': u'5',
'to': u'icq', 'tor': None, 'source': u'julius@haar.student.utwente.nl',
'highestActive': u'campus (middelvinger)'} :: Updating status on legacy service
[2006-11-02 11:15:23] INFO :: julius@haar.student.utwente.nl ::
glue.LegacyConnection :: setStatus :: {'url': None, 'nickname': u'Z_God',
'friendly': None, 'show': None} ::
[2006-11-02 11:15:44] INFO :: julius@haar.student.utwente.nl :: icqt.B ::
connectionLost :: {'message': 'Oversize arg', 'reason':
<twisted.python.failure.Failure twisted.internet.error.ConnectionLost>} ::
ICQ connection lost! Reason: [Failure instance: Traceback:
twisted.internet.error.ConnectionLost, Connection to the other side was lost in
a non-clean fashion.
]
[2006-11-02 11:15:44] INFO :: julius@haar.student.utwente.nl :: Session ::
sendMessage :: {'body': 'Oversize arg', 'delay': None, 'fro': 'icq', 'mtype':
'error', 'to': u'julius@haar.student.utwente.nl', 'xhtml': None, 'nickname':
None} ::
[2006-11-02 11:15:44] INFO :: :: :: sendMessage :: {'body': 'Oversize arg',
'delay': None, 'fro': 'icq', 'mtype': 'error', 'to':
u'julius@haar.student.utwente.nl', 'xhtml': None, 'nickname': None, 'pytrans':
<main.PyTransport instance at 0x403a7bec>} ::
[2006-11-02 11:15:44] Connection Lost! <legacy.icqt.B instance at
0x4097184c>
[2006-11-02 11:15:44] INFO :: julius@haar.student.utwente.nl :: Session ::
removeMe :: {} ::
[2006-11-02 11:15:44] INFO :: julius@haar.student.utwente.nl :: Session ::
sendPresence :: {'status': None, 'show': None, 'url': None, 'fro': 'icq',
'avatarHash': None, 'ptype': 'unavailable', 'priority': None, 'to':
u'julius@haar.student.utwente.nl', 'nickname': None, 'payload': []} ::
[2006-11-02 11:15:44] INFO :: julius@haar.student.utwente.nl ::
glue.LegacyConnection :: removeMe :: {'icq2jid': <function icq2jid at
0x407ffdf4>} ::
[2006-11-02 11:15:44] INFO :: julius@haar.student.utwente.nl :: ContactList ::
removeMe :: {} ::
[2006-11-02 11:15:44] INFO :: julius@haar.student.utwente.nl :: Session ::
removeMe :: {'tmpjid': 'icq'} :: Removed!
[2006-11-02 11:15:44] Stopping factory
<twisted.internet.protocol._InstanceFactory instance at 0x4096dbec>
Posted by Z_God at 2006-11-02 10:28:26
[2006-11-02 11:22:19] Stopping factory
<twisted.internet.protocol._InstanceFactory instance at 0x4098c26c>
Got just printed after this. I only attempted a single log-in from a single
JID.
Could it be possible instances get mixed up somewhere or something like that?
Posted by Z_God at 2006-11-02 10:32:48
It seems I could indeed log on fine now again. So it seems waiting for that
second stopping factory seemed to enable me to log in properly again.
During the log on, I did get another "Connection lost in non clean
fashion" error, but that didn't seem to prevent PyICQt from logging me in.
I could provide the debug log of this too if interesting.
Posted by Z_God at 2006-11-06 10:04:14
This bug seems to be related:
http://www.blathersource.org/bugs_view.php?projid=pyicq-t&bugid=205
The person who sent this message was also able to fix his problem by switching
reactors:
http://modevia.com/pipermail/py-transports/2006-November/003591.html
He did it by switching to the poll reactor.
I'm using Linux 2.4 myself which seems to cause the poll reactor to be used by
default already though.
Posted by Z_God at 2006-11-06 10:14:09
Addition to the previous message, poll still seems to give problems with him.
Could it be that epoll is the only safe reactor?
What is weird, he has the problem with PyAIMt, while I have it with PyICQt most
of the time and not very often with PyAIMt.
Jan 19, 2008
Project Member
#1
d...@jabber.fsinf.de
Status:
Fixed
|