View Issue Details

Jump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0002595GNUnetcadet servicepublic2012-10-22 16:362012-11-05 18:33
ReporterBart Polot Assigned ToBart Polot  
PrioritynormalSeveritymajorReproducibilitysometimes
Status closedResolutionfixed 
Product VersionGit master 
Target Version0.9.4Fixed in Version0.9.4 
Summary0002595: Mesh fails to find peers in reasonable time
DescriptionTest: test_mesh_small.c

Topology: 5 peer line

Each 2 seconds a DHT put is done by each peer with his ID.

Timeout is set to 20 seconds for peer 1 to find peer 5.
Steps To ReproduceRun any test from test_mesh_small.c (unicast, multicast, speed) several times.
Fail rate is about 1 in 5.
Additional InformationOkt 22 16:31:04-648293 test_mesh_small-30070 DEBUG Starting daemons.
Okt 22 16:31:04-648535 test_mesh_small-30070 DEBUG Found file size 3481426 for hostkeys
Okt 22 16:31:04-648561 test_mesh_small-30070 DEBUG Will read 3809 hostkeys from file
Okt 22 16:31:04-655667 test_mesh_small-30070 DEBUG Setting up peer with configuration file `/tmp/test_mesh_small//4//gnunet-testing-configbfGyH4'.
Okt 22 16:31:04-656523 test_mesh_small-30070 DEBUG No need to copy configuration file since we are running locally.
Okt 22 16:31:04-656542 test_mesh_small-30070 DEBUG Peer 2UVH FSM is in phase 1.
Hostkeys created Progress: [0%Okt 22 16:31:04-656603 test_mesh_small-30070 DEBUG Setting up peer with configuration file `/tmp/test_mesh_small//3//gnunet-testing-configKm9ORc'.
Okt 22 16:31:04-657375 test_mesh_small-30070 DEBUG No need to copy configuration file since we are running locally.
Okt 22 16:31:04-657392 test_mesh_small-30070 DEBUG Peer 4Q99 FSM is in phase 1.
20%Okt 22 16:31:04-657439 test_mesh_small-30070 DEBUG Setting up peer with configuration file `/tmp/test_mesh_small//2//gnunet-testing-configFSkf2k'.
Okt 22 16:31:04-658173 test_mesh_small-30070 DEBUG No need to copy configuration file since we are running locally.
Okt 22 16:31:04-658189 test_mesh_small-30070 DEBUG Peer 9P6V FSM is in phase 1.
40%Okt 22 16:31:04-658235 test_mesh_small-30070 DEBUG Setting up peer with configuration file `/tmp/test_mesh_small//1//gnunet-testing-configVsQOct'.
Okt 22 16:31:04-658952 test_mesh_small-30070 DEBUG No need to copy configuration file since we are running locally.
Okt 22 16:31:04-658968 test_mesh_small-30070 DEBUG Peer KJI3 FSM is in phase 1.
60%Okt 22 16:31:04-659016 test_mesh_small-30070 DEBUG Setting up peer with configuration file `/tmp/test_mesh_small//0//gnunet-testing-configvMCxnB'.
Okt 22 16:31:04-659728 test_mesh_small-30070 DEBUG No need to copy configuration file since we are running locally.
Okt 22 16:31:04-659744 test_mesh_small-30070 DEBUG Peer FC74 FSM is in phase 1.
80%100%]
Okt 22 16:31:04-659767 test_mesh_small-30070 DEBUG Creating no allowed topology (all peers can connect at core level)
Okt 22 16:31:04-659778 test_mesh_small-30070 DEBUG Connecting peer 0 to peer 1
Okt 22 16:31:04-659785 test_mesh_small-30070 DEBUG Connecting peer 0 to peer 2
Okt 22 16:31:04-659790 test_mesh_small-30070 DEBUG Connecting peer 0 to peer 3
Okt 22 16:31:04-659794 test_mesh_small-30070 DEBUG Connecting peer 0 to peer 4
Okt 22 16:31:04-659799 test_mesh_small-30070 DEBUG Connecting peer 1 to peer 2
Okt 22 16:31:04-659804 test_mesh_small-30070 DEBUG Connecting peer 1 to peer 3
Okt 22 16:31:04-659809 test_mesh_small-30070 DEBUG Connecting peer 1 to peer 4
Okt 22 16:31:04-659813 test_mesh_small-30070 DEBUG Connecting peer 2 to peer 3
Okt 22 16:31:04-659818 test_mesh_small-30070 DEBUG Connecting peer 2 to peer 4
Okt 22 16:31:04-659823 test_mesh_small-30070 DEBUG Connecting peer 3 to peer 4
Okt 22 16:31:04-659829 test_mesh_small-30070 DEBUG Blacklisting all but straight line topology
Okt 22 16:31:04-659834 test_mesh_small-30070 DEBUG Connecting peer 0 to peer 1
Okt 22 16:31:04-659839 test_mesh_small-30070 DEBUG Connecting peer 1 to peer 2
Okt 22 16:31:04-659844 test_mesh_small-30070 DEBUG Connecting peer 2 to peer 3
Okt 22 16:31:04-659849 test_mesh_small-30070 DEBUG Connecting peer 3 to peer 4
Okt 22 16:31:04-659854 test_mesh_small-30070 DEBUG Creating blacklist with `tcp udp http unix'
Okt 22 16:31:04-659999 test_mesh_small-30070 DEBUG Copying file with RENAME (/tmp/blacklist34oqyJ,/tmp/test_mesh_small//0//blacklist)
Okt 22 16:31:04-660129 test_mesh_small-30070 DEBUG Copying file with RENAME (/tmp/blacklistoyXkJR,/tmp/test_mesh_small//1//blacklist)
Okt 22 16:31:04-660292 test_mesh_small-30070 DEBUG Copying file with RENAME (/tmp/blacklistjZZgUZ,/tmp/test_mesh_small//2//blacklist)
Okt 22 16:31:04-660419 test_mesh_small-30070 DEBUG Copying file with RENAME (/tmp/blacklist7mYe57,/tmp/test_mesh_small//3//blacklist)
Okt 22 16:31:04-660547 test_mesh_small-30070 DEBUG Copying file with RENAME (/tmp/blacklistDIuegg,/tmp/test_mesh_small//4//blacklist)
Okt 22 16:31:04-660566 test_mesh_small-30070 DEBUG Checking copy status of file 0
Okt 22 16:31:04-660576 test_mesh_small-30070 DEBUG Checking copy status of file 1
Okt 22 16:31:04-660581 test_mesh_small-30070 DEBUG Checking copy status of file 2
Okt 22 16:31:04-660585 test_mesh_small-30070 DEBUG Checking copy status of file 3
Okt 22 16:31:04-660590 test_mesh_small-30070 DEBUG Checking copy status of file 4
Okt 22 16:31:04-660595 test_mesh_small-30070 DEBUG Finished copying all blacklist files!
Okt 22 16:31:04-660600 test_mesh_small-30070 DEBUG Blacklist files created/copied successfully!
Okt 22 16:31:04-660605 test_mesh_small-30070 DEBUG Topology set up, have 25 expected connections, now starting peers!
Okt 22 16:31:04-660619 test_mesh_small-30070 DEBUG Peer FC74 FSM is in phase 3.
Okt 22 16:31:04-660630 test_mesh_small-30070 DEBUG Peer KJI3 FSM is in phase 4.
Okt 22 16:31:04-660637 test_mesh_small-30070 DEBUG Starting local `gnunet-arm', with command `gnunet-arm -c /tmp/test_mesh_small//1//gnunet-testing-configVsQOct -s'.
Okt 22 16:31:04-660895 test_mesh_small-30070 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up.
Okt 22 16:31:04-660934 test_mesh_small-30070 DEBUG Peer 9P6V FSM is in phase 4.
Okt 22 16:31:04-660943 test_mesh_small-30070 DEBUG Starting local `gnunet-arm', with command `gnunet-arm -c /tmp/test_mesh_small//2//gnunet-testing-configFSkf2k -s'.
Okt 22 16:31:04-661127 test_mesh_small-30070 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up.
Okt 22 16:31:04-661170 test_mesh_small-30070 DEBUG Peer 4Q99 FSM is in phase 4.
Okt 22 16:31:04-661187 test_mesh_small-30070 DEBUG Starting local `gnunet-arm', with command `gnunet-arm -c /tmp/test_mesh_small//3//gnunet-testing-configKm9ORc -s'.
Okt 22 16:31:04-661386 test_mesh_small-30070 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up.
Okt 22 16:31:04-661426 test_mesh_small-30070 DEBUG Peer 2UVH FSM is in phase 4.
Okt 22 16:31:04-661442 test_mesh_small-30070 DEBUG Starting local `gnunet-arm', with command `gnunet-arm -c /tmp/test_mesh_small//4//gnunet-testing-configbfGyH4 -s'.
Okt 22 16:31:04-661667 test_mesh_small-30070 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up.
Oct 22 16:31:04-680479 mesh-30095 DEBUG starting to run
Oct 22 16:31:04-680734 mesh-30095 DEBUG APP_ANNOUNCE_TIME 2000 ms
Oct 22 16:31:04-680980 mesh-30095 INFO Mesh for peer [KJI3] starting
Oct 22 16:31:04-680938 mesh-30098 DEBUG starting to run
Oct 22 16:31:04-681073 mesh-30095 DEBUG adding path [1] to peer KJI3
Oct 22 16:31:04-681078 mesh-30098 DEBUG APP_ANNOUNCE_TIME 2000 ms
Oct 22 16:31:04-681170 mesh-30095 DEBUG Mesh service running
Oct 22 16:31:04-681239 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:04-681271 mesh-30098 INFO Mesh for peer [2UVH] starting
Oct 22 16:31:04-681342 mesh-30098 DEBUG adding path [1] to peer 2UVH
Oct 22 16:31:04-681384 mesh-30098 DEBUG Mesh service running
Oct 22 16:31:04-681434 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:04-684192 mesh-30095 DEBUG Core init
Oct 22 16:31:04-684306 mesh-30098 DEBUG Core init
Oct 22 16:31:04-689735 mesh-30101 DEBUG starting to run
Oct 22 16:31:04-691572 mesh-30104 DEBUG starting to run
Oct 22 16:31:04-694024 mesh-30104 DEBUG APP_ANNOUNCE_TIME 2000 ms
Oct 22 16:31:04-697120 mesh-30104 INFO Mesh for peer [9P6V] starting
Oct 22 16:31:04-697196 mesh-30104 DEBUG adding path [1] to peer 9P6V
Oct 22 16:31:04-697285 mesh-30104 DEBUG Mesh service running
Oct 22 16:31:04-697355 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:04-698317 mesh-30104 DEBUG Core init
Oct 22 16:31:04-698889 mesh-30101 DEBUG APP_ANNOUNCE_TIME 2000 ms
Oct 22 16:31:04-699128 mesh-30101 INFO Mesh for peer [4Q99] starting
Oct 22 16:31:04-699192 mesh-30101 DEBUG adding path [1] to peer 4Q99
Oct 22 16:31:04-699237 mesh-30101 DEBUG Mesh service running
Oct 22 16:31:04-699286 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:04-699891 mesh-30101 DEBUG Core init
Okt 22 16:31:04-861077 test_mesh_small-30070 DEBUG Peer 2UVH FSM is in phase 5.
Okt 22 16:31:04-861161 test_mesh_small-30070 DEBUG Successfully started `gnunet-arm'.
Okt 22 16:31:04-861242 test_mesh_small-30070 DEBUG Calling CORE_connect
Okt 22 16:31:04-861370 test_mesh_small-30070 DEBUG Connected to transport service `2UVH', getting HELLO
Okt 22 16:31:04-861396 test_mesh_small-30070 DEBUG Peer 4Q99 FSM is in phase 5.
Okt 22 16:31:04-861415 test_mesh_small-30070 DEBUG Successfully started `gnunet-arm'.
Okt 22 16:31:04-861457 test_mesh_small-30070 DEBUG Calling CORE_connect
Okt 22 16:31:04-861514 test_mesh_small-30070 DEBUG Connected to transport service `4Q99', getting HELLO
Okt 22 16:31:04-861541 test_mesh_small-30070 DEBUG Peer 9P6V FSM is in phase 5.
Okt 22 16:31:04-861559 test_mesh_small-30070 DEBUG Successfully started `gnunet-arm'.
Okt 22 16:31:04-861601 test_mesh_small-30070 DEBUG Calling CORE_connect
Okt 22 16:31:04-861657 test_mesh_small-30070 DEBUG Connected to transport service `9P6V', getting HELLO
Okt 22 16:31:04-861682 test_mesh_small-30070 DEBUG Peer KJI3 FSM is in phase 5.
Okt 22 16:31:04-861700 test_mesh_small-30070 DEBUG Successfully started `gnunet-arm'.
Okt 22 16:31:04-861732 test_mesh_small-30070 DEBUG Calling CORE_connect
Okt 22 16:31:04-861766 test_mesh_small-30070 DEBUG Connected to transport service `KJI3', getting HELLO
Okt 22 16:31:04-861781 test_mesh_small-30070 DEBUG Peer FC74 FSM is in phase 4.
Okt 22 16:31:04-861789 test_mesh_small-30070 DEBUG Starting local `gnunet-arm', with command `gnunet-arm -c /tmp/test_mesh_small//0//gnunet-testing-configvMCxnB -s'.
Okt 22 16:31:04-862094 test_mesh_small-30070 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up.
Peers started Progress: [0%20%40%60%Okt 22 16:31:04-862522 test_mesh_small-30070 DEBUG Received `HELLO' from transport service of `9P6V'
Okt 22 16:31:04-862659 test_mesh_small-30070 DEBUG Received `HELLO' from transport service of `KJI3'
Okt 22 16:31:04-862775 test_mesh_small-30070 DEBUG Received `HELLO' from transport service of `2UVH'
Okt 22 16:31:04-862886 test_mesh_small-30070 DEBUG Received `HELLO' from transport service of `4Q99'
Oct 22 16:31:04-888098 mesh-30129 DEBUG starting to run
Oct 22 16:31:04-888341 mesh-30129 DEBUG APP_ANNOUNCE_TIME 2000 ms
Oct 22 16:31:04-888579 mesh-30129 INFO Mesh for peer [FC74] starting
Oct 22 16:31:04-888676 mesh-30129 DEBUG adding path [1] to peer FC74
Oct 22 16:31:04-888743 mesh-30129 DEBUG Mesh service running
Oct 22 16:31:04-888820 mesh-30129 DEBUG DHT_put for ID FC74 started.
Oct 22 16:31:04-889040 mesh-30129 DEBUG Core init
Okt 22 16:31:05-063280 test_mesh_small-30070 DEBUG Peer FC74 FSM is in phase 5.
Okt 22 16:31:05-063323 test_mesh_small-30070 DEBUG Successfully started `gnunet-arm'.
Okt 22 16:31:05-063371 test_mesh_small-30070 DEBUG Calling CORE_connect
Okt 22 16:31:05-063410 test_mesh_small-30070 DEBUG Connected to transport service `FC74', getting HELLO
80%100%]
Okt 22 16:31:05-063436 test_mesh_small-30070 DEBUG Creating straight line CONNECT topology
Okt 22 16:31:05-063443 test_mesh_small-30070 DEBUG Connecting peer 0 to peer 1
Okt 22 16:31:05-063449 test_mesh_small-30070 DEBUG Connecting peer 1 to peer 2
Okt 22 16:31:05-063454 test_mesh_small-30070 DEBUG Connecting peer 2 to peer 3
Okt 22 16:31:05-063459 test_mesh_small-30070 DEBUG Connecting peer 3 to peer 4
Okt 22 16:31:05-063478 test_mesh_small-30070 DEBUG Have 8 expected connections
Okt 22 16:31:05-063503 test_mesh_small-30070 DEBUG Creating connection, outstanding_connections is 0 (max 75)
Okt 22 16:31:05-063519 test_mesh_small-30070 DEBUG Asked to connect peer 4Q99 to peer 9P6V
Okt 22 16:31:05-063574 test_mesh_small-30070 DEBUG Creating connection, outstanding_connections is 1 (max 75)
Okt 22 16:31:05-063590 test_mesh_small-30070 DEBUG Asked to connect peer 4Q99 to peer 2UVH
Okt 22 16:31:05-063610 test_mesh_small-30070 DEBUG Creating connection, outstanding_connections is 2 (max 75)
Okt 22 16:31:05-063617 test_mesh_small-30070 DEBUG Asked to connect peer KJI3 to peer FC74
Okt 22 16:31:05-063638 test_mesh_small-30070 DEBUG Creating connection, outstanding_connections is 3 (max 75)
Okt 22 16:31:05-063644 test_mesh_small-30070 DEBUG Asked to connect peer 9P6V to peer KJI3
Okt 22 16:31:05-063664 test_mesh_small-30070 DEBUG Creating connection, outstanding_connections is 4 (max 75)
Okt 22 16:31:05-063675 test_mesh_small-30070 DEBUG Asked to connect peer 9P6V to peer 4Q99
Okt 22 16:31:05-063697 test_mesh_small-30070 DEBUG Creating connection, outstanding_connections is 5 (max 75)
Okt 22 16:31:05-063709 test_mesh_small-30070 DEBUG Asked to connect peer 2UVH to peer 4Q99
Okt 22 16:31:05-063738 test_mesh_small-30070 DEBUG Creating connection, outstanding_connections is 6 (max 75)
Okt 22 16:31:05-063747 test_mesh_small-30070 DEBUG Asked to connect peer KJI3 to peer 9P6V
Okt 22 16:31:05-063765 test_mesh_small-30070 DEBUG Creating connection, outstanding_connections is 7 (max 75)
Okt 22 16:31:05-063777 test_mesh_small-30070 DEBUG Asked to connect peer FC74 to peer KJI3
Okt 22 16:31:05-063894 test_mesh_small-30070 DEBUG Received `HELLO' from transport service of `FC74'
Okt 22 16:31:05-063960 test_mesh_small-30070 DEBUG Peers are NOT connected, connecting to core!
Okt 22 16:31:05-064045 test_mesh_small-30070 DEBUG Peers are NOT connected, connecting to core!
Okt 22 16:31:05-064112 test_mesh_small-30070 DEBUG Peers are NOT connected, connecting to core!
Okt 22 16:31:05-064176 test_mesh_small-30070 DEBUG Peers are NOT connected, connecting to core!
Okt 22 16:31:05-064238 test_mesh_small-30070 DEBUG Peers are NOT connected, connecting to core!
Okt 22 16:31:05-064299 test_mesh_small-30070 DEBUG Peers are NOT connected, connecting to core!
Okt 22 16:31:05-064473 test_mesh_small-30070 DEBUG Connected peer KJI3 to peer KJI3
Okt 22 16:31:05-064498 test_mesh_small-30070 DEBUG Connected peer 2UVH to peer 2UVH
Okt 22 16:31:05-064514 test_mesh_small-30070 DEBUG Connected peer KJI3 to peer KJI3
Okt 22 16:31:05-064596 test_mesh_small-30070 DEBUG Connected peer 4Q99 to peer 4Q99
Okt 22 16:31:05-064610 test_mesh_small-30070 DEBUG Connected peer 4Q99 to peer 4Q99
Okt 22 16:31:05-064618 test_mesh_small-30070 DEBUG Connected peer FC74 to peer FC74
Okt 22 16:31:05-064775 test_mesh_small-30070 DEBUG Peers are NOT connected, connecting to core!
Okt 22 16:31:05-064849 test_mesh_small-30070 DEBUG Peers are NOT connected, connecting to core!
Okt 22 16:31:05-064960 test_mesh_small-30070 DEBUG Connected peer 9P6V to peer 9P6V
Okt 22 16:31:05-064985 test_mesh_small-30070 DEBUG Connected peer 9P6V to peer 9P6V
Okt 22 16:31:05-564724 test_mesh_small-30070 DEBUG Offering hello of 4Q99 to 9P6V
Okt 22 16:31:05-564805 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of 9P6V for peer 4Q99C96I
Okt 22 16:31:05-564827 test_mesh_small-30070 DEBUG Offering hello of KJI3 to 9P6V
Okt 22 16:31:05-564855 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of 9P6V for peer KJI3AL00
Okt 22 16:31:05-564869 test_mesh_small-30070 DEBUG Offering hello of KJI3 to FC74
Okt 22 16:31:05-564922 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of FC74 for peer KJI3AL00
Okt 22 16:31:05-564938 test_mesh_small-30070 DEBUG Offering hello of 9P6V to KJI3
Okt 22 16:31:05-564965 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of KJI3 for peer 9P6V9SKT
Okt 22 16:31:05-564978 test_mesh_small-30070 DEBUG Offering hello of 4Q99 to 2UVH
Okt 22 16:31:05-565010 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of 2UVH for peer 4Q99C96I
Okt 22 16:31:05-565027 test_mesh_small-30070 DEBUG Offering hello of FC74 to KJI3
Okt 22 16:31:05-565054 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of KJI3 for peer FC74R2SO
Okt 22 16:31:05-565067 test_mesh_small-30070 DEBUG Offering hello of 2UVH to 4Q99
Okt 22 16:31:05-565119 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of 4Q99 for peer 2UVHII0H
Okt 22 16:31:05-565132 test_mesh_small-30070 DEBUG Offering hello of 9P6V to 4Q99
Okt 22 16:31:05-565158 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of 4Q99 for peer 9P6V9SKT
Okt 22 16:31:06-565509 test_mesh_small-30070 DEBUG Offering hello of 9P6V to 4Q99
Okt 22 16:31:06-565595 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of 4Q99 for peer 9P6V9SKT
Okt 22 16:31:06-565616 test_mesh_small-30070 DEBUG Offering hello of 2UVH to 4Q99
Okt 22 16:31:06-565671 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of 4Q99 for peer 2UVHII0H
Okt 22 16:31:06-565685 test_mesh_small-30070 DEBUG Offering hello of FC74 to KJI3
Okt 22 16:31:06-565711 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of KJI3 for peer FC74R2SO
Okt 22 16:31:06-565726 test_mesh_small-30070 DEBUG Offering hello of 4Q99 to 2UVH
Okt 22 16:31:06-565753 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of 2UVH for peer 4Q99C96I
Okt 22 16:31:06-565768 test_mesh_small-30070 DEBUG Offering hello of 9P6V to KJI3
Okt 22 16:31:06-565794 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of KJI3 for peer 9P6V9SKT
Okt 22 16:31:06-565807 test_mesh_small-30070 DEBUG Offering hello of KJI3 to FC74
Okt 22 16:31:06-565859 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of FC74 for peer KJI3AL00
Okt 22 16:31:06-565874 test_mesh_small-30070 DEBUG Offering hello of KJI3 to 9P6V
Okt 22 16:31:06-565901 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of 9P6V for peer KJI3AL00
Okt 22 16:31:06-565914 test_mesh_small-30070 DEBUG Offering hello of 4Q99 to 9P6V
Okt 22 16:31:06-565939 test_mesh_small-30070 DEBUG Sending connect request to TRANSPORT of 9P6V for peer 4Q99C96I
Okt 22 16:31:06-599914 test_mesh_small-30070 DEBUG Connected peer FC74 to peer KJI3
Peer connection Progress: [0%Okt 22 16:31:06-600276 test_mesh_small-30070 DEBUG Connected peer KJI3 to peer FC74
Okt 22 16:31:06-600304 test_mesh_small-30070 DEBUG Connected peer KJI3 to peer FC74
.Oct 22 16:31:06-601087 mesh-30095 DEBUG adding path [2] to peer FC74
Oct 22 16:31:06-601382 mesh-30129 DEBUG adding path [2] to peer KJI3
Okt 22 16:31:06-610560 test_mesh_small-30070 DEBUG Connected peer 2UVH to peer 4Q99
25%Okt 22 16:31:06-610921 test_mesh_small-30070 DEBUG Connected peer 4Q99 to peer 2UVH
Okt 22 16:31:06-611057 test_mesh_small-30070 DEBUG Connected peer 4Q99 to peer 2UVH
.Oct 22 16:31:06-611962 mesh-30101 DEBUG adding path [2] to peer 2UVH
Oct 22 16:31:06-612482 mesh-30098 DEBUG adding path [2] to peer 4Q99
Okt 22 16:31:06-612918 test_mesh_small-30070 DEBUG Connected peer 9P6V to peer 4Q99
Okt 22 16:31:06-612942 test_mesh_small-30070 DEBUG Connected peer 9P6V to peer 4Q99
50%Okt 22 16:31:06-613032 test_mesh_small-30070 DEBUG Connected peer 4Q99 to peer 9P6V
.Oct 22 16:31:06-613949 mesh-30104 DEBUG adding path [2] to peer 4Q99
Oct 22 16:31:06-613971 mesh-30101 DEBUG adding path [2] to peer 9P6V
Okt 22 16:31:06-615468 test_mesh_small-30070 DEBUG Connected peer KJI3 to peer 9P6V
75%Okt 22 16:31:06-615853 test_mesh_small-30070 DEBUG Connected peer 9P6V to peer KJI3
.100%]
Okt 22 16:31:06-615928 test_mesh_small-30070 DEBUG ************************************************************
Okt 22 16:31:06-615946 test_mesh_small-30070 DEBUG Peer Group started successfully!
Okt 22 16:31:06-615956 test_mesh_small-30070 DEBUG Have 8 connections
Okt 22 16:31:06-615982 test_mesh_small-30070 DEBUG 1: FC74
Okt 22 16:31:06-615995 test_mesh_small-30070 DEBUG 2: KJI3
Okt 22 16:31:06-616005 test_mesh_small-30070 DEBUG 3: 9P6V
Okt 22 16:31:06-616015 test_mesh_small-30070 DEBUG 4: 4Q99
Okt 22 16:31:06-616026 test_mesh_small-30070 DEBUG 5: 2UVH
Okt 22 16:31:06-616037 test_mesh_small-30070 DEBUG Peer looking: FC74
Okt 22 16:31:06-616097 test_mesh_small-30070 DEBUG connect_mesh_service
Okt 22 16:31:06-616115 test_mesh_small-30070 DEBUG connecting to mesh service of peer FC74
Okt 22 16:31:06-616126 test_mesh_small-30070 DEBUG connecting to mesh service of peer 2UVH
Okt 22 16:31:06-616148 mesh-api-30070 DEBUG GNUNET_MESH_connect()
Okt 22 16:31:06-616164 mesh-api-30070 DEBUG addr 0x16b6000
Okt 22 16:31:06-616213 mesh-api-30070 DEBUG type 1
Okt 22 16:31:06-616232 mesh-api-30070 DEBUG Sending 10 bytes long message 1 types and 0 apps
Okt 22 16:31:06-616247 mesh-api-30070 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT
Okt 22 16:31:06-616265 mesh-api-30070 DEBUG queued
Okt 22 16:31:06-616272 mesh-api-30070 DEBUG calling ntfy tmt rdy for 10 bytes
Okt 22 16:31:06-616281 mesh-api-30070 DEBUG GNUNET_MESH_connect() END
Okt 22 16:31:06-616287 mesh-api-30070 DEBUG GNUNET_MESH_connect()
Okt 22 16:31:06-616292 mesh-api-30070 DEBUG addr 0x16b5df0
Okt 22 16:31:06-616316 mesh-api-30070 DEBUG type 1
Okt 22 16:31:06-616327 mesh-api-30070 DEBUG Sending 10 bytes long message 1 types and 0 apps
Okt 22 16:31:06-616337 mesh-api-30070 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT
Okt 22 16:31:06-616346 mesh-api-30070 DEBUG queued
Okt 22 16:31:06-616353 mesh-api-30070 DEBUG calling ntfy tmt rdy for 10 bytes
Okt 22 16:31:06-616362 mesh-api-30070 DEBUG GNUNET_MESH_connect() END
Okt 22 16:31:06-616370 mesh-api-30070 DEBUG Creating new tunnel
Okt 22 16:31:06-616376 mesh-api-30070 DEBUG at 0x16acab0
Okt 22 16:31:06-616383 mesh-api-30070 DEBUG number 80000000
Okt 22 16:31:06-616390 mesh-api-30070 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_TUNNEL_CREATE
Okt 22 16:31:06-616395 mesh-api-30070 DEBUG queued
Okt 22 16:31:06-616412 mesh-api-30070 DEBUG
Okt 22 16:31:06-616419 mesh-api-30070 DEBUG Send packet() Buffer 10
Okt 22 16:31:06-616424 mesh-api-30070 DEBUG message internal
Okt 22 16:31:06-616430 mesh-api-30070 DEBUG mesh traffic, type GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT
Okt 22 16:31:06-616436 mesh-api-30070 DEBUG message internal
Okt 22 16:31:06-616441 mesh-api-30070 DEBUG total size: 10
Okt 22 16:31:06-616446 mesh-api-30070 DEBUG message internal
Okt 22 16:31:06-616451 mesh-api-30070 DEBUG next size: 8
Okt 22 16:31:06-616457 mesh-api-30070 DEBUG start receiving from service
Okt 22 16:31:06-616464 mesh-api-30070 DEBUG Send packet() END
Okt 22 16:31:06-616476 mesh-api-30070 DEBUG
Okt 22 16:31:06-616486 mesh-api-30070 DEBUG Send packet() Buffer 10
Okt 22 16:31:06-616494 mesh-api-30070 DEBUG message internal
Okt 22 16:31:06-616501 mesh-api-30070 DEBUG mesh traffic, type GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT
Oct 22 16:31:06-616498 mesh-30129 DEBUG new client connected
Okt 22 16:31:06-616510 mesh-api-30070 DEBUG total size: 10
Okt 22 16:31:06-616519 mesh-api-30070 DEBUG nothing left to transmit
Oct 22 16:31:06-616521 mesh-30129 DEBUG CLIENT NEW 0
Okt 22 16:31:06-616528 mesh-api-30070 DEBUG start receiving from service
Oct 22 16:31:06-616533 mesh-30129 DEBUG msg type: 1
Okt 22 16:31:06-616537 mesh-api-30070 DEBUG Send packet() END
Okt 22 16:31:06-616556 mesh-api-30070 DEBUG
Okt 22 16:31:06-616570 mesh-api-30070 DEBUG Send packet() Buffer 10
Okt 22 16:31:06-616580 mesh-api-30070 DEBUG message internal
Okt 22 16:31:06-616587 mesh-api-30070 DEBUG mesh traffic, type GNUNET_MESSAGE_TYPE_MESH_LOCAL_TUNNEL_CREATE
Okt 22 16:31:06-616596 mesh-api-30070 DEBUG total size: 8
Oct 22 16:31:06-616590 mesh-30098 DEBUG new client connected
Okt 22 16:31:06-616605 mesh-api-30070 DEBUG nothing left to transmit
Oct 22 16:31:06-616611 mesh-30098 DEBUG CLIENT NEW 0
Oct 22 16:31:06-616556 mesh-30129 DEBUG client has 0+1 subscriptions
Okt 22 16:31:06-616613 mesh-api-30070 DEBUG Send packet() END
Oct 22 16:31:06-616651 mesh-30129 DEBUG new client processed
Oct 22 16:31:06-616692 mesh-30129 DEBUG new tunnel requested
Oct 22 16:31:06-616707 mesh-30129 DEBUG by client 0
Oct 22 16:31:06-616805 mesh-30129 DEBUG CREATED TUNNEL FC74 [0] (80000000)
Oct 22 16:31:06-616819 mesh-30129 DEBUG new tunnel created
Oct 22 16:31:06-616622 mesh-30098 DEBUG msg type: 1
Oct 22 16:31:06-616623 mesh-30104 DEBUG adding path [2] to peer KJI3
Oct 22 16:31:06-616865 mesh-30098 DEBUG client has 0+1 subscriptions
Oct 22 16:31:06-616899 mesh-30098 DEBUG new client processed
Oct 22 16:31:06-616987 mesh-30095 DEBUG adding path [2] to peer 9P6V
Oct 22 16:31:06-681142 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:06-682025 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:06-698045 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:06-699118 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:06-889260 mesh-30129 DEBUG DHT_put for ID FC74 started.
Okt 22 16:31:07-617786 test_mesh_small-30070 DEBUG test_task
Okt 22 16:31:07-617828 test_mesh_small-30070 DEBUG add peer 2
Okt 22 16:31:07-617854 mesh-api-30070 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_PEER_ADD
Okt 22 16:31:07-617866 mesh-api-30070 DEBUG queued
Okt 22 16:31:07-617875 mesh-api-30070 DEBUG calling ntfy tmt rdy for 72 bytes
Okt 22 16:31:07-617906 test_mesh_small-30070 DEBUG schedule timeout in SHORT_TIME
Okt 22 16:31:07-617933 mesh-api-30070 DEBUG
Okt 22 16:31:07-617944 mesh-api-30070 DEBUG Send packet() Buffer 72
Okt 22 16:31:07-617953 mesh-api-30070 DEBUG message internal
Okt 22 16:31:07-617962 mesh-api-30070 DEBUG mesh traffic, type GNUNET_MESSAGE_TYPE_MESH_LOCAL_PEER_ADD
Okt 22 16:31:07-617972 mesh-api-30070 DEBUG total size: 72
Okt 22 16:31:07-617985 mesh-api-30070 DEBUG nothing left to transmit
Okt 22 16:31:07-617995 mesh-api-30070 DEBUG Send packet() END
Oct 22 16:31:07-618059 mesh-30129 DEBUG Got connection request
Oct 22 16:31:07-618083 mesh-30129 DEBUG by client 0
Oct 22 16:31:07-618120 mesh-30129 DEBUG for 2UVH
Oct 22 16:31:07-618134 mesh-30129 DEBUG tunnel_add_peer
Oct 22 16:31:07-618156 mesh-30129 DEBUG Starting DHT GET for peer 2UVH
Oct 22 16:31:07-618180 mesh-30129 DEBUG tunnel_add_peer END
Oct 22 16:31:08-683802 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:08-683870 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:08-700656 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:08-700660 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:08-890627 mesh-30129 DEBUG DHT_put for ID FC74 started.
Oct 22 16:31:10-685386 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:10-685545 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:10-700285 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:10-702468 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:10-892228 mesh-30129 DEBUG DHT_put for ID FC74 started.
Oct 22 16:31:12-687130 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:12-688087 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:12-702798 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:12-703659 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:12-894770 mesh-30129 DEBUG DHT_put for ID FC74 started.
Oct 22 16:31:14-689830 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:14-690336 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:14-704290 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:14-704359 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:14-896403 mesh-30129 DEBUG DHT_put for ID FC74 started.
Oct 22 16:31:16-691595 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:16-692947 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:16-706819 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:16-706817 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:16-899023 mesh-30129 DEBUG DHT_put for ID FC74 started.
Oct 22 16:31:18-693250 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:18-694548 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:18-708361 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:18-708906 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:18-901525 mesh-30129 DEBUG DHT_put for ID FC74 started.
Oct 22 16:31:20-696049 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:20-697122 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:20-710079 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:20-710251 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:20-903227 mesh-30129 DEBUG DHT_put for ID FC74 started.
Oct 22 16:31:22-698700 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:22-699737 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:22-712772 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:22-712875 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:22-905889 mesh-30129 DEBUG DHT_put for ID FC74 started.
Oct 22 16:31:24-700368 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:24-701456 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:24-713652 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:24-714273 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:24-907533 mesh-30129 DEBUG DHT_put for ID FC74 started.
Oct 22 16:31:26-701208 mesh-30098 DEBUG DHT_put for ID 2UVH started.
Oct 22 16:31:26-704054 mesh-30095 DEBUG DHT_put for ID KJI3 started.
Oct 22 16:31:26-715121 mesh-30104 DEBUG DHT_put for ID 9P6V started.
Oct 22 16:31:26-716855 mesh-30101 DEBUG DHT_put for ID 4Q99 started.
Oct 22 16:31:26-910103 mesh-30129 DEBUG DHT_put for ID FC74 started.
Okt 22 16:31:27-637125 test_mesh_small-30070 DEBUG disconnecting mesh service of peers
Okt 22 16:31:27-637173 mesh-api-30070 DEBUG Destroying tunnel
Okt 22 16:31:27-637189 mesh-api-30070 DEBUG destroy_tunnel 80000000
Okt 22 16:31:27-637205 mesh-api-30070 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_TUNNEL_DESTROY
Okt 22 16:31:27-637216 mesh-api-30070 DEBUG queued
Okt 22 16:31:27-637226 mesh-api-30070 DEBUG calling ntfy tmt rdy for 8 bytes
Okt 22 16:31:27-637241 mesh-api-30070 DEBUG MESH DISCONNECT
Okt 22 16:31:27-637251 mesh-api-30070 INFO Sent 0 ACKs
Okt 22 16:31:27-637261 mesh-api-30070 INFO Recv 0 ACKs

Okt 22 16:31:27-637300 mesh-api-30070 DEBUG MESH DISCONNECT
Okt 22 16:31:27-637321 mesh-api-30070 INFO Sent 0 ACKs
Okt 22 16:31:27-637335 mesh-api-30070 INFO Recv 0 ACKs
TagsNo tags attached.

Activities

Bart Polot

2012-11-05 14:38

reporter   ~0006523

Seems to not happen anymore.

Issue History

Date Modified Username Field Change
2012-10-22 16:36 Bart Polot New Issue
2012-10-22 16:36 Bart Polot Status new => assigned
2012-10-22 16:36 Bart Polot Assigned To => Bart Polot
2012-11-04 22:39 Christian Grothoff Target Version => 0.9.4
2012-11-05 14:38 Bart Polot Note Added: 0006523
2012-11-05 14:38 Bart Polot Status assigned => resolved
2012-11-05 14:38 Bart Polot Fixed in Version => 0.9.4
2012-11-05 14:38 Bart Polot Resolution open => fixed
2012-11-05 18:33 Christian Grothoff Status resolved => closed
2014-05-09 18:34 Christian Grothoff Category mesh service => cadet service