Tigase Forge
Dashboards
Projects
Pull Requests
Issues
Builds
Packages
Code Search
tigase-muc
Code
Pull Requests
Issues
List
Boards
Iterations
Builds
Statistics
Child Projects
OneDev 11.6.0
Documentation Support & Bug Report RESTful API Incompatibilities License Agreement Check Update
Projects tigase _server tigase-muc Issues #146
NPE in ModeratorModule (#146)
wojciech.kapcia@tigase.net opened 3 years ago 
2022-02-12 01:44:26:819] [FINEST  ] [              in_1-muc ] Room.getAffiliation()            : Getting affiliations for: null, result : null
[2022-02-12 01:44:26:819] [FINEST  ] [              in_1-muc ] ModeratorModule.processSet()     : Error during processing invitation
java.lang.NullPointerException
	at java.base/java.util.concurrent.ConcurrentHashMap.get(Unknown Source)
	at tigase.muc.PresenceStore.getBestPresenceInt(PresenceStore.java:147)
	at tigase.muc.PresenceStore.getBestPresence(PresenceStore.java:136)
	at tigase.muc.Room.getLastPresenceCopyByJid(Room.java:267)
	at tigase.muc.modules.ModeratorModule.makePresence(ModeratorModule.java:234)
	at tigase.muc.modules.ModeratorModule.processSetRole(ModeratorModule.java:544)
	at tigase.muc.modules.ModeratorModule.processSet(ModeratorModule.java:428)
	at tigase.muc.modules.ModeratorModule.process(ModeratorModule.java:120)
	at tigase.component.modules.StanzaProcessor.execute(StanzaProcessor.java:214)
	at tigase.component.modules.StanzaProcessor.process(StanzaProcessor.java:201)
	at tigase.component.modules.StanzaProcessor.processPacket(StanzaProcessor.java:103)
	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:1398)
wojciech.kapcia@tigase.net commented 3 years ago

Complete log, possibly ACS-MUC issue:

[2022-02-12 01:44:26:817] [FINEST  ] [              in_1-muc ] ClusterConnectionManager.processSocketData(): Processing socket data: from=null, to=null, serverAuthorisedStanzaFrom=Optional.empty, DATA=<cluster xmlns="tigase:cluster" pr="HIGH" type="set" id="cl-5271" to="muc@ip-172-31-41-211.us-west-2.compute.internal" from="muc@ip-172-31-2-168.us-west-2.compute.internal"><control><visited-nodes><node-id>muc@ip-172-31-2-168.us-west-2.compute.internal</node-id></visited-nodes><method-call name="muc-muc-packet-forward-cmd"/><first-node>muc@ip-172-31-2-168.us-west-2.compute.internal</first-node></control><data><iq xmlns="jabber:client" type="set" id="a1031a" to="test2@muc.tigase.org" from="neustradamus@jabber.org/Ψ+"><query xmlns="http://jabber.org/protocol/muc#admin">
<item role="moderator" nick="Neustradamus_"/>
</query></iq></data></cluster>, SIZE=651, XMLNS=tigase:cluster, PRIORITY=HIGH, PERMISSION=NONE, TYPE=set, STABLE_ID=null
[2022-02-12 01:44:26:817] [FINEST  ] [              in_1-muc ] ClusterElement.<init>()          : Parsing cluster element: <cluster xmlns="tigase:cluster" pr="HIGH" type="set" id="cl-5271" to="muc@ip-172-31-41-211.us-west-2.compute.internal" from="muc@ip-172-31-2-168.us-west-2.compute.internal"><control><visited-nodes><node-id>muc@ip-172-31-2-168.us-west-2.compute.internal</node-id></visited-nodes><method-call name="muc-muc-packet-forward-cmd"/><first-node>muc@ip-172-31-2-168.us-west-2.compute.internal</first-node></control><data><iq xmlns="jabber:client" type="set" id="a1031a" to="test2@muc.tigase.org" from="neustradamus@jabber.org/Ψ+"><query xmlns="http://jabber.org/protocol/muc#admin">
<item role="moderator" nick="Neustradamus_"/>
</query></iq></data></cluster>
[2022-02-12 01:44:26:817] [FINEST  ] [              in_1-muc ] ClusterElement.<init>()          : First node found: muc@ip-172-31-2-168.us-west-2.compute.internal
[2022-02-12 01:44:26:817] [FINEST  ] [              in_1-muc ] ClusterElement.<init>()          : Found and added visited nodes: 1
[2022-02-12 01:44:26:817] [FINEST  ] [              in_1-muc ] ClusterElement.<init>()          : Parsing cluster element: <cluster xmlns="tigase:cluster" pr="HIGH" type="set" id="cl-5271" to="muc@ip-172-31-41-211.us-west-2.compute.internal" from="muc@ip-172-31-2-168.us-west-2.compute.internal"><control><visited-nodes><node-id>muc@ip-172-31-2-168.us-west-2.compute.internal</node-id></visited-nodes><method-call name="muc-muc-packet-forward-cmd"/><first-node>muc@ip-172-31-2-168.us-west-2.compute.internal</first-node></control><data><iq xmlns="jabber:client" type="set" id="a1031a" to="test2@muc.tigase.org" from="neustradamus@jabber.org/Ψ+"><query xmlns="http://jabber.org/protocol/muc#admin">
<item role="moderator" nick="Neustradamus_"/>
</query></iq></data></cluster>
[2022-02-12 01:44:26:817] [FINEST  ] [              in_1-muc ] ClusterElement.<init>()          : First node found: muc@ip-172-31-2-168.us-west-2.compute.internal
[2022-02-12 01:44:26:817] [FINEST  ] [              in_1-muc ] ClusterElement.<init>()          : Found and added visited nodes: 1
[2022-02-12 01:44:26:817] [FINEST  ] [              in_1-muc ] AbstractStrategy$PacketForwardCmd.executeCommand(): received packet from=null, to=null, serverAuthorisedStanzaFrom=Optional.empty, DATA=<iq xmlns="jabber:client" type="set" id="a1031a" to="test2@muc.tigase.org" from="neustradamus@jabber.org/Ψ+"><query xmlns="http://jabber.org/protocol/muc#admin">
<item role="moderator" nick="Neustradamus_"/>
</query></iq>, SIZE=221, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=set, STABLE_ID=null forwarded from node muc@ip-172-31-2-168.us-west-2.compute.internal
[2022-02-12 01:44:26:818] [FINEST  ] [              in_1-muc ] AbstractStrategy$PacketForwardCmd.executeCommand(): forwarded packet added to processing queue of component = from=null, to=null, serverAuthorisedStanzaFrom=Optional.empty, DATA=<iq xmlns="jabber:client" type="set" id="a1031a" to="test2@muc.tigase.org" from="neustradamus@jabber.org/Ψ+"><query xmlns="http://jabber.org/protocol/muc#admin">
<item role="moderator" nick="Neustradamus_"/>
</query></iq>, SIZE=221, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=set, STABLE_ID=null
[2022-02-12 01:44:26:818] [FINER   ] [              in_1-muc ] Ghostbuster2.update()            : Update activity of neustradamus@jabber.org/Ψ+
[2022-02-12 01:44:26:818] [FINER   ] [              in_1-muc ] StanzaProcessor.processPacket()  : Received: <iq xmlns="jabber:client" type="set" id="a1031a" to="test2@muc.tigase.org" from="neustradamus@jabber.org/Ψ+"><query xmlns="http://jabber.org/protocol/muc#admin">
<item role="moderator" nick="Neustradamus_"/>
</query></iq>
[2022-02-12 01:44:26:818] [FINEST  ] [              in_1-muc ] StanzaProcessor.process()        : Processing packet: from=null, to=null, serverAuthorisedStanzaFrom=Optional.empty, DATA=<iq xmlns="jabber:client" type="set" id="a1031a" to="test2@muc.tigase.org" from="neustradamus@jabber.org/Ψ+"><query xmlns="http://jabber.org/protocol/muc#admin">
<item role="moderator" nick="Neustradamus_"/>
</query></iq>, SIZE=221, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=set, STABLE_ID=null
[2022-02-12 01:44:26:818] [FINER   ] [              in_1-muc ] StanzaProcessor.process()        : Handled by module class tigase.muc.modules.ModeratorModule
[2022-02-12 01:44:26:818] [FINEST  ] [              in_1-muc ] Room.getAffiliation()            : Getting affiliations for: neustradamus@jabber.org, result : [aff: owner,persistent: false,nickname: null]
[2022-02-12 01:44:26:818] [FINEST  ] [              in_1-muc ] Room.getRole()                   : Getting role for nick: Neustradamus. OccupantEntry: [Neustradamus; moderator; neustradamus@jabber.org; [neustradamus@jabber.org/Ψ+]]
[2022-02-12 01:44:26:819] [FINEST  ] [              in_1-muc ] Room.getAffiliation()            : Getting affiliations for: null, result : null
[2022-02-12 01:44:26:819] [FINEST  ] [              in_1-muc ] ModeratorModule.processSet()     : Error during processing invitation
java.lang.NullPointerException
	at java.base/java.util.concurrent.ConcurrentHashMap.get(Unknown Source)
	at tigase.muc.PresenceStore.getBestPresenceInt(PresenceStore.java:147)
	at tigase.muc.PresenceStore.getBestPresence(PresenceStore.java:136)
	at tigase.muc.Room.getLastPresenceCopyByJid(Room.java:267)
	at tigase.muc.modules.ModeratorModule.makePresence(ModeratorModule.java:234)
	at tigase.muc.modules.ModeratorModule.processSetRole(ModeratorModule.java:544)
	at tigase.muc.modules.ModeratorModule.processSet(ModeratorModule.java:428)
	at tigase.muc.modules.ModeratorModule.process(ModeratorModule.java:120)
	at tigase.component.modules.StanzaProcessor.execute(StanzaProcessor.java:214)
	at tigase.component.modules.StanzaProcessor.process(StanzaProcessor.java:201)
	at tigase.component.modules.StanzaProcessor.processPacket(StanzaProcessor.java:103)
	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:1398)

[2022-02-12 01:44:26:819] [FINEST  ] [              in_1-muc ] ModeratorModule.process()        : Error during processing invitation
java.lang.RuntimeException: java.lang.NullPointerException
	at tigase.muc.modules.ModeratorModule.processSet(ModeratorModule.java:436)
	at tigase.muc.modules.ModeratorModule.process(ModeratorModule.java:120)
	at tigase.component.modules.StanzaProcessor.execute(StanzaProcessor.java:214)
	at tigase.component.modules.StanzaProcessor.process(StanzaProcessor.java:201)
	at tigase.component.modules.StanzaProcessor.processPacket(StanzaProcessor.java:103)
	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:1398)
Caused by: java.lang.NullPointerException
	at java.base/java.util.concurrent.ConcurrentHashMap.get(Unknown Source)
	at tigase.muc.PresenceStore.getBestPresenceInt(PresenceStore.java:147)
	at tigase.muc.PresenceStore.getBestPresence(PresenceStore.java:136)
	at tigase.muc.Room.getLastPresenceCopyByJid(Room.java:267)
	at tigase.muc.modules.ModeratorModule.makePresence(ModeratorModule.java:234)
	at tigase.muc.modules.ModeratorModule.processSetRole(ModeratorModule.java:544)
	at tigase.muc.modules.ModeratorModule.processSet(ModeratorModule.java:428)
	... 8 more

[2022-02-12 01:44:26:823] [SEVERE  ] [              in_1-muc ] StanzaProcessor.processPacket()  : java.lang.RuntimeException: java.lang.NullPointerException when processing from=null, to=null, serverAuthorisedStanzaFrom=Optional.empty, DATA=<iq xmlns="jabber:client" type="set" id="a1031a" to="test2@muc.tigase.org" from="neustradamus@jabber.org/Ψ+"><query xmlns="http://jabber.org/protocol/muc#admin">
<item role="moderator" nick="Neustradamus_"/>
</query></iq>, SIZE=221, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=set, STABLE_ID=null
java.lang.RuntimeException: java.lang.RuntimeException: java.lang.NullPointerException
	at tigase.muc.modules.ModeratorModule.process(ModeratorModule.java:130)
	at tigase.component.modules.StanzaProcessor.execute(StanzaProcessor.java:214)
	at tigase.component.modules.StanzaProcessor.process(StanzaProcessor.java:201)
	at tigase.component.modules.StanzaProcessor.processPacket(StanzaProcessor.java:103)
	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:1398)
Caused by: java.lang.RuntimeException: java.lang.NullPointerException
	at tigase.muc.modules.ModeratorModule.processSet(ModeratorModule.java:436)
	at tigase.muc.modules.ModeratorModule.process(ModeratorModule.java:120)
	... 7 more
Caused by: java.lang.NullPointerException
	at java.base/java.util.concurrent.ConcurrentHashMap.get(Unknown Source)
	at tigase.muc.PresenceStore.getBestPresenceInt(PresenceStore.java:147)
	at tigase.muc.PresenceStore.getBestPresence(PresenceStore.java:136)
	at tigase.muc.Room.getLastPresenceCopyByJid(Room.java:267)
	at tigase.muc.modules.ModeratorModule.makePresence(ModeratorModule.java:234)
	at tigase.muc.modules.ModeratorModule.processSetRole(ModeratorModule.java:544)
	at tigase.muc.modules.ModeratorModule.processSet(ModeratorModule.java:428)
	... 8 more
issue 1 of 1
Type
Bug
Priority
Normal
Assignee
wojciech.kapcia@tigase.net
Version
tigase-server-8.2.0
Issue Votes (0)
Login to vote
Watchers (0)
Reference
tigase/_server/tigase-muc#146
Please wait...
Page is in error, reload to recover