Got XMPPException internal-server-error(500) when I use Roster.createEntry()

Smack Smack Dev
1

when I use Roster.createEntry() I got XMPPException internal-server-error(500),

I saw that some other friends here got the same problem, but there problem is not well answered.

I got the debug.log below

2013.12.11 18:25:47 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/127.0.0.1:54527] Data Read: org.apache.mina.filter.support.SSLHandler@430f4 (HeapBuffer[pos=0 lim=22 cap=64: 17 03 01 00 11 10 62 A8 72 73 B2 6D 6C 57 B6 E1 7E C2 BC E0 7B 8C])

2013.12.11 18:25:47 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/127.0.0.1:54527] unwrap()

2013.12.11 18:25:47 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/127.0.0.1:54527] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=22 cap=16665]

2013.12.11 18:25:47 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/127.0.0.1:54527] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]

2013.12.11 18:25:47 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/127.0.0.1:54527] Unwrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING

bytesConsumed = 22 bytesProduced = 1

2013.12.11 18:25:47 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/127.0.0.1:54527] inNetBuffer: java.nio.DirectByteBuffer[pos=22 lim=22 cap=16665]

2013.12.11 18:25:47 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/127.0.0.1:54527] appBuffer: java.nio.DirectByteBuffer[pos=1 lim=33330 cap=33330]

2013.12.11 18:25:47 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/127.0.0.1:54527] Unwrap res:Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING

bytesConsumed = 0 bytesProduced = 0

2013.12.11 18:25:47 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/127.0.0.1:54527] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=1 cap=33330]

2013.12.11 18:25:47 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/127.0.0.1:54527] app data read: HeapBuffer[pos=0 lim=1 cap=1: 20] (20)

2013.12.11 18:25:47 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /127.0.0.1:54527

2013.12.11 18:25:47 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /127.0.0.1:54527

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] Data Read: org.apache.mina.filter.support.SSLHandler@154aed9 (HeapBuffer[pos=0 lim=169 cap=256: 17 03 01 00 A4 23 A5 3B 23 66 7B A0 E1 58 51 9D 11 31 CA 1F B9 D7 A0 73 4A E2 58 C5 69 50 D0 88 CA 84 EC E5 E6 E0 5D EA 1E 7A B8 1A 1F C5 6B F1 CD C3 35 02 DF 0D 2F 90 2F BB 1A 5D 53 89 F7 AE 87 A9 F8 26 7D 34 92 29 2E 10 5F 00 54 CD AB 8E 86 69 98 75 3A CC 6E 07 46 3C 72 2D 20 47 74 25 B1 6A AC ED 9D 94 C6 DE 74 A6 81 5C B3 1D E9 EF 89 66 71 E2 42 FF 8A 42 81 E7 64 77 E9 81 5D D4 DF F5 99 A4 7F 65 34 35 1A 92 3D AA CA AB 42 59 85 01 D2 BA 0E 16 C8 19 B2 4F 07 A9 AB 00 3C 64 2D C2 39 6C B6 1D 07 E0 7B])

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] unwrap()

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] inNetBuffer: java.nio.DirectByteBuffer[pos=0 lim=169 cap=16665]

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=33330 cap=33330]

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] Unwrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING

bytesConsumed = 169 bytesProduced = 148

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] inNetBuffer: java.nio.DirectByteBuffer[pos=169 lim=169 cap=16665]

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] appBuffer: java.nio.DirectByteBuffer[pos=148 lim=33330 cap=33330]

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] Unwrap res:Status = BUFFER_UNDERFLOW HandshakeStatus = NOT_HANDSHAKING

bytesConsumed = 0 bytesProduced = 0

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] appBuffer: java.nio.DirectByteBuffer[pos=0 lim=148 cap=33330]

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] app data read: HeapBuffer[pos=0 lim=148 cap=148: 3C 69 71 20 69 64 3D 22 49 64 4C 39 56 2D 36 22 20 74 79 70 65 3D 22 73 65 74 22 3E 3C 71 75 65 72 79 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 69 71 3A 72 6F 73 74 65 72 22 20 3E 3C 69 74 65 6D 20 6A 69 64 3D 22 74 65 73 74 32 40 6E 69 63 68 6F 6C 61 73 2D 70 63 22 20 6E 61 6D 65 3D 22 74 65 73 74 32 22 3E 3C 67 72 6F 75 70 3E E5 A5 BD E6 9C 8B E5 8F 8B 3C 2F 67 72 6F 75 70 3E 3C 2F 69 74 65 6D 3E 3C 2F 71 75 65 72 79 3E 3C 2F 69 71 3E] (3C 69 71 20 69 64 3D 22 49 64 4C 39 56 2D 36 22 20 74 79 70 65 3D 22 73 65 74 22 3E 3C 71 75 65 72 79 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 69 71 3A 72 6F 73 74 65 72 22 20 3E 3C 69 74 65 6D 20 6A 69 64 3D 22 74 65 73 74 32 40 6E 69 63 68 6F 6C 61 73 2D 70 63 22 20 6E 61 6D 65 3D 22 74 65 73 74 32 22 3E 3C 67 72 6F 75 70 3E E5 A5 BD E6 9C 8B E5 8F 8B 3C 2F 67 72 6F 75 70 3E 3C 2F 69 74 65 6D 3E 3C 2F 71 75 65 72 79 3E 3C 2F 69 71 3E)

2013.12.11 18:25:49 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /10.2.160.1:56835

2013.12.11 18:25:49 org.jivesoftware.openfire.plugin.gojara.messagefilter.processors.AbstractRemote RosterProcessor - Processing packet in ClientToComponentUpdateProcessor:

������

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] Filtered Write: org.apache.mina.filter.support.SSLHandler@154aed9

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] encrypt: HeapBuffer[pos=0 lim=284 cap=4096: 3C 69 71 20 74 79 70 65 3D 22 65 72 72 6F 72 22 20 69 64 3D 22 49 64 4C 39 56 2D 36 22 20 74 6F 3D 22 74 65 73 74 33 40 6E 69 63 68 6F 6C 61 73 2D 70 63 2F 53 6D 61 63 6B 22 3E 3C 71 75 65 72 79 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 69 71 3A 72 6F 73 74 65 72 22 3E 3C 69 74 65 6D 20 6A 69 64 3D 22 74 65 73 74 32 40 6E 69 63 68 6F 6C 61 73 2D 70 63 22 20 6E 61 6D 65 3D 22 74 65 73 74 32 22 3E 3C 67 72 6F 75 70 3E E5 A5 BD E6 9C 8B E5 8F 8B 3C 2F 67 72 6F 75 70 3E 3C 2F 69 74 65 6D 3E 3C 2F 71 75 65 72 79 3E 3C 65 72 72 6F 72 20 63 6F 64 65 3D 22 35 30 30 22 20 74 79 70 65 3D 22 77 61 69 74 22 3E 3C 69 6E 74 65 72 6E 61 6C 2D 73 65 72 76 65 72 2D 65 72 72 6F 72 20 78 6D 6C 6E 73 3D 22 75 72 6E 3A 69 65 74 66 3A 70 61 72 61 6D 73 3A 78 6D 6C 3A 6E 73 3A 78 6D 70 70 2D 73 74 61 6E 7A 61 73 22 2F 3E 3C 2F 65 72 72 6F 72 3E 3C 2F 69 71 3E]

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] Wrap res:Status = OK HandshakeStatus = NOT_HANDSHAKING

bytesConsumed = 284 bytesProduced = 305

2013.12.11 18:25:49 org.jivesoftware.openfire.nio.ClientConnectionHandler - [/10.2.160.1:56835] encrypted buf: HeapBuffer[pos=0 lim=305 cap=305: 17 03 01 01 2C D1 E6 23 46 4B 7B 2D 24 72 83 FA 80 89 FE 15 BD 72 3B 38 F9 97 35 F8 29 B1 BE 83 FE 73 89 BC 6F 70 8C 0B 04 91 1D A1 66 BB D3 B9 84 5F 5A 9E 2A C7 83 08 C7 B8 BE 11 EF 5F 2F 80 3B F1 AB C4 52 45 18 C3 69 2E 5A 81 C3 8F B9 D6 15 25 21 12 B7 2C F0 06 34 00 CC 28 8C 8F 49 B7 43 00 89 56 17 09 8F A4 7D A8 62 9B EB DD C8 7A B2 B7 85 2A 7E F3 98 74 91 43 C2 14 D7 51 A1 AE D2 80 53 B7 E4 CB C6 F3 09 52 E0 FA 9B B4 D8 46 ED FE 78 A7 E8 42 85 C6 76 65 3A B6 49 C7 14 51 57 51 08 B0 56 BD 7E D8 F0 BB 17 BC F7 A8 46 37 C2 E0 00 DD 2E 52 01 B1 EC A1 8C 2C 65 EC 68 4E 10 5A D5 A0 B6 7B 54 36 27 78 A0 A4 F6 B5 89 15 66 48 1D 4E 23 F1 FF AB 36 86 5E 60 A0 DB 10 DF 28 7B 2E 76 29 16 90 C7 D5 B5 2D 62 41 E0 FD 06 92 07 19 40 7B C8 AF E5 44 CF 2A F1 CE E5 FC 2F 9C 69 4E 2E 5A 98 83 76 D8 D7 A9 0A 60 4C 25 71 FE A0 DA AC 4C 5F CD 83 75 A0 17 E5 3B 88 B3 B2 74 80 A8 F2 A1 CA 37 3C 8C 47 DC 0D C2 ED F2 4E DE]

2013.12.11 18:25:49 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /10.2.160.1:56835

2013.12.11 18:25:49 org.apache.mina.filter.executor.ExecutorFilter - Launching thread for /10.2.160.1:56835

2013.12.11 18:25:49 org.apache.mina.filter.executor.ExecutorFilter - Exiting since queue is empty for /10.2.160.1:56835

and warn.log below

at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:1723)

at com.mysql.jdbc.Connection.execSQL(Connection.java:3283)

at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1332)

at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1604)

at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1519)

at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:1504)

at sun.reflect.GeneratedMethodAccessor54.invoke(Unknown Source)

at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)

at java.lang.reflect.Method.invoke(Unknown Source)

at org.logicalcobwebs.proxool.ProxyStatement.invoke(ProxyStatement.java:100)

at org.logicalcobwebs.proxool.ProxyStatement.intercept(ProxyStatement.java:57)

at $java.sql.Statement$$EnhancerByProxool$$6be0a454.executeUpdate()

at org.jivesoftware.openfire.roster.DefaultRosterItemProvider.createItem(DefaultRo sterItemProvider.java:99)

at org.jivesoftware.openfire.roster.Roster.provideRosterItem(Roster.java:354)

at org.jivesoftware.openfire.roster.Roster.createRosterItem(Roster.java:311)

at org.jivesoftware.openfire.handler.IQRosterHandler.manageRoster(IQRosterHandler. java:243)

at org.jivesoftware.openfire.handler.IQRosterHandler.handleIQ(IQRosterHandler.java :122)

at org.jivesoftware.openfire.handler.IQHandler.process(IQHandler.java:65)

at org.jivesoftware.openfire.IQRouter.handle(IQRouter.java:374)

at org.jivesoftware.openfire.IQRouter.route(IQRouter.java:121)

at org.jivesoftware.openfire.spi.PacketRouterImpl.route(PacketRouterImpl.java:76)

at org.jivesoftware.openfire.net.StanzaHandler.processIQ(StanzaHandler.java:337)

at org.jivesoftware.openfire.net.ClientStanzaHandler.processIQ(ClientStanzaHandler .java:93)

at org.jivesoftware.openfire.net.StanzaHandler.process(StanzaHandler.java:302)

at org.jivesoftware.openfire.net.StanzaHandler.process(StanzaHandler.java:194)

at org.jivesoftware.openfire.nio.ConnectionHandler.messageReceived(ConnectionHandl er.java:181)

at org.apache.mina.common.support.AbstractIoFilterChain$TailFilter.messageReceived (AbstractIoFilterChain.java:570)

at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(Ab stractIoFilterChain.java:299)

at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilt erChain.java:53)

at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceive d(AbstractIoFilterChain.java:648)

at org.apache.mina.common.IoFilterAdapter.messageReceived(IoFilterAdapter.java:80)

at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(Ab stractIoFilterChain.java:299)

at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilt erChain.java:53)

at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceive d(AbstractIoFilterChain.java:648)

at org.apache.mina.filter.codec.support.SimpleProtocolDecoderOutput.flush(SimplePr otocolDecoderOutput.java:58)

at org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecF ilter.java:185)

at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(Ab stractIoFilterChain.java:299)

at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilt erChain.java:53)

at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceive d(AbstractIoFilterChain.java:648)

at org.apache.mina.common.IoFilterAdapter.messageReceived(IoFilterAdapter.java:80)

at org.jivesoftware.openfire.plugin.RawPrintFilter.messageReceived(RawPrintFilter. java:66)

at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(Ab stractIoFilterChain.java:299)

at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilt erChain.java:53)

at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceive d(AbstractIoFilterChain.java:648)

at org.apache.mina.filter.executor.ExecutorFilter.processEvent(ExecutorFilter.java :239)

at org.apache.mina.filter.executor.ExecutorFilter$ProcessEventsRunnable.run(Execut orFilter.java:283)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)

at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51)

at java.lang.Thread.run(Unknown Source)

Could someone help me to get through this? poor English, is my problem clear?

2

another problem I am suffering is when I open the User Summary I got this

Total Users: 8 – Sorted by Username – Users per page:

No users in the system.

users is not correctly listed here

and I got the error.log below

at com.mysql.jdbc.PreparedStatement.realClose(PreparedStatement.java:2204)

at com.mysql.jdbc.PreparedStatement.close(PreparedStatement.java:695)

at org.logicalcobwebs.proxool.AbstractProxyStatement.close(AbstractProxyStatement. java:115)

at org.logicalcobwebs.proxool.ProxyStatement.invoke(ProxyStatement.java:93)

at org.logicalcobwebs.proxool.ProxyStatement.intercept(ProxyStatement.java:57)

at $java.sql.Statement$$EnhancerByProxool$$178d346a.close()

at org.jivesoftware.database.DbConnectionManager.closeStatement(DbConnectionManage r.java:277)

at org.jivesoftware.database.DbConnectionManager.closeConnection(DbConnectionManag er.java:397)

at org.jivesoftware.openfire.user.DefaultUserProvider.getUsernames(DefaultUserProv ider.java:294)

at org.jivesoftware.openfire.user.DefaultUserProvider.getUsers(DefaultUserProvider .java:300)

at org.jivesoftware.openfire.user.UserManager.getUsers(UserManager.java:282)

at org.jivesoftware.openfire.admin.user_002dsummary_jsp._jspService(user_002dsumma ry_jsp.java:232)

at org.apache.jasper.runtime.HttpJspBase.service(HttpJspBase.java:97)

at javax.servlet.http.HttpServlet.service(HttpServlet.java:820)

at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:547)

at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.ja va:1359)

at com.opensymphony.module.sitemesh.filter.PageFilter.parsePage(PageFilter.java:11 8)

at com.opensymphony.module.sitemesh.filter.PageFilter.doFilter(PageFilter.java:52)

at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.ja va:1330)

at org.jivesoftware.util.LocaleFilter.doFilter(LocaleFilter.java:74)

at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.ja va:1330)

at org.jivesoftware.util.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingF ilter.java:50)

at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.ja va:1330)

at org.jivesoftware.admin.PluginFilter.doFilter(PluginFilter.java:78)

at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.ja va:1330)

at org.jivesoftware.admin.AuthCheckFilter.doFilter(AuthCheckFilter.java:164)

at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.ja va:1330)

at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:478)

at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:119)

at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:520)

at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:22 7)

at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:94 1)

at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:409)

at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:186 )

at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:875 )

at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:117)

at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandler Collection.java:250)

at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.jav a:149)

at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:110)

at org.eclipse.jetty.server.Server.handle(Server.java:349)

at org.eclipse.jetty.server.HttpConnection.handleRequest(HttpConnection.java:441)

at org.eclipse.jetty.server.HttpConnection$RequestHandler.headerComplete(HttpConne ction.java:919)

at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:582)

at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:218)

at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:51 )

at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.jav a:586)

at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java :44)

at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:598 )

at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:533)

at java.lang.Thread.run(Unknown Source)