Projects tigase _server tigase-pubsub Issues #119
NPE in DiscoveryModule (#119)
wojciech.kapcia@tigase.net opened 4 years ago
[2021-04-11 18:38:37:375] [SEVERE  ] [           in_3-pubsub ] StanzaProcessor.processPacket()  : null when processing from=sess-man@ip-172-31-0-99.us-west-2.compute.internal, to=pubsub@ip-172-31-0-99.us-west-2.compute.internal, DATA=<iq id="B5449831-961A-450B-9171-C5A359BDFCF8" xmlns="jabber:client" to="…@sure.im" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>, SIZE=159, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=get, STABLE_ID=null
java.lang.NullPointerException
	at tigase.component.modules.impl.DiscoveryModule.prepareDiscoInfoResponse(DiscoveryModule.java:177)
	at tigase.pubsub.modules.DiscoveryModule.prepareDiscoInfoResponse(DiscoveryModule.java:333)
	at tigase.component.modules.impl.DiscoveryModule.processDiscoInfo(DiscoveryModule.java:160)
	at tigase.pubsub.modules.DiscoveryModule.processDiscoInfo(DiscoveryModule.java:80)
	at tigase.component.modules.impl.DiscoveryModule.process(DiscoveryModule.java:126)
	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.pubsub.PubSubComponent.processPacket(PubSubComponent.java:191)
	at tigase.pubsub.cluster.PubSubComponentClustered.processPacket(SourceFile:183)
	at tigase.server.AbstractMessageReceiver$QueueListener.run(AbstractMessageReceiver.java:1397)
wojciech.kapcia@tigase.net commented 4 years ago

Most likely because from is missing.

@andrzej.wojcik - didn't we have a case, where we had packets with missing from even though is should have been stamped by our pre-processor?

wojciech.kapcia@tigase.net commented 4 years ago

More logs:

[2021-04-11 18:38:37:372] [FINEST  ] [           in_3-pubsub ] XMPPProcessor.canHandle()        : PushNotifications (urn:xmpp:push:0), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.19.0.2_5223_209.58.142.156_32913, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<iq id="B5449831-961A-450B-9171-C5A359BDFCF8" xmlns="jabber:client" to="privacy@sure.im" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>, SIZE=159, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=get, STABLE_ID=00503135-3cad-467f-b194-3039d49fe663, conn: XMPPResourceConnection=[user_jid=privacy@sure.im/12f59cdb-4f1d-40f7-8e34-aa78a97894c9, packets=5, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.19.0.2_5223_45.87.214.116_43866, domain=sure.im, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=1477950655, parentSession liveTime=540039]
[2021-04-11 18:38:37:374] [FINEST  ] [           in_3-pubsub ] MessageRouter.processPacket()    : 1. Packet will be processed by: pubsub@ip-172-31-0-99.us-west-2.compute.internal, from=sess-man@ip-172-31-0-99.us-west-2.compute.internal, to=pubsub@ip-172-31-0-99.us-west-2.compute.internal, DATA=<iq id="B5449831-961A-450B-9171-C5A359BDFCF8" xmlns="jabber:client" to="privacy@sure.im" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>, SIZE=159, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=get, STABLE_ID=null
[2021-04-11 18:38:37:369] [FINEST  ] [           in_3-pubsub ] MessageRouter.getServerComponentsForRegex(): Checking routings for: amp
[2021-04-11 18:38:37:374] [FINEST  ] [           in_3-pubsub ] PubSubComponentClustered.processPacket(): Received packet: from=sess-man@ip-172-31-0-99.us-west-2.compute.internal, to=pubsub@ip-172-31-0-99.us-west-2.compute.internal, DATA=<iq id="B5449831-961A-450B-9171-C5A359BDFCF8" xmlns="jabber:client" to="privacy@sure.im" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>, SIZE=159, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=get, STABLE_ID=null
[2021-04-11 18:38:37:374] [FINEST  ] [           in_3-pubsub ] XMPPProcessor.canHandle()        : DefaultHandlerProc (default-handler), authorization/canHandle: null, Request: from=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.19.0.2_5223_209.58.142.156_32913, to=sess-man@ip-172-31-0-99.us-west-2.compute.internal, DATA=<iq id="B5449831-961A-450B-9171-C5A359BDFCF8" xmlns="jabber:client" to="privacy@sure.im" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>, SIZE=159, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=get, STABLE_ID=00503135-3cad-467f-b194-3039d49fe663, conn: XMPPResourceConnection=[user_jid=privacy@sure.im/12f59cdb-4f1d-40f7-8e34-aa78a97894c9, packets=5, connectioId=c2s@ip-172-31-0-99.us-west-2.compute.internal/172.19.0.2_5223_45.87.214.116_43866, domain=sure.im, authState=AUTHORIZED, isAnon=false, isTmp=false, parentSession hash=1477950655, parentSession liveTime=540039]
…
[2021-04-11 18:38:37:374] [FINEST  ] [           in_3-pubsub ] ClusteredNodeStrategy.getNodesForPacket(): IQ packet, other, returning current node, packet: from=sess-man@ip-172-31-0-99.us-west-2.compute.internal, to=pubsub@ip-172-31-0-99.us-west-2.compute.internal, DATA=<iq id="B5449831-961A-450B-9171-C5A359BDFCF8" xmlns="jabber:client" to="privacy@sure.im" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>, SIZE=159, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=get, STABLE_ID=null
[2021-04-11 18:38:37:374] [FINEST  ] [           in_3-pubsub ] MessageRouter.getLocalComponent(): Called for : privacy@sure.im/iPhone
[2021-04-11 18:38:37:374] [FINEST  ] [           in_3-pubsub ] PubSubComponentClustered.processPacket(): packet, processLocally: true, will be processed by nodes: [pubsub@ip-172-31-0-99.us-west-2.compute.internal]
[2021-04-11 18:38:37:374] [FINEST  ] [           in_3-pubsub ] MessageRouter.getLocalComponent(): No componentID matches (fast lookup against exact address): privacy@sure.im/iPhone, 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, stats@ip-172-31-0-99.us-west-2.compute.internal, ws2s@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-04-11 18:38:37:374] [FINER   ] [           in_3-pubsub ] StanzaProcessor.processPacket()  : Received: <iq id="B5449831-961A-450B-9171-C5A359BDFCF8" xmlns="jabber:client" to="privacy@sure.im" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>
[2021-04-11 18:38:37:374] [FINEST  ] [           in_3-pubsub ] MessageRouter.getLocalComponent(): No component name matches (VHost lookup against component name): privacy@sure.im/iPhone, for map: [ext, vhost-man, amp, upload, c2s, ws2s, monitor, bosh, cl-comp, push, ext-disco, stats, eventbus, s2s, http, muc, sess-man, message-archive, cluster-contr, mix, message-router, pubsub], for all VHosts: [hosts count: 34]; trying other forms of addressing
[2021-04-11 18:38:37:374] [FINEST  ] [           in_3-pubsub ] StanzaProcessor.process()        : Processing packet: from=sess-man@ip-172-31-0-99.us-west-2.compute.internal, to=pubsub@ip-172-31-0-99.us-west-2.compute.internal, DATA=<iq id="B5449831-961A-450B-9171-C5A359BDFCF8" xmlns="jabber:client" to="privacy@sure.im" type="get"><query xmlns="http://jabber.org/protocol/disco#info"/></iq>, SIZE=159, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=AUTH, TYPE=get, STABLE_ID=null
Andrzej Wójcik (Tigase) commented 4 years ago

@wojtek Yes, we had an issue like that. and it looks like this may be an issue here.

issue 1 of 1
Type
Bug
Priority
Normal
Assignee
Version
tigase-server-8.2.0
Issue Votes (0)
Watchers (0)
Reference
tigase/_server/tigase-pubsub#119
Please wait...
Page is in error, reload to recover