XV: t+0.01s : [VirtualDatagramSocketImpl] send packet size=1000 bytes to /0.0.0.43:2802 aV: t+0.0s : [VirtualDatagramSocketImpl] incoming virtual packet=1020 bytes from 0.0.0.43:21402 FV: t+0.0s : [VirtualDatagramSocketImpl] bind laddr=/0.0.0.42 lport=0 FV: t+0.0s : [VirtualDatagramSocketImpl] bind laddr=/0.0.0.43 lport=0 XV: t+0.0s : [VirtualDatagramSocketImpl] send packet size=1000 bytes to /0.0.0.43:43194 bV: t+0.01s : [VirtualDatagramSocketImpl] incoming virtual packet=1020 bytes from 0.0.0.42:30056 BD: t+0.0s : [UdpForwardRule : 59463 -> 127.0.0.1:52151] listening aD: t+0.0s : [VirtualNodeDatagramSocket for 0.0.0.42 ] Started on 43358 waiting for first packet aD: t+0.0s : [VirtualNodeDatagramSocket for 0.0.0.43 ] Started on 34437 waiting for first packet eD: t+0.07s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 31739 waiting for first packet 4I: t+0.07s : [ChainSocketServer: 169.254.1.1] init eD: t+0.08s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 21754 waiting for first packet 4I: 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 kD: t+0.09s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out eV: t+0.1s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 xV: 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 zV: t+0.12s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=1 sentTime=1699269613688 fV: t+0.12s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 yV: 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 zV: t+0.12s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=2 sentTime=1699269613693 fV: t+0.12s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 yV: 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 KV: t+0.12s : [VirtualDatagramSocketImpl] bind laddr=/169.254.1.1 lport=81 KV: t+0.12s : [VirtualDatagramSocketImpl] bind laddr=/169.254.1.2 lport=82 WV: 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 6I: t+0.12s : [ChainSocketServer: 169.254.1.1] closed LD: t+0.12s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished 6I: t+0.12s : [ChainSocketServer: 169.254.1.2] closed LD: t+0.12s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished gD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.41.107 ] Started on 39886 waiting for first packet 6I: t+0.0s : [ChainSocketServer: 169.254.41.107] init hD: t+0.01s : [VirtualNodeDatagramSocket for 169.254.68.230 ] Started on 17509 waiting for first packet 7I: 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 nD: t+0.01s : [OriginatingMessageManager for /169.254.41.107] : addNeighbor - sending originating messages out lV: 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 lV: 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 IV: 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 bD: t+0.02s : [VirtualNode 169.254.68.230] Incoming packet received, but no socket listening on: 0 lV: 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 bD: t+0.02s : [VirtualNode 169.254.41.107] Incoming packet received, but no socket listening on: 0 OD: t+0.02s : [VirtualNodeDatagramSocket for 169.254.41.107 ] : run : finished 9I: t+0.02s : [ChainSocketServer: 169.254.41.107] closed 9I: t+0.02s : [ChainSocketServer: 169.254.68.230] closed OD: t+0.02s : [VirtualNodeDatagramSocket for 169.254.68.230 ] : run : finished dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 13513 waiting for first packet 3I: t+0.0s : [ChainSocketServer: 169.254.1.1] init 3I: t+0.0s : [ChainSocketServer: 169.254.1.2] init dD: 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 jD: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 xV: 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 zV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 yV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=1 sentTime=1699269613732 eV: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 xV: 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 zV: t+0.0s : [OriginatingMessageManager for /169.254.1.1] update originator messages: currently known nodes = 169.254.1.2 yV: t+0.0s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=2 sentTime=1699269613733 eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 xV: 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 zV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 wI: t+0.03s : [ChainSocketFactoryImpl for /169.254.1.1] created socket to /169.254.1.2:44987 nexthop = /127.0.0.1:44987 6I: t+0.03s : [ChainSocketServer: 169.254.1.1] closed LD: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished 6I: t+0.03s : [ChainSocketServer: 169.254.1.2] closed LD: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished cD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 8200 waiting for first packet 3I: t+0.0s : [ChainSocketServer: 169.254.1.1] init dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 60395 waiting for first packet 3I: t+0.0s : [ChainSocketServer: 169.254.1.2] init dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ] Started on 10235 waiting for first packet 3I: 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 kD: t+0.01s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out fV: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 yV: 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 zV: t+0.01s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=1 sentTime=1699269613772 fV: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 yV: 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 zV: t+0.01s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=2 sentTime=1699269613773 fV: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 yV: 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 kD: t+0.01s : [OriginatingMessageManager for /169.254.1.2] : addNeighbor - sending originating messages out fV: t+0.01s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 yV: 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 zV: t+0.01s : [OriginatingMessageManager for /169.254.1.3] sending originating message messageId=1 sentTime=1699269613775 fV: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 yV: 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 zV: t+0.01s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=3 sentTime=1699269613776 fV: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 yV: 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 fV: t+0.01s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 yV: 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 qV: 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 fV: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 yV: 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 yV: t+1.0s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=3 sentTime=1699269614763 eV: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 xV: 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 jV: t+1.0s : [OriginatingMessageManager for /169.254.1.1] pingNeighborsRunnable: send ping to 169.254.1.2 pV: 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 XV: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.1 eV: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 yV: t+1.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=4 sentTime=1699269614765 KV: t+1.0s : [VirtualNode 169.254.1.2] Received ping(id=4) from 169.254.1.1 jV: t+1.0s : [OriginatingMessageManager for /169.254.1.2] pingNeighborsRunnable: send ping to 169.254.1.3 xV: t+1.0s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.1 via 169.254.1.2 BV: t+1.0s : [VirtualNode 169.254.1.2] Sending pong to 169.254.1.1 jV: 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 eV: 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 xV: 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 eV: 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 xV: 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 yV: t+1.0s : [OriginatingMessageManager for /169.254.1.3] sending originating message messageId=2 sentTime=1699269614766 XV: 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)} eV: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 xV: 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 pV: 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 XV: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpPing) from 169.254.1.2 jV: t+1.0s : [OriginatingMessageManager for /169.254.1.3] pingNeighborsRunnable: send ping to 169.254.1.2 dV: t+1.0s : [OriginatingMessageManager for /169.254.1.1] received ping from 169.254.1.2 pingTime=3 KV: t+1.0s : [VirtualNode 169.254.1.3] Received ping(id=5) from 169.254.1.2 XV: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.3 BV: t+1.0s : [VirtualNode 169.254.1.3] Sending pong to 169.254.1.2 KV: t+1.0s : [VirtualNode 169.254.1.2] Received ping(id=3) from 169.254.1.3 BV: 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 XV: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpPong) from 169.254.1.2 XV: 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)} dV: t+1.0s : [OriginatingMessageManager for /169.254.1.3] received ping from 169.254.1.2 pingTime=1 dV: 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 XV: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPing) from 169.254.1.2 5I: t+1.0s : [ChainSocketServer: 169.254.1.1] closed KV: t+1.0s : [VirtualNode 169.254.1.1] Received ping(id=7) from 169.254.1.2 BV: t+1.0s : [VirtualNode 169.254.1.1] Sending pong to 169.254.1.2 5I: t+1.0s : [ChainSocketServer: 169.254.1.2] closed KD: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished 5I: t+1.0s : [ChainSocketServer: 169.254.1.3] closed KD: 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 T at java.base/sun.nio.ch.DatagramSocketAdaptor.send(DatagramSocketAdaptor.java:222) D at java.base/java.net.DatagramSocket.send(DatagramSocket.java:664) d at com.ustadmobile.meshrabiya.vnet.VirtualNodeDatagramSocket.send(VirtualNodeDatagramSocket.kt:89) J at com.ustadmobile.meshrabiya.vnet.VirtualNode.route(VirtualNode.kt:488) b at com.ustadmobile.meshrabiya.vnet.VirtualRouter$DefaultImpls.route$default(VirtualRouter.kt:22) Z at com.ustadmobile.meshrabiya.vnet.VirtualNode.onIncomingMmcpMessage(VirtualNode.kt:370) J at com.ustadmobile.meshrabiya.vnet.VirtualNode.route(VirtualNode.kt:445) c at com.ustadmobile.meshrabiya.vnet.VirtualNodeDatagramSocket.run(VirtualNodeDatagramSocket.kt:64) V at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) G 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) 4 at java.base/java.lang.Thread.run(Thread.java:833) 4Caused by: java.nio.channels.ClosedChannelException V at java.base/sun.nio.ch.DatagramChannelImpl.ensureOpen(DatagramChannelImpl.java:267) X at java.base/sun.nio.ch.DatagramChannelImpl.blockingSend(DatagramChannelImpl.java:850) T at java.base/sun.nio.ch.DatagramSocketAdaptor.send(DatagramSocketAdaptor.java:218)  ... 12 more  (java.net.SocketException: Socket closed T at java.base/sun.nio.ch.DatagramSocketAdaptor.send(DatagramSocketAdaptor.java:222) D at java.base/java.net.DatagramSocket.send(DatagramSocket.java:664) d at com.ustadmobile.meshrabiya.vnet.VirtualNodeDatagramSocket.send(VirtualNodeDatagramSocket.kt:89) J at com.ustadmobile.meshrabiya.vnet.VirtualNode.route(VirtualNode.kt:488) b at com.ustadmobile.meshrabiya.vnet.VirtualRouter$DefaultImpls.route$default(VirtualRouter.kt:22) Z at com.ustadmobile.meshrabiya.vnet.VirtualNode.onIncomingMmcpMessage(VirtualNode.kt:370) J at com.ustadmobile.meshrabiya.vnet.VirtualNode.route(VirtualNode.kt:445) c at com.ustadmobile.meshrabiya.vnet.VirtualNodeDatagramSocket.run(VirtualNodeDatagramSocket.kt:64) V at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) G 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) 4 at java.base/java.lang.Thread.run(Thread.java:833) 4Caused by: java.nio.channels.ClosedChannelException V at java.base/sun.nio.ch.DatagramChannelImpl.ensureOpen(DatagramChannelImpl.java:267) X at java.base/sun.nio.ch.DatagramChannelImpl.blockingSend(DatagramChannelImpl.java:850) T 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 LD: t+1.01s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished  dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 58849 waiting for first packet  3I: t+0.0s : [ChainSocketServer: 169.254.1.1] init  3I: t+0.0s : [ChainSocketServer: 169.254.1.2] init  dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 30980 waiting for first packet  dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ] Started on 60074 waiting for first packet  3I: 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  jD: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out  eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1  xV: 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  zV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1  zV: t+0.01s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=1 sentTime=1699269614786  fV: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2  yV: 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  zV: 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  fV: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1  yV: t+0.01s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.1 via 169.254.1.1  kD: 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  fV: t+0.01s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2  yV: 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  zV: t+0.01s : [OriginatingMessageManager for /169.254.1.3] sending originating message messageId=1 sentTime=1699269614790  fV: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3  yV: 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  qV: 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  fV: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3  yV: 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  zV: 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  fV: t+0.01s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2  fV: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2  yV: t+0.01s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.2 via 169.254.1.2  yV: 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  CD: t+0.02s : [ChainSocketServer: 169.254.1.2] accepted new client  P/127.0.0.1:30980 is not final destination - write init request and get response  hD: t+0.02s : [ChainSocketServer: 169.254.1.2] /127.0.0.1:40310 : init client - reading init request...  xD: t+0.02s : [ChainSocketServer: 169.254.1.2] /127.0.0.1:40310 : receive init request to connect to /169.254.1.3:36845  wI: t+0.02s : [ChainSocketFactoryImpl for /169.254.1.2] created socket to /169.254.1.3:36845 nexthop = /127.0.0.1:36845  XD: 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  wI: 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  G at java.base/sun.nio.ch.NioSocketImpl.endRead(NioSocketImpl.java:248)  H at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:327)  D at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:350)  F at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:803)  F at java.base/java.net.Socket$SocketInputStream.read(Socket.java:966)  = at java.base/java.io.InputStream.read(InputStream.java:218)  5 at kotlin.io.ByteStreamsKt.copyTo(IOStreams.kt:106)  = at kotlin.io.ByteStreamsKt.copyTo$default(IOStreams.kt:103)  n at com.ustadmobile.meshrabiya.vnet.socket.ChainSocketServer$CopyStreamRunnable.run(ChainSocketServer.kt:144)  V at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539)  G 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)  4 at java.base/java.lang.Thread.run(Thread.java:833)    6I: t+0.03s : [ChainSocketServer: 169.254.1.1] closed  LD: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished  6I: t+0.03s : [ChainSocketServer: 169.254.1.2] closed  LD: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished  6I: t+0.03s : [ChainSocketServer: 169.254.1.3] closed  LD: t+0.03s : [VirtualNodeDatagramSocket for 169.254.1.3 ] : run : finished !6I: t+0.0s : [ChainSocketServer: 169.254.71.112] init !gD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.71.112 ] Started on 60286 waiting for first packet !hD: t+0.01s : [VirtualNodeDatagramSocket for 169.254.22.140 ] Started on 46951 waiting for first packet !7I: 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 !nD: t+0.01s : [OriginatingMessageManager for /169.254.71.112] : addNeighbor - sending originating messages out !lV: 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 !lV: 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 !iV: t+0.01s : [VirtualNode 169.254.71.112] received MMCP message (MmcpHotspotRequest) from 169.254.22.140 !RI: t+0.01s : [VirtualNode 169.254.71.112] Received hotspotrequest (id=-387288174) !bD: t+0.01s : [VirtualNode 169.254.71.112] Incoming packet received, but no socket listening on: 0 !lV: 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 !TI: t+0.01s : [VirtualNode 169.254.71.112] sending hotspotresponse to 169.254.22.140 !jV: t+0.01s : [VirtualNode 169.254.22.140] received MMCP message (MmcpHotspotResponse) from 169.254.71.112 !bD: t+0.01s : [VirtualNode 169.254.22.140] Incoming packet received, but no socket listening on: 0 !9I: t+0.01s : [ChainSocketServer: 169.254.71.112] closed !OD: t+0.01s : [VirtualNodeDatagramSocket for 169.254.71.112 ] : run : finished !9I: t+0.02s : [ChainSocketServer: 169.254.22.140] closed !OD: t+0.02s : [VirtualNodeDatagramSocket for 169.254.22.140 ] : run : finished "dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 12578 waiting for first packet "3I: t+0.0s : [ChainSocketServer: 169.254.1.1] init "dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 26640 waiting for first packet "3I: 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 "jD: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out "eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 "xV: 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 "zV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 "yV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=1 sentTime=1699269614849 "fV: t+0.01s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 "yV: 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 "zV: t+0.01s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=2 sentTime=1699269614850 "fV: t+0.01s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 "yV: 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 "DD: t+0.01s : [UdpForwardRule : 33371 -> 169.254.1.2:8000] listening "MV: t+0.01s : [VirtualDatagramSocketImpl] bind laddr=/169.254.1.2 lport=8000 "BD: t+0.01s : [UdpForwardRule : 8000 -> 127.0.0.1:43171] listening ")Send packet to localhost/127.0.0.1:33371 "JV: t+0.01s : [VirtualDatagramSocketImpl] bind laddr=/169.254.1.1 lport=0 "XV: t+0.01s : [VirtualDatagramSocketImpl] send packet size=5 bytes to /169.254.1.2:8000 "cV: t+0.01s : [VirtualDatagramSocketImpl] incoming virtual packet=25 bytes from 169.254.1.1:15746 "YV: t+0.01s : [VirtualDatagramSocketImpl] send packet size=5 bytes to /169.254.1.1:15746 "bV: t+0.01s : [VirtualDatagramSocketImpl] incoming virtual packet=25 bytes from 169.254.1.2:8000 "6I: t+0.01s : [ChainSocketServer: 169.254.1.1] closed "LD: t+0.01s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished "6I: t+0.01s : [ChainSocketServer: 169.254.1.2] closed "LD: t+0.01s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished #6I: t+0.0s : [ChainSocketServer: 169.254.13.145] init #gD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.13.145 ] Started on 38025 waiting for first packet #fD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.42.128 ] Started on 1863 waiting for first packet #6I: 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 #mD: t+0.0s : [OriginatingMessageManager for /169.254.42.128] : addNeighbor - sending originating messages out #kV: 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 #kV: 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 #kV: 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 #eD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.7.88 ] Started on 19338 waiting for first packet #4I: 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 #kD: t+0.0s : [OriginatingMessageManager for /169.254.7.88] : addNeighbor - sending originating messages out #iV: 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 #yV: 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 #jV: 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 #jV: 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 #lV: 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 #jV: 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 #yV: 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 #jV: 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 #hD: t+0.01s : [VirtualNodeDatagramSocket for 169.254.86.120 ] Started on 41506 waiting for first packet #7I: 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 #nD: t+0.01s : [OriginatingMessageManager for /169.254.86.120] : addNeighbor - sending originating messages out #lV: 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 #jV: t+0.01s : [VirtualNode 169.254.7.88] received MMCP message (MmcpOriginatorMessage) from 169.254.86.120 #lV: 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 #lV: t+0.01s : [VirtualNode 169.254.86.120] received MMCP message (MmcpOriginatorMessage) from 169.254.13.145 #lV: 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 #jV: 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 #lV: 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 #lV: 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 #jV: 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 #vV: 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 #9I: t+0.02s : [ChainSocketServer: 169.254.13.145] closed #OD: t+0.02s : [VirtualNodeDatagramSocket for 169.254.13.145 ] : run : finished #OD: t+0.02s : [VirtualNodeDatagramSocket for 169.254.42.128 ] : run : finished #9I: t+0.02s : [ChainSocketServer: 169.254.42.128] closed #7I: t+0.02s : [ChainSocketServer: 169.254.7.88] closed #MD: t+0.02s : [VirtualNodeDatagramSocket for 169.254.7.88 ] : run : finished #9I: t+0.02s : [ChainSocketServer: 169.254.86.120] closed #OD: t+0.02s : [VirtualNodeDatagramSocket for 169.254.86.120 ] : run : finished $3I: t+0.0s : [ChainSocketServer: 169.254.1.1] init $cD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 1570 waiting for first packet $cD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 3993 waiting for first packet $3I: 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 $jD: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out $eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 $xV: 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 $zV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 $yV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=1 sentTime=1699269614891 $eV: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 $xV: 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 $zV: t+0.0s : [OriginatingMessageManager for /169.254.1.1] update originator messages: currently known nodes = 169.254.1.2 $yV: t+0.0s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=2 sentTime=1699269614892 $eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 $xV: 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 $zV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 $jV: t+1.0s : [OriginatingMessageManager for /169.254.1.1] pingNeighborsRunnable: send ping to 169.254.1.2 $yV: t+1.0s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=3 sentTime=1699269615885 $XV: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.1 $KV: t+1.0s : [VirtualNode 169.254.1.2] Received ping(id=4) from 169.254.1.1 $BV: 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 $XV: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPong) from 169.254.1.2 $eV: 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)} $xV: t+1.0s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.1 via 169.254.1.1 $dV: 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 $zV: 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 $jV: t+1.0s : [OriginatingMessageManager for /169.254.1.2] pingNeighborsRunnable: send ping to 169.254.1.1 $yV: t+1.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=3 sentTime=1699269615888 $XV: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPing) from 169.254.1.2 $KV: t+1.0s : [VirtualNode 169.254.1.1] Received ping(id=4) from 169.254.1.2 $BV: 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 $XV: 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)} $dV: 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 $eV: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 $xV: 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 $zV: t+1.0s : [OriginatingMessageManager for /169.254.1.1] update originator messages: currently known nodes = 169.254.1.2 $yV: t+4.0s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=6 sentTime=1699269618885 $eV: t+4.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 $xV: 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 $zV: t+4.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 $5I: t+4.0s : [ChainSocketServer: 169.254.1.1] closed $KD: t+4.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished $5I: t+4.0s : [ChainSocketServer: 169.254.1.2] closed $KD: t+4.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished %cD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 3929 waiting for first packet %3I: t+0.0s : [ChainSocketServer: 169.254.1.1] init %dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 21304 waiting for first packet %3I: t+0.0s : [ChainSocketServer: 169.254.1.2] init %dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ] Started on 24156 waiting for first packet %3I: 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 %jD: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out %eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 %xV: 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 %zV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 %yV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=1 sentTime=1699269618893 %eV: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 %xV: 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 %zV: t+0.0s : [OriginatingMessageManager for /169.254.1.1] update originator messages: currently known nodes = 169.254.1.2 %yV: 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 %eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 %jD: t+0.0s : [OriginatingMessageManager for /169.254.1.2] : addNeighbor - sending originating messages out %xV: 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 %zV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 %eV: t+0.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 %xV: 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 %zV: t+0.0s : [OriginatingMessageManager for /169.254.1.3] update originator messages: currently known nodes = 169.254.1.2 %yV: t+0.0s : [OriginatingMessageManager for /169.254.1.3] sending originating message messageId=1 sentTime=1699269618895 %eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 %xV: 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 %pV: 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 %yV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=3 sentTime=1699269618895 %eV: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 %xV: 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 %eV: t+0.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 %xV: 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 %eV: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 %xV: 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 %jV: t+1.0s : [OriginatingMessageManager for /169.254.1.1] pingNeighborsRunnable: send ping to 169.254.1.2 %yV: t+1.0s : [OriginatingMessageManager for /169.254.1.1] sending originating message messageId=3 sentTime=1699269619889 %XV: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.1 %KV: t+1.0s : [VirtualNode 169.254.1.2] Received ping(id=4) from 169.254.1.1 %BV: 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 %XV: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPong) from 169.254.1.2 %eV: 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)} %xV: t+1.0s : [OriginatingMessageManager for /169.254.1.2] received originating message from 169.254.1.1 via 169.254.1.1 %dV: 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 %pV: 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 %eV: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 %xV: 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 %yV: t+1.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=5 sentTime=1699269619890 %eV: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 %eV: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 %xV: t+1.0s : [OriginatingMessageManager for /169.254.1.3] received originating message from 169.254.1.2 via 169.254.1.2 %1test: node 3 knows about node 1 : discovery done %xV: 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 %jV: t+1.0s : [OriginatingMessageManager for /169.254.1.2] pingNeighborsRunnable: send ping to 169.254.1.3 %jV: t+1.0s : [OriginatingMessageManager for /169.254.1.2] pingNeighborsRunnable: send ping to 169.254.1.1 %XV: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpPing) from 169.254.1.2 %KV: t+1.0s : [VirtualNode 169.254.1.3] Received ping(id=6) from 169.254.1.2 %BV: t+1.0s : [VirtualNode 169.254.1.3] Sending pong to 169.254.1.2 %XV: t+1.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpPing) from 169.254.1.2 %KV: 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 %BV: t+1.0s : [VirtualNode 169.254.1.1] Sending pong to 169.254.1.2 %XV: 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 %dV: 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 %XV: 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)} %dV: 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 %XV: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.1 %XV: t+1.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpPing) from 169.254.1.1 %QV: t+1.0s : [VirtualNode 169.254.1.3] Received ping(id=1000042) from 169.254.1.1 %BV: 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 %XV: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPong) from 169.254.1.3 %jV: t+1.0s : [OriginatingMessageManager for /169.254.1.3] pingNeighborsRunnable: send ping to 169.254.1.2 %yV: t+1.0s : [OriginatingMessageManager for /169.254.1.3] sending originating message messageId=4 sentTime=1699269619892 %XV: 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 %XV: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpPing) from 169.254.1.3 %KV: 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 %BV: 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 %XV: 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)} %dV: t+1.0s : [OriginatingMessageManager for /169.254.1.3] received ping from 169.254.1.2 pingTime=1 %eV: t+1.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 %xV: 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 %5I: t+1.0s : [ChainSocketServer: 169.254.1.1] closed %KD: 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 %pV: 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 %5I: t+1.0s : [ChainSocketServer: 169.254.1.2] closed %KD: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished %5I: t+1.0s : [ChainSocketServer: 169.254.1.3] closed %KD: t+1.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ] : run : finished &dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.1 ] Started on 57660 waiting for first packet &3I: t+0.0s : [ChainSocketServer: 169.254.1.1] init &cD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.2 ] Started on 9046 waiting for first packet &3I: t+0.0s : [ChainSocketServer: 169.254.1.2] init &dD: t+0.0s : [VirtualNodeDatagramSocket for 169.254.1.3 ] Started on 28526 waiting for first packet &3I: 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 &jD: t+0.0s : [OriginatingMessageManager for /169.254.1.1] : addNeighbor - sending originating messages out &eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 &xV: 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 &zV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 &yV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=1 sentTime=1699269619899 &eV: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 &xV: 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 &zV: t+0.0s : [OriginatingMessageManager for /169.254.1.1] update originator messages: currently known nodes = 169.254.1.2 &yV: 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 &eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.1 &jD: t+0.0s : [OriginatingMessageManager for /169.254.1.2] : addNeighbor - sending originating messages out &xV: 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 &zV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] update originator messages: currently known nodes = 169.254.1.1 &eV: t+0.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 &xV: 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 &zV: t+0.0s : [OriginatingMessageManager for /169.254.1.3] update originator messages: currently known nodes = 169.254.1.2 &yV: t+0.0s : [OriginatingMessageManager for /169.254.1.3] sending originating message messageId=1 sentTime=1699269619900 &eV: t+0.0s : [VirtualNode 169.254.1.2] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 &xV: 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 &pV: 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 &yV: t+0.0s : [OriginatingMessageManager for /169.254.1.2] sending originating message messageId=3 sentTime=1699269619900 &eV: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.3 &xV: 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 &eV: t+0.0s : [VirtualNode 169.254.1.3] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 &xV: 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 &eV: t+0.0s : [VirtualNode 169.254.1.1] received MMCP message (MmcpOriginatorMessage) from 169.254.1.2 &xV: 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 &6I: t+0.17s : [ChainSocketServer: 169.254.1.1] closed &LD: t+0.17s : [VirtualNodeDatagramSocket for 169.254.1.1 ] : run : finished &6I: t+0.17s : [ChainSocketServer: 169.254.1.2] closed &LD: t+0.17s : [VirtualNodeDatagramSocket for 169.254.1.2 ] : run : finished &6I: t+0.17s : [ChainSocketServer: 169.254.1.3] closed &LD: t+0.17s : [VirtualNodeDatagramSocket for 169.254.1.3 ] : run : finished /.|I: t+0.0s : [ChainSocketFactoryImpl for /169.254.52.158] created socket to /169.254.54.100:46443 nexthop = /127.0.0.1:46443 /0P/127.0.0.1:36239 is not final destination - write init request and get response /0#/127.0.0.1:36239 got init response /0{I: t+0.0s : [ChainSocketFactoryImpl for /169.254.101.80] created socket to /169.254.30.243:1042 nexthop = /127.0.0.1:36239 21-I: t+0.07s : [ChainSocketServer: test] init 21D: t+0.0s : [ChainSocketServer: server1] accepted new client 24cD: t+0.0s : [ChainSocketServer: server1] /127.0.0.1:36206 : init client - reading init request... 24uD: t+0.0s : [ChainSocketServer: server1] /127.0.0.1:36206 : receive init request to connect to /169.254.70.74:43153 24?D: t+0.01s : [ChainSocketServer: server2] accepted new client 24Ylocalhost/127.0.0.1:43123 is not final destination - write init request and get response 24dD: t+0.01s : [ChainSocketServer: server2] /127.0.0.1:36822 : init client - reading init request... 24vD: t+0.01s : [ChainSocketServer: server2] /127.0.0.1:36822 : receive init request to connect to /169.254.70.74:43153 24I: t+0.01s : [ChainSocketFactoryImpl for /169.254.104.16] created socket to /169.254.70.74:43153 nexthop = localhost/127.0.0.1:43153 24TD: t+0.01s : [ChainSocketServer: server2] /127.0.0.1:36822 : created onward socket 24XD: t+0.01s : [ChainSocketServer: server2] /127.0.0.1:36822 : wrote chain init response 24,localhost/127.0.0.1:43123 got init response 24I: t+0.01s : [ChainSocketFactoryImpl for /169.254.38.25] created socket to /169.254.70.74:43153 nexthop = localhost/127.0.0.1:43123 24TD: t+0.01s : [ChainSocketServer: server1] /127.0.0.1:36206 : created onward socket 24XD: t+0.01s : [ChainSocketServer: server1] /127.0.0.1:36206 : wrote chain init response 24{V: t+0.01s : [ChainSocketServer: server2] /127.0.0.1:36822 : CopyStream: onwardToIncoming - start copying input to output 24{V: t+0.01s : [ChainSocketServer: server2] /127.0.0.1:36822 : CopyStream: incomingToOnward - start copying input to output 24{V: t+0.01s : [ChainSocketServer: server1] /127.0.0.1:36206 : CopyStream: onwardToIncoming - start copying input to output 24{V: t+0.01s : [ChainSocketServer: server1] /127.0.0.1:36206 : CopyStream: incomingToOnward - start copying input to output 24V: t+0.01s : [ChainSocketServer: server2] /127.0.0.1:36822 : CopyStream: onwardToIncoming - finished copying - reached end of stream 24zW: t+0.01s : [ChainSocketServer: server2] /127.0.0.1:36822: CopyStream: aborting java.net.SocketException: Socket closed 24G at java.base/sun.nio.ch.NioSocketImpl.endRead(NioSocketImpl.java:248) 24H at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:327) 24D at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:350) 24F at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:803) 24F at java.base/java.net.Socket$SocketInputStream.read(Socket.java:966) 24= at java.base/java.io.InputStream.read(InputStream.java:218) 245 at kotlin.io.ByteStreamsKt.copyTo(IOStreams.kt:106) 24= at kotlin.io.ByteStreamsKt.copyTo$default(IOStreams.kt:103) 24n at com.ustadmobile.meshrabiya.vnet.socket.ChainSocketServer$CopyStreamRunnable.run(ChainSocketServer.kt:144) 24V at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) 24G at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) 24^ at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) 24^ at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) 244 at java.base/java.lang.Thread.run(Thread.java:833) 24 24V: t+0.01s : [ChainSocketServer: server1] /127.0.0.1:36206 : CopyStream: onwardToIncoming - finished copying - reached end of stream 24zW: t+0.01s : [ChainSocketServer: server1] /127.0.0.1:36206: CopyStream: aborting java.net.SocketException: Socket closed 24G at java.base/sun.nio.ch.NioSocketImpl.endRead(NioSocketImpl.java:248) 24H at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:327) 24D at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:350) 24F at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:803) 24F at java.base/java.net.Socket$SocketInputStream.read(Socket.java:966) 24= at java.base/java.io.InputStream.read(InputStream.java:218) 245 at kotlin.io.ByteStreamsKt.copyTo(IOStreams.kt:106) 24= at kotlin.io.ByteStreamsKt.copyTo$default(IOStreams.kt:103) 24n at com.ustadmobile.meshrabiya.vnet.socket.ChainSocketServer$CopyStreamRunnable.run(ChainSocketServer.kt:144) 24V at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) 24G at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) 24^ at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) 24^ at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) 244 at java.base/java.lang.Thread.run(Thread.java:833) 24 242I: t+0.01s : [ChainSocketServer: server1] closed 242I: t+0.01s : [ChainSocketServer: server2] closed