Closed GoogleCodeExporter closed 9 years ago
More logs:
(4815ms)D47C63EDEF975513F... (line 1816)
[DEBUG] GWT CONNECTOR RECEIVED: <body
xmlns='http://jabber.org/protocol/httpbind'><presence
from="test100@emitedemo.ourproject.org/emiteui-1209409726804"
to="test1@emitedemo.ourproject.org"><show>dnd</show></presence></body>D47C63EDEF
975513F...
(line 24)
[DEBUG] Dispatching:
com.calclab.emite.client.core.packet.gwt.GWTPacket@3cD47C63EDEF975513F... (line
24)
[DEBUG] DISPATCH BEGINS >>>>>>>>>>>D47C63EDEF975513F... (line 24)
[DEBUG] Dispatching:
com.calclab.emite.client.core.packet.gwt.GWTPacket@3dD47C63EDEF975513F... (line
24)
[INFO] Updating roster item: test100@emitedemo.ourproject.org name: test100
subsc:
bothD47C63EDEF975513F... (line 24)
[INFO] PRESENCE: type: available from:
test100@emitedemo.ourproject.org/emiteui-1209409726804 show: dnd status:
nullD47C63EDEF975513F... (line 24)
[INFO] PRESENCE: type: available from:
test100@emitedemo.ourproject.org/emiteui-1209409726804 show: dnd status:
nullD47C63EDEF975513F... (line 24)
[DEBUG] <<<<<<<<<<<< DISPATCH ENDSD47C63EDEF975513F... (line 24)
[DEBUG] DELAY: 1718D47C63EDEF975513F... (line 24)
[DEBUG] Dispatching: <event xmlns="emite:event" type="connection:do:pull"
/>D47C63EDEF975513F... (line 24)
[DEBUG] DISPATCH BEGINS >>>>>>>>>>>D47C63EDEF975513F... (line 24)
[DEBUG] <<<<<<<<<<<< DISPATCH ENDSD47C63EDEF975513F... (line 24)
[DEBUG] Sending: Poll overhead: -119, connections: 0D47C63EDEF975513F... (line
24)
[DEBUG] DELAY: 0D47C63EDEF975513F... (line 24)
[DEBUG] GWT CONNECTOR SEND: <body xmlns="http://jabber.org/protocol/httpbind"
rid="202819" sid="8f275019" />D47C63EDEF975513F... (line 24)
POST http://emite.ourproject.org/proxy (2385ms)D47C63EDEF975513F... (line 1816)
[DEBUG] GWT CONNECTOR RECEIVED: <body
xmlns="http://jabber.org/protocol/httpbind"
type="terminal" condition="policy-violation"></body>D47C63EDEF975513F... (line
24)
[DEBUG] Dispatching:
com.calclab.emite.client.core.packet.gwt.GWTPacket@3eD47C63EDEF975513F... (line
24)
[DEBUG] DISPATCH BEGINS >>>>>>>>>>>D47C63EDEF975513F... (line 24)
[DEBUG] Dispatching: <event xmlns="emite:event" type="connection:on:error"
cause="terminal" info="policy-violation" />D47C63EDEF975513F... (line 24)
[INFO] STATE CHANGED: error - old: connectedD47C63EDEF975513F... (line 24)
[INFO] STATE CHANGED: disconnected - old: errorD47C63EDEF975513F... (line 24)
[DEBUG] <<<<<<<<<<<< DISPATCH ENDSD47C63EDEF975513F... (line 24)
[DEBUG] BOSH IS STOP. NO NEED TO SEND ANYTHING
Original comment by vruiz.ju...@gmail.com
on 28 Apr 2008 at 7:39
More important, log in the server (warn.log):
2008.04.28 21:39:51 Request 789497 > 789496, ending session.
2008.04.28 21:39:52
[org.jivesoftware.openfire.net.VirtualConnection.close(VirtualConnection.java:16
0)]
Could not close socket
org.jivesoftware.openfire.http.HttpSession$HttpVirtualConnection@ca2076
java.lang.NullPointerException
at
org.jivesoftware.openfire.http.HttpSession$Deliverable.getPackets(HttpSession.ja
va:809)
at org.jivesoftware.openfire.http.HttpSession.failDelivery(HttpSession.java:682)
at
org.jivesoftware.openfire.http.HttpSession.closeConnection(HttpSession.java:671)
at org.jivesoftware.openfire.http.HttpSession.access$200(HttpSession.java:52)
at
org.jivesoftware.openfire.http.HttpSession$HttpVirtualConnection.closeVirtualCon
nection(HttpSession.java:740)
at
org.jivesoftware.openfire.net.VirtualConnection.close(VirtualConnection.java:156
)
at org.jivesoftware.openfire.http.HttpSession.close(HttpSession.java:161)
at
org.jivesoftware.openfire.http.HttpBindServlet.sendError(HttpBindServlet.java:18
8)
at
org.jivesoftware.openfire.http.HttpBindServlet.handleSessionRequest(HttpBindServ
let.java:225)
at
org.jivesoftware.openfire.http.HttpBindServlet.parseDocument(HttpBindServlet.jav
a:150)
at
org.jivesoftware.openfire.http.HttpBindServlet.doPost(HttpBindServlet.java:120)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:727)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:820)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:487)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:362)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:726)
at
org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollecti
on.java:206)
at org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114)
at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152)
at org.mortbay.jetty.Server.handle(Server.java:324)
at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:505)
at
org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:842)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:648)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:205)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:380)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:395)
at
org.mortbay.thread.BoundedThreadPool$PoolThread.run(BoundedThreadPool.java:450)
***** And just in the same time this a "item-not-found" error:
[DEBUG] BOSH IS STOP. NO NEED TO SEND ANYTHINGD47C63EDEF975513F... (line 24)
[DEBUG] Dispatching: <event xmlns="emite:event" type="connection:do:start"
domain="emitedemo.ourproject.org" />D47C63EDEF975513F... (line 24)
[DEBUG] <<<<<<<<<<<< DISPATCH ENDSD47C63EDEF975513F... (line 24)
[DEBUG] Sending: Poll overhead: -41, connections: 0D47C63EDEF975513F... (line
24)
[DEBUG] DELAY: 0D47C63EDEF975513F... (line 24)
[DEBUG] GWT CONNECTOR SEND: <body xmlns="http://jabber.org/protocol/httpbind"
rid="789494" content="text/xml; charset=utf-8" to="emitedemo.ourproject.org"
secure="true" ver="1.6" wait="60" ack="1" hold="1" xml:lang="en"
/>D47C63EDEF975513F... (line 24)
POST http://emite.ourproject.org/proxy (4481ms)D47C63EDEF975513F... (line 1816)
[DEBUG] Dispatching: <event xmlns="emite:event"
type="connection:do:send"><presence
xmlns="jabber:client"><show>available</show></presence></event>D47C63EDEF975513F
...
(line 24)
[DEBUG] DISPATCH BEGINS >>>>>>>>>>>D47C63EDEF975513F... (line 24)
[DEBUG] <<<<<<<<<<<< DISPATCH ENDSD47C63EDEF975513F... (line 24)
[DEBUG] Sending not empty. Conn: 1D47C63EDEF975513F... (line 24)
[DEBUG] DELAY: 0D47C63EDEF975513F... (line 24)
[DEBUG] GWT CONNECTOR SEND: <body xmlns="http://jabber.org/protocol/httpbind"
rid="789495"><presence
xmlns="jabber:client"><show>available</show></presence></body>D47C63EDEF975513F.
..
(line 24)
POST http://emite.ourproject.org/proxy (6565ms)D47C63EDEF975513F... (line 1816)
[DEBUG] GWT CONNECTOR RECEIVED: <body
xmlns="http://jabber.org/protocol/httpbind"
xmlns:stream="http://etherx.jabber.org/streams" authid="debf3808" sid="debf3808"
secure="true" requests="2" inactivity="30" polling="5" wait="60"
ver="1.6"><stream:features><mechanisms
xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>DIGEST-MD5</mechanism><mecha
nism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism><mechanism>CRAM-MD5</mecha
nism></mechanisms><compression
xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><
bind
xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session
xmlns="urn:ietf:params:xml:ns:xmpp-session"/></stream:features></body>D47C63EDEF
975513F...
(line 24)
[DEBUG] Dispatching:
com.calclab.emite.client.core.packet.gwt.GWTPacket@3fD47C63EDEF975513F... (line
24)
[DEBUG] DISPATCH BEGINS >>>>>>>>>>>D47C63EDEF975513F... (line 24)
[DEBUG] Dispatching:
com.calclab.emite.client.core.packet.gwt.GWTPacket@40D47C63EDEF975513F... (line
24)
[DEBUG] Dispatching: <event xmlns="emite:event" type="session:do:authorization"
/>D47C63EDEF975513F... (line 24)
[DEBUG] Dispatching: <event xmlns="emite:event" type="connection:do:send"><auth
xmlns="urn:ietf:params:xml:ns:xmpp-sasl"
mechanism="PLAIN">dGVzdDFAZW1pdGVkZW1vLm91cnByb2plY3Qub3JnAHRlc3QxAHRlc3Qx</auth
></event>D47C63EDEF975513F...
(line 24)
[DEBUG] <<<<<<<<<<<< DISPATCH ENDSD47C63EDEF975513F... (line 24)
[DEBUG] Sending not empty. Conn: 1D47C63EDEF975513F... (line 24)
[DEBUG] DELAY: 0D47C63EDEF975513F... (line 24)
[DEBUG] GWT CONNECTOR SEND: <body xmlns="http://jabber.org/protocol/httpbind"
rid="789496" sid="debf3808"><auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl"
mechanism="PLAIN">dGVzdDFAZW1pdGVkZW1vLm91cnByb2plY3Qub3JnAHRlc3QxAHRlc3Qx</auth
></body>D47C63EDEF975513F...
(line 24)
POST http://emite.ourproject.org/proxy (64791ms)D47C63EDEF975513F... (line 1816)
[DEBUG] GWT CONNECTOR RECEIVED: <body
xmlns="http://jabber.org/protocol/httpbind"
xmlns:stream="http://etherx.jabber.org/streams" authid="edd8bd42" sid="edd8bd42"
secure="true" requests="2" inactivity="30" polling="5"
wait="60"><stream:features><mechanisms
xmlns="urn:ietf:params:xml:ns:xmpp-sasl"><mechanism>DIGEST-MD5</mechanism><mecha
nism>PLAIN</mechanism><mechanism>ANONYMOUS</mechanism><mechanism>CRAM-MD5</mecha
nism></mechanisms><compression
xmlns="http://jabber.org/features/compress"><method>zlib</method></compression><
bind
xmlns="urn:ietf:params:xml:ns:xmpp-bind"/><session
xmlns="urn:ietf:params:xml:ns:xmpp-session"/></stream:features></body>D47C63EDEF
975513F...
(line 24)
[DEBUG] Dispatching:
com.calclab.emite.client.core.packet.gwt.GWTPacket@41D47C63EDEF975513F... (line
24)
[DEBUG] DISPATCH BEGINS >>>>>>>>>>>D47C63EDEF975513F... (line 24)
[DEBUG] Dispatching:
com.calclab.emite.client.core.packet.gwt.GWTPacket@42D47C63EDEF975513F... (line
24)
[DEBUG] Dispatching: <event xmlns="emite:event" type="session:do:authorization"
/>D47C63EDEF975513F... (line 24)
[DEBUG] Dispatching: <event xmlns="emite:event" type="connection:do:send"><auth
xmlns="urn:ietf:params:xml:ns:xmpp-sasl"
mechanism="PLAIN">dGVzdDFAZW1pdGVkZW1vLm91cnByb2plY3Qub3JnAHRlc3QxAHRlc3Qx</auth
></event>D47C63EDEF975513F...
(line 24)
[DEBUG] <<<<<<<<<<<< DISPATCH ENDSD47C63EDEF975513F... (line 24)
[DEBUG] Sending not empty. Conn: 1D47C63EDEF975513F... (line 24)
[DEBUG] DELAY: 0D47C63EDEF975513F... (line 24)
[DEBUG] GWT CONNECTOR SEND: <body xmlns="http://jabber.org/protocol/httpbind"
rid="789497" sid="debf3808"><auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl"
mechanism="PLAIN">dGVzdDFAZW1pdGVkZW1vLm91cnByb2plY3Qub3JnAHRlc3QxAHRlc3Qx</auth
></body>D47C63EDEF975513F...
(line 24)
POST http://emite.ourproject.org/proxy (18894ms)D47C63EDEF975513F... (line 1816)
[DEBUG] GWT CONNECTOR RECEIVED: <body
xmlns="http://jabber.org/protocol/httpbind"
type="terminal" condition="item-not-found"></body>D47C63EDEF975513F... (line 24)
[DEBUG] Dispatching:
com.calclab.emite.client.core.packet.gwt.GWTPacket@43D47C63EDEF975513F... (line
24)
[DEBUG] DISPATCH BEGINS >>>>>>>>>>>D47C63EDEF975513F... (line 24)
[DEBUG] Dispatching: <event xmlns="emite:event" type="connection:on:error"
cause="terminal" info="item-not-found" />D47C63EDEF975513F... (line 24)
[INFO] STATE CHANGED: error - old: connectingD47C63EDEF975513F... (line 24)
[INFO] STATE CHANGED: disconnected - old: errorD47C63EDEF975513F... (line 24)
[DEBUG] <<<<<<<<<<<< DISPATCH ENDSD47C63EDEF975513F... (line 24)
[DEBUG] BOSH IS STOP. NO NEED TO SEND ANYTHING
Original comment by vruiz.ju...@gmail.com
on 28 Apr 2008 at 7:45
Probably solved
Original comment by danigb
on 24 Aug 2010 at 7:52
Original issue reported on code.google.com by
vruiz.ju...@gmail.com
on 23 Apr 2008 at 12:17