Class com.ustadmobile.meshrabiya.vnet.VirtualNodeSharedTest

all > com.ustadmobile.meshrabiya.vnet > VirtualNodeSharedTest

tests

failures

ignored

6.497s

duration

100%

successful

Tests
Standard output
Standard error

Tests

Test	Duration	Result
givenConnectedNodes_whenBroadcastIsSent_thenAllWillReceive	0.027s	passed
givenMmcpHotspotRequestReceived_whenPacketRouted_thenWillRequestFromHotspotManagerAndReplyWithConfig	0.025s	passed
givenThreeNodesConnected_whenSocketCreatedUsingSocketFactory_thenWillConnectOverMultihopAndDownload	0.038s	passed
givenThreeNodesConnected_whenSocketFactoryUsedToCreateOkHttpClient_thenCanMakeHttpRequestsOverMultipleHops	0.177s	passed
givenThreeNodes_whenConnected_thenCanPingFromOneToOtherViaHop	1.006s	passed
givenThreeNodes_whenConnected_thenShouldReceiveOriginatingMessagesFromOthers	1.013s	passed
givenTwoNodesConnected_whenPacketSentUsingVirtualSocket_thenShouldBeReceived	0.128s	passed
givenTwoNodesConnected_whenSocketCreatedUsingSocketFactory_thenWillConnectAndDownload	0.038s	passed
givenTwoNodes_whenConnected_thenPingTimesWillBeDetermined	4.003s	passed
givenTwoNodes_whenForwardingSetup_thenEchoWillBeReceived	0.017s	passed
givenTwoVirtualNodesConnectedOverDatagramSocket_whenPingSent_thenReplyWillBeReceived	0.025s	passed

Standard output

D: t+0.07s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  Started on 31739 waiting for first packet
I: t+0.07s : [ChainSocketServer: 169.254.1.1]  init
D: t+0.08s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  Started on 21754 waiting for first packet
I: t+0.08s : [ChainSocketServer: 169.254.1.2]  init
D: t+0.09s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:21754
D: t+0.09s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out
V: t+0.1s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.1s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.11s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=1  hopCount=1 sentTime=1699269613670  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.11s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.12s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=1 sentTime=1699269613688
V: t+0.12s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.12s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.12s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=1  hopCount=1 sentTime=1699269613688  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.12s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.12s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=2 sentTime=1699269613693
V: t+0.12s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.12s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.12s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=2  hopCount=1 sentTime=1699269613693  Currently known: senttime=1699269613670  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.12s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.12s : [VirtualDatagramSocketImpl]  bind laddr=/169.254.1.1 lport=81
V: t+0.12s : [VirtualDatagramSocketImpl]  bind laddr=/169.254.1.2 lport=82
V: t+0.12s : [VirtualDatagramSocketImpl]  send packet size=11 bytes to /169.254.1.2:82
V: t+0.12s : [VirtualDatagramSocketImpl]  incoming virtual packet=31 bytes from 169.254.1.1:81 
I: t+0.12s : [ChainSocketServer: 169.254.1.1]  closed
D: t+0.12s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  : run : finished
I: t+0.12s : [ChainSocketServer: 169.254.1.2]  closed
D: t+0.12s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  : run : finished
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.41.107 ]  Started on 39886 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.41.107]  init
D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.68.230 ]  Started on 17509 waiting for first packet
I: t+0.01s : [ChainSocketServer: 169.254.68.230]  init
D: t+0.01s : [VirtualNode 169.254.41.107] addNewNeighborConnection connection to virtual addr 169.254.68.230 via datagram to localhost/127.0.0.1:17509
D: t+0.01s : [OriginatingMessageManager for /169.254.41.107] : addNeighbor - sending originating messages out
V: t+0.01s : [VirtualNode 169.254.68.230] received MMCP message (MmcpOriginatorMessage) from 169.254.41.107
V: t+0.01s : [OriginatingMessageManager for /169.254.68.230]  received originating message from 169.254.41.107 via 169.254.41.107
V: t+0.01s : [OriginatingMessageManager for /169.254.68.230]  received originating message from 169.254.41.107 via 169.254.41.107 messageId=1  hopCount=1 sentTime=1699269613715  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.68.230]  update originator messages: currently known nodes = 169.254.41.107
V: t+0.01s : [OriginatingMessageManager for /169.254.68.230]  sending originating message messageId=1 sentTime=1699269613716
V: t+0.01s : [VirtualNode 169.254.41.107] received MMCP message (MmcpOriginatorMessage) from 169.254.68.230
V: t+0.01s : [OriginatingMessageManager for /169.254.41.107]  received originating message from 169.254.68.230 via 169.254.68.230
V: t+0.01s : [OriginatingMessageManager for /169.254.41.107]  received originating message from 169.254.68.230 via 169.254.68.230 messageId=1  hopCount=1 sentTime=1699269613716  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.41.107]  update originator messages: currently known nodes = 169.254.68.230
V: t+0.01s : [OriginatingMessageManager for /169.254.41.107]  sending originating message messageId=2 sentTime=1699269613717
Connected node1 -> node2
V: t+0.01s : [VirtualNode 169.254.68.230] received MMCP message (MmcpPing) from 169.254.41.107
V: t+0.02s : [VirtualNode 169.254.68.230] Received ping(id=1533913366) from 169.254.41.107
V: t+0.02s : [VirtualNode 169.254.68.230] Sending pong to 169.254.41.107
V: t+0.02s : [VirtualNode 169.254.41.107] received MMCP message (MmcpPong) from 169.254.68.230
D: t+0.02s : [VirtualNode 169.254.68.230] Incoming packet received, but no socket listening on: 0
V: t+0.02s : [VirtualNode 169.254.68.230] received MMCP message (MmcpOriginatorMessage) from 169.254.41.107
V: t+0.02s : [OriginatingMessageManager for /169.254.68.230]  received originating message from 169.254.41.107 via 169.254.41.107
V: t+0.02s : [VirtualNode 169.254.41.107] Received pong(id=2)}
V: t+0.02s : [OriginatingMessageManager for /169.254.68.230]  received originating message from 169.254.41.107 via 169.254.41.107 messageId=2  hopCount=1 sentTime=1699269613717  Currently known: senttime=1699269613715  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.02s : [OriginatingMessageManager for /169.254.68.230]  update originator messages: currently known nodes = 169.254.41.107
W: t+0.02s : [OriginatingMessageManager for /169.254.41.107]  : onPongReceived : pong from 169.254.68.230 does not match any known sent ping
D: t+0.02s : [VirtualNode 169.254.41.107] Incoming packet received, but no socket listening on: 0
D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.41.107 ]  : run : finished
I: t+0.02s : [ChainSocketServer: 169.254.41.107]  closed
I: t+0.02s : [ChainSocketServer: 169.254.68.230]  closed
D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.68.230 ]  : run : finished
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  Started on 13513 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.1]  init
I: t+0.0s : [ChainSocketServer: 169.254.1.2]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  Started on 49145 waiting for first packet
D: t+0.0s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:49145
D: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=1  hopCount=1 sentTime=1699269613732  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=1 sentTime=1699269613732
V: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=1  hopCount=1 sentTime=1699269613732  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=2 sentTime=1699269613733
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=2  hopCount=1 sentTime=1699269613733  Currently known: senttime=1699269613732  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
I: t+0.03s : [ChainSocketFactoryImpl for /169.254.1.1] created socket to /169.254.1.2:44987 nexthop = /127.0.0.1:44987
I: t+0.03s : [ChainSocketServer: 169.254.1.1]  closed
D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  : run : finished
I: t+0.03s : [ChainSocketServer: 169.254.1.2]  closed
D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  : run : finished
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  Started on 8200 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.1]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  Started on 60395 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.2]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ]  Started on 10235 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.3]  init
D: t+0.0s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:60395
D: t+0.01s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out
V: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=1  hopCount=1 sentTime=1699269613772  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=1 sentTime=1699269613772
V: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=1  hopCount=1 sentTime=1699269613772  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=2 sentTime=1699269613773
V: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=2  hopCount=1 sentTime=1699269613773  Currently known: senttime=1699269613772  hop count = 1 isMoreRecentOrBetter=true 
D: t+0.01s : [VirtualNode 169.254.1.2] addNewNeighborConnection connection to virtual addr 169.254.1.3 via datagram to localhost/127.0.0.1:10235
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
D: t+0.01s : [OriginatingMessageManager for /169.254.1.2] : addNeighbor - sending originating messages out
V: t+0.01s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=2  hopCount=1 sentTime=1699269613774  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  sending originating message messageId=1 sentTime=1699269613775
V: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3 messageId=1  hopCount=1 sentTime=1699269613775  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.3, 169.254.1.1
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=3 sentTime=1699269613776
V: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=3  hopCount=1 sentTime=1699269613776  Currently known: senttime=1699269613772  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.01s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=3  hopCount=1 sentTime=1699269613776  Currently known: senttime=1699269613774  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.01s : [VirtualNode 169.254.1.2] broadcast packet from=169.254.1.3 lasthop=169.254.1.3 send to 169.254.1.1
V: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.3 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.3 via 169.254.1.2 messageId=1  hopCount=2 sentTime=1699269613775  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=3 sentTime=1699269614763
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=3  hopCount=1 sentTime=1699269614763  Currently known: senttime=1699269613773  hop count = 1 isMoreRecentOrBetter=true 
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.3, 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  pingNeighborsRunnable: send ping to 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.2] broadcast packet from=169.254.1.1 lasthop=169.254.1.1 send to 169.254.1.3
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=4 sentTime=1699269614765
V: t+1.0s : [VirtualNode 169.254.1.2] Received ping(id=4) from 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  pingNeighborsRunnable: send ping to 169.254.1.3
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.1 via 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.2] Sending pong to 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  pingNeighborsRunnable: send ping to 169.254.1.1
D: t+1.0s : [VirtualNode 169.254.1.2] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.1 via 169.254.1.2 messageId=3  hopCount=2 sentTime=1699269614763  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=4  hopCount=1 sentTime=1699269614765  Currently known: senttime=1699269613776  hop count = 1 isMoreRecentOrBetter=true 
V: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=4  hopCount=1 sentTime=1699269614765  Currently known: senttime=1699269613776  hop count = 1 isMoreRecentOrBetter=true 
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  sending originating message messageId=2 sentTime=1699269614766
V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPong) from 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.1] Received pong(id=6)}
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3 messageId=2  hopCount=1 sentTime=1699269614766  Currently known: senttime=1699269613775  hop count = 1 isMoreRecentOrBetter=true 
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.3, 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.2] broadcast packet from=169.254.1.3 lasthop=169.254.1.3 send to 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpPing) from 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  pingNeighborsRunnable: send ping to 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  received ping from 169.254.1.2 pingTime=3
V: t+1.0s : [VirtualNode 169.254.1.3] Received ping(id=5) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.3
V: t+1.0s : [VirtualNode 169.254.1.3] Sending pong to 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.2] Received ping(id=3) from 169.254.1.3
V: t+1.0s : [VirtualNode 169.254.1.2] Sending pong to 169.254.1.3
D: t+1.0s : [VirtualNode 169.254.1.3] Incoming packet received, but no socket listening on: 0
D: t+1.0s : [VirtualNode 169.254.1.2] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpPong) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPong) from 169.254.1.3
V: t+1.0s : [VirtualNode 169.254.1.3] Received pong(id=8)}
V: t+1.0s : [VirtualNode 169.254.1.2] Received pong(id=4)}
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  received ping from 169.254.1.2 pingTime=1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received ping from 169.254.1.3 pingTime=3
D: t+1.0s : [VirtualNode 169.254.1.2] Incoming packet received, but no socket listening on: 0
D: t+1.0s : [VirtualNode 169.254.1.3] Incoming packet received, but no socket listening on: 0
D: t+1.0s : [VirtualNode 169.254.1.1] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPing) from 169.254.1.2
I: t+1.0s : [ChainSocketServer: 169.254.1.1]  closed
V: t+1.0s : [VirtualNode 169.254.1.1] Received ping(id=7) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.1] Sending pong to 169.254.1.2
I: t+1.0s : [ChainSocketServer: 169.254.1.2]  closed
D: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  : run : finished
I: t+1.0s : [ChainSocketServer: 169.254.1.3]  closed
D: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ]  : run : finished
E: t+1.0s : [VirtualNode 169.254.1.1] : route : exception routing packet from 169.254.1.1 java.net.SocketException: Socket closed
	at java.base/sun.nio.ch.DatagramSocketAdaptor.send(DatagramSocketAdaptor.java:222)
	at java.base/java.net.DatagramSocket.send(DatagramSocket.java:664)
	at com.ustadmobile.meshrabiya.vnet.VirtualNodeDatagramSocket.send(VirtualNodeDatagramSocket.kt:89)
	at com.ustadmobile.meshrabiya.vnet.VirtualNode.route(VirtualNode.kt:488)
	at com.ustadmobile.meshrabiya.vnet.VirtualRouter$DefaultImpls.route$default(VirtualRouter.kt:22)
	at com.ustadmobile.meshrabiya.vnet.VirtualNode.onIncomingMmcpMessage(VirtualNode.kt:370)
	at com.ustadmobile.meshrabiya.vnet.VirtualNode.route(VirtualNode.kt:445)
	at com.ustadmobile.meshrabiya.vnet.VirtualNodeDatagramSocket.run(VirtualNodeDatagramSocket.kt:64)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
	at java.base/java.lang.Thread.run(Thread.java:833)
Caused by: java.nio.channels.ClosedChannelException
	at java.base/sun.nio.ch.DatagramChannelImpl.ensureOpen(DatagramChannelImpl.java:267)
	at java.base/sun.nio.ch.DatagramChannelImpl.blockingSend(DatagramChannelImpl.java:850)
	at java.base/sun.nio.ch.DatagramSocketAdaptor.send(DatagramSocketAdaptor.java:218)
	... 12 more

D: t+1.01s : Drop mmcp packet from -1442971390
D: t+1.01s : [VirtualNode 169.254.1.1] Incoming packet received, but no socket listening on: 0
D: t+1.01s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  : run : finished
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  Started on 58849 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.1]  init
I: t+0.0s : [ChainSocketServer: 169.254.1.2]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  Started on 30980 waiting for first packet
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ]  Started on 60074 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.3]  init
D: t+0.0s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:30980
D: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=1  hopCount=1 sentTime=1699269614785  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=1 sentTime=1699269614786
V: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=1  hopCount=1 sentTime=1699269614786  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=2 sentTime=1699269614786
D: t+0.01s : [VirtualNode 169.254.1.2] addNewNeighborConnection connection to virtual addr 169.254.1.3 via datagram to localhost/127.0.0.1:60074
V: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
D: t+0.01s : [OriginatingMessageManager for /169.254.1.2] : addNeighbor - sending originating messages out
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=2  hopCount=1 sentTime=1699269614786  Currently known: senttime=1699269614785  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.01s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=2  hopCount=1 sentTime=1699269614787  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  sending originating message messageId=1 sentTime=1699269614790
V: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3 messageId=1  hopCount=1 sentTime=1699269614790  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.3, 169.254.1.1
V: t+0.01s : [VirtualNode 169.254.1.2] broadcast packet from=169.254.1.3 lasthop=169.254.1.3 send to 169.254.1.1
V: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.3 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.3 via 169.254.1.2 messageId=1  hopCount=2 sentTime=1699269614790  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=3 sentTime=1699269614792
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3
V: t+0.01s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=3  hopCount=1 sentTime=1699269614792  Currently known: senttime=1699269614787  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=3  hopCount=1 sentTime=1699269614792  Currently known: senttime=1699269614786  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.3]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3
D: t+0.02s : [ChainSocketServer: 169.254.1.2]  accepted new client
/127.0.0.1:30980 is not final destination - write init request and get response
D: t+0.02s : [ChainSocketServer: 169.254.1.2]  /127.0.0.1:40310 : init client - reading init request...
D: t+0.02s : [ChainSocketServer: 169.254.1.2]  /127.0.0.1:40310 : receive init request to connect to /169.254.1.3:36845
I: t+0.02s : [ChainSocketFactoryImpl for /169.254.1.2] created socket to /169.254.1.3:36845 nexthop = /127.0.0.1:36845
D: t+0.02s : [ChainSocketServer: 169.254.1.2]  /127.0.0.1:40310 : created onward socket
D: t+0.02s : [ChainSocketServer: 169.254.1.2]  /127.0.0.1:40310 : wrote chain init response
/127.0.0.1:30980 got init response
I: t+0.02s : [ChainSocketFactoryImpl for /169.254.1.1] created socket to /169.254.1.3:36845 nexthop = /127.0.0.1:30980
V: t+0.02s : [ChainSocketServer: 169.254.1.2]  /127.0.0.1:40310 : CopyStream: onwardToIncoming - start copying input to output
V: t+0.03s : [ChainSocketServer: 169.254.1.2]  /127.0.0.1:40310 : CopyStream: incomingToOnward - start copying input to output
V: t+0.03s : [ChainSocketServer: 169.254.1.2]  /127.0.0.1:40310 : CopyStream: onwardToIncoming - finished copying - reached end of stream
W: t+0.03s : [ChainSocketServer: 169.254.1.2]  /127.0.0.1:40310: CopyStream: aborting java.net.SocketException: Socket closed
	at java.base/sun.nio.ch.NioSocketImpl.endRead(NioSocketImpl.java:248)
	at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:327)
	at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:350)
	at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:803)
	at java.base/java.net.Socket$SocketInputStream.read(Socket.java:966)
	at java.base/java.io.InputStream.read(InputStream.java:218)
	at kotlin.io.ByteStreamsKt.copyTo(IOStreams.kt:106)
	at kotlin.io.ByteStreamsKt.copyTo$default(IOStreams.kt:103)
	at com.ustadmobile.meshrabiya.vnet.socket.ChainSocketServer$CopyStreamRunnable.run(ChainSocketServer.kt:144)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
	at java.base/java.lang.Thread.run(Thread.java:833)

I: t+0.03s : [ChainSocketServer: 169.254.1.1]  closed
D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  : run : finished
I: t+0.03s : [ChainSocketServer: 169.254.1.2]  closed
D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  : run : finished
I: t+0.03s : [ChainSocketServer: 169.254.1.3]  closed
D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.3 ]  : run : finished
I: t+0.0s : [ChainSocketServer: 169.254.71.112]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.71.112 ]  Started on 60286 waiting for first packet
D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.22.140 ]  Started on 46951 waiting for first packet
I: t+0.01s : [ChainSocketServer: 169.254.22.140]  init
D: t+0.01s : [VirtualNode 169.254.71.112] addNewNeighborConnection connection to virtual addr 169.254.22.140 via datagram to localhost/127.0.0.1:46951
D: t+0.01s : [OriginatingMessageManager for /169.254.71.112] : addNeighbor - sending originating messages out
V: t+0.01s : [VirtualNode 169.254.22.140] received MMCP message (MmcpOriginatorMessage) from 169.254.71.112
V: t+0.01s : [OriginatingMessageManager for /169.254.22.140]  received originating message from 169.254.71.112 via 169.254.71.112
V: t+0.01s : [OriginatingMessageManager for /169.254.22.140]  received originating message from 169.254.71.112 via 169.254.71.112 messageId=1  hopCount=1 sentTime=1699269614825  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.22.140]  update originator messages: currently known nodes = 169.254.71.112
V: t+0.01s : [OriginatingMessageManager for /169.254.22.140]  sending originating message messageId=1 sentTime=1699269614826
V: t+0.01s : [VirtualNode 169.254.71.112] received MMCP message (MmcpOriginatorMessage) from 169.254.22.140
V: t+0.01s : [OriginatingMessageManager for /169.254.71.112]  received originating message from 169.254.22.140 via 169.254.22.140
V: t+0.01s : [OriginatingMessageManager for /169.254.71.112]  received originating message from 169.254.22.140 via 169.254.22.140 messageId=1  hopCount=1 sentTime=1699269614826  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.71.112]  update originator messages: currently known nodes = 169.254.22.140
V: t+0.01s : [OriginatingMessageManager for /169.254.71.112]  sending originating message messageId=2 sentTime=1699269614827
V: t+0.01s : [VirtualNode 169.254.71.112] received MMCP message (MmcpHotspotRequest) from 169.254.22.140
I: t+0.01s : [VirtualNode 169.254.71.112] Received hotspotrequest (id=-387288174)
D: t+0.01s : [VirtualNode 169.254.71.112] Incoming packet received, but no socket listening on: 0
V: t+0.01s : [VirtualNode 169.254.22.140] received MMCP message (MmcpOriginatorMessage) from 169.254.71.112
V: t+0.01s : [OriginatingMessageManager for /169.254.22.140]  received originating message from 169.254.71.112 via 169.254.71.112
V: t+0.01s : [OriginatingMessageManager for /169.254.22.140]  received originating message from 169.254.71.112 via 169.254.71.112 messageId=2  hopCount=1 sentTime=1699269614827  Currently known: senttime=1699269614825  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.22.140]  update originator messages: currently known nodes = 169.254.71.112
I: t+0.01s : [VirtualNode 169.254.71.112] sending hotspotresponse to 169.254.22.140
V: t+0.01s : [VirtualNode 169.254.22.140] received MMCP message (MmcpHotspotResponse) from 169.254.71.112
D: t+0.01s : [VirtualNode 169.254.22.140] Incoming packet received, but no socket listening on: 0
I: t+0.01s : [ChainSocketServer: 169.254.71.112]  closed
D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.71.112 ]  : run : finished
I: t+0.02s : [ChainSocketServer: 169.254.22.140]  closed
D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.22.140 ]  : run : finished
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  Started on 12578 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.1]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  Started on 26640 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.2]  init
D: t+0.0s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:26640
D: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=1  hopCount=1 sentTime=1699269614848  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=1 sentTime=1699269614849
V: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=1  hopCount=1 sentTime=1699269614849  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.01s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=2 sentTime=1699269614850
V: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=2  hopCount=1 sentTime=1699269614850  Currently known: senttime=1699269614848  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
D: t+0.01s : [UdpForwardRule : 33371 -> 169.254.1.2:8000] listening
V: t+0.01s : [VirtualDatagramSocketImpl]  bind laddr=/169.254.1.2 lport=8000
D: t+0.01s : [UdpForwardRule : 8000 -> 127.0.0.1:43171] listening
Send packet to localhost/127.0.0.1:33371
V: t+0.01s : [VirtualDatagramSocketImpl]  bind laddr=/169.254.1.1 lport=0
V: t+0.01s : [VirtualDatagramSocketImpl]  send packet size=5 bytes to /169.254.1.2:8000
V: t+0.01s : [VirtualDatagramSocketImpl]  incoming virtual packet=25 bytes from 169.254.1.1:15746 
V: t+0.01s : [VirtualDatagramSocketImpl]  send packet size=5 bytes to /169.254.1.1:15746
V: t+0.01s : [VirtualDatagramSocketImpl]  incoming virtual packet=25 bytes from 169.254.1.2:8000 
I: t+0.01s : [ChainSocketServer: 169.254.1.1]  closed
D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  : run : finished
I: t+0.01s : [ChainSocketServer: 169.254.1.2]  closed
D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  : run : finished
I: t+0.0s : [ChainSocketServer: 169.254.13.145]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.13.145 ]  Started on 38025 waiting for first packet
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.42.128 ]  Started on 1863 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.42.128]  init
D: t+0.0s : [VirtualNode 169.254.42.128] addNewNeighborConnection connection to virtual addr 169.254.13.145 via datagram to localhost/127.0.0.1:38025
D: t+0.0s : [OriginatingMessageManager for /169.254.42.128] : addNeighbor - sending originating messages out
V: t+0.0s : [VirtualNode 169.254.13.145] received MMCP message (MmcpOriginatorMessage) from 169.254.42.128
V: t+0.0s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.42.128 via 169.254.42.128
V: t+0.0s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.42.128 via 169.254.42.128 messageId=1  hopCount=1 sentTime=1699269614863  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.13.145]  update originator messages: currently known nodes = 169.254.42.128
V: t+0.0s : [OriginatingMessageManager for /169.254.13.145]  sending originating message messageId=1 sentTime=1699269614864
V: t+0.0s : [VirtualNode 169.254.42.128] received MMCP message (MmcpOriginatorMessage) from 169.254.13.145
V: t+0.0s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.13.145 via 169.254.13.145
V: t+0.0s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.13.145 via 169.254.13.145 messageId=1  hopCount=1 sentTime=1699269614864  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.42.128]  update originator messages: currently known nodes = 169.254.13.145
V: t+0.0s : [OriginatingMessageManager for /169.254.42.128]  sending originating message messageId=2 sentTime=1699269614865
V: t+0.0s : [VirtualNode 169.254.13.145] received MMCP message (MmcpOriginatorMessage) from 169.254.42.128
V: t+0.0s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.42.128 via 169.254.42.128
V: t+0.0s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.42.128 via 169.254.42.128 messageId=2  hopCount=1 sentTime=1699269614865  Currently known: senttime=1699269614863  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.13.145]  update originator messages: currently known nodes = 169.254.42.128
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.7.88 ]  Started on 19338 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.7.88]  init
D: t+0.0s : [VirtualNode 169.254.7.88] addNewNeighborConnection connection to virtual addr 169.254.13.145 via datagram to localhost/127.0.0.1:38025
D: t+0.0s : [OriginatingMessageManager for /169.254.7.88] : addNeighbor - sending originating messages out
V: t+0.0s : [VirtualNode 169.254.13.145] received MMCP message (MmcpOriginatorMessage) from 169.254.7.88
V: t+0.0s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.7.88 via 169.254.7.88
V: t+0.0s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.7.88 via 169.254.7.88 messageId=1  hopCount=1 sentTime=1699269614867  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.13.145]  update originator messages: currently known nodes = 169.254.7.88, 169.254.42.128
V: t+0.01s : [VirtualNode 169.254.13.145] broadcast packet from=169.254.7.88 lasthop=169.254.7.88 send to 169.254.42.128
V: t+0.01s : [OriginatingMessageManager for /169.254.13.145]  sending originating message messageId=2 sentTime=1699269614868
V: t+0.01s : [VirtualNode 169.254.42.128] received MMCP message (MmcpOriginatorMessage) from 169.254.7.88
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.7.88 via 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.7.88 via 169.254.13.145 messageId=1  hopCount=2 sentTime=1699269614867  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  update originator messages: currently known nodes = 169.254.7.88, 169.254.13.145
V: t+0.01s : [VirtualNode 169.254.7.88] received MMCP message (MmcpOriginatorMessage) from 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.7.88]  received originating message from 169.254.13.145 via 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.7.88]  received originating message from 169.254.13.145 via 169.254.13.145 messageId=2  hopCount=1 sentTime=1699269614868  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [VirtualNode 169.254.42.128] received MMCP message (MmcpOriginatorMessage) from 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.7.88]  update originator messages: currently known nodes = 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.13.145 via 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.13.145 via 169.254.13.145 messageId=2  hopCount=1 sentTime=1699269614868  Currently known: senttime=1699269614864  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  update originator messages: currently known nodes = 169.254.7.88, 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.7.88]  sending originating message messageId=2 sentTime=1699269614868
V: t+0.01s : [VirtualNode 169.254.13.145] received MMCP message (MmcpOriginatorMessage) from 169.254.7.88
V: t+0.01s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.7.88 via 169.254.7.88
V: t+0.01s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.7.88 via 169.254.7.88 messageId=2  hopCount=1 sentTime=1699269614868  Currently known: senttime=1699269614867  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.13.145]  update originator messages: currently known nodes = 169.254.7.88, 169.254.42.128
V: t+0.01s : [VirtualNode 169.254.13.145] broadcast packet from=169.254.7.88 lasthop=169.254.7.88 send to 169.254.42.128
V: t+0.01s : [VirtualNode 169.254.42.128] received MMCP message (MmcpOriginatorMessage) from 169.254.7.88
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.7.88 via 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.7.88 via 169.254.13.145 messageId=2  hopCount=2 sentTime=1699269614868  Currently known: senttime=1699269614867  hop count = 2 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  update originator messages: currently known nodes = 169.254.7.88, 169.254.13.145
D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.86.120 ]  Started on 41506 waiting for first packet
I: t+0.01s : [ChainSocketServer: 169.254.86.120]  init
D: t+0.01s : [VirtualNode 169.254.86.120] addNewNeighborConnection connection to virtual addr 169.254.13.145 via datagram to localhost/127.0.0.1:38025
D: t+0.01s : [OriginatingMessageManager for /169.254.86.120] : addNeighbor - sending originating messages out
V: t+0.01s : [VirtualNode 169.254.13.145] received MMCP message (MmcpOriginatorMessage) from 169.254.86.120
V: t+0.01s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.86.120 via 169.254.86.120
V: t+0.01s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.86.120 via 169.254.86.120 messageId=1  hopCount=1 sentTime=1699269614876  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.13.145]  update originator messages: currently known nodes = 169.254.7.88, 169.254.86.120, 169.254.42.128
V: t+0.01s : [VirtualNode 169.254.13.145] broadcast packet from=169.254.86.120 lasthop=169.254.86.120 send to 169.254.7.88
V: t+0.01s : [VirtualNode 169.254.13.145] broadcast packet from=169.254.86.120 lasthop=169.254.86.120 send to 169.254.42.128
V: t+0.01s : [OriginatingMessageManager for /169.254.13.145]  sending originating message messageId=3 sentTime=1699269614876
V: t+0.01s : [VirtualNode 169.254.7.88] received MMCP message (MmcpOriginatorMessage) from 169.254.86.120
V: t+0.01s : [VirtualNode 169.254.42.128] received MMCP message (MmcpOriginatorMessage) from 169.254.86.120
V: t+0.01s : [OriginatingMessageManager for /169.254.7.88]  received originating message from 169.254.86.120 via 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.86.120 via 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.7.88]  received originating message from 169.254.86.120 via 169.254.13.145 messageId=1  hopCount=2 sentTime=1699269614876  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.86.120 via 169.254.13.145 messageId=1  hopCount=2 sentTime=1699269614876  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.7.88]  update originator messages: currently known nodes = 169.254.86.120, 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  update originator messages: currently known nodes = 169.254.7.88, 169.254.86.120, 169.254.13.145
V: t+0.01s : [VirtualNode 169.254.86.120] received MMCP message (MmcpOriginatorMessage) from 169.254.13.145
V: t+0.01s : [VirtualNode 169.254.42.128] received MMCP message (MmcpOriginatorMessage) from 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.86.120]  received originating message from 169.254.13.145 via 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.13.145 via 169.254.13.145
V: t+0.01s : [VirtualNode 169.254.7.88] received MMCP message (MmcpOriginatorMessage) from 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.86.120]  received originating message from 169.254.13.145 via 169.254.13.145 messageId=3  hopCount=1 sentTime=1699269614876  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.7.88]  received originating message from 169.254.13.145 via 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.13.145 via 169.254.13.145 messageId=3  hopCount=1 sentTime=1699269614876  Currently known: senttime=1699269614868  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.86.120]  update originator messages: currently known nodes = 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.7.88]  received originating message from 169.254.13.145 via 169.254.13.145 messageId=3  hopCount=1 sentTime=1699269614876  Currently known: senttime=1699269614868  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.01s : [OriginatingMessageManager for /169.254.42.128]  update originator messages: currently known nodes = 169.254.7.88, 169.254.86.120, 169.254.13.145
V: t+0.01s : [OriginatingMessageManager for /169.254.7.88]  update originator messages: currently known nodes = 169.254.86.120, 169.254.13.145
V: t+0.02s : [OriginatingMessageManager for /169.254.86.120]  sending originating message messageId=2 sentTime=1699269614878
V: t+0.02s : [VirtualNode 169.254.13.145] received MMCP message (MmcpOriginatorMessage) from 169.254.86.120
V: t+0.02s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.86.120 via 169.254.86.120
V: t+0.02s : [OriginatingMessageManager for /169.254.13.145]  received originating message from 169.254.86.120 via 169.254.86.120 messageId=2  hopCount=1 sentTime=1699269614878  Currently known: senttime=1699269614876  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.02s : [OriginatingMessageManager for /169.254.13.145]  update originator messages: currently known nodes = 169.254.7.88, 169.254.86.120, 169.254.42.128
V: t+0.02s : [VirtualNode 169.254.13.145] broadcast packet from=169.254.86.120 lasthop=169.254.86.120 send to 169.254.7.88
V: t+0.02s : [VirtualNode 169.254.13.145] broadcast packet from=169.254.86.120 lasthop=169.254.86.120 send to 169.254.42.128
V: t+0.02s : [VirtualNode 169.254.42.128] received MMCP message (MmcpOriginatorMessage) from 169.254.86.120
V: t+0.02s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.86.120 via 169.254.13.145
V: t+0.02s : [OriginatingMessageManager for /169.254.42.128]  received originating message from 169.254.86.120 via 169.254.13.145 messageId=2  hopCount=2 sentTime=1699269614878  Currently known: senttime=1699269614876  hop count = 2 isMoreRecentOrBetter=true 
V: t+0.02s : [OriginatingMessageManager for /169.254.42.128]  update originator messages: currently known nodes = 169.254.7.88, 169.254.86.120, 169.254.13.145
V: t+0.02s : [VirtualNode 169.254.7.88] received MMCP message (MmcpOriginatorMessage) from 169.254.86.120
V: t+0.02s : [OriginatingMessageManager for /169.254.7.88]  received originating message from 169.254.86.120 via 169.254.13.145
V: t+0.02s : [VirtualNode 169.254.42.128] broadcast packet from=169.254.42.128 lasthop=0.0.0.0 send to 169.254.13.145
V: t+0.02s : [OriginatingMessageManager for /169.254.7.88]  received originating message from 169.254.86.120 via 169.254.13.145 messageId=2  hopCount=2 sentTime=1699269614878  Currently known: senttime=1699269614876  hop count = 2 isMoreRecentOrBetter=true 
V: t+0.02s : [OriginatingMessageManager for /169.254.7.88]  update originator messages: currently known nodes = 169.254.86.120, 169.254.13.145
V: t+0.02s : [VirtualNode 169.254.13.145] received MMCP message (MmcpPing) from 169.254.42.128
V: t+0.02s : [VirtualNode 169.254.13.145] broadcast packet from=169.254.42.128 lasthop=169.254.42.128 send to 169.254.7.88
V: t+0.02s : [VirtualNode 169.254.13.145] broadcast packet from=169.254.42.128 lasthop=169.254.42.128 send to 169.254.86.120
V: t+0.02s : [VirtualNode 169.254.7.88] received MMCP message (MmcpPing) from 169.254.42.128
V: t+0.02s : [VirtualNode 169.254.86.120] received MMCP message (MmcpPing) from 169.254.42.128
I: t+0.02s : [ChainSocketServer: 169.254.13.145]  closed
D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.13.145 ]  : run : finished
D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.42.128 ]  : run : finished
I: t+0.02s : [ChainSocketServer: 169.254.42.128]  closed
I: t+0.02s : [ChainSocketServer: 169.254.7.88]  closed
D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.7.88 ]  : run : finished
I: t+0.02s : [ChainSocketServer: 169.254.86.120]  closed
D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.86.120 ]  : run : finished
I: t+0.0s : [ChainSocketServer: 169.254.1.1]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  Started on 1570 waiting for first packet
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  Started on 3993 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.2]  init
Test node1=169.254.1.1 node2=169.254.1.2
D: t+0.0s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:3993
D: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=1  hopCount=1 sentTime=1699269614890  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=1 sentTime=1699269614891
V: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=1  hopCount=1 sentTime=1699269614891  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=2 sentTime=1699269614892
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=2  hopCount=1 sentTime=1699269614892  Currently known: senttime=1699269614890  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  pingNeighborsRunnable: send ping to 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=3 sentTime=1699269615885
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.2] Received ping(id=4) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.2] Sending pong to 169.254.1.1
D: t+1.0s : [VirtualNode 169.254.1.2] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPong) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.1] Received pong(id=2)}
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  received ping from 169.254.1.2 pingTime=1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=3  hopCount=1 sentTime=1699269615885  Currently known: senttime=1699269614892  hop count = 1 isMoreRecentOrBetter=true 
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
D: t+1.0s : [VirtualNode 169.254.1.1] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  pingNeighborsRunnable: send ping to 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=3 sentTime=1699269615888
V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPing) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.1] Received ping(id=4) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.1] Sending pong to 169.254.1.2
D: t+1.0s : [VirtualNode 169.254.1.1] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPong) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.2] Received pong(id=5)}
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received ping from 169.254.1.1 pingTime=1
D: t+1.0s : [VirtualNode 169.254.1.2] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=3  hopCount=1 sentTime=1699269615888  Currently known: senttime=1699269614891  hop count = 1 isMoreRecentOrBetter=true 
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2
V: t+4.0s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=6 sentTime=1699269618885
V: t+4.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+4.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+4.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=6  hopCount=1 sentTime=1699269618885  Currently known: senttime=1699269615885  hop count = 1 isMoreRecentOrBetter=true 
V: t+4.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
I: t+4.0s : [ChainSocketServer: 169.254.1.1]  closed
D: t+4.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  : run : finished
I: t+4.0s : [ChainSocketServer: 169.254.1.2]  closed
D: t+4.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  : run : finished
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  Started on 3929 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.1]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  Started on 21304 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.2]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ]  Started on 24156 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.3]  init
D: t+0.0s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:21304
D: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=1  hopCount=1 sentTime=1699269618893  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=1 sentTime=1699269618893
V: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=1  hopCount=1 sentTime=1699269618893  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=2 sentTime=1699269618894
D: t+0.0s : [VirtualNode 169.254.1.2] addNewNeighborConnection connection to virtual addr 169.254.1.3 via datagram to localhost/127.0.0.1:24156
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
D: t+0.0s : [OriginatingMessageManager for /169.254.1.2] : addNeighbor - sending originating messages out
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=2  hopCount=1 sentTime=1699269618894  Currently known: senttime=1699269618893  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=2  hopCount=1 sentTime=1699269618895  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  sending originating message messageId=1 sentTime=1699269618895
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3 messageId=1  hopCount=1 sentTime=1699269618895  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.3, 169.254.1.1
V: t+0.0s : [VirtualNode 169.254.1.2] broadcast packet from=169.254.1.3 lasthop=169.254.1.3 send to 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=3 sentTime=1699269618895
V: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.3 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.3 via 169.254.1.2 messageId=1  hopCount=2 sentTime=1699269618895  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3
V: t+0.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=3  hopCount=1 sentTime=1699269618895  Currently known: senttime=1699269618895  hop count = 1 isMoreRecentOrBetter=false 
D: t+0.0s : Drop mmcp packet from -1442971390
V: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=3  hopCount=1 sentTime=1699269618895  Currently known: senttime=1699269618893  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3
test: wait for discovery
test: node 1 knows about node 3
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  pingNeighborsRunnable: send ping to 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=3 sentTime=1699269619889
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.2] Received ping(id=4) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.2] Sending pong to 169.254.1.1
D: t+1.0s : [VirtualNode 169.254.1.2] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPong) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.1] Received pong(id=4)}
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  received ping from 169.254.1.2 pingTime=1
D: t+1.0s : [VirtualNode 169.254.1.1] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=3  hopCount=1 sentTime=1699269619889  Currently known: senttime=1699269618894  hop count = 1 isMoreRecentOrBetter=true 
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.3, 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.2] broadcast packet from=169.254.1.1 lasthop=169.254.1.1 send to 169.254.1.3
V: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.1 via 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.1 via 169.254.1.2 messageId=3  hopCount=2 sentTime=1699269619889  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=5 sentTime=1699269619890
V: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2
test: node 3 knows about node 1 : discovery done
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=5  hopCount=1 sentTime=1699269619890  Currently known: senttime=1699269618895  hop count = 1 isMoreRecentOrBetter=true 
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=5  hopCount=1 sentTime=1699269619890  Currently known: senttime=1699269618895  hop count = 1 isMoreRecentOrBetter=true 
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.1
V: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  pingNeighborsRunnable: send ping to 169.254.1.3
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  pingNeighborsRunnable: send ping to 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpPing) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.3] Received ping(id=6) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.3] Sending pong to 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPing) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.1] Received ping(id=7) from 169.254.1.2
D: t+1.0s : [VirtualNode 169.254.1.3] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.1] Sending pong to 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPong) from 169.254.1.3
V: t+1.0s : [VirtualNode 169.254.1.2] Received pong(id=2)}
D: t+1.0s : [VirtualNode 169.254.1.1] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received ping from 169.254.1.3 pingTime=1
D: t+1.0s : [VirtualNode 169.254.1.2] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPong) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.2] Received pong(id=5)}
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received ping from 169.254.1.1 pingTime=1
D: t+1.0s : [VirtualNode 169.254.1.2] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpPing) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.3] Received ping(id=1000042) from 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.3] Sending pong to 169.254.1.1
D: t+1.0s : [VirtualNode 169.254.1.3] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPong) from 169.254.1.3
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  pingNeighborsRunnable: send ping to 169.254.1.2
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  sending originating message messageId=4 sentTime=1699269619892
V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPong) from 169.254.1.3
V: t+1.0s : [VirtualNode 169.254.1.1] Received pong(id=3)}
W: t+1.0s : [OriginatingMessageManager for /169.254.1.1]  : onPongReceived : pong from 169.254.1.3 does not match any known sent ping
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.3
V: t+1.0s : [VirtualNode 169.254.1.2] Received ping(id=5) from 169.254.1.3
D: t+1.0s : [VirtualNode 169.254.1.1] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.2] Sending pong to 169.254.1.3
D: t+1.0s : [VirtualNode 169.254.1.2] Incoming packet received, but no socket listening on: 0
V: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpPong) from 169.254.1.2
V: t+1.0s : [VirtualNode 169.254.1.3] Received pong(id=8)}
V: t+1.0s : [OriginatingMessageManager for /169.254.1.3]  received ping from 169.254.1.2 pingTime=1
V: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3
D: t+1.0s : [VirtualNode 169.254.1.3] Incoming packet received, but no socket listening on: 0
I: t+1.0s : [ChainSocketServer: 169.254.1.1]  closed
D: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  : run : finished
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3 messageId=4  hopCount=1 sentTime=1699269619892  Currently known: senttime=1699269618895  hop count = 1 isMoreRecentOrBetter=true 
V: t+1.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.3, 169.254.1.1
V: t+1.0s : [VirtualNode 169.254.1.2] broadcast packet from=169.254.1.3 lasthop=169.254.1.3 send to 169.254.1.1
I: t+1.0s : [ChainSocketServer: 169.254.1.2]  closed
D: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  : run : finished
I: t+1.0s : [ChainSocketServer: 169.254.1.3]  closed
D: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ]  : run : finished
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  Started on 57660 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.1]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  Started on 9046 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.2]  init
D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ]  Started on 28526 waiting for first packet
I: t+0.0s : [ChainSocketServer: 169.254.1.3]  init
D: t+0.0s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:9046
D: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=1  hopCount=1 sentTime=1699269619898  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=1 sentTime=1699269619899
V: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=1  hopCount=1 sentTime=1699269619899  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  sending originating message messageId=2 sentTime=1699269619899
D: t+0.0s : [VirtualNode 169.254.1.2] addNewNeighborConnection connection to virtual addr 169.254.1.3 via datagram to localhost/127.0.0.1:28526
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1
D: t+0.0s : [OriginatingMessageManager for /169.254.1.2] : addNeighbor - sending originating messages out
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.1 via 169.254.1.1 messageId=2  hopCount=1 sentTime=1699269619899  Currently known: senttime=1699269619898  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.1
V: t+0.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=2  hopCount=1 sentTime=1699269619900  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  update originator messages: currently known nodes = 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  sending originating message messageId=1 sentTime=1699269619900
V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  received originating message from 169.254.1.3 via 169.254.1.3 messageId=1  hopCount=1 sentTime=1699269619900  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  update originator messages: currently known nodes = 169.254.1.3, 169.254.1.1
V: t+0.0s : [VirtualNode 169.254.1.2] broadcast packet from=169.254.1.3 lasthop=169.254.1.3 send to 169.254.1.1
V: t+0.0s : [OriginatingMessageManager for /169.254.1.2]  sending originating message messageId=3 sentTime=1699269619900
V: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.3 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.3 via 169.254.1.2 messageId=1  hopCount=2 sentTime=1699269619900  Currently known: senttime=0  hop count = 127 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3
V: t+0.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.3]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=3  hopCount=1 sentTime=1699269619900  Currently known: senttime=1699269619900  hop count = 1 isMoreRecentOrBetter=false 
D: t+0.0s : Drop mmcp packet from -1442971390
V: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  received originating message from 169.254.1.2 via 169.254.1.2 messageId=3  hopCount=1 sentTime=1699269619900  Currently known: senttime=1699269619899  hop count = 1 isMoreRecentOrBetter=true 
V: t+0.0s : [OriginatingMessageManager for /169.254.1.1]  update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3
I: t+0.17s : [ChainSocketServer: 169.254.1.1]  closed
D: t+0.17s : [VirtualNodeDatagramSocket for 169.254.1.1 ]  : run : finished
I: t+0.17s : [ChainSocketServer: 169.254.1.2]  closed
D: t+0.17s : [VirtualNodeDatagramSocket for 169.254.1.2 ]  : run : finished
I: t+0.17s : [ChainSocketServer: 169.254.1.3]  closed
D: t+0.17s : [VirtualNodeDatagramSocket for 169.254.1.3 ]  : run : finished

Standard error

java.net.SocketException: Socket closed
	at java.base/sun.nio.ch.DatagramSocketAdaptor.send(DatagramSocketAdaptor.java:222)
	at java.base/java.net.DatagramSocket.send(DatagramSocket.java:664)
	at com.ustadmobile.meshrabiya.vnet.VirtualNodeDatagramSocket.send(VirtualNodeDatagramSocket.kt:89)
	at com.ustadmobile.meshrabiya.vnet.VirtualNode.route(VirtualNode.kt:488)
	at com.ustadmobile.meshrabiya.vnet.VirtualRouter$DefaultImpls.route$default(VirtualRouter.kt:22)
	at com.ustadmobile.meshrabiya.vnet.VirtualNode.onIncomingMmcpMessage(VirtualNode.kt:370)
	at com.ustadmobile.meshrabiya.vnet.VirtualNode.route(VirtualNode.kt:445)
	at com.ustadmobile.meshrabiya.vnet.VirtualNodeDatagramSocket.run(VirtualNodeDatagramSocket.kt:64)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
	at java.base/java.lang.Thread.run(Thread.java:833)
Caused by: java.nio.channels.ClosedChannelException
	at java.base/sun.nio.ch.DatagramChannelImpl.ensureOpen(DatagramChannelImpl.java:267)
	at java.base/sun.nio.ch.DatagramChannelImpl.blockingSend(DatagramChannelImpl.java:850)
	at java.base/sun.nio.ch.DatagramSocketAdaptor.send(DatagramSocketAdaptor.java:218)
	... 12 more