NullPointerException when processing message with subject (#142)

Wojciech Kapcia (Tigase) opened 4 years ago

[2021-03-28 21:55:07:016] [SEVERE  ] [              in_7-muc ] StanzaProcessor.processPacket()  : java.lang.RuntimeException: java.lang.NullPointerException when processing from=sess-man@ip-172-31-0-99.us-west-2.compute.internal, to=null, DATA=<message id="35458888368" xmlns="jabber:client" to="…@muc.jabber.today" from="…@jabber.today/…" type="groupchat"><body/><subject>…</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=null
java.lang.RuntimeException: java.lang.RuntimeException: java.lang.NullPointerException
	at tigase.muc.modules.GroupchatMessageModule.process(GroupchatMessageModule.java:242)
	at tigase.component.modules.StanzaProcessor.process(StanzaProcessor.java:164)
	at tigase.component.modules.StanzaProcessor.processPacket(StanzaProcessor.java:85)
	at tigase.component.AbstractKernelBasedComponent.processPacket(AbstractKernelBasedComponent.java:81)
	at tigase.muc.MUCComponent.processPacket(MUCComponent.java:122)
	at tigase.muc.cluster.MUCComponentClustered.processPacket(SourceFile:87)
	at tigase.server.AbstractMessageReceiver$QueueListener.run(AbstractMessageReceiver.java:1397)
Caused by: java.lang.RuntimeException: java.lang.NullPointerException
	at tigase.muc.repository.inmemory.InMemoryMucRepository$1.onChangeSubject(InMemoryMucRepository.java:78)
	at tigase.muc.Room.fireOnSetSubject(Room.java:627)
	at tigase.muc.Room.setNewSubject(Room.java:529)
	at tigase.muc.modules.GroupchatMessageModule.process(GroupchatMessageModule.java:214)
	... 6 more
Caused by: java.lang.NullPointerException
	at tigase.muc.repository.JDBCMucDAO.setSubject(JDBCMucDAO.java:307)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.base/java.lang.reflect.Method.invoke(Unknown Source)
	at tigase.stats.StatisticsInvocationHandler.invoke(StatisticsInvocationHandler.java:75)
	at com.sun.proxy.$Proxy52.setSubject(Unknown Source)
	at tigase.muc.repository.MucDAOMDBean.setSubject(MucDAOMDBean.java:117)
	at tigase.muc.repository.inmemory.InMemoryMucRepository$1.onChangeSubject(InMemoryMucRepository.java:75)
	... 9 more

Related
- NPE in VCardModule.process/JDBCMucDAO.updateRoomAvatar (#148) Closed
- NPE in JDBCMucDAO.setSubject (#145) Closed

Activities

Wojciech Kapcia (Tigase) commented 4 years ago



Tigase Monitor generated event!

hostname: ip-172-31-0-99.us-west-2.compute.internal
Event: LoggerMonitorEvent
    timestamp: 2021-03-28T22:08:27Z
    hostname: ip-172-31-0-99.us-west-2.compute.internal
    external_hostname: ec2-54-189-207-239.us-west-2.compute.amazonaws.com
    log: [2021-03-28 22:08:27:599] [FINEST  ] [              in_7-muc ] DomainFilter.filter()            : Filtering (packet): from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787
[2021-03-28 22:08:27:599] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : UrnXmppPing (urn:xmpp:ping), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] DomainFilter.getDomains()        : Domains read from user session: ALL for VHost: jabber.today
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] MessageRouter.processPacket()    : Processing packet: from=sess-man@ip-172-31-0-99.us-west-2.compute.internal, to=null, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=null
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] MessageRouter.getLocalComponent(): Called for : echo-szja-capsule@muc.jabber.today
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] MessageRouter.getLocalComponent(): No componentID matches (fast lookup against exact address): echo-szja-capsule@muc.jabber.today, for map: [upload@ip-172-31-0-99.us-west-2.compute.internal, cl-comp@ip-172-31-0-99.us-west-2.compute.internal, ext-disco@ip-172-31-0-99.us-west-2.compute.internal, message-router@ip-172-31-0-99.us-west-2.compute.internal, muc@ip-172-31-0-99.us-west-2.compute.internal, message-archive@ip-172-31-0-99.us-west-2.compute.internal, mix@ip-172-31-0-99.us-west-2.compute.internal, cluster-contr@ip-172-31-0-99.us-west-2.compute.internal, ext@ip-172-31-0-99.us-west-2.compute.internal, amp@ip-172-31-0-99.us-west-2.compute.internal, http@ip-172-31-0-99.us-west-2.compute.internal, bosh@ip-172-31-0-99.us-west-2.compute.internal, c2s@ip-172-31-0-99.us-west-2.compute.internal, sess-man@ip-172-31-0-99.us-west-2.compute.internal, eventbus@ip-172-31-0-99.us-west-2.compute.internal, vhost-man@ip-172-31-0-99.us-west-2.compute.internal, pubsub@ip-172-31-0-99.us-west-2.compute.internal, monitor@ip-172-31-0-99.us-west-2.compute.internal, push@ip-172-31-0-99.us-west-2.compute.internal, ws2s@ip-172-31-0-99.us-west-2.compute.internal, stats@ip-172-31-0-99.us-west-2.compute.internal, s2s@ip-172-31-0-99.us-west-2.compute.internal]; trying VHost lookup
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] MessageRouter.getLocalComponent(): No component name matches (VHost lookup against component name): echo-szja-capsule@muc.jabber.today, for map: [ext, vhost-man, amp, upload, c2s, ws2s, monitor, bosh, push, cl-comp, ext-disco, stats, s2s, eventbus, http, muc, sess-man, message-archive, cluster-contr, mix, message-router, pubsub], for all VHosts: [hosts count: 34]; trying other forms of addressing
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] MessageRouter.getLocalComponent(): Component matched: echo-szja-capsule@muc.jabber.today, for comp: [ext, vhost-man, amp, upload, c2s, ws2s, monitor, bosh, push, cl-comp, ext-disco, stats, s2s, eventbus, http, muc, sess-man, message-archive, cluster-contr, mix, message-router, pubsub], basename: jabber.today
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] MessageRouter.getLocalComponent(): Called for : echo-szja-capsule@muc.jabber.today
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] MessageRouter.getLocalComponent(): No componentID matches (fast lookup against exact address): echo-szja-capsule@muc.jabber.today, for map: [upload@ip-172-31-0-99.us-west-2.compute.internal, cl-comp@ip-172-31-0-99.us-west-2.compute.internal, ext-disco@ip-172-31-0-99.us-west-2.compute.internal, message-router@ip-172-31-0-99.us-west-2.compute.internal, muc@ip-172-31-0-99.us-west-2.compute.internal, message-archive@ip-172-31-0-99.us-west-2.compute.internal, mix@ip-172-31-0-99.us-west-2.compute.internal, cluster-contr@ip-172-31-0-99.us-west-2.compute.internal, ext@ip-172-31-0-99.us-west-2.compute.internal, amp@ip-172-31-0-99.us-west-2.compute.internal, http@ip-172-31-0-99.us-west-2.compute.internal, bosh@ip-172-31-0-99.us-west-2.compute.internal, c2s@ip-172-31-0-99.us-west-2.compute.internal, sess-man@ip-172-31-0-99.us-west-2.compute.internal, eventbus@ip-172-31-0-99.us-west-2.compute.internal, vhost-man@ip-172-31-0-99.us-west-2.compute.internal, pubsub@ip-172-31-0-99.us-west-2.compute.internal, monitor@ip-172-31-0-99.us-west-2.compute.internal, push@ip-172-31-0-99.us-west-2.compute.internal, ws2s@ip-172-31-0-99.us-west-2.compute.internal, stats@ip-172-31-0-99.us-west-2.compute.internal, s2s@ip-172-31-0-99.us-west-2.compute.internal]; trying VHost lookup
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] MessageRouter.getLocalComponent(): No component name matches (VHost lookup against component name): echo-szja-capsule@muc.jabber.today, for map: [ext, vhost-man, amp, upload, c2s, ws2s, monitor, bosh, push, cl-comp, ext-disco, stats, s2s, eventbus, http, muc, sess-man, message-archive, cluster-contr, mix, message-router, pubsub], for all VHosts: [hosts count: 34]; trying other forms of addressing
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] MessageRouter.getLocalComponent(): Component matched: echo-szja-capsule@muc.jabber.today, for comp: [ext, vhost-man, amp, upload, c2s, ws2s, monitor, bosh, push, cl-comp, ext-disco, stats, s2s, eventbus, http, muc, sess-man, message-archive, cluster-contr, mix, message-router, pubsub], basename: jabber.today
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] MessageRouter.processPacket()    : 1. Packet will be processed by: muc@ip-172-31-0-99.us-west-2.compute.internal, from=sess-man@ip-172-31-0-99.us-west-2.compute.internal, to=null, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=null
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : JabberIqRegister (jabber:iq:register), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:600] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : SaslAuth (urn:ietf:params:xml:ns:xmpp-sasl), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:600] [FINER   ] [              in_7-muc ] Ghostbuster2.update()            : Update activity of szja@jabber.today/mcabber.2cabbe37
[2021-03-28 22:08:27:601] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : ClientStateIndication (urn:xmpp:csi:0), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:601] [FINER   ] [              in_7-muc ] StanzaProcessor.processPacket()  : Received: <message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>
[2021-03-28 22:08:27:601] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : PepPlugin (pep), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:601] [FINEST  ] [              in_7-muc ] StanzaProcessor.process()        : Processing packet: from=sess-man@ip-172-31-0-99.us-west-2.compute.internal, to=null, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=null
[2021-03-28 22:08:27:601] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : UnifiedArchiveXep0136MessageArchivingProcessor (message-archive-xep-0136), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:601] [FINER   ] [              in_7-muc ] StanzaProcessor.process()        : Handled by module class tigase.muc.modules.GroupchatMessageModule
[2021-03-28 22:08:27:601] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : PushNotifications (urn:xmpp:push:0), authorization/canHandle: AUTHORIZED, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:601] [FINEST  ] [              in_7-muc ] Room.getAffiliation()            : Getting affiliations for: szja@jabber.today from set: {szja@jabber.today=[aff: owner,persistent: true,nickname: szja]}
[2021-03-28 22:08:27:601] [FINEST  ] [              in_7-muc ] SessionManager.walk()            : XMPPProcessorIfc: PushNotifications (urn:xmpp:push:0)Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:601] [FINEST  ] [              in_7-muc ] GroupchatMessageModule.process() : Processing groupchat message. room=echo-szja-capsule@muc.jabber.today; senderJID=szja@jabber.today/mcabber.2cabbe37; senderNickname=szja; role=moderator; affiliation=owner;
[2021-03-28 22:08:27:601] [FINEST  ] [              in_7-muc ] DomainFilter.filter()            : Filtering (packet): from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : DefaultHandlerProc (default-handler), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : JabberIqRoster (jabber:iq:roster), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : StartTLS (starttls), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : PresenceState (presence-state), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : JabberIqVersion (jabber:iq:version), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : StartZLib (zlib), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : EntityTime (urn:xmpp:time), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : SessionOpenProc (session-open), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : JabberIqPrivacy (jabber:iq:privacy), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : ExternalServiceDiscoveryProcessor (urn:xmpp:extdisco:2), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:602] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : BindResource (urn:ietf:params:xml:ns:xmpp-bind), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:603] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : JabberIqCommand (http://jabber.org/protocol/commands), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:603] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : VCard4 (vcard-xep-0292), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:603] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : MessageArchivePlugin (message-archive), authorization/canHandle: AUTHORIZED, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644420]
[2021-03-28 22:08:27:603] [FINEST  ] [              in_7-muc ] SessionManager.walk()            : XMPPProcessorIfc: MessageArchivePlugin (message-archive)Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644421]
[2021-03-28 22:08:27:603] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : SessionCloseProc (session-close), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644421]
[2021-03-28 22:08:27:603] [FINEST  ] [              in_7-muc ] DomainFilter.filter()            : Filtering (packet): from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787
[2021-03-28 22:08:27:603] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : JabberIqPrivate (jabber:iq:private), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644421]
[2021-03-28 22:08:27:603] [FINEST  ] [              in_7-muc ] XMPPProcessor.canHandle()        : SessionBind (urn:ietf:params:xml:ns:xmpp-session), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787, conn: XMPPResourceConnection=[user_jid=szja@jabber.today/mcabber.2cabbe37, packets=113, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, domain=jabber.today, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=657719820, parentSession liveTime=3644421]
[2021-03-28 22:08:27:603] [FINEST  ] [              in_7-muc ] DomainFilter.filter()            : Filtering (packet): from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.20.0.2_5222_172.31.4.176_16284, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=8fe9ec39-c225-3733-9c2b-538cc39ee787
[2021-03-28 22:08:27:603] [FINEST  ] [              in_7-muc ] SessionManager.processPacket()   : Packet processed by: [message-carbons, amp, urn:xmpp:push:0, message-archive]
[2021-03-28 22:08:27:610] [FINEST  ] [              in_7-muc ] GroupchatMessageModule.process() : Error during processing groupchat message
java.lang.RuntimeException: java.lang.NullPointerException
	at tigase.muc.repository.inmemory.InMemoryMucRepository$1.onChangeSubject(InMemoryMucRepository.java:78)
	at tigase.muc.Room.fireOnSetSubject(Room.java:627)
	at tigase.muc.Room.setNewSubject(Room.java:529)
	at tigase.muc.modules.GroupchatMessageModule.process(GroupchatMessageModule.java:214)
	at tigase.component.modules.StanzaProcessor.process(StanzaProcessor.java:164)
	at tigase.component.modules.StanzaProcessor.processPacket(StanzaProcessor.java:85)
	at tigase.component.AbstractKernelBasedComponent.processPacket(AbstractKernelBasedComponent.java:81)
	at tigase.muc.MUCComponent.processPacket(MUCComponent.java:122)
	at tigase.muc.cluster.MUCComponentClustered.processPacket(SourceFile:87)
	at tigase.server.AbstractMessageReceiver$QueueListener.run(AbstractMessageReceiver.java:1397)
Caused by: java.lang.NullPointerException
	at tigase.muc.repository.JDBCMucDAO.setSubject(JDBCMucDAO.java:307)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.base/java.lang.reflect.Method.invoke(Unknown Source)
	at tigase.stats.StatisticsInvocationHandler.invoke(StatisticsInvocationHandler.java:75)
	at com.sun.proxy.$Proxy52.setSubject(Unknown Source)
	at tigase.muc.repository.MucDAOMDBean.setSubject(MucDAOMDBean.java:117)
	at tigase.muc.repository.inmemory.InMemoryMucRepository$1.onChangeSubject(InMemoryMucRepository.java:75)
	... 9 more

[2021-03-28 22:08:27:619] [SEVERE  ] [              in_7-muc ] StanzaProcessor.processPacket()  : java.lang.RuntimeException: java.lang.NullPointerException when processing from=sess-man@ip-172-31-0-99.us-west-2.compute.internal, to=null, DATA=<message id="45248504153" xmlns="jabber:client" to="echo-szja-capsule@muc.jabber.today" from="szja@jabber.today/mcabber.2cabbe37" type="groupchat"><body/><subject>gemini://szczezuja.flounder.online/gemlog/2021-03-28.gmi</subject></message>, SIZE=239, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=groupchat, STABLE_ID=null
java.lang.RuntimeException: java.lang.RuntimeException: java.lang.NullPointerException
	at tigase.muc.modules.GroupchatMessageModule.process(GroupchatMessageModule.java:242)
	at tigase.component.modules.StanzaProcessor.process(StanzaProcessor.java:164)
	at tigase.component.modules.StanzaProcessor.processPacket(StanzaProcessor.java:85)
	at tigase.component.AbstractKernelBasedComponent.processPacket(AbstractKernelBasedComponent.java:81)
	at tigase.muc.MUCComponent.processPacket(MUCComponent.java:122)
	at tigase.muc.cluster.MUCComponentClustered.processPacket(SourceFile:87)
	at tigase.server.AbstractMessageReceiver$QueueListener.run(AbstractMessageReceiver.java:1397)
Caused by: java.lang.RuntimeException: java.lang.NullPointerException
	at tigase.muc.repository.inmemory.InMemoryMucRepository$1.onChangeSubject(InMemoryMucRepository.java:78)
	at tigase.muc.Room.fireOnSetSubject(Room.java:627)
	at tigase.muc.Room.setNewSubject(Room.java:529)
	at tigase.muc.modules.GroupchatMessageModule.process(GroupchatMessageModule.java:214)
	... 6 more
Caused by: java.lang.NullPointerException
	at tigase.muc.repository.JDBCMucDAO.setSubject(JDBCMucDAO.java:307)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.base/java.lang.reflect.Method.invoke(Unknown Source)
	at tigase.stats.StatisticsInvocationHandler.invoke(StatisticsInvocationHandler.java:75)
	at com.sun.proxy.$Proxy52.setSubject(Unknown Source)
	at tigase.muc.repository.MucDAOMDBean.setSubject(MucDAOMDBean.java:117)
	at tigase.muc.repository.inmemory.InMemoryMucRepository$1.onChangeSubject(InMemoryMucRepository.java:75)
	... 9 more

Wojciech Kapcia (Tigase) commented 3 years ago

Issue most likely due to using IDs that are generated by the database asynchronously. Given extremely low occurrence (happened once) slating it as wontfix.
Login to comment

Type	Bug
Priority	Normal
Assignee	Wojciech Kapcia (Tigase)
Version	tigase-server-8.2.0

Issue Votes (0)

Watchers (0)

Reference

tigase/_server/tigase-muc#142