ᥭ 30 20:42:09-00000000000327711742 util-12860 DEBUG Service `dht' runs with configuration from `C:\Users\LRN\AppData\Local\Temp\test_stream_locala12176\0\config'
ᥭ 30 20:42:09-00000000000327711742 util-12860 DEBUG CreateNamedPipe: name = \\.\pipe\gnunet-12860-1, size = 512
ᥭ 30 20:42:09-00000000000327711742 util-12860 DEBUG pipe read handle = 0x3c8
ᥭ 30 20:42:09-00000000000327711742 util-12860 DEBUG CreateFile: name = \\.\pipe\gnunet-12860-1
ᥭ 30 20:42:09-00000000000327711742 util-12860 DEBUG pipe write handle = 0x180
ᥭ 30 20:42:09-00000000000327711742 util-scheduler-12860 DEBUG Adding continuation task: 1 / 0x28fe08
ᥭ 30 20:42:09-00000000000327711742 util-scheduler-12860 DEBUG Adding task: 2 / 0x0
ᥭ 30 20:42:09-00000000000327711742 util-12860 DEBUG FD 0x19c (0x739988) is SET in rfds
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG Reading 0 bytes from the pipe 0x19c
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG Adding the pipe's 0x19c overlapped event to the array as 0
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG nfds: 0, handles: 1, will wait: 0 ms
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG WaitForMultipleObjects Returned : 258
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG return pos is : 258
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG Returning from _select() with nothing!
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG Zeroing rfds
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG Zeroing wfds
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG rfds:
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG wfds:
ᥭ 30 20:42:09-00000000000327711743 util-12860 DEBUG Returning 0 or 0
ᥭ 30 20:42:09-00000000000327711743 util-scheduler-12860 DEBUG Checking readiness of task: 2 / 0x0
ᥭ 30 20:42:09-00000000000327711744 util-scheduler-12860 DEBUG Running task: 2 / 0x0
ᥭ 30 20:42:09-00000000000327711744 util-12860 DEBUG Adding parent control handler pipe `\\.\pipe\gnunet-6740405670414481021' to the scheduler
ᥭ 30 20:42:09-00000000000327711744 util-scheduler-12860 DEBUG Adding task: 3 / 0x1d19378
ᥭ 30 20:42:09-00000000000327711744 util-scheduler-12860 DEBUG Running task: 1 / 0x28fe08
ᥭ 30 20:42:09-00000000000327711744 util-scheduler-12860 DEBUG Adding task: 4 / 0x73d458
ᥭ 30 20:42:09-00000000000327711744 util-scheduler-12860 DEBUG Adding task: 5 / 0x28fe08
ᥭ 30 20:42:09-00000000000327711745 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_dht'
ᥭ 30 20:42:09-00000000000327711746 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_dht'
ᥭ 30 20:42:09-00000000000327711746 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_dns'
ᥭ 30 20:42:09-00000000000327711747 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_dns'
ᥭ 30 20:42:09-00000000000327711747 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_fs'
ᥭ 30 20:42:09-00000000000327711748 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_fs'
ᥭ 30 20:42:09-00000000000327711748 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_gns'
ᥭ 30 20:42:09-00000000000327711749 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_gns'
ᥭ 30 20:42:09-00000000000327711866 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_mesh'
ᥭ 30 20:42:09-00000000000327711943 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_mesh'
ᥭ 30 20:42:09-00000000000327711944 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_template'
ᥭ 30 20:42:09-00000000000327711944 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_template'
ᥭ 30 20:42:09-00000000000327711945 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_test'
ᥭ 30 20:42:09-00000000000327711945 dht-12860 DEBUG Loading block plugin `libgnunet_plugin_block_test'
ᥭ 30 20:42:09-00000000000327711946 util-scheduler-12860 DEBUG Adding task: 6 / 0x1d0e468
ᥭ 30 20:42:09-00000000000327711947 datacache-12860 INFO Loading `sqlite' datacache plugin
ᥭ 30 20:42:09-00000000000327711949 datacache-sqlite-12860 INFO Sqlite datacache running
ᥭ 30 20:42:09-00000000000327711949 util-scheduler-12860 DEBUG Adding task: 7 / 0x1d1e8a0
ᥭ 30 20:42:09-00000000000327711949 util-12860 DEBUG Need to wait to schedule transmission for connection, adding timeout task (0x73f5c8).
ᥭ 30 20:42:09-00000000000327711949 util-scheduler-12860 DEBUG Adding task: 8 / 0x73f5c8
ᥭ 30 20:42:09-00000000000327711949 util-scheduler-12860 DEBUG Adding task: 9 / 0x1d1e8f8
ᥭ 30 20:42:09-00000000000327711949 util-12860 DEBUG calling GNUNET_CONNECTION_receive
ᥭ 30 20:42:09-00000000000327711949 util-12860 DEBUG Need to wait to schedule transmission for connection, adding timeout task (0x1d59c78).
ᥭ 30 20:42:09-00000000000327711949 util-scheduler-12860 DEBUG Adding task: 10 / 0x1d59c78
ᥭ 30 20:42:09-00000000000327711949 core-api-12860 DEBUG Connecting to CORE service
ᥭ 30 20:42:09-00000000000327711949 util-scheduler-12860 DEBUG Adding task: 11 / 0x1d1e950
ᥭ 30 20:42:09-00000000000327711949 core-api-12860 INFO (Re)connecting to CORE service, monitoring messages of type 0
ᥭ 30 20:42:09-00000000000327711949 util-12860 DEBUG Need to wait to schedule transmission for connection, adding timeout task (0x1d59d70).
ᥭ 30 20:42:09-00000000000327711949 util-scheduler-12860 DEBUG Adding task: 12 / 0x1d59d70
ᥭ 30 20:42:09-00000000000327711949 util-scheduler-12860 DEBUG Adding task: 13 / 0x0
ᥭ 30 20:42:09-00000000000327711949 transport-api-12860 DEBUG Connecting to transport service.
ᥭ 30 20:42:09-00000000000327711949 util-scheduler-12860 DEBUG Adding task: 14 / 0x1d1e9a8
ᥭ 30 20:42:09-00000000000327711949 transport-api-12860 DEBUG Control transmit of 72 bytes requested
ᥭ 30 20:42:09-00000000000327711949 transport-api-12860 DEBUG Scheduling next transmission to service in 0 ms
ᥭ 30 20:42:09-00000000000327711949 util-scheduler-12860 DEBUG Adding task: 15 / 0x1d58408
ᥭ 30 20:42:09-00000000000327711949 util-12860 DEBUG FD 0x19c (0x1d1e368) is SET in rfds
ᥭ 30 20:42:09-00000000000327711949 util-12860 DEBUG FD 0x22c (0x1d1e390) is SET in rfds
ᥭ 30 20:42:09-00000000000327711949 util-12860 DEBUG Reading 0 bytes from the pipe 0x19c
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Adding the pipe's 0x19c overlapped event to the array as 0
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Reading 0 bytes from the pipe 0x22c
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Adding the read ready event to the array as 1
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Adding the socket event to the array as 2
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG nfds: 29, handles: 3, will wait: 0 ms
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG WaitForMultipleObjects Returned : 1
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Finished waiting for the select thread: 0 1
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG return pos is : 1
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG ReadPipes is : 2
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Peek at read pipe 0 (0x19c) returned 1 (0 bytes available) GLE 0
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Peek at read pipe 1 (0x22c) returned 1 (1 bytes available) GLE 0
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Added read Pipe 0x1d1e390 (0x22c)
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Zeroing rfds, copying fdset
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Zeroing wfds, copying fdset
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG rfds:
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG 12
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG 556
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG wfds:
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Returning 2 or 0
ᥭ 30 20:42:09-00000000000327711950 util-scheduler-12860 DEBUG Checking readiness of task: 4 / 0x73d458
ᥭ 30 20:42:09-00000000000327711950 util-scheduler-12860 DEBUG Checking readiness of task: 3 / 0x1d19378
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Checking that FD 0x22c is in another set:
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG 0x22c
ᥭ 30 20:42:09-00000000000327711950 util-scheduler-12860 DEBUG Running task: 4 / 0x73d458
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Accepting connection on `0.0.0.0:12052'
ᥭ 30 20:42:09-00000000000327711950 util-12860 INFO Accepting connection from `127.0.0.1:65322': 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711950 util-12860 DEBUG Server accepted incoming connection.
ᥭ 30 20:42:09-00000000000327711951 util-scheduler-12860 DEBUG Adding task: 16 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711951 util-scheduler-12860 DEBUG Adding task: 17 / 0x73d458
ᥭ 30 20:42:09-00000000000327711951 util-scheduler-12860 DEBUG Running task: 3 / 0x1d19378
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG `parent_control_handler' invoked because of 20
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Read 1 bytes from pipe
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Got control code 15 from parent
ᥭ 30 20:42:09-00000000000327711951 util-scheduler-12860 DEBUG Adding task: 18 / 0x1d19378
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG It is a pipe trying to write 1 bytes
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Wrote 1 bytes (ovr says 1), picking the greatest
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Wrote 1 bytes
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG FD 0x19c (0x1d1e408) is SET in rfds
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG FD 0x22c (0x1d1e390) is SET in rfds
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Reading 0 bytes from the pipe 0x19c
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Adding the read ready event to the array as 0
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Reading 0 bytes from the pipe 0x22c
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Adding the pipe's 0x22c overlapped event to the array as 1
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Adding the socket event to the array as 2
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG nfds: 705, handles: 3, will wait: 0 ms
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG WaitForMultipleObjects Returned : 0
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Finished waiting for the select thread: 0 1
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG return pos is : 0
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG ReadPipes is : 2
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Peek at read pipe 0 (0x19c) returned 1 (1 bytes available) GLE 0
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Added read Pipe 0x1d1e408 (0x19c)
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Peek at read pipe 1 (0x22c) returned 1 (0 bytes available) GLE 0
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Zeroing rfds, copying fdset
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG Zeroing wfds, copying fdset
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG rfds:
ᥭ 30 20:42:09-00000000000327711951 util-12860 DEBUG 704
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG 412
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG wfds:
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG Returning 2 or 0
ᥭ 30 20:42:09-00000000000327711952 util-scheduler-12860 DEBUG Checking readiness of task: 18 / 0x1d19378
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG Checking that FD 0x19c is in another set:
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG 0x22c
ᥭ 30 20:42:09-00000000000327711952 util-scheduler-12860 DEBUG Checking readiness of task: 17 / 0x73d458
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG Checking that FD 0x19c is in another set:
ᥭ 30 20:42:09-00000000000327711952 util-scheduler-12860 DEBUG Checking readiness of task: 16 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711952 util-scheduler-12860 DEBUG Running task: 16 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG receive_ready read 328/65535 bytes from `127.0.0.1:65322' (0x1d19ea8)!
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG Server receives 328 bytes from `127.0.0.1:65322'.
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG Server-mst receives 328 bytes with 0 bytes already in private buffer
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG Server-mst has 328 bytes left in inbound buffer
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG Tokenizer gives server message of type 142 from client
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG Server schedules transmission of 164-byte message of type 142 to client.
ᥭ 30 20:42:09-00000000000327711952 util-scheduler-12860 DEBUG Adding task: 19 / 0x1d59280
ᥭ 30 20:42:09-00000000000327711952 util-scheduler-12860 DEBUG Adding task: 20 / 0x1d1ea00
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG Need to wait to schedule transmission for connection, adding timeout task (0x1d593b0).
ᥭ 30 20:42:09-00000000000327711952 util-scheduler-12860 DEBUG Adding task: 21 / 0x1d593b0
ᥭ 30 20:42:09-00000000000327711952 dht-12860 DEBUG Handling local PUT of 68-bytes for query 0P0JCCMP
ᥭ 30 20:42:09-00000000000327711952 datacache-sqlite-12860 DEBUG Processing `PUT' of 72 bytes with key `0P0JCCMP' and expiration 59 m
ᥭ 30 20:42:09-00000000000327711952 datacache-12860 DEBUG Stored data under key `0P0JCCMP' in cache
ᥭ 30 20:42:09-00000000000327711952 util-scheduler-12860 DEBUG Adding task: 22 / 0x1d1ea58
ᥭ 30 20:42:09-00000000000327711952 util-12860 DEBUG Need to wait to schedule transmission for connection, adding timeout task (0x73fd88).
ᥭ 30 20:42:09-00000000000327711953 util-scheduler-12860 DEBUG Adding task: 23 / 0x73fd88
ᥭ 30 20:42:09-00000000000327711953 dht-12860 DEBUG Adding myself (0000) to PUT bloomfilter for 0P0JCCMP
ᥭ 30 20:42:09-00000000000327711953 dht-12860 DEBUG Selected 0/0 peers at hop 0 for 0P0JCCMP (target was 4)
ᥭ 30 20:42:09-00000000000327711953 dht-12860 DEBUG Routing PUT for 0P0JCCMP terminates after 0 hops at 0000
ᥭ 30 20:42:09-00000000000327711953 dht-12860 DEBUG Asking for transmission of 16 bytes to client 0x1d59280
ᥭ 30 20:42:09-00000000000327711953 util-12860 DEBUG Scheduling transmission (0x1d19ea8).
ᥭ 30 20:42:09-00000000000327711953 util-scheduler-12860 DEBUG Adding task: 24 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711953 util-scheduler-12860 DEBUG Canceling task: 19 / 0x1d59280
ᥭ 30 20:42:09-00000000000327711953 util-12860 DEBUG GNUNET_SERVER_receive_done called while still in processing loop
ᥭ 30 20:42:09-00000000000327711953 util-12860 DEBUG Server-mst has 164 bytes left in inbound buffer
ᥭ 30 20:42:09-00000000000327711953 util-12860 DEBUG Server-mst leaves 164 bytes in private buffer
ᥭ 30 20:42:09-00000000000327711953 util-12860 DEBUG Server processes additional messages instantly.
ᥭ 30 20:42:09-00000000000327711953 util-12860 DEBUG Server-mst receives 0 bytes with 164 bytes already in private buffer
ᥭ 30 20:42:09-00000000000327711953 util-12860 DEBUG Tokenizer gives server message of type 142 from client
ᥭ 30 20:42:09-00000000000327711953 util-12860 DEBUG Server schedules transmission of 164-byte message of type 142 to client.
ᥭ 30 20:42:09-00000000000327711953 util-scheduler-12860 DEBUG Adding task: 25 / 0x1d59280
ᥭ 30 20:42:09-00000000000327711953 dht-12860 DEBUG Handling local PUT of 68-bytes for query FC74R2SO
ᥭ 30 20:42:09-00000000000327711953 datacache-sqlite-12860 DEBUG Processing `PUT' of 72 bytes with key `FC74R2SO' and expiration forever
ᥭ 30 20:42:09-00000000000327711953 datacache-12860 DEBUG Stored data under key `FC74R2SO' in cache
ᥭ 30 20:42:09-00000000000327711953 dht-12860 DEBUG Adding myself (0000) to PUT bloomfilter for FC74R2SO
ᥭ 30 20:42:09-00000000000327711953 dht-12860 DEBUG Selected 0/0 peers at hop 0 for FC74R2SO (target was 3)
ᥭ 30 20:42:09-00000000000327711953 dht-12860 DEBUG Routing PUT for FC74R2SO terminates after 0 hops at 0000
ᥭ 30 20:42:09-00000000000327711953 dht-12860 DEBUG Not asking for transmission to 0x1d59280 now: request already pending
ᥭ 30 20:42:09-00000000000327711953 util-scheduler-12860 DEBUG Canceling task: 25 / 0x1d59280
ᥭ 30 20:42:09-00000000000327711953 util-12860 DEBUG GNUNET_SERVER_receive_done called while still in processing loop
ᥭ 30 20:42:09-00000000000327711953 util-12860 DEBUG Server-mst leaves 0 bytes in private buffer
ᥭ 30 20:42:09-00000000000327711954 util-12860 DEBUG Server re-enters receive loop, timeout: 18446744073709551615.
ᥭ 30 20:42:09-00000000000327711954 util-scheduler-12860 DEBUG Adding task: 26 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711954 util-12860 DEBUG Server leaves instant processing loop: ret = 1, server = 0x73d458, shutdown = 0, suspended = 0
ᥭ 30 20:42:09-00000000000327711954 util-scheduler-12860 DEBUG Running task: 15 / 0x1d58408
ᥭ 30 20:42:09-00000000000327711954 transport-api-12860 DEBUG Calling notify_transmit_ready
ᥭ 30 20:42:09-00000000000327711954 util-12860 DEBUG Need to wait to schedule transmission for connection, adding timeout task (0x1d584a8).
ᥭ 30 20:42:09-00000000000327711954 util-scheduler-12860 DEBUG Adding task: 27 / 0x1d584a8
ᥭ 30 20:42:09-00000000000327711954 util-scheduler-12860 DEBUG Running task: 14 / 0x1d1e9a8
ᥭ 30 20:42:09-00000000000327711954 util-12860 DEBUG Trying to connect using address `127.0.0.1:12037/127.0.0.1:12037'
ᥭ 30 20:42:09-00000000000327711954 util-12860 INFO Trying to connect to `127.0.0.1:12037' (0x1d584a8)
ᥭ 30 20:42:09-00000000000327711954 util-scheduler-12860 DEBUG Adding task: 28 / 0x1d103d0
ᥭ 30 20:42:09-00000000000327711954 util-scheduler-12860 DEBUG Running task: 11 / 0x1d1e950
ᥭ 30 20:42:09-00000000000327711954 util-12860 DEBUG Trying to connect using address `127.0.0.1:12054/127.0.0.1:12054'
ᥭ 30 20:42:09-00000000000327711954 util-12860 INFO Trying to connect to `127.0.0.1:12054' (0x1d59d70)
ᥭ 30 20:42:09-00000000000327711954 util-scheduler-12860 DEBUG Adding task: 29 / 0x1d10408
ᥭ 30 20:42:09-00000000000327711954 util-scheduler-12860 DEBUG Running task: 9 / 0x1d1e8f8
ᥭ 30 20:42:09-00000000000327711954 util-12860 DEBUG Trying to connect using address `127.0.0.1:12056/127.0.0.1:12056'
ᥭ 30 20:42:09-00000000000327711954 util-12860 INFO Trying to connect to `127.0.0.1:12056' (0x1d59c78)
ᥭ 30 20:42:09-00000000000327711954 util-scheduler-12860 DEBUG Adding task: 30 / 0x1d10440
ᥭ 30 20:42:09-00000000000327711955 util-scheduler-12860 DEBUG Running task: 7 / 0x1d1e8a0
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Trying to connect using address `127.0.0.1:12042/127.0.0.1:12042'
ᥭ 30 20:42:09-00000000000327711955 util-12860 INFO Trying to connect to `127.0.0.1:12042' (0x73f5c8)
ᥭ 30 20:42:09-00000000000327711955 util-scheduler-12860 DEBUG Adding task: 31 / 0x1d10478
ᥭ 30 20:42:09-00000000000327711955 util-scheduler-12860 DEBUG Running task: 6 / 0x1d0e468
ᥭ 30 20:42:09-00000000000327711955 nse-api-12860 DEBUG Connecting to network size estimation service.
ᥭ 30 20:42:09-00000000000327711955 util-scheduler-12860 DEBUG Adding task: 32 / 0x1d1e8a0
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Need to wait to schedule transmission for connection, adding timeout task (0x73fe80).
ᥭ 30 20:42:09-00000000000327711955 util-scheduler-12860 DEBUG Adding task: 33 / 0x73fe80
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Read 1 bytes from pipe
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG FD 0x19c (0x1d1e430) is SET in rfds
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG FD 0x22c (0x1d1e390) is SET in rfds
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Reading 0 bytes from the pipe 0x19c
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Adding the pipe's 0x19c overlapped event to the array as 0
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Reading 0 bytes from the pipe 0x22c
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Adding the pipe's 0x22c overlapped event to the array as 1
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Adding the socket event to the array as 2
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG nfds: 721, handles: 3, will wait: 0 ms
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG WaitForMultipleObjects Returned : 258
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Finished waiting for the select thread: 0 6
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG return pos is : 258
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Returning from _select() with nothing!
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Zeroing rfds
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG Zeroing wfds
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG rfds:
ᥭ 30 20:42:09-00000000000327711955 util-12860 DEBUG wfds:
ᥭ 30 20:42:09-00000000000327711956 util-12860 DEBUG Returning 0 or 0
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Checking readiness of task: 31 / 0x1d10478
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Checking readiness of task: 30 / 0x1d10440
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Checking readiness of task: 29 / 0x1d10408
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Checking readiness of task: 28 / 0x1d103d0
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Checking readiness of task: 26 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Checking readiness of task: 24 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Checking readiness of task: 18 / 0x1d19378
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Checking readiness of task: 17 / 0x73d458
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Running task: 17 / 0x73d458
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Adding task: 34 / 0x73d458
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Running task: 18 / 0x1d19378
ᥭ 30 20:42:09-00000000000327711956 util-12860 DEBUG `parent_control_handler' invoked because of 17
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Running task: 24 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711956 util-12860 DEBUG transmit_ready running (0x1d19ea8).
ᥭ 30 20:42:09-00000000000327711956 util-12860 DEBUG Re-scheduling transmit_ready (more to do) (0x1d19ea8).
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Adding task: 35 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Running task: 26 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Adding task: 36 / 0x1d19ea8
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Running task: 28 / 0x1d103d0
ᥭ 30 20:42:09-00000000000327711956 util-12860 DEBUG Closed 0x2c4, closesocket() returned 0, GLE is 0
ᥭ 30 20:42:09-00000000000327711956 util-12860 INFO Failed to establish TCP connection to `127.0.0.1:12037', no further addresses to try.
ᥭ 30 20:42:09-00000000000327711956 util-scheduler-12860 DEBUG Canceling task: 27 / 0x1d584a8
ᥭ 30 20:42:09-00000000000327711956 util-12860 DEBUG Transmission encounterd error (Connection refused), connection closed (0x1d584a8)
ᥭ 30 20:42:09-00000000000327711956 util-12860 DEBUG Transmission failed 0 times, giving up.
ᥭ 30 20:42:09-00000000000327711956 util-12860 DEBUG Shutting down connection (0x1d584a8)
ᥭ 30 20:42:09-00000000000327711957 transport-api-12860 DEBUG Shutdown while trying to transmit `START' request.
ᥭ 30 20:42:09-00000000000327711957 transport-api-12860 DEBUG Scheduling task to reconnect to transport service in 0 ms.
ᥭ 30 20:42:09-00000000000327711957 util-scheduler-12860 DEBUG Adding task: 37 / 0x1d58408
ᥭ 30 20:42:09-00000000000327711957 util-scheduler-12860 DEBUG Running task: 29 / 0x1d10408
ᥭ 30 20:42:09-00000000000327711957 util-12860 DEBUG Closed 0x2c8, closesocket() returned 0, GLE is 0
ᥭ 30 20:42:09-00000000000327711957 util-12860 INFO Failed to establish TCP connection to `127.0.0.1:12054', no further addresses to try.
ᥭ 30 20:42:09-00000000000327711957 util-scheduler-12860 DEBUG Canceling task: 12 / 0x1d59d70
ᥭ 30 20:42:09-00000000000327711957 util-12860 DEBUG Transmission encounterd error (Connection refused), connection closed (0x1d59d70)
ᥭ 30 20:42:09-00000000000327711957 util-12860 DEBUG Transmission failed 0 times, giving up.
ᥭ 30 20:42:09-00000000000327711957 core-api-12860 DEBUG Transmission failed, initiating reconnect
ᥭ 30 20:42:09-00000000000327711957 util-12860 DEBUG Shutting down connection (0x1d59d70)
ᥭ 30 20:42:09-00000000000327711957 util-scheduler-12860 DEBUG Adding task: 38 / 0x1d20798
ᥭ 30 20:42:09-00000000000327711957 core-api-12860 DEBUG Failed to exchange INIT with core, retrying
ᥭ 30 20:42:09-00000000000327711957 util-scheduler-12860 DEBUG Running task: 30 / 0x1d10440
ᥭ 30 20:42:09-00000000000327711957 util-12860 DEBUG Closed 0x2cc, closesocket() returned 0, GLE is 0
ᥭ 30 20:42:09-00000000000327711957 util-12860 INFO Failed to establish TCP connection to `127.0.0.1:12056', no further addresses to try.
ᥭ 30 20:42:09-00000000000327711957 util-12860 DEBUG Receive encounters error (Connection refused), connection closed (0x1d59c78)
ᥭ 30 20:42:09-00000000000327711957 util-12860 DEBUG Timeout in receive_helper, available 0, client->connection non-NULL, errCode `Connection refused'
ᥭ 30 20:42:09-00000000000327711957 util-scheduler-12860 DEBUG Canceling task: 10 / 0x1d59c78
ᥭ 30 20:42:09-00000000000327711957 util-12860 DEBUG Shutting down connection (0x1d59c78)
ᥭ 30 20:42:09-00000000000327711957 util-scheduler-12860 DEBUG Adding task: 39 / 0x1d102b8
ᥭ 30 20:42:09-00000000000327711957 util-scheduler-12860 ERROR Attempt to cancel dead task 13451671603782742029!
ᥭ 30 20:42:09-00000000000327711957 dht-12860 ERROR Assertion failed at scheduler.c:918.