node2 V: t+0.02s : [VirtualNode 169.254.112.234] received MMCP message (MmcpPing) from 169.254.127.63 V: t+0.02s : [VirtualNode 169.254.112.234] Received ping(id=-1407287034) from 169.254.127.63 V: t+0.02s : [VirtualNode 169.254.112.234] Sending pong to 169.254.127.63 V: t+0.02s : [VirtualNode 169.254.127.63] received MMCP message (MmcpPong) from 169.254.112.234 V: t+0.02s : [VirtualNode 169.254.127.63] Received pong(id=2)} W: t+0.02s : [OriginatingMessageManager for /169.254.127.63] : onPongReceived : pong from 169.254.112.234 does not match any known sent ping I: t+0.02s : [ChainSocketServer: 169.254.127.63] closed D: t+0.02s : [VirtualNode 169.254.127.63] Incoming packet received, but no socket listening on: 0 D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.127.63 ] : run : finished I: t+0.03s : [ChainSocketServer: 169.254.112.234] closed D: t+0.03s : [VirtualNode 169.254.112.234] Incoming packet received, but no socket listening on: 0 D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.112.234 ] : run : finished I: t+0.0s : [ChainSocketServer: 169.254.1.1] init D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 4871 waiting for first packet D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 20686 waiting for first packet I: t+0.01s : [ChainSocketServer: 169.254.1.2] init D: t+0.01s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:20686 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=1699269605059 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=1699269605060 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=1699269605060 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=1699269605061 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=1699269605061 Currently known: senttime=1699269605059 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 I: t+0.05s : [ChainSocketFactoryImpl for /169.254.1.1] created socket to /169.254.1.2:36815 nexthop = /127.0.0.1:36815 I: t+0.05s : [ChainSocketServer: 169.254.1.1] closed D: t+0.05s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished I: t+0.06s : [ChainSocketServer: 169.254.1.2] closed D: t+0.06s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 50204 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 35758 waiting for first packet I: t+0.0s : [ChainSocketServer: 169.254.1.2] init D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.1.3 ] Started on 3380 waiting for first packet I: t+0.01s : [ChainSocketServer: 169.254.1.3] init D: t+0.01s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:35758 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=1699269605126 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=1699269605127 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=1699269605127 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 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:3380 D: t+0.01s : [OriginatingMessageManager for /169.254.1.2] : addNeighbor - sending originating messages out V: t+0.01s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=2 sentTime=1699269605129 V: t+0.02s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 V: t+0.02s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.1 via 169.254.1.1 V: t+0.02s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.1 via 169.254.1.1 messageId=2 hopCount=1 sentTime=1699269605129 Currently known: senttime=1699269605126 hop count = 1 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 V: t+0.02s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 V: t+0.02s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.2 via 169.254.1.2 V: t+0.02s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.2 via 169.254.1.2 messageId=2 hopCount=1 sentTime=1699269605129 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.1.3] update originator messages: currently known nodes = 169.254.1.2 V: t+0.02s : [OriginatingMessageManager for /169.254.1.3] sending originating message messageId=1 sentTime=1699269605132 V: t+0.02s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 V: t+0.02s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.3 via 169.254.1.3 V: t+0.02s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.3 via 169.254.1.3 messageId=1 hopCount=1 sentTime=1699269605132 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.3, 169.254.1.1 V: t+0.02s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=3 sentTime=1699269605136 V: t+0.02s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 V: t+0.02s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.2 via 169.254.1.2 V: t+0.02s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.2 via 169.254.1.2 messageId=3 hopCount=1 sentTime=1699269605136 Currently known: senttime=1699269605127 hop count = 1 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.1.1] update originator messages: currently known nodes = 169.254.1.2 V: t+0.03s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 V: t+0.03s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.2 via 169.254.1.2 V: t+0.03s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.2 via 169.254.1.2 messageId=3 hopCount=1 sentTime=1699269605136 Currently known: senttime=1699269605129 hop count = 1 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.1.3] update originator messages: currently known nodes = 169.254.1.2 V: t+0.03s : [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.03s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 V: t+0.03s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.3 via 169.254.1.2 V: t+0.03s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.3 via 169.254.1.2 messageId=1 hopCount=2 sentTime=1699269605132 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.03s : [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=1699269606111 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=1699269606111 Currently known: senttime=1699269605129 hop count = 1 isMoreRecentOrBetter=true 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.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.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.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=1699269606111 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=4 sentTime=1699269606113 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.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=1699269606113 Currently known: senttime=1699269605136 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.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.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=4 hopCount=1 sentTime=1699269606113 Currently known: senttime=1699269605136 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, 169.254.1.3 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 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 D: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished I: t+1.0s : [ChainSocketServer: 169.254.1.1] closed 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 D: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished V: t+1.01s : [OriginatingMessageManager for /169.254.1.3] sending originating message messageId=3 sentTime=1699269606120 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.2] closed V: t+1.01s : [OriginatingMessageManager for /169.254.1.3] pingNeighborsRunnable: send ping to 169.254.1.2 I: t+1.02s : [ChainSocketServer: 169.254.1.3] closed D: t+1.02s : [VirtualNodeDatagramSocket for 169.254.1.3 ] : run : finished I: t+0.01s : [ChainSocketServer: 169.254.1.1] init D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 52077 waiting for first packet D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 48866 waiting for first packet I: t+0.02s : [ChainSocketServer: 169.254.1.2] init D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.3 ] Started on 55066 waiting for first packet I: t+0.03s : [ChainSocketServer: 169.254.1.3] init D: t+0.03s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:48866 D: t+0.03s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out V: t+0.03s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 V: t+0.03s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.1 via 169.254.1.1 V: t+0.03s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.1 via 169.254.1.1 messageId=1 hopCount=1 sentTime=1699269606168 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 V: t+0.03s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=1 sentTime=1699269606169 V: t+0.03s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 V: t+0.03s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.2 via 169.254.1.2 V: t+0.03s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.2 via 169.254.1.2 messageId=1 hopCount=1 sentTime=1699269606169 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.1.1] update originator messages: currently known nodes = 169.254.1.2 V: t+0.03s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=2 sentTime=1699269606170 D: t+0.03s : [VirtualNode 169.254.1.2] addNewNeighborConnection connection to virtual addr 169.254.1.3 via datagram to localhost/127.0.0.1:55066 D: t+0.03s : [OriginatingMessageManager for /169.254.1.2] : addNeighbor - sending originating messages out V: t+0.03s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 V: t+0.03s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.1 via 169.254.1.1 V: t+0.03s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.1 via 169.254.1.1 messageId=2 hopCount=1 sentTime=1699269606170 Currently known: senttime=1699269606168 hop count = 1 isMoreRecentOrBetter=true V: t+0.03s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 V: t+0.03s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.2 via 169.254.1.2 V: t+0.04s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.2 via 169.254.1.2 messageId=2 hopCount=1 sentTime=1699269606171 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.04s : [OriginatingMessageManager for /169.254.1.3] update originator messages: currently known nodes = 169.254.1.2 V: t+0.03s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 V: t+0.04s : [OriginatingMessageManager for /169.254.1.3] sending originating message messageId=1 sentTime=1699269606172 V: t+0.04s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 V: t+0.04s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.3 via 169.254.1.3 V: t+0.04s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.3 via 169.254.1.3 messageId=1 hopCount=1 sentTime=1699269606172 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.04s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.3, 169.254.1.1 V: t+0.04s : [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.04s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 V: t+0.04s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.3 via 169.254.1.2 V: t+0.04s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.3 via 169.254.1.2 messageId=1 hopCount=2 sentTime=1699269606172 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.04s : [OriginatingMessageManager for /169.254.1.1] update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3 V: t+0.04s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=3 sentTime=1699269606175 V: t+0.04s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 V: t+0.04s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.2 via 169.254.1.2 V: t+0.04s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.2 via 169.254.1.2 messageId=3 hopCount=1 sentTime=1699269606175 Currently known: senttime=1699269606171 hop count = 1 isMoreRecentOrBetter=true V: t+0.04s : [OriginatingMessageManager for /169.254.1.3] update originator messages: currently known nodes = 169.254.1.2 V: t+0.04s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 V: t+0.04s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.2 via 169.254.1.2 V: t+0.04s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.2 via 169.254.1.2 messageId=3 hopCount=1 sentTime=1699269606175 Currently known: senttime=1699269606169 hop count = 1 isMoreRecentOrBetter=true V: t+0.04s : [OriginatingMessageManager for /169.254.1.1] update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3 D: t+0.05s : [ChainSocketServer: 169.254.1.2] accepted new client /127.0.0.1:48866 is not final destination - write init request and get response D: t+0.05s : [ChainSocketServer: 169.254.1.2] /127.0.0.1:51050 : init client - reading init request... D: t+0.05s : [ChainSocketServer: 169.254.1.2] /127.0.0.1:51050 : receive init request to connect to /169.254.1.3:33315 I: t+0.05s : [ChainSocketFactoryImpl for /169.254.1.2] created socket to /169.254.1.3:33315 nexthop = /127.0.0.1:33315 D: t+0.05s : [ChainSocketServer: 169.254.1.2] /127.0.0.1:51050 : created onward socket D: t+0.05s : [ChainSocketServer: 169.254.1.2] /127.0.0.1:51050 : wrote chain init response /127.0.0.1:48866 got init response I: t+0.05s : [ChainSocketFactoryImpl for /169.254.1.1] created socket to /169.254.1.3:33315 nexthop = /127.0.0.1:48866 V: t+0.05s : [ChainSocketServer: 169.254.1.2] /127.0.0.1:51050 : CopyStream: onwardToIncoming - start copying input to output V: t+0.05s : [ChainSocketServer: 169.254.1.2] /127.0.0.1:51050 : CopyStream: incomingToOnward - start copying input to output V: t+0.06s : [ChainSocketServer: 169.254.1.2] /127.0.0.1:51050 : CopyStream: onwardToIncoming - finished copying - reached end of stream I: t+0.06s : [ChainSocketServer: 169.254.1.1] closed D: t+0.06s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished I: t+0.06s : [ChainSocketServer: 169.254.1.2] closed D: t+0.06s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished D: t+0.06s : [VirtualNodeDatagramSocket for 169.254.1.3 ] : run : finished I: t+0.06s : [ChainSocketServer: 169.254.1.3] closed W: t+0.06s : [ChainSocketServer: 169.254.1.2] /127.0.0.1:51050: 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) D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.102.132 ] Started on 28097 waiting for first packet I: t+0.0s : [ChainSocketServer: 169.254.102.132] init D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.81.133 ] Started on 41110 waiting for first packet I: t+0.01s : [ChainSocketServer: 169.254.81.133] init D: t+0.01s : [VirtualNode 169.254.102.132] addNewNeighborConnection connection to virtual addr 169.254.81.133 via datagram to localhost/127.0.0.1:41110 D: t+0.01s : [OriginatingMessageManager for /169.254.102.132] : addNeighbor - sending originating messages out V: t+0.01s : [VirtualNode 169.254.81.133] received MMCP message (MmcpOriginatorMessage) from 169.254.102.132 V: t+0.01s : [OriginatingMessageManager for /169.254.81.133] received originating message from 169.254.102.132 via 169.254.102.132 V: t+0.01s : [OriginatingMessageManager for /169.254.81.133] received originating message from 169.254.102.132 via 169.254.102.132 messageId=1 hopCount=1 sentTime=1699269606214 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.01s : [OriginatingMessageManager for /169.254.81.133] update originator messages: currently known nodes = 169.254.102.132 V: t+0.01s : [OriginatingMessageManager for /169.254.81.133] sending originating message messageId=1 sentTime=1699269606216 V: t+0.01s : [VirtualNode 169.254.102.132] received MMCP message (MmcpOriginatorMessage) from 169.254.81.133 V: t+0.01s : [OriginatingMessageManager for /169.254.102.132] received originating message from 169.254.81.133 via 169.254.81.133 V: t+0.01s : [OriginatingMessageManager for /169.254.102.132] received originating message from 169.254.81.133 via 169.254.81.133 messageId=1 hopCount=1 sentTime=1699269606216 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.01s : [OriginatingMessageManager for /169.254.102.132] update originator messages: currently known nodes = 169.254.81.133 V: t+0.01s : [OriginatingMessageManager for /169.254.102.132] sending originating message messageId=2 sentTime=1699269606217 V: t+0.02s : [VirtualNode 169.254.81.133] received MMCP message (MmcpOriginatorMessage) from 169.254.102.132 V: t+0.02s : [OriginatingMessageManager for /169.254.81.133] received originating message from 169.254.102.132 via 169.254.102.132 V: t+0.02s : [OriginatingMessageManager for /169.254.81.133] received originating message from 169.254.102.132 via 169.254.102.132 messageId=2 hopCount=1 sentTime=1699269606217 Currently known: senttime=1699269606214 hop count = 1 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.81.133] update originator messages: currently known nodes = 169.254.102.132 V: t+0.02s : [VirtualNode 169.254.102.132] received MMCP message (MmcpHotspotRequest) from 169.254.81.133 I: t+0.02s : [VirtualNode 169.254.102.132] Received hotspotrequest (id=-1006537128) D: t+0.02s : [VirtualNode 169.254.102.132] Incoming packet received, but no socket listening on: 0 I: t+0.02s : [VirtualNode 169.254.102.132] sending hotspotresponse to 169.254.81.133 V: t+0.02s : [VirtualNode 169.254.81.133] received MMCP message (MmcpHotspotResponse) from 169.254.102.132 D: t+0.02s : [VirtualNode 169.254.81.133] Incoming packet received, but no socket listening on: 0 D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.102.132 ] : run : finished I: t+0.02s : [ChainSocketServer: 169.254.102.132] closed I: t+0.02s : [ChainSocketServer: 169.254.81.133] closed D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.81.133 ] : run : finished D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 6233 waiting for first packet I: t+0.0s : [ChainSocketServer: 169.254.1.1] init I: t+0.01s : [ChainSocketServer: 169.254.1.2] init D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 65398 waiting for first packet D: t+0.01s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:65398 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=1699269606236 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=1699269606237 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=1699269606237 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=1699269606239 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=1699269606239 Currently known: senttime=1699269606236 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 : 60983 -> 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:53362] listening Send packet to localhost/127.0.0.1:60983 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:25602 V: t+0.01s : [VirtualDatagramSocketImpl] send packet size=5 bytes to /169.254.1.1:25602 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 I: t+0.01s : [ChainSocketServer: 169.254.1.2] closed D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.67.161 ] Started on 52950 waiting for first packet I: t+0.0s : [ChainSocketServer: 169.254.67.161] init D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.15.238 ] Started on 30418 waiting for first packet I: t+0.01s : [ChainSocketServer: 169.254.15.238] init D: t+0.01s : [VirtualNode 169.254.15.238] addNewNeighborConnection connection to virtual addr 169.254.67.161 via datagram to localhost/127.0.0.1:52950 D: t+0.01s : [OriginatingMessageManager for /169.254.15.238] : addNeighbor - sending originating messages out V: t+0.01s : [VirtualNode 169.254.67.161] received MMCP message (MmcpOriginatorMessage) from 169.254.15.238 V: t+0.01s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.15.238 via 169.254.15.238 V: t+0.01s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.15.238 via 169.254.15.238 messageId=1 hopCount=1 sentTime=1699269606257 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.01s : [OriginatingMessageManager for /169.254.67.161] update originator messages: currently known nodes = 169.254.15.238 V: t+0.01s : [OriginatingMessageManager for /169.254.67.161] sending originating message messageId=1 sentTime=1699269606258 V: t+0.01s : [VirtualNode 169.254.15.238] received MMCP message (MmcpOriginatorMessage) from 169.254.67.161 V: t+0.01s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.67.161 via 169.254.67.161 V: t+0.01s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.67.161 via 169.254.67.161 messageId=1 hopCount=1 sentTime=1699269606258 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.01s : [OriginatingMessageManager for /169.254.15.238] update originator messages: currently known nodes = 169.254.67.161 V: t+0.01s : [OriginatingMessageManager for /169.254.15.238] sending originating message messageId=2 sentTime=1699269606259 V: t+0.01s : [VirtualNode 169.254.67.161] received MMCP message (MmcpOriginatorMessage) from 169.254.15.238 V: t+0.01s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.15.238 via 169.254.15.238 V: t+0.01s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.15.238 via 169.254.15.238 messageId=2 hopCount=1 sentTime=1699269606259 Currently known: senttime=1699269606257 hop count = 1 isMoreRecentOrBetter=true V: t+0.01s : [OriginatingMessageManager for /169.254.67.161] update originator messages: currently known nodes = 169.254.15.238 D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.3.194 ] Started on 17309 waiting for first packet I: t+0.02s : [ChainSocketServer: 169.254.3.194] init D: t+0.02s : [VirtualNode 169.254.3.194] addNewNeighborConnection connection to virtual addr 169.254.67.161 via datagram to localhost/127.0.0.1:52950 D: t+0.02s : [OriginatingMessageManager for /169.254.3.194] : addNeighbor - sending originating messages out V: t+0.02s : [VirtualNode 169.254.67.161] received MMCP message (MmcpOriginatorMessage) from 169.254.3.194 V: t+0.02s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.3.194 via 169.254.3.194 V: t+0.02s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.3.194 via 169.254.3.194 messageId=1 hopCount=1 sentTime=1699269606269 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.67.161] update originator messages: currently known nodes = 169.254.15.238, 169.254.3.194 V: t+0.02s : [VirtualNode 169.254.67.161] broadcast packet from=169.254.3.194 lasthop=169.254.3.194 send to 169.254.15.238 V: t+0.02s : [VirtualNode 169.254.15.238] received MMCP message (MmcpOriginatorMessage) from 169.254.3.194 V: t+0.02s : [OriginatingMessageManager for /169.254.67.161] sending originating message messageId=2 sentTime=1699269606270 V: t+0.02s : [VirtualNode 169.254.3.194] received MMCP message (MmcpOriginatorMessage) from 169.254.67.161 V: t+0.02s : [OriginatingMessageManager for /169.254.3.194] received originating message from 169.254.67.161 via 169.254.67.161 V: t+0.02s : [OriginatingMessageManager for /169.254.3.194] received originating message from 169.254.67.161 via 169.254.67.161 messageId=2 hopCount=1 sentTime=1699269606270 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.3.194] update originator messages: currently known nodes = 169.254.67.161 V: t+0.02s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.3.194 via 169.254.67.161 V: t+0.02s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.3.194 via 169.254.67.161 messageId=1 hopCount=2 sentTime=1699269606269 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.15.238] update originator messages: currently known nodes = 169.254.3.194, 169.254.67.161 V: t+0.02s : [VirtualNode 169.254.15.238] received MMCP message (MmcpOriginatorMessage) from 169.254.67.161 V: t+0.02s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.67.161 via 169.254.67.161 V: t+0.02s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.67.161 via 169.254.67.161 messageId=2 hopCount=1 sentTime=1699269606270 Currently known: senttime=1699269606258 hop count = 1 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.3.194] sending originating message messageId=2 sentTime=1699269606272 V: t+0.02s : [OriginatingMessageManager for /169.254.15.238] update originator messages: currently known nodes = 169.254.3.194, 169.254.67.161 V: t+0.02s : [VirtualNode 169.254.67.161] received MMCP message (MmcpOriginatorMessage) from 169.254.3.194 V: t+0.02s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.3.194 via 169.254.3.194 V: t+0.02s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.3.194 via 169.254.3.194 messageId=2 hopCount=1 sentTime=1699269606272 Currently known: senttime=1699269606269 hop count = 1 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.67.161] update originator messages: currently known nodes = 169.254.15.238, 169.254.3.194 V: t+0.02s : [VirtualNode 169.254.67.161] broadcast packet from=169.254.3.194 lasthop=169.254.3.194 send to 169.254.15.238 V: t+0.02s : [VirtualNode 169.254.15.238] received MMCP message (MmcpOriginatorMessage) from 169.254.3.194 V: t+0.02s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.3.194 via 169.254.67.161 V: t+0.02s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.3.194 via 169.254.67.161 messageId=2 hopCount=2 sentTime=1699269606272 Currently known: senttime=1699269606269 hop count = 2 isMoreRecentOrBetter=true V: t+0.02s : [OriginatingMessageManager for /169.254.15.238] update originator messages: currently known nodes = 169.254.3.194, 169.254.67.161 D: t+0.02s : [VirtualNodeDatagramSocket for 169.254.91.226 ] Started on 29922 waiting for first packet I: t+0.02s : [ChainSocketServer: 169.254.91.226] init D: t+0.02s : [VirtualNode 169.254.91.226] addNewNeighborConnection connection to virtual addr 169.254.67.161 via datagram to localhost/127.0.0.1:52950 D: t+0.02s : [OriginatingMessageManager for /169.254.91.226] : addNeighbor - sending originating messages out V: t+0.03s : [VirtualNode 169.254.67.161] received MMCP message (MmcpOriginatorMessage) from 169.254.91.226 V: t+0.03s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.91.226 via 169.254.91.226 V: t+0.03s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.91.226 via 169.254.91.226 messageId=1 hopCount=1 sentTime=1699269606276 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.67.161] update originator messages: currently known nodes = 169.254.15.238, 169.254.3.194, 169.254.91.226 V: t+0.03s : [OriginatingMessageManager for /169.254.67.161] sending originating message messageId=3 sentTime=1699269606276 V: t+0.03s : [VirtualNode 169.254.67.161] broadcast packet from=169.254.91.226 lasthop=169.254.91.226 send to 169.254.15.238 V: t+0.03s : [VirtualNode 169.254.15.238] received MMCP message (MmcpOriginatorMessage) from 169.254.67.161 V: t+0.03s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.67.161 via 169.254.67.161 V: t+0.03s : [VirtualNode 169.254.3.194] received MMCP message (MmcpOriginatorMessage) from 169.254.67.161 V: t+0.03s : [VirtualNode 169.254.67.161] broadcast packet from=169.254.91.226 lasthop=169.254.91.226 send to 169.254.3.194 V: t+0.03s : [VirtualNode 169.254.91.226] received MMCP message (MmcpOriginatorMessage) from 169.254.67.161 V: t+0.03s : [OriginatingMessageManager for /169.254.3.194] received originating message from 169.254.67.161 via 169.254.67.161 V: t+0.03s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.67.161 via 169.254.67.161 messageId=3 hopCount=1 sentTime=1699269606276 Currently known: senttime=1699269606270 hop count = 1 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.91.226] received originating message from 169.254.67.161 via 169.254.67.161 V: t+0.03s : [OriginatingMessageManager for /169.254.3.194] received originating message from 169.254.67.161 via 169.254.67.161 messageId=3 hopCount=1 sentTime=1699269606276 Currently known: senttime=1699269606270 hop count = 1 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.91.226] received originating message from 169.254.67.161 via 169.254.67.161 messageId=3 hopCount=1 sentTime=1699269606276 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.15.238] update originator messages: currently known nodes = 169.254.3.194, 169.254.67.161 V: t+0.03s : [OriginatingMessageManager for /169.254.3.194] update originator messages: currently known nodes = 169.254.67.161 V: t+0.03s : [OriginatingMessageManager for /169.254.91.226] update originator messages: currently known nodes = 169.254.67.161 V: t+0.03s : [VirtualNode 169.254.15.238] received MMCP message (MmcpOriginatorMessage) from 169.254.91.226 V: t+0.03s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.91.226 via 169.254.67.161 V: t+0.03s : [VirtualNode 169.254.3.194] received MMCP message (MmcpOriginatorMessage) from 169.254.91.226 V: t+0.03s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.91.226 via 169.254.67.161 messageId=1 hopCount=2 sentTime=1699269606276 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.3.194] received originating message from 169.254.91.226 via 169.254.67.161 V: t+0.03s : [OriginatingMessageManager for /169.254.15.238] update originator messages: currently known nodes = 169.254.3.194, 169.254.91.226, 169.254.67.161 V: t+0.03s : [OriginatingMessageManager for /169.254.3.194] received originating message from 169.254.91.226 via 169.254.67.161 messageId=1 hopCount=2 sentTime=1699269606276 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.3.194] update originator messages: currently known nodes = 169.254.91.226, 169.254.67.161 V: t+0.03s : [OriginatingMessageManager for /169.254.91.226] sending originating message messageId=2 sentTime=1699269606278 V: t+0.03s : [VirtualNode 169.254.67.161] received MMCP message (MmcpOriginatorMessage) from 169.254.91.226 V: t+0.03s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.91.226 via 169.254.91.226 V: t+0.03s : [OriginatingMessageManager for /169.254.67.161] received originating message from 169.254.91.226 via 169.254.91.226 messageId=2 hopCount=1 sentTime=1699269606278 Currently known: senttime=1699269606276 hop count = 1 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.67.161] update originator messages: currently known nodes = 169.254.15.238, 169.254.3.194, 169.254.91.226 V: t+0.03s : [VirtualNode 169.254.67.161] broadcast packet from=169.254.91.226 lasthop=169.254.91.226 send to 169.254.15.238 V: t+0.03s : [VirtualNode 169.254.15.238] broadcast packet from=169.254.15.238 lasthop=0.0.0.0 send to 169.254.67.161 V: t+0.03s : [VirtualNode 169.254.67.161] broadcast packet from=169.254.91.226 lasthop=169.254.91.226 send to 169.254.3.194 V: t+0.03s : [VirtualNode 169.254.3.194] received MMCP message (MmcpOriginatorMessage) from 169.254.91.226 V: t+0.03s : [OriginatingMessageManager for /169.254.3.194] received originating message from 169.254.91.226 via 169.254.67.161 V: t+0.03s : [VirtualNode 169.254.67.161] received MMCP message (MmcpPing) from 169.254.15.238 V: t+0.03s : [OriginatingMessageManager for /169.254.3.194] received originating message from 169.254.91.226 via 169.254.67.161 messageId=2 hopCount=2 sentTime=1699269606278 Currently known: senttime=1699269606276 hop count = 2 isMoreRecentOrBetter=true V: t+0.03s : [VirtualNode 169.254.67.161] broadcast packet from=169.254.15.238 lasthop=169.254.15.238 send to 169.254.3.194 V: t+0.03s : [OriginatingMessageManager for /169.254.3.194] update originator messages: currently known nodes = 169.254.91.226, 169.254.67.161 V: t+0.03s : [VirtualNode 169.254.67.161] broadcast packet from=169.254.15.238 lasthop=169.254.15.238 send to 169.254.91.226 V: t+0.03s : [VirtualNode 169.254.3.194] received MMCP message (MmcpPing) from 169.254.15.238 V: t+0.03s : [VirtualNode 169.254.91.226] received MMCP message (MmcpPing) from 169.254.15.238 I: t+0.03s : [ChainSocketServer: 169.254.67.161] closed D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.67.161 ] : run : finished I: t+0.03s : [ChainSocketServer: 169.254.15.238] closed V: t+0.03s : [VirtualNode 169.254.15.238] received MMCP message (MmcpOriginatorMessage) from 169.254.91.226 V: t+0.03s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.91.226 via 169.254.67.161 V: t+0.03s : [OriginatingMessageManager for /169.254.15.238] received originating message from 169.254.91.226 via 169.254.67.161 messageId=2 hopCount=2 sentTime=1699269606278 Currently known: senttime=1699269606276 hop count = 2 isMoreRecentOrBetter=true V: t+0.03s : [OriginatingMessageManager for /169.254.15.238] update originator messages: currently known nodes = 169.254.3.194, 169.254.91.226, 169.254.67.161 D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.15.238 ] : run : finished I: t+0.03s : [ChainSocketServer: 169.254.3.194] closed D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.3.194 ] : run : finished I: t+0.03s : [ChainSocketServer: 169.254.91.226] closed D: t+0.03s : [VirtualNodeDatagramSocket for 169.254.91.226 ] : run : finished D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 64341 waiting for first packet I: t+0.0s : [ChainSocketServer: 169.254.1.1] init D: t+0.01s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 35679 waiting for first packet I: t+0.01s : [ChainSocketServer: 169.254.1.2] init Test node1=169.254.1.1 node2=169.254.1.2 D: t+0.01s : [VirtualNode 169.254.1.1] addNewNeighborConnection connection to virtual addr 169.254.1.2 via datagram to localhost/127.0.0.1:35679 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=1699269606296 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=1699269606296 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=1699269606296 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=1699269606297 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=1699269606297 Currently known: senttime=1699269606296 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+1.0s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=3 sentTime=1699269607284 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=1699269607284 Currently known: senttime=1699269606297 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 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] 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.1] Received pong(id=2)} 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.01s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=3 sentTime=1699269607293 V: t+1.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 V: t+1.01s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.2 via 169.254.1.2 V: t+1.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=1699269607293 Currently known: senttime=1699269606296 hop count = 1 isMoreRecentOrBetter=true V: t+1.01s : [OriginatingMessageManager for /169.254.1.1] update originator messages: currently known nodes = 169.254.1.2 V: t+1.01s : [OriginatingMessageManager for /169.254.1.2] pingNeighborsRunnable: send ping to 169.254.1.1 V: t+1.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPing) from 169.254.1.2 V: t+1.01s : [VirtualNode 169.254.1.1] Received ping(id=4) from 169.254.1.2 V: t+1.01s : [VirtualNode 169.254.1.1] Sending pong to 169.254.1.2 D: t+1.01s : [VirtualNode 169.254.1.1] Incoming packet received, but no socket listening on: 0 V: t+1.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPong) from 169.254.1.1 V: t+1.01s : [VirtualNode 169.254.1.2] Received pong(id=5)} V: t+1.01s : [OriginatingMessageManager for /169.254.1.2] received ping from 169.254.1.1 pingTime=4 D: t+1.01s : [VirtualNode 169.254.1.2] Incoming packet received, but no socket listening on: 0 V: t+4.0s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=6 sentTime=1699269610284 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=1699269610284 Currently known: senttime=1699269607284 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 14284 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 52322 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 58317 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:52322 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=1699269610290 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=1699269610291 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=1699269610291 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=1699269610292 V: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 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:58317 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=1699269610292 Currently known: senttime=1699269610290 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 D: t+0.0s : [OriginatingMessageManager for /169.254.1.2] : addNeighbor - sending originating messages out 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=1699269610293 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=1699269610294 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=1699269610294 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=1699269610295 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=1699269610294 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 test: wait for discovery V: t+0.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 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.3] 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 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=1699269610295 Currently known: senttime=1699269610293 hop count = 1 isMoreRecentOrBetter=true 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=1699269610295 Currently known: senttime=1699269610291 hop count = 1 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.1] update originator messages: currently known nodes = 169.254.1.2, 169.254.1.3 test: node 1 knows about node 3 V: t+1.0s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=3 sentTime=1699269611288 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] 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.1] Received pong(id=4)} 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.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 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=1699269611288 Currently known: senttime=1699269610292 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 : [OriginatingMessageManager for /169.254.1.2] pingNeighborsRunnable: send ping 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.2] sending originating message messageId=5 sentTime=1699269611289 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.2] pingNeighborsRunnable: send ping to 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 messageId=3 hopCount=2 sentTime=1699269611288 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 : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 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.1] received originating message from 169.254.1.2 via 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 : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.2 via 169.254.1.2 messageId=5 hopCount=1 sentTime=1699269611289 Currently known: senttime=1699269610295 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, 169.254.1.3 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.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPing) from 169.254.1.2 test: node 3 knows about node 1 : discovery done V: t+1.0s : [OriginatingMessageManager for /169.254.1.3] pingNeighborsRunnable: send ping to 169.254.1.2 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 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.3] received originating message from 169.254.1.2 via 169.254.1.2 V: t+1.0s : [OriginatingMessageManager for /169.254.1.3] sending originating message messageId=3 sentTime=1699269611289 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)} 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=1699269611289 Currently known: senttime=1699269610295 hop count = 1 isMoreRecentOrBetter=true V: t+1.0s : [OriginatingMessageManager for /169.254.1.2] received ping from 169.254.1.3 pingTime=3 V: t+1.0s : [OriginatingMessageManager for /169.254.1.3] update originator messages: currently known nodes = 169.254.1.2, 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.2] received MMCP message (MmcpPing) from 169.254.1.3 V: t+1.0s : [VirtualNode 169.254.1.2] Received ping(id=4) 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.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 : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.1 V: t+1.0s : [OriginatingMessageManager for /169.254.1.3] received ping from 169.254.1.2 pingTime=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.2] received MMCP message (MmcpPong) 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.2] Received pong(id=5)} V: t+1.0s : [OriginatingMessageManager for /169.254.1.2] received ping from 169.254.1.1 pingTime=3 V: t+1.0s : [VirtualNode 169.254.1.3] Received ping(id=1000042) from 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.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 (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=3 hopCount=1 sentTime=1699269611289 Currently known: senttime=1699269610294 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.2] received MMCP message (MmcpPong) from 169.254.1.3 V: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 V: t+1.0s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.3 via 169.254.1.2 V: t+1.0s : [OriginatingMessageManager for /169.254.1.1] received originating message from 169.254.1.3 via 169.254.1.2 messageId=3 hopCount=2 sentTime=1699269611289 Currently known: senttime=1699269610294 hop count = 2 isMoreRecentOrBetter=true 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 : [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=5)} W: t+1.0s : [OriginatingMessageManager for /169.254.1.1] : onPongReceived : pong from 169.254.1.3 does not match any known sent ping D: t+1.0s : [VirtualNode 169.254.1.1] Incoming packet received, but no socket listening on: 0 D: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished I: t+1.0s : [ChainSocketServer: 169.254.1.1] closed 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 I: t+0.0s : [ChainSocketServer: 169.254.1.1] init D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 29023 waiting for first packet D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 2403 waiting for first packet I: t+0.0s : [ChainSocketServer: 169.254.1.2] init I: t+0.0s : [ChainSocketServer: 169.254.1.3] init D: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ] Started on 35481 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:2403 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=1699269611298 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=1699269611298 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=1699269611298 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=1699269611299 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:35481 D: t+0.0s : [OriginatingMessageManager for /169.254.1.2] : 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=2 hopCount=1 sentTime=1699269611299 Currently known: senttime=1699269611298 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=1699269611299 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=1699269611300 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=1699269611300 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 : [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=1699269611300 Currently known: senttime=0 hop count = 127 isMoreRecentOrBetter=true V: t+0.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=3 sentTime=1699269611300 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.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 : [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.1] received originating message from 169.254.1.2 via 169.254.1.2 messageId=3 hopCount=1 sentTime=1699269611300 Currently known: senttime=1699269611298 hop count = 1 isMoreRecentOrBetter=true 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=1699269611300 Currently known: senttime=1699269611299 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 V: t+0.0s : [OriginatingMessageManager for /169.254.1.3] update originator messages: currently known nodes = 169.254.1.2 I: t+0.17s : [ChainSocketServer: 169.254.1.1] closed D: t+0.17s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished D: t+0.17s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished I: t+0.17s : [ChainSocketServer: 169.254.1.2] closed I: t+0.17s : [ChainSocketServer: 169.254.1.3] closed D: t+0.17s : [VirtualNodeDatagramSocket for 169.254.1.3 ] : run : finished ]]>