Projects tigase _server server-core Issues #601
Read All nodes pulls from null node (#601)
Daniel Wisnewski opened 9 years ago
Due Date
2015-11-17

Reading from all nodes yields the following message

2015-11-12 14:44:16.401 [in_12-message-router]  MessageRouter.processPacket()  FINEST:  Processing packet: from=rest@lenovo-z585/577981c3-ba9e-4c8a-800a-8ace131bfcd5, to=null, DATA=<iq from="admin@domain.freeddns.org" type="get" to="domain.freeddns.org" xmlns="jabber:client" id="3cb0bbd8-bf30-4c8e-977a-387dc4f4a1e9"><query xmlns="http://jabber.org/protocol/disco#items"/></iq>, SIZE=197, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=ADMIN, TYPE=get

The search for null node begins a stuck loop:

2015-11-12 14:44:16.402 [in_12-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : domain.freeddns.org
2015-11-12 14:44:16.402 [in_12-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): domain.freeddns.org, for map: [vhost-man@lenovo-z585, message-archive@lenovo-z585, stats@lenovo-z585, proxy@lenovo-z585, ws2s@lenovo-z585, stun@lenovo-z585, c2s@lenovo-z585, amp@lenovo-z585, monitor@lenovo-z585, cluster-contr@lenovo-z585, basic-conf@lenovo-z585, pubsub@lenovo-z585, rest@lenovo-z585, eventbus@lenovo-z585, sess-man@lenovo-z585, muc@lenovo-z585, bosh@lenovo-z585, s2s@lenovo-z585]; trying VHost lookup
2015-11-12 14:44:16.402 [in_12-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): domain.freeddns.org, for map: [rest, vhost-man, c2s, amp, monitor, ws2s, bosh, stun, proxy, stats, eventbus, s2s, basic-conf, muc, message-archive, sess-man, cluster-contr, message-router, pubsub], for all VHosts: [horsemeat.com, domain.freeddns.org]; trying other forms of addressing
2015-11-12 14:44:16.403 [in_12-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: domain.freeddns.org, for comp: [rest, vhost-man, c2s, amp, monitor, ws2s, bosh, stun, proxy, stats, eventbus, s2s, basic-conf, muc, message-archive, sess-man, cluster-contr, message-router, pubsub], basename: freeddns.org
2015-11-12 14:44:16.403 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Processing disco query by: from=rest@lenovo-z585/577981c3-ba9e-4c8a-800a-8ace131bfcd5, to=null, DATA=<iq from="admin@domain.freeddns.org" type="get" to="domain.freeddns.org" xmlns="jabber:client" id="3cb0bbd8-bf30-4c8e-977a-387dc4f4a1e9"><query xmlns="http://jabber.org/protocol/disco#items"/></iq>, SIZE=197, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=ADMIN, TYPE=get
2015-11-12 14:44:16.404 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: rest General disco items request, node: null
2015-11-12 14:44:16.404 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: rest Disco items request for null node
2015-11-12 14:44:16.404 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: rest@lenovo-z585, items: [<item jid="rest.domain.freeddns.org" name="HTTP server integration module"/>]
2015-11-12 14:44:16.404 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: vhost-man General disco items request, node: null
2015-11-12 14:44:16.404 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: vhost-man Disco items request for null node
2015-11-12 14:44:16.404 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: vhost-man@lenovo-z585, items: [<item jid="vhost-man@domain.freeddns.org" name="VHost Manager"/>]
2015-11-12 14:44:16.405 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: c2s General disco items request, node: null
2015-11-12 14:44:16.405 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: c2s Disco items request for null node
2015-11-12 14:44:16.405 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: c2s@lenovo-z585, items: [<item jid="c2s@domain.freeddns.org" name="Client connection manager clustered"/>]
2015-11-12 14:44:16.405 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: amp General disco items request, node: null
2015-11-12 14:44:16.405 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: amp Disco items request for null node
2015-11-12 14:44:16.405 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: amp@lenovo-z585, items: [<item jid="amp@domain.freeddns.org" name="IM AMP Support"/>]
2015-11-12 14:44:16.405 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: monitor General disco items request, node: null
2015-11-12 14:44:16.405 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: monitor Disco items request for null node
2015-11-12 14:44:16.406 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: monitor@lenovo-z585, items: [<item jid="monitor@domain.freeddns.org" name="Monitor Component"/>]
2015-11-12 14:44:16.406 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: ws2s General disco items request, node: null
2015-11-12 14:44:16.406 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: ws2s Disco items request for null node
2015-11-12 14:44:16.406 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: ws2s@lenovo-z585, items: [<item jid="ws2s@domain.freeddns.org" name="Websocket connection manager clustered"/>]
2015-11-12 14:44:16.406 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: bosh General disco items request, node: null
2015-11-12 14:44:16.406 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: bosh Disco items request for null node
2015-11-12 14:44:16.406 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: bosh@lenovo-z585, items: [<item jid="bosh@domain.freeddns.org" name="Bosh connection manager clustered"/>]
2015-11-12 14:44:16.407 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: stun General disco items request, node: null
2015-11-12 14:44:16.407 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: stun Disco items request for null node
2015-11-12 14:44:16.407 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: stun@lenovo-z585, items: [<item jid="stun@domain.freeddns.org" name="STUN Component"/>]
2015-11-12 14:44:16.407 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: proxy General disco items request, node: null
2015-11-12 14:44:16.407 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: proxy Disco items request for null node
2015-11-12 14:44:16.407 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: proxy@lenovo-z585, items: [<item jid="proxy@domain.freeddns.org" name="Socks5 Bytestreams Service"/>]
2015-11-12 14:44:16.408 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: stats@lenovo-z585, items: [<item jid="stats@domain.freeddns.org" name="Server statistics" node="stats"/>]
2015-11-12 14:44:16.408 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: eventbus General disco items request, node: null
2015-11-12 14:44:16.408 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: eventbus Disco items request for null node
2015-11-12 14:44:16.408 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: eventbus@lenovo-z585, items: [<item jid="eventbus@domain.freeddns.org" name="Distributed EventBus"/>]
2015-11-12 14:44:16.408 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: s2s General disco items request, node: null
2015-11-12 14:44:16.408 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: s2s Disco items request for null node
2015-11-12 14:44:16.408 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: s2s@lenovo-z585, items: [<item jid="s2s@domain.freeddns.org" name="S2S connection manager"/>]
2015-11-12 14:44:16.409 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: basic-conf General disco items request, node: null
2015-11-12 14:44:16.409 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: basic-conf Disco items request for null node
2015-11-12 14:44:16.409 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: basic-conf@lenovo-z585, items: [<item jid="basic-conf@domain.freeddns.org" name="Configuration management"/>]
2015-11-12 14:44:16.409 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: muc General disco items request, node: null
2015-11-12 14:44:16.409 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: muc Disco items request for null node
2015-11-12 14:44:16.409 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: muc@lenovo-z585, items: [<item jid="muc.domain.freeddns.org" name="Multi User Chat"/>]
2015-11-12 14:44:16.409 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: message-archive General disco items request, node: null
2015-11-12 14:44:16.409 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: message-archive Disco items request for null node
2015-11-12 14:44:16.409 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: message-archive@lenovo-z585, items: [<item jid="message-archive@domain.freeddns.org" name="Message Archiving (XEP-0136) Support"/>]
2015-11-12 14:44:16.410 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: sess-man General disco items request, node: null
2015-11-12 14:44:16.410 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: sess-man Disco items request for null node
2015-11-12 14:44:16.410 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: sess-man@lenovo-z585, items: [<item jid="sess-man@domain.freeddns.org" name="Session manager clustered, basic strategy"/>]
2015-11-12 14:44:16.410 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: cluster-contr General disco items request, node: null
2015-11-12 14:44:16.410 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: cluster-contr Disco items request for null node
2015-11-12 14:44:16.411 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: cluster-contr@lenovo-z585, items: [<item jid="cluster-contr@domain.freeddns.org" name="Cluster controller"/>]
2015-11-12 14:44:16.411 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: pubsub General disco items request, node: null
2015-11-12 14:44:16.411 [in_12-message-router]  BasicComponent.getDiscoItems()  FINEST: pubsub Disco items request for null node
2015-11-12 14:44:16.411 [in_12-message-router]  MessageRouter.processDiscoQuery()  FINEST: Localdomain: domain.freeddns.org, DiscoItems processed by: pubsub@lenovo-z585, items: [<item jid="pubsub.domain.freeddns.org" name="PubSub"/>]
2015-11-12 14:44:16.414 [in_13-message-router]  MessageRouter.processPacket()  FINEST:  Processing packet: from=domain.freeddns.org, to=rest@lenovo-z585/577981c3-ba9e-4c8a-800a-8ace131bfcd5, DATA=<iq from="domain.freeddns.org" type="result" to="admin@domain.freeddns.org" xmlns="jabber:client" id="3cb0bbd8-bf30-4c8e-977a-387dc4f4a1e9"><query xmlns="http://jabber.org/protocol/disco#items"><item jid="rest.domain.freeddns.org" name="HTTP server integration module"/><item jid="vhost-man@domain.freeddns.org" name="VHost Manager"/><item jid="c2s@domain.freeddns.org" name="Client connection manager clustered"/><item jid="amp@domain.freeddns.org" name="IM AMP Support"/><item jid="monitor@domain.freeddns.org" name="Monitor Component"/><item jid="ws2s@domain.freeddns.org" name="Websocket connection manager clustered"/><item jid="bosh@domain.freeddns.org" name="Bosh connection manager clustered"/><item jid="stun@domain.freeddns.org" name="STUN Component"/><item jid="proxy@domain.freeddns.org" name="Socks5 Bytestreams Service"/><item jid="stats@domain.freeddns.org" name="Server statistics" node="stats"/><item jid="eventbus@domain.freeddns.org" name="Distributed EventBus"/><item jid="s2s@domain.freeddns.org" name=" ... , SIZE=1509, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=result
2015-11-12 14:44:16.415 [in_13-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : admin@domain.freeddns.org
2015-11-12 14:44:16.415 [in_13-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): admin@domain.freeddns.org, for map: [vhost-man@lenovo-z585, message-archive@lenovo-z585, stats@lenovo-z585, proxy@lenovo-z585, ws2s@lenovo-z585, stun@lenovo-z585, c2s@lenovo-z585, amp@lenovo-z585, monitor@lenovo-z585, cluster-contr@lenovo-z585, basic-conf@lenovo-z585, pubsub@lenovo-z585, rest@lenovo-z585, eventbus@lenovo-z585, sess-man@lenovo-z585, muc@lenovo-z585, bosh@lenovo-z585, s2s@lenovo-z585]; trying VHost lookup
2015-11-12 14:44:16.415 [in_13-message-router]  MessageRouter.getLocalComponent()  FINEST: No component name matches (VHost lookup against component name): admin@domain.freeddns.org, for map: [rest, vhost-man, c2s, amp, monitor, ws2s, bosh, stun, proxy, stats, eventbus, s2s, basic-conf, muc, message-archive, sess-man, cluster-contr, message-router, pubsub], for all VHosts: [horsemeat.com, domain.freeddns.org]; trying other forms of addressing
2015-11-12 14:44:16.416 [in_13-message-router]  MessageRouter.getLocalComponent()  FINEST: Component match failed: admin@domain.freeddns.org, for comp: [rest, vhost-man, c2s, amp, monitor, ws2s, bosh, stun, proxy, stats, eventbus, s2s, basic-conf, muc, message-archive, sess-man, cluster-contr, message-router, pubsub], basename: freeddns.org
2015-11-12 14:44:16.416 [in_13-message-router]  MessageRouter.getLocalComponent()  FINEST: Called for : rest@lenovo-z585/577981c3-ba9e-4c8a-800a-8ace131bfcd5
2015-11-12 14:44:16.416 [in_13-message-router]  MessageRouter.getLocalComponent()  FINEST: No componentID matches (fast lookup against exact address): rest@lenovo-z585/577981c3-ba9e-4c8a-800a-8ace131bfcd5, for map: [vhost-man@lenovo-z585, message-archive@lenovo-z585, stats@lenovo-z585, proxy@lenovo-z585, ws2s@lenovo-z585, stun@lenovo-z585, c2s@lenovo-z585, amp@lenovo-z585, monitor@lenovo-z585, cluster-contr@lenovo-z585, basic-conf@lenovo-z585, pubsub@lenovo-z585, rest@lenovo-z585, eventbus@lenovo-z585, sess-man@lenovo-z585, muc@lenovo-z585, bosh@lenovo-z585, s2s@lenovo-z585]; trying VHost lookup
2015-11-12 14:44:16.416 [in_13-message-router]  MessageRouter.processPacket()  FINEST:  1. Packet will be processed by: rest@lenovo-z585, from=domain.freeddns.org, to=rest@lenovo-z585/577981c3-ba9e-4c8a-800a-8ace131bfcd5, DATA=<iq from="domain.freeddns.org" type="result" to="admin@domain.freeddns.org" xmlns="jabber:client" id="3cb0bbd8-bf30-4c8e-977a-387dc4f4a1e9"><query xmlns="http://jabber.org/protocol/disco#items"><item jid="rest.domain.freeddns.org" name="HTTP server integration module"/><item jid="vhost-man@domain.freeddns.org" name="VHost Manager"/><item jid="c2s@domain.freeddns.org" name="Client connection manager clustered"/><item jid="amp@domain.freeddns.org" name="IM AMP Support"/><item jid="monitor@domain.freeddns.org" name="Monitor Component"/><item jid="ws2s@domain.freeddns.org" name="Websocket connection manager clustered"/><item jid="bosh@domain.freeddns.org" name="Bosh connection manager clustered"/><item jid="stun@domain.freeddns.org" name="STUN Component"/><item jid="proxy@domain.freeddns.org" name="Socks5 Bytestreams Service"/><item jid="stats@domain.freeddns.org" name="Server statistics" node="stats"/><item jid="eventbus@domain.freeddns.org" name="Distributed EventBus"/><item jid="s2s@domain.freeddns.org" name=" ... , SIZE=1509, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=NONE, TYPE=result
2015-11-12 14:44:16.445 [in_10-message-router]  MessageRouter.processPacket()  FINEST:  Processing packet: from=rest@lenovo-z585/63838e29-c4dd-4558-905a-bc644cd98229, to=null, DATA=<iq from="admin@domain.freeddns.org" type="get" to="rest.domain.freeddns.org" xmlns="jabber:client" id="64ea27ea-9157-4531-8148-17f33d776df6"><query xmlns="http://jabber.org/protocol/disco#items" node="http://jabber.org/protocol/commands"/></iq>, SIZE=245, XMLNS=jabber:client, PRIORITY=NORMAL, PERMISSION=ADMIN, TYPE=get

Loop starts repeating after this since Tigase cannot parse 'null'

tigase-console.log tigase.log.0

Andrzej Wójcik (Tigase) commented 9 years ago

I was not able to replicate this issue and when I execute this command using newest versions all looks OK.

Could you attach full logs of Tigase XMPP Server when this issue manifests?

Daniel Wisnewski commented 9 years ago

Attached logs,

Command works OK when using XML console, error shows when using admin interface.

(no information shows when Submit Query is hit).

Just checked Disco and seems to have a roudabout structure (pubsub>node>pubsub>node etc..) I will check database.

Daniel Wisnewski commented 9 years ago

Checked with fresh DB and install with latest build, behavior is the same.

Andrzej Wójcik (Tigase) commented 9 years ago

What you seen and think it is a loop is Admin UI quering every component of XMPP server for list of available adhoc commands. This is expected and done on every page load/refresh/submit. Each time iq query is addressed to different component.

As for command, now I see that it is not executed properly as no field is available so Admin UI is not able to properly use this command. Now I will work on this issue.

Andrzej Wójcik (Tigase) commented 9 years ago

I found solution for executing adhoc commands with no fields from Admin UI and applied it, so on new build it should work fine.

Changes are in Tigase PubSub Component and in Tigase HTTP API.

Daniel Wisnewski commented 9 years ago

Script runs to completion, works OK, closing issue.

issue 1 of 1
Type
Bug
Priority
Normal
Assignee
RedmineID
3693
Spent time
24h 30m
Issue Votes (0)
Watchers (0)
Reference
tigase/_server/server-core#601
Please wait...
Page is in error, reload to recover