** [04:01:12] -VIV- {T_SELECT-0} <main> opening selector
** [04:01:13] -VIV- {T_SELECT-0} <main> constructed selector thread object
** [04:01:13] -VIV- {T_WORK-1} <main> constructed worker thread object
** [04:01:13] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread run()
** [04:01:13] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step in (1)
** [04:01:13] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selecting
** [04:01:13] -VIV- {T_WORK-2} <main> constructed worker thread object
** [04:01:13] -VIV- {T_WORK-1} <T_WORK-1> worker thread run called
** [04:01:13] -VIV- {T_WORK-1} <T_WORK-1> worker thread step in (1)
** [04:01:13] -VIV- {T_WORK-1} <T_WORK-1> worker thread will fetch selectionkey
** [04:01:13] -VIV- {T_WORK-3} <main> constructed worker thread object
** [04:01:13] -VIV- {T_WORK-2} <T_WORK-2> worker thread run called
** [04:01:13] -VIV- {T_WORK-2} <T_WORK-2> worker thread step in (1)
** [04:01:13] -VIV- {T_WORK-2} <T_WORK-2> worker thread will fetch selectionkey
** [04:01:13] -VIV- {T_DISPATCH-4} <main> constructed dispatcher thread object
** [04:01:13] -VIV- {T_WORK-3} <T_WORK-3> worker thread run called
** [04:01:13] -VIV- {T_WORK-3} <T_WORK-3> worker thread step in (1)
** [04:01:13] -VIV- {T_WORK-3} <T_WORK-3> worker thread will fetch selectionkey
** [04:01:13] -VIV- {T_DISPATCH-5} <main> constructed dispatcher thread object
** [04:01:13] -VIV- {T_DISPATCH-4} <T_DISPATCH-4> dispatcher thread run called
** [04:01:13] -VIV- {T_DISPATCH-4} <T_DISPATCH-4> dispatcher thread step in (1)
** [04:01:13] -VIV- {T_DISPATCH-4} <T_DISPATCH-4> dispatcher thread will fetch packet
** [04:01:13] -VIV- {T_DISPATCH-5} <T_DISPATCH-5> dispatcher thread run called
** [04:01:13] -VIV- {T_DISPATCH-5} <T_DISPATCH-5> dispatcher thread step in (1)
** [04:01:13] -VIV- {T_DISPATCH-5} <T_DISPATCH-5> dispatcher thread will fetch packet
** [04:01:13] -VIV- {T_DISPATCH-6} <main> constructed dispatcher thread object
** [04:01:13] -VIV- {T_LISTEN-7-ON-16188} <main> constructed listener thread object
** [04:01:13] -VIV- {T_DISPATCH-6} <T_DISPATCH-6> dispatcher thread run called
** [04:01:13] -VIV- {T_DISPATCH-6} <T_DISPATCH-6> dispatcher thread step in (1)
** [04:01:13] -VIV- {T_DISPATCH-6} <T_DISPATCH-6> dispatcher thread will fetch packet
** [04:01:13] -VIV- {T_LISTEN-8-ON-16190} <main> constructed listener thread object
** [04:01:13] -VIV- {T_LISTEN-7-ON-16188} <T_LISTEN-7-ON-16188> listener thread run()
** [04:01:13] -VIV- {T_LISTEN-7-ON-16188} <T_LISTEN-7-ON-16188> opening selector
** [04:01:13] -VIV- {T_LISTEN-7-ON-16188} <T_LISTEN-7-ON-16188> opening serversocket chan
** [04:01:13] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> listener thread run()
** [04:01:13] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> opening selector
** [04:01:13] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> opening serversocket chan
** [04:01:13] -VIV- {T_LISTEN-7-ON-16188} <T_LISTEN-7-ON-16188> setting to non blocking
** [04:01:13] -VIV- {T_LISTEN-7-ON-16188} <T_LISTEN-7-ON-16188> binding to port 16188 on default addr
** [04:01:13] -VIV- {T_LISTEN-7-ON-16188} <T_LISTEN-7-ON-16188> registering channel with selector
** [04:01:13] -VIV- {T_LISTEN-7-ON-16188} <T_LISTEN-7-ON-16188> listener thread step in (1)
** [04:01:13] -VIV- {T_LISTEN-7-ON-16188} <T_LISTEN-7-ON-16188> listener thread selecting
** [04:01:13] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> setting to non blocking
** [04:01:13] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> binding to port 16190 on default addr
** [04:01:13] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> registering channel with selector
** [04:01:13] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> listener thread step in (1)
** [04:01:13] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> listener thread selecting
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> listener thread selected 1 keys
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> listener will now iterate over selected keys
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> got a key
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> a valid one! :)
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> key is OP_ACCEPT, accepting the connection
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> setting to non blocking mode
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> will now notify core about the new connection
** [04:01:25] -VIV- {core} <T_LISTEN-8-ON-16190> a new connection
** [04:01:25] -VIV- {core} <T_LISTEN-8-ON-16190> will now register new connection
!! [04:01:25] -VIV- {T_SELECT-0} <T_LISTEN-8-ON-16190> attempting to register a channel with this selector
!! [04:01:25] -VIV- {T_SELECT-0} <T_LISTEN-8-ON-16190> waking up selector
** [04:01:25] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selected 0 keys
** [04:01:25] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread yield and continue
!! [04:01:25] -VIV- {T_SELECT-0} <T_LISTEN-8-ON-16190> chan is now registered
** [04:01:25] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step in (2)
** [04:01:25] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selecting
** [04:01:25] -VIV- {core} <T_LISTEN-8-ON-16190> new client NA-127.0.0.1
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> returned from notifying core
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> finally removing sel key 
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> listener finished iterating over keys
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> listener thread step out
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> listener thread step in (2)
** [04:01:25] -VIV- {T_LISTEN-8-ON-16190} <T_LISTEN-8-ON-16190> listener thread selecting
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selected 1 keys
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now collect selected keys
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> there is another one
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now put collected keys into core sel queue (1)
** [04:01:26] -VIV- {core} <T_SELECT-0> put sth on selection Q
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step out (2)
** [04:01:26] -VIV- {T_WORK-1} <T_WORK-1> worker thread fetched selectionkey
** [04:01:26] -VIV- {T_WORK-1} <T_WORK-1> key is valid
** [04:01:26] -VIV- {T_WORK-1} <T_WORK-1> worker threads fetched key is OP_READ - way to go m8
** [04:01:26] -VIV- {T_WORK-1} <T_WORK-1> worker thread will notify core now
** [04:01:26] -VIV- {core} <T_WORK-1> here is socketdata!
** [04:01:26] -VIV- {core} <T_WORK-1> will now read
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step in (3)
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selecting
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selected 1 keys
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now collect selected keys
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> there is another one
** [04:01:26] -VIV- {core} <T_WORK-1> read 5 bytes
** [04:01:26] -INF- {core} <T_WORK-1> here is a packet: null
!! [04:01:26] -VIV- {core} <T_WORK-1> null packet
** [04:01:26] -VIV- {T_WORK-1} <T_WORK-1> worker thread returned from notifying core
** [04:01:26] -VIV- {T_WORK-1} <T_WORK-1> worker thread step out (1)
** [04:01:26] -VIV- {T_WORK-1} <T_WORK-1> worker thread step in (2)
** [04:01:26] -VIV- {T_WORK-1} <T_WORK-1> worker thread will fetch selectionkey
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now put collected keys into core sel queue (0)
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step out (3)
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step in (4)
** [04:01:26] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selecting
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selected 1 keys
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now collect selected keys
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> there is another one
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now put collected keys into core sel queue (1)
** [04:01:27] -VIV- {core} <T_SELECT-0> put sth on selection Q
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step out (4)
** [04:01:27] -VIV- {T_WORK-2} <T_WORK-2> worker thread fetched selectionkey
** [04:01:27] -VIV- {T_WORK-2} <T_WORK-2> key is valid
** [04:01:27] -VIV- {T_WORK-2} <T_WORK-2> worker threads fetched key is OP_READ - way to go m8
** [04:01:27] -VIV- {T_WORK-2} <T_WORK-2> worker thread will notify core now
** [04:01:27] -VIV- {core} <T_WORK-2> here is socketdata!
** [04:01:27] -VIV- {core} <T_WORK-2> will now read
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step in (5)
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selecting
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selected 1 keys
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now collect selected keys
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> there is another one
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now put collected keys into core sel queue (0)
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step out (5)
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step in (6)
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selecting
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selected 1 keys
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now collect selected keys
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> there is another one
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now put collected keys into core sel queue (0)
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step out (6)
** [04:01:27] -VIV- {core} <T_WORK-2> read 5 bytes
** [04:01:27] -INF- {core} <T_WORK-2> here is a packet: null
!! [04:01:27] -VIV- {core} <T_WORK-2> null packet
** [04:01:27] -VIV- {T_WORK-2} <T_WORK-2> worker thread returned from notifying core
** [04:01:27] -VIV- {T_WORK-2} <T_WORK-2> worker thread step out (1)
** [04:01:27] -VIV- {T_WORK-2} <T_WORK-2> worker thread step in (2)
** [04:01:27] -VIV- {T_WORK-2} <T_WORK-2> worker thread will fetch selectionkey
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step in (7)
** [04:01:27] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selecting
** [04:01:31] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selected 1 keys
** [04:01:31] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now collect selected keys
** [04:01:31] -VIV- {T_SELECT-0} <T_SELECT-0> there is another one
** [04:01:31] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now put collected keys into core sel queue (1)
** [04:01:31] -VIV- {core} <T_SELECT-0> put sth on selection Q
** [04:01:31] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step out (7)
** [04:01:31] -VIV- {T_WORK-3} <T_WORK-3> worker thread fetched selectionkey
** [04:01:31] -VIV- {T_WORK-3} <T_WORK-3> key is valid
** [04:01:31] -VIV- {T_WORK-3} <T_WORK-3> worker threads fetched key is OP_READ - way to go m8
** [04:01:31] -VIV- {T_WORK-3} <T_WORK-3> worker thread will notify core now
** [04:01:31] -VIV- {core} <T_WORK-3> here is socketdata!
** [04:01:31] -VIV- {core} <T_WORK-3> will now read
** [04:01:31] -VIV- {core} <T_WORK-3> read 5 bytes
** [04:01:31] -INF- {core} <T_WORK-3> here is a packet: null
!! [04:01:31] -VIV- {core} <T_WORK-3> null packet
** [04:01:31] -VIV- {T_WORK-3} <T_WORK-3> worker thread returned from notifying core
** [04:01:31] -VIV- {T_WORK-3} <T_WORK-3> worker thread step out (1)
** [04:01:31] -VIV- {T_WORK-3} <T_WORK-3> worker thread step in (2)
** [04:01:31] -VIV- {T_WORK-3} <T_WORK-3> worker thread will fetch selectionkey
** [04:01:31] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step in (8)
** [04:01:31] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selecting
** [04:01:33] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selected 1 keys
** [04:01:33] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now collect selected keys
** [04:01:33] -VIV- {T_SELECT-0} <T_SELECT-0> there is another one
** [04:01:33] -VIV- {T_SELECT-0} <T_SELECT-0> selector will now put collected keys into core sel queue (1)
** [04:01:33] -VIV- {core} <T_SELECT-0> put sth on selection Q
** [04:01:33] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step out (8)
** [04:01:33] -VIV- {T_WORK-1} <T_WORK-1> worker thread fetched selectionkey
** [04:01:33] -VIV- {T_WORK-1} <T_WORK-1> key is valid
** [04:01:33] -VIV- {T_WORK-1} <T_WORK-1> worker threads fetched key is OP_READ - way to go m8
** [04:01:33] -VIV- {T_WORK-1} <T_WORK-1> worker thread will notify core now
** [04:01:33] -VIV- {core} <T_WORK-1> here is socketdata!
** [04:01:33] -VIV- {core} <T_WORK-1> will now read
** [04:01:33] -VIV- {core} <T_WORK-1> read 3 bytes
!! [04:01:33] -ERR- {core} <T_WORK-1> got an invalid packet -.- will drop connection
** [04:01:33] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread step in (9)
** [04:01:33] -VIV- {T_SELECT-0} <T_SELECT-0> selector thread selecting
** [04:01:33] -VIV- {T_WORK-1} <T_WORK-1> worker thread returned from notifying core
** [04:01:33] -VIV- {T_WORK-1} <T_WORK-1> worker thread step out (2)
** [04:01:33] -VIV- {T_WORK-1} <T_WORK-1> worker thread step in (3)
** [04:01:33] -VIV- {T_WORK-1} <T_WORK-1> worker thread will fetch selectionkey