[Juser] No more message with Jabber::SimpleSend since 12th Januar

Erik Wasser erik.wasser at iquer.net
Thu Mar 4 03:05:56 CST 2010


On Wednesday 03 March 2010 18:11:57 Peter Saint-Andre wrote:

> > What else can I do to pin down this problem?
> 
> Can you tell if your script is attempting to connect to port 5223?

According to the log, no:

> XMPP::Conn: Connect: host(jabber.org:5222) namespace(jabber:client)

The logfile is at the end of this mail. But first some other details, here's 
the challenge:

> nonce="m1ED9BZpOQggytyqs/4YFrnAdmoOgbqTwWscdyNdeFQ=",realm="jabber.org",
> qop="auth",maxbuf=16384,charset=utf-8,algorithm=md5-sess

...and the response...

> authzid="iquer.net at jabber.org",charset=utf-8,
> cnonce="bc82a685c77caa198d7f4c0d5f589a4c",digest-uri="/",nc=00000001,
> nonce="m1ED9BZpOQggytyqs/4YFrnAdmoOgbqTwWscdyNdeFQ=",
> qop=auth,realm="jabber.org",response=6ab7586c0223f44738dc05d9e7402327,
> username="iquer.net"

It seems that I'm not the only one with this problem:

http://www.igniterealtime.org/community/thread/20551

or (more detailed) here:

http://www.ejabberd.im/node/3545

I'm unsure if this is a server or a client problem. It seems to me that there 
is some confusion about the 'digest-url' parameter of the challenge/response.

In the meantime I've modified the Net/XMPP/Protocol.pm file so you can disable 
SASL on the fly. So the problem is solved (for me) but I think we should still 
narrow down the problem further.

> > The library can give an
> > debug output (~200 lines) of the calls. Should I post it here?
> 
> That would be helpful, thanks.

Okay. Here we go...

XML::Stream: new: hostname = (monitor0.iquer0.ffm0.iquer.net)
XML::Stream: SetCallBacks: tag(node) func(CODE(0x8405bf0))
XMPP::Conn: xmppCallbackInit: start
XMPP::Conn: SetCallBacks: tag(message) func(CODE(0x8405d10))
XMPP::Conn: SetCallBacks: tag(presence) func(CODE(0x8405b48))
XMPP::Conn: SetCallBacks: tag(iq) func(CODE(0x8405ae8))
XMPP::Conn: SetPresenceCallBacks: type(subscribe) func(CODE(0x8405b3c))
XMPP::Conn: SetPresenceCallBacks: type(subscribed) func(CODE(0x8405e9c))
XMPP::Conn: SetPresenceCallBacks: type(unsubscribe) func(CODE(0x8405e3c))
XMPP::Conn: SetPresenceCallBacks: type(unsubscribed) func(CODE(0x8405efc))
XMPP::Conn: SetDirectXPathCallBacks: 
xpath(/[@xmlns="urn:ietf:params:xml:ns:xmpp-tls"]) func(CODE(0x8405fec))
XMPP::Conn: SetDirectXPathCallBacks: 
xpath(/[@xmlns="urn:ietf:params:xml:ns:xmpp-sasl"]) func(CODE(0x8406130))
XMPP::Conn: xmppCallbackInit: stop
XMPP::Conn: Connect: host(jabber.org:5222) namespace(jabber:client)
XMPP::Conn: Connect: timeout(10)
XML::Stream: Connect: timeout(10)
XML::Stream: Connect: type(tcpip)
XML::Stream: Connect: Got a connection
XML::Stream: MarkActivity: sid(newconnection)
XML::Stream: Send: (<?xml version='1.0'?><stream:stream version='1.0' 
xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' 
to='jabber.org' from='monitor0.iquer0.ffm0.iquer.net' xml:lang='en' >)
XML::Stream: Send: sid(newconnection)
XML::Stream: Send: status(0)
XML::Stream: Send: socket(IO::Socket::INET=GLOB(0x84063b8))
XML::Stream: Send: can_write
XML::Stream: Send: SENDWRITTEN(189)
XML::Stream: Send: no exceptions
XML::Stream: MarkActivity: sid(newconnection)
XML::Stream: Connect: can_read(  )
XML::Stream: Read: sid(newconnection)
XML::Stream: Read: connectionType(tcpip)
XML::Stream: Read: socket(IO::Socket::INET=GLOB(0x84063b8))
XML::Stream: Read: buff(<?xml version='1.0'?><stream:stream 
xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' 
from='jabber.org' id='6cc9f04560925577' 
version='1.0'><stream:features><starttls xmlns='urn:ietf:params:xml:ns:xmpp-
tls'/><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>CRAM-
MD5</mechanism><mechanism>LOGIN</mechanism><mechanism>PLAIN</mechanism><mechanism>DIGEST-
MD5</mechanism></mechanisms><compression 
xmlns='http://jabber.org/features/compress'><method>zlib</method></compression><ver 
xmlns='urn:xmpp:features:rosterver'><optional/></ver></stream:features>)
XML::Stream: Read: status(582)
XML::Stream: ParseStream: sid(newconnection) stream(<?xml 
version='1.0'?><stream:stream xmlns='jabber:client' 
xmlns:stream='http://etherx.jabber.org/streams' from='jabber.org' 
id='6cc9f04560925577' version='1.0'><stream:features><starttls 
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/><mechanisms 
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>CRAM-
MD5</mechanism><mechanism>LOGIN</mechanism><mechanism>PLAIN</mechanism><mechanism>DIGEST-
MD5</mechanism></mechanisms><compression 
xmlns='http://jabber.org/features/compress'><method>zlib</method></compression><ver 
xmlns='urn:xmpp:features:rosterver'><optional/></ver></stream:features>)
XML::Stream: _handle_root: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(stream:stream) att( xmlns:stream 
http://etherx.jabber.org/streams xmlns jabber:client version 1.0 from 
jabber.org id 6cc9f04560925577 )
XML::Stream: _handle_root: (jabber:client)
XML::Stream: _handle_root: status(1)
XML::Stream: _handle_root: RegisterPrefix: 
prefix(http://etherx.jabber.org/streams) ns(stream)
XML::Stream: _handle_root: stream_prefix(stream)
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(stream:features) att( )
XML::Stream: Node: _handle_element: check( -1 )
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(starttls) att( xmlns 
urn:ietf:params:xml:ns:xmpp-tls )
XML::Stream: Node: _handle_element: check( 0 )
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(starttls)
XML::Stream: Node: _handle_close: check( 1 )
XML::Stream: Node: _handle_close: check2( 0 )
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(mechanisms) att( xmlns 
urn:ietf:params:xml:ns:xmpp-sasl )
XML::Stream: Node: _handle_element: check( 0 )
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(mechanism) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_cdata: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) cdata(CRAM-MD5)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8406790)) 
cdata(CRAM-MD5)
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(mechanism)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(mechanism) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_cdata: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) cdata(LOGIN)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8406790)) 
cdata(LOGIN)
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(mechanism)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(mechanism) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_cdata: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) cdata(PLAIN)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8406790)) 
cdata(PLAIN)
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(mechanism)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(mechanism) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_cdata: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) cdata(DIGEST-MD5)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8406790)) 
cdata(DIGEST-MD5)
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(mechanism)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(mechanisms)
XML::Stream: Node: _handle_close: check( 1 )
XML::Stream: Node: _handle_close: check2( 0 )
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(compression) att( xmlns 
http://jabber.org/features/compress )
XML::Stream: Node: _handle_element: check( 0 )
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(method) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_cdata: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) cdata(zlib)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8406790)) 
cdata(zlib)
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(method)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(compression)
XML::Stream: Node: _handle_close: check( 1 )
XML::Stream: Node: _handle_close: check2( 0 )
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(ver) att( xmlns 
urn:xmpp:features:rosterver )
XML::Stream: Node: _handle_element: check( 0 )
XML::Stream: Node: _handle_element: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(optional) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(optional)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(ver)
XML::Stream: Node: _handle_close: check( 1 )
XML::Stream: Node: _handle_close: check2( 0 )
XML::Stream: Node: _handle_close: sid(newconnection) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(stream:features)
XML::Stream: Node: _handle_close: check( 0 )
XML::Stream: Node: _handle_close: check2( -1 )
XML::Stream: Connect: status(1)
XMPP::Conn: Connect: connection made
XML::Stream: SetCallBacks: tag(node) func(CODE(0x840628c))
XML::Stream: Send: (<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>)
XML::Stream: Send: sid(6cc9f04560925577)
XML::Stream: Send: status(1)
XML::Stream: Send: socket(IO::Socket::INET=GLOB(0x84063b8))
XML::Stream: Send: can_write
XML::Stream: Send: SENDWRITTEN(51)
XML::Stream: Send: no exceptions
XML::Stream: MarkActivity: sid(6cc9f04560925577)
XML::Stream: Process: timeout(1)
XML::Stream: Process: initialize sid(6cc9f04560925577) status to 0
XML::Stream: Process: let's wait for data
XML::Stream: Process: connection(IO::Socket::INET=GLOB(0x84063b8))
XML::Stream: Process: sid(6cc9f04560925577)
XML::Stream: Process: connection_status(1)
XML::Stream: Process: there's something to read
XML::Stream: Process: connection(IO::Socket::INET=GLOB(0x84063b8)) 
sid(6cc9f04560925577)
XML::Stream: Process: read
XML::Stream: Read: sid(6cc9f04560925577)
XML::Stream: Read: connectionType(tcpip)
XML::Stream: Read: socket(IO::Socket::INET=GLOB(0x84063b8))
XML::Stream: Read: buff(<proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>)
XML::Stream: Read: status(50)
XML::Stream: Process: connection_status(1)
XML::Stream: Process: parse(<proceed xmlns='urn:ietf:params:xml:ns:xmpp-
tls'/>)
XML::Stream: ParseStream: sid(6cc9f04560925577) stream(<proceed 
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>)
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(proceed) att( xmlns 
urn:ietf:params:xml:ns:xmpp-tls )
XML::Stream: Node: _handle_element: check( -1 )
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8406790)) tag(proceed)
XML::Stream: Node: _handle_close: check( 0 )
XML::Stream: Node: _handle_close: check2( -1 )
XML::Stream: TLSClientProceed: Convert normal socket to SSL
XML::Stream: TLSClientProceed: sock(IO::Socket::INET=GLOB(0x84063b8))
XML::Stream: LoadSSL: Load the IO::Socket::SSL module
XML::Stream: LoadSSL: Success
XML::Stream: TLSClientProceed: ssl_sock(IO::Socket::SSL=GLOB(0x84063b8))
XML::Stream: TLSClientProceed: SSL: We are secure
XML::Stream: Process: Everyone out of the pool! Time to stop blocking.
XML::Stream: Process: timeout(1)
XML::Stream: Process: check for keepalives
XML::Stream: Process: check for timeouts
XML::Stream: Process: sid(6cc9f04560925577) time( 1267692395 ) timeout(undef)
XML::Stream: Process: block(0)
XML::Stream: MarkActivity: sid(6cc9f04560925577)
XML::Stream: Send: (<?xml version='1.0'?><stream:stream version='1.0' 
xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' 
to='jabber.org' from='monitor0.iquer0.ffm0.iquer.net' xml:lang='en' >)
XML::Stream: Send: sid(6cc9f04560925577)
XML::Stream: Send: status(0)
XML::Stream: Send: socket(IO::Socket::SSL=GLOB(0x84063b8))
XML::Stream: Send: can_write
XML::Stream: Send: SENDWRITTEN(189)
XML::Stream: Send: no exceptions
XML::Stream: MarkActivity: sid(6cc9f04560925577)
XML::Stream: Connect: can_read(  )
XML::Stream: Read: sid(6cc9f04560925577)
XML::Stream: Read: connectionType(tcpip)
XML::Stream: Read: socket(IO::Socket::SSL=GLOB(0x84063b8))
XML::Stream: Read: buff(<?xml version='1.0'?><stream:stream 
xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' 
from='jabber.org' id='0fc1937cb92c18ea' 
version='1.0'><stream:features><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-
sasl'><mechanism>CRAM-
MD5</mechanism><mechanism>LOGIN</mechanism><mechanism>PLAIN</mechanism><mechanism>DIGEST-
MD5</mechanism></mechanisms><compression 
xmlns='http://jabber.org/features/compress'><method>zlib</method></compression><ver 
xmlns='urn:xmpp:features:rosterver'><optional/></ver></stream:features>)
XML::Stream: Read: status(531)
XML::Stream: ParseStream: sid(6cc9f04560925577) stream(<?xml 
version='1.0'?><stream:stream xmlns='jabber:client' 
xmlns:stream='http://etherx.jabber.org/streams' from='jabber.org' 
id='0fc1937cb92c18ea' version='1.0'><stream:features><mechanisms 
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>CRAM-
MD5</mechanism><mechanism>LOGIN</mechanism><mechanism>PLAIN</mechanism><mechanism>DIGEST-
MD5</mechanism></mechanisms><compression 
xmlns='http://jabber.org/features/compress'><method>zlib</method></compression><ver 
xmlns='urn:xmpp:features:rosterver'><optional/></ver></stream:features>)
XML::Stream: _handle_root: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(stream:stream) att( xmlns:stream 
http://etherx.jabber.org/streams xmlns jabber:client version 1.0 from 
jabber.org id 0fc1937cb92c18ea )
XML::Stream: _handle_root: (jabber:client)
XML::Stream: _handle_root: status(1)
XML::Stream: _handle_root: RegisterPrefix: 
prefix(http://etherx.jabber.org/streams) ns(stream)
XML::Stream: _handle_root: stream_prefix(stream)
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(stream:features) att( )
XML::Stream: Node: _handle_element: check( -1 )
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(mechanisms) att( xmlns 
urn:ietf:params:xml:ns:xmpp-sasl )
XML::Stream: Node: _handle_element: check( 0 )
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(mechanism) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_cdata: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) cdata(CRAM-MD5)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8d684bc)) 
cdata(CRAM-MD5)
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(mechanism)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(mechanism) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_cdata: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) cdata(LOGIN)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8d684bc)) 
cdata(LOGIN)
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(mechanism)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(mechanism) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_cdata: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) cdata(PLAIN)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8d684bc)) 
cdata(PLAIN)
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(mechanism)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(mechanism) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_cdata: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) cdata(DIGEST-MD5)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8d684bc)) 
cdata(DIGEST-MD5)
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(mechanism)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(mechanisms)
XML::Stream: Node: _handle_close: check( 1 )
XML::Stream: Node: _handle_close: check2( 0 )
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(compression) att( xmlns 
http://jabber.org/features/compress )
XML::Stream: Node: _handle_element: check( 0 )
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(method) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_cdata: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) cdata(zlib)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8d684bc)) 
cdata(zlib)
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(method)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(compression)
XML::Stream: Node: _handle_close: check( 1 )
XML::Stream: Node: _handle_close: check2( 0 )
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(ver) att( xmlns 
urn:xmpp:features:rosterver )
XML::Stream: Node: _handle_element: check( 0 )
XML::Stream: Node: _handle_element: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(optional) att( )
XML::Stream: Node: _handle_element: check( 1 )
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(optional)
XML::Stream: Node: _handle_close: check( 2 )
XML::Stream: Node: _handle_close: check2( 1 )
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(ver)
XML::Stream: Node: _handle_close: check( 1 )
XML::Stream: Node: _handle_close: check2( 0 )
XML::Stream: Node: _handle_close: sid(6cc9f04560925577) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(stream:features)
XML::Stream: Node: _handle_close: check( 0 )
XML::Stream: Node: _handle_close: check2( -1 )
XML::Stream: Connect: status(1)
XMPP::Conn: AuthSASL: shiney new auth
XML::Stream: Send: (<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' 
mechanism='DIGEST-MD5'></auth>)
XML::Stream: Send: sid(0fc1937cb92c18ea)
XML::Stream: Send: status(1)
XML::Stream: Send: socket(IO::Socket::SSL=GLOB(0x84063b8))
XML::Stream: Send: can_write
XML::Stream: Send: SENDWRITTEN(77)
XML::Stream: Send: no exceptions
XML::Stream: MarkActivity: sid(0fc1937cb92c18ea)
XMPP::Conn: AuthSASL: haven't authed yet... let's wait.
XMPP::Conn: Process: timeout(1)
XML::Stream: Process: timeout(1)
XML::Stream: Process: initialize sid(0fc1937cb92c18ea) status to 0
XML::Stream: Process: let's wait for data
XML::Stream: Process: connection(IO::Socket::SSL=GLOB(0x84063b8))
XML::Stream: Process: sid(0fc1937cb92c18ea)
XML::Stream: Process: connection_status(1)
XML::Stream: Process: there's something to read
XML::Stream: Process: connection(IO::Socket::SSL=GLOB(0x84063b8)) 
sid(0fc1937cb92c18ea)
XML::Stream: Process: read
XML::Stream: Read: sid(0fc1937cb92c18ea)
XML::Stream: Read: connectionType(tcpip)
XML::Stream: Read: socket(IO::Socket::SSL=GLOB(0x84063b8))
XML::Stream: Read: buff(<challenge xmlns='urn:ietf:params:xml:ns:xmpp-
sasl'>bm9uY2U9Im0xRUQ5QlpwT1FnZ3l0eXFzLzRZRnJuQWRtb09nYnFUd1dzY2R5TmRlRlE9IixyZWFsbT0iamFiYmVyLm9yZyIscW9wPSJhdXRoIixtYXhidWY9MTYzODQsY2hhcnNldD11dGYtOCxhbGdvcml0aG09bWQ1LXNlc3M=</challenge>)
XML::Stream: Read: status(236)
XML::Stream: Process: connection_status(1)
XML::Stream: Process: parse(<challenge xmlns='urn:ietf:params:xml:ns:xmpp-
sasl'>bm9uY2U9Im0xRUQ5QlpwT1FnZ3l0eXFzLzRZRnJuQWRtb09nYnFUd1dzY2R5TmRlRlE9IixyZWFsbT0iamFiYmVyLm9yZyIscW9wPSJhdXRoIixtYXhidWY9MTYzODQsY2hhcnNldD11dGYtOCxhbGdvcml0aG09bWQ1LXNlc3M=</challenge>)
XML::Stream: ParseStream: sid(0fc1937cb92c18ea) stream(<challenge 
xmlns='urn:ietf:params:xml:ns:xmpp-
sasl'>bm9uY2U9Im0xRUQ5QlpwT1FnZ3l0eXFzLzRZRnJuQWRtb09nYnFUd1dzY2R5TmRlRlE9IixyZWFsbT0iamFiYmVyLm9yZyIscW9wPSJhdXRoIixtYXhidWY9MTYzODQsY2hhcnNldD11dGYtOCxhbGdvcml0aG09bWQ1LXNlc3M=</challenge>)
XML::Stream: Node: _handle_element: sid(0fc1937cb92c18ea) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(challenge) att( xmlns 
urn:ietf:params:xml:ns:xmpp-sasl )
XML::Stream: Node: _handle_element: check( -1 )
XML::Stream: Node: _handle_cdata: sid(0fc1937cb92c18ea) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) 
cdata(bm9uY2U9Im0xRUQ5QlpwT1FnZ3l0eXFzLzRZRnJuQWRtb09nYnFUd1dzY2R5TmRlRlE9IixyZWFsbT0iamFiYmVyLm9yZyIscW9wPSJhdXRoIixtYXhidWY9MTYzODQsY2hhcnNldD11dGYtOCxhbGdvcml0aG09bWQ1LXNlc3M=)
XML::Stream: Node: _handle_cdata: sax(XML::Stream::Parser=HASH(0x8d684bc)) 
cdata(bm9uY2U9Im0xRUQ5QlpwT1FnZ3l0eXFzLzRZRnJuQWRtb09nYnFUd1dzY2R5TmRlRlE9IixyZWFsbT0iamFiYmVyLm9yZyIscW9wPSJhdXRoIixtYXhidWY9MTYzODQsY2hhcnNldD11dGYtOCxhbGdvcml0aG09bWQ1LXNlc3M=)
XML::Stream: Node: _handle_close: sid(0fc1937cb92c18ea) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(challenge)
XML::Stream: Node: _handle_close: check( 0 )
XML::Stream: Node: _handle_close: check2( -1 )
XML::Stream: Send: (<response xmlns='urn:ietf:params:xml:ns:xmpp-
sasl'>YXV0aHppZD0iaXF1ZXIubmV0QGphYmJlci5vcmciLGNoYXJzZXQ9dXRmLTgsY25vbmNlPSJiYzgyYTY4NWM3N2NhYTE5OGQ3ZjRjMGQ1ZjU4OWE0YyIsZGlnZXN0LXVyaT0iLyIsbmM9MDAwMDAwMDEsbm9uY2U9Im0xRUQ5QlpwT1FnZ3l0eXFzLzRZRnJuQWRtb09nYnFUd1dzY2R5TmRlRlE9Iixxb3A9YXV0aCxyZWFsbT0iamFiYmVyLm9yZyIscmVzcG9uc2U9NmFiNzU4NmMwMjIzZjQ0NzM4ZGMwNWQ5ZTc0MDIzMjcsdXNlcm5hbWU9ImlxdWVyLm5ldCI=</response>)
XML::Stream: Send: sid(0fc1937cb92c18ea)
XML::Stream: Send: status(1)
XML::Stream: Send: socket(IO::Socket::SSL=GLOB(0x84063b8))
XML::Stream: Send: can_write
XML::Stream: Send: SENDWRITTEN(406)
XML::Stream: Send: no exceptions
XML::Stream: MarkActivity: sid(0fc1937cb92c18ea)
XML::Stream: Process: timeout(1)
XML::Stream: Process: check for keepalives
XML::Stream: Process: check for timeouts
XML::Stream: Process: sid(0fc1937cb92c18ea) time( 1267692395 ) timeout(undef)
XML::Stream: Process: block(0)
XMPP::Conn: AuthSASL: haven't authed yet... let's wait.
XMPP::Conn: Process: timeout(1)
XML::Stream: Process: timeout(1)
XML::Stream: Process: initialize sid(0fc1937cb92c18ea) status to 0
XML::Stream: Process: let's wait for data
XML::Stream: Process: connection(IO::Socket::SSL=GLOB(0x84063b8))
XML::Stream: Process: sid(0fc1937cb92c18ea)
XML::Stream: Process: connection_status(1)
XML::Stream: Process: there's something to read
XML::Stream: Process: connection(IO::Socket::SSL=GLOB(0x84063b8)) 
sid(0fc1937cb92c18ea)
XML::Stream: Process: read
XML::Stream: Read: sid(0fc1937cb92c18ea)
XML::Stream: Read: connectionType(tcpip)
XML::Stream: Read: socket(IO::Socket::SSL=GLOB(0x84063b8))
XML::Stream: Read: buff(<failure xmlns='urn:ietf:params:xml:ns:xmpp-
sasl'><invalid-authzid/></failure>)
XML::Stream: Read: status(78)
XML::Stream: Process: connection_status(1)
XML::Stream: Process: parse(<failure xmlns='urn:ietf:params:xml:ns:xmpp-
sasl'><invalid-authzid/></failure>)
XML::Stream: ParseStream: sid(0fc1937cb92c18ea) stream(<failure 
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><invalid-authzid/></failure>)
XML::Stream: Node: _handle_element: sid(0fc1937cb92c18ea) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(failure) att( xmlns 
urn:ietf:params:xml:ns:xmpp-sasl )
XML::Stream: Node: _handle_element: check( -1 )
XML::Stream: Node: _handle_element: sid(0fc1937cb92c18ea) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(invalid-authzid) att( )
XML::Stream: Node: _handle_element: check( 0 )
XML::Stream: Node: _handle_close: sid(0fc1937cb92c18ea) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(invalid-authzid)
XML::Stream: Node: _handle_close: check( 1 )
XML::Stream: Node: _handle_close: check2( 0 )
XML::Stream: Node: _handle_close: sid(0fc1937cb92c18ea) 
sax(XML::Stream::Parser=HASH(0x8d684bc)) tag(failure)
XML::Stream: Node: _handle_close: check( 0 )
XML::Stream: Node: _handle_close: check2( -1 )
XML::Stream: Process: Everyone out of the pool! Time to stop blocking.
XML::Stream: Process: timeout(1)
XML::Stream: Process: check for keepalives
XML::Stream: Process: check for timeouts
XML::Stream: Process: sid(0fc1937cb92c18ea) time( 1267692396 ) timeout(undef)
XML::Stream: Process: block(0)
XMPP::Conn: AuthSASL: Authentication failed.
XML::Stream: Send: (</stream:stream>)
XML::Stream: Send: sid(0fc1937cb92c18ea)
XML::Stream: Send: status(1)
XML::Stream: Send: socket(IO::Socket::SSL=GLOB(0x84063b8))
XML::Stream: Send: can_write
XML::Stream: Send: SENDWRITTEN(16)
XML::Stream: Send: no exceptions
XML::Stream: MarkActivity: sid(0fc1937cb92c18ea)
XML::Stream: SetCallBacks: tag(node) func(CODE(0x8d68ba0))
XMPP::Conn: Disconnect: bye bye

-- 
So long... Erik


More information about the JUser mailing list