View Issue Details
| ID | Project | Category | View Status | Date Submitted | Last Update |
|---|---|---|---|---|---|
| 0001892 | GNUnet | testing library | public | 2011-11-09 00:07 | 2012-02-28 11:05 |
| Reporter | Bart Polot | Assigned To | Bart Polot | ||
| Priority | high | Severity | major | Reproducibility | sometimes |
| Status | closed | Resolution | fixed | ||
| Product Version | 0.9.0 | ||||
| Target Version | 0.9.2 | Fixed in Version | 0.9.2 | ||
| Summary | 0001892: Testing library fails to connect peers | ||||
| Description | Nov 08 23:32:09-474612 test_testing_2dtorus-10215 DEBUG test: Starting daemons. Nov 08 23:32:09-474826 test_testing_2dtorus-10215 DEBUG Will read 3809 hostkeys from file Nov 08 23:32:09-488279 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//0//gnunet-testing-configFUhkPf'. Nov 08 23:32:09-488921 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-488967 test_testing_2dtorus-10215 DEBUG Peer FC74 FSM is in phase 1. Nov 08 23:32:09-489003 test_testing_2dtorus-10215 DEBUG Hostkey (0/16) created for peer `FC74' Hostkeys created Progress: [0%Nov 08 23:32:09-489102 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//1//gnunet-testing-configK203xN'. Nov 08 23:32:09-489606 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-489644 test_testing_2dtorus-10215 DEBUG Peer KJI3 FSM is in phase 1. Nov 08 23:32:09-489676 test_testing_2dtorus-10215 DEBUG Hostkey (1/16) created for peer `KJI3' .Nov 08 23:32:09-489764 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//2//gnunet-testing-configaPUSgl'. Nov 08 23:32:09-490274 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-490310 test_testing_2dtorus-10215 DEBUG Peer 9P6V FSM is in phase 1. Nov 08 23:32:09-490341 test_testing_2dtorus-10215 DEBUG Hostkey (2/16) created for peer `9P6V' .Nov 08 23:32:09-490439 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//3//gnunet-testing-confignw5MZS'. Nov 08 23:32:09-490912 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-490930 test_testing_2dtorus-10215 DEBUG Peer 4Q99 FSM is in phase 1. Nov 08 23:32:09-490944 test_testing_2dtorus-10215 DEBUG Hostkey (3/16) created for peer `4Q99' .Nov 08 23:32:09-490999 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//4//gnunet-testing-config5AILIq'. Nov 08 23:32:09-491449 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-491465 test_testing_2dtorus-10215 DEBUG Peer 2UVH FSM is in phase 1. Nov 08 23:32:09-491486 test_testing_2dtorus-10215 DEBUG Hostkey (4/16) created for peer `2UVH' 25%Nov 08 23:32:09-491540 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//5//gnunet-testing-configgjvOrY'. Nov 08 23:32:09-492047 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-492066 test_testing_2dtorus-10215 DEBUG Peer 2GDS FSM is in phase 1. Nov 08 23:32:09-492079 test_testing_2dtorus-10215 DEBUG Hostkey (5/16) created for peer `2GDS' .Nov 08 23:32:09-492134 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//6//gnunet-testing-configw3ZVaw'. Nov 08 23:32:09-492589 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-492605 test_testing_2dtorus-10215 DEBUG Peer GHT1 FSM is in phase 1. Nov 08 23:32:09-492626 test_testing_2dtorus-10215 DEBUG Hostkey (6/16) created for peer `GHT1' .Nov 08 23:32:09-492681 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//7//gnunet-testing-configNLL7T3'. Nov 08 23:32:09-493164 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-493183 test_testing_2dtorus-10215 DEBUG Peer OH4T FSM is in phase 1. Nov 08 23:32:09-493196 test_testing_2dtorus-10215 DEBUG Hostkey (7/16) created for peer `OH4T' .Nov 08 23:32:09-493251 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//8//gnunet-testing-configy7YnDB'. Nov 08 23:32:09-493757 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-493776 test_testing_2dtorus-10215 DEBUG Peer 2TTQ FSM is in phase 1. Nov 08 23:32:09-493789 test_testing_2dtorus-10215 DEBUG Hostkey (8/16) created for peer `2TTQ' 50%Nov 08 23:32:09-493844 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//9//gnunet-testing-configMgLIm9'. Nov 08 23:32:09-494317 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-494337 test_testing_2dtorus-10215 DEBUG Peer B3CP FSM is in phase 1. Nov 08 23:32:09-494350 test_testing_2dtorus-10215 DEBUG Hostkey (9/16) created for peer `B3CP' .Nov 08 23:32:09-494436 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//10//gnunet-testing-configO0975G'. Nov 08 23:32:09-494915 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-494933 test_testing_2dtorus-10215 DEBUG Peer KPST FSM is in phase 1. Nov 08 23:32:09-494947 test_testing_2dtorus-10215 DEBUG Hostkey (10/16) created for peer `KPST' .Nov 08 23:32:09-495000 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//11//gnunet-testing-configQW2BPe'. Nov 08 23:32:09-495458 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-495474 test_testing_2dtorus-10215 DEBUG Peer JP32 FSM is in phase 1. Nov 08 23:32:09-495495 test_testing_2dtorus-10215 DEBUG Hostkey (11/16) created for peer `JP32' .Nov 08 23:32:09-495547 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//12//gnunet-testing-configmIcazM'. Nov 08 23:32:09-496044 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-496063 test_testing_2dtorus-10215 DEBUG Peer BMEP FSM is in phase 1. Nov 08 23:32:09-496076 test_testing_2dtorus-10215 DEBUG Hostkey (12/16) created for peer `BMEP' 75%Nov 08 23:32:09-496129 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//13//gnunet-testing-configcdUMik'. Nov 08 23:32:09-496610 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-496629 test_testing_2dtorus-10215 DEBUG Peer CHLG FSM is in phase 1. Nov 08 23:32:09-496642 test_testing_2dtorus-10215 DEBUG Hostkey (13/16) created for peer `CHLG' .Nov 08 23:32:09-496697 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//14//gnunet-testing-configlgZt2R'. Nov 08 23:32:09-497160 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-497178 test_testing_2dtorus-10215 DEBUG Peer UOOE FSM is in phase 1. Nov 08 23:32:09-497191 test_testing_2dtorus-10215 DEBUG Hostkey (14/16) created for peer `UOOE' .Nov 08 23:32:09-497244 test_testing_2dtorus-10215 DEBUG Setting up peer with configuration file `/tmp/test_testing_small//15//gnunet-testing-configHkkfMp'. Nov 08 23:32:09-497750 test_testing_2dtorus-10215 DEBUG No need to copy configuration file since we are running locally. Nov 08 23:32:09-497769 test_testing_2dtorus-10215 DEBUG Peer 9393 FSM is in phase 1. Nov 08 23:32:09-497783 test_testing_2dtorus-10215 DEBUG Hostkey (15/16) created for peer `9393' .100%] Nov 08 23:32:09-497812 test_testing_2dtorus-10215 DEBUG Creating 2d torus topology Nov 08 23:32:09-497833 test_testing_2dtorus-10215 DEBUG Connecting nodes in 2d torus topology: 4 rows 4 columns Nov 08 23:32:09-497847 test_testing_2dtorus-10215 DEBUG Connecting peer 0 to peer 1 Nov 08 23:32:09-497865 test_testing_2dtorus-10215 DEBUG Connecting peer 0 to peer 12 Nov 08 23:32:09-497880 test_testing_2dtorus-10215 DEBUG Connecting peer 1 to peer 2 Nov 08 23:32:09-497894 test_testing_2dtorus-10215 DEBUG Connecting peer 1 to peer 13 Nov 08 23:32:09-497908 test_testing_2dtorus-10215 DEBUG Connecting peer 2 to peer 3 Nov 08 23:32:09-497922 test_testing_2dtorus-10215 DEBUG Connecting peer 2 to peer 14 Nov 08 23:32:09-497937 test_testing_2dtorus-10215 DEBUG Connecting peer 3 to peer 0 Nov 08 23:32:09-497949 test_testing_2dtorus-10215 DEBUG Connecting peer 3 to peer 15 Nov 08 23:32:09-497960 test_testing_2dtorus-10215 DEBUG Connecting peer 4 to peer 5 Nov 08 23:32:09-497972 test_testing_2dtorus-10215 DEBUG Connecting peer 4 to peer 0 Nov 08 23:32:09-497983 test_testing_2dtorus-10215 DEBUG Connecting peer 5 to peer 6 Nov 08 23:32:09-497997 test_testing_2dtorus-10215 DEBUG Connecting peer 5 to peer 1 Nov 08 23:32:09-498011 test_testing_2dtorus-10215 DEBUG Connecting peer 6 to peer 7 Nov 08 23:32:09-498025 test_testing_2dtorus-10215 DEBUG Connecting peer 6 to peer 2 Nov 08 23:32:09-498039 test_testing_2dtorus-10215 DEBUG Connecting peer 7 to peer 4 Nov 08 23:32:09-498053 test_testing_2dtorus-10215 DEBUG Connecting peer 7 to peer 3 Nov 08 23:32:09-498067 test_testing_2dtorus-10215 DEBUG Connecting peer 8 to peer 9 Nov 08 23:32:09-498081 test_testing_2dtorus-10215 DEBUG Connecting peer 8 to peer 4 Nov 08 23:32:09-498095 test_testing_2dtorus-10215 DEBUG Connecting peer 9 to peer 10 Nov 08 23:32:09-498109 test_testing_2dtorus-10215 DEBUG Connecting peer 9 to peer 5 Nov 08 23:32:09-498123 test_testing_2dtorus-10215 DEBUG Connecting peer 10 to peer 11 Nov 08 23:32:09-498134 test_testing_2dtorus-10215 DEBUG Connecting peer 10 to peer 6 Nov 08 23:32:09-498146 test_testing_2dtorus-10215 DEBUG Connecting peer 11 to peer 8 Nov 08 23:32:09-498160 test_testing_2dtorus-10215 DEBUG Connecting peer 11 to peer 7 Nov 08 23:32:09-498173 test_testing_2dtorus-10215 DEBUG Connecting peer 12 to peer 13 Nov 08 23:32:09-498187 test_testing_2dtorus-10215 DEBUG Connecting peer 12 to peer 8 Nov 08 23:32:09-498201 test_testing_2dtorus-10215 DEBUG Connecting peer 13 to peer 14 Nov 08 23:32:09-498215 test_testing_2dtorus-10215 DEBUG Connecting peer 13 to peer 9 Nov 08 23:32:09-498229 test_testing_2dtorus-10215 DEBUG Connecting peer 14 to peer 15 Nov 08 23:32:09-498243 test_testing_2dtorus-10215 DEBUG Connecting peer 14 to peer 10 Nov 08 23:32:09-498257 test_testing_2dtorus-10215 DEBUG Connecting peer 15 to peer 12 Nov 08 23:32:09-498271 test_testing_2dtorus-10215 DEBUG Connecting peer 15 to peer 11 Nov 08 23:32:09-498287 test_testing_2dtorus-10215 DEBUG Topology set up, have 64 expected connections, now starting peers! Nov 08 23:32:09-498332 test_testing_2dtorus-10215 DEBUG Peer FC74 FSM is in phase 3. Nov 08 23:32:09-498350 test_testing_2dtorus-10215 DEBUG Peer KJI3 FSM is in phase 3. Nov 08 23:32:09-498398 test_testing_2dtorus-10215 DEBUG Peer 9P6V FSM is in phase 3. Nov 08 23:32:09-498418 test_testing_2dtorus-10215 DEBUG Peer 4Q99 FSM is in phase 3. Nov 08 23:32:09-498435 test_testing_2dtorus-10215 DEBUG Peer 2UVH FSM is in phase 3. Nov 08 23:32:09-498452 test_testing_2dtorus-10215 DEBUG Peer 2GDS FSM is in phase 3. Nov 08 23:32:09-498468 test_testing_2dtorus-10215 DEBUG Peer GHT1 FSM is in phase 3. Nov 08 23:32:09-498485 test_testing_2dtorus-10215 DEBUG Peer OH4T FSM is in phase 3. Nov 08 23:32:09-498502 test_testing_2dtorus-10215 DEBUG Peer 2TTQ FSM is in phase 3. Nov 08 23:32:09-498519 test_testing_2dtorus-10215 DEBUG Peer B3CP FSM is in phase 3. Nov 08 23:32:09-498536 test_testing_2dtorus-10215 DEBUG Peer KPST FSM is in phase 3. Nov 08 23:32:09-498553 test_testing_2dtorus-10215 DEBUG Peer JP32 FSM is in phase 3. Nov 08 23:32:09-498569 test_testing_2dtorus-10215 DEBUG Peer BMEP FSM is in phase 3. Nov 08 23:32:09-498586 test_testing_2dtorus-10215 DEBUG Peer CHLG FSM is in phase 3. Nov 08 23:32:09-498603 test_testing_2dtorus-10215 DEBUG Peer UOOE FSM is in phase 3. Nov 08 23:32:09-498631 test_testing_2dtorus-10215 DEBUG Peer 9393 FSM is in phase 3. Nov 08 23:32:09-698496 test_testing_2dtorus-10215 DEBUG Peer 9393 FSM is in phase 3. Nov 08 23:32:09-698576 test_testing_2dtorus-10215 DEBUG Peer UOOE FSM is in phase 3. Nov 08 23:32:09-698607 test_testing_2dtorus-10215 DEBUG Peer CHLG FSM is in phase 3. Nov 08 23:32:09-698636 test_testing_2dtorus-10215 DEBUG Peer BMEP FSM is in phase 3. Nov 08 23:32:09-698664 test_testing_2dtorus-10215 DEBUG Peer JP32 FSM is in phase 3. Nov 08 23:32:09-698692 test_testing_2dtorus-10215 DEBUG Peer KPST FSM is in phase 3. Nov 08 23:32:09-698721 test_testing_2dtorus-10215 DEBUG Peer B3CP FSM is in phase 4. Nov 08 23:32:09-698763 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//9//gnunet-testing-configMgLIm9 -L DEBUG -s'. Nov 08 23:32:09-699921 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:09-700015 test_testing_2dtorus-10215 DEBUG Peer 2TTQ FSM is in phase 4. Nov 08 23:32:09-700089 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//8//gnunet-testing-configy7YnDB -L DEBUG -s'. Nov 08 23:32:09-700772 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:09-700813 test_testing_2dtorus-10215 DEBUG Peer OH4T FSM is in phase 4. Nov 08 23:32:09-700843 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//7//gnunet-testing-configNLL7T3 -L DEBUG -s'. Nov 08 23:32:09-710537 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:09-710560 test_testing_2dtorus-10215 DEBUG Peer GHT1 FSM is in phase 4. Nov 08 23:32:09-710575 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//6//gnunet-testing-configw3ZVaw -L DEBUG -s'. Nov 08 23:32:09-713660 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:09-713682 test_testing_2dtorus-10215 DEBUG Peer 2GDS FSM is in phase 4. Nov 08 23:32:09-713697 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//5//gnunet-testing-configgjvOrY -L DEBUG -s'. Nov 08 23:32:09-723648 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:09-723677 test_testing_2dtorus-10215 DEBUG Peer 2UVH FSM is in phase 4. Nov 08 23:32:09-723692 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//4//gnunet-testing-config5AILIq -L DEBUG -s'. Nov 08 23:32:09-729925 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:09-729951 test_testing_2dtorus-10215 DEBUG Peer 4Q99 FSM is in phase 4. Nov 08 23:32:09-729966 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//3//gnunet-testing-confignw5MZS -L DEBUG -s'. Nov 08 23:32:09-733336 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:09-733358 test_testing_2dtorus-10215 DEBUG Peer 9P6V FSM is in phase 4. Nov 08 23:32:09-733374 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//2//gnunet-testing-configaPUSgl -L DEBUG -s'. Nov 08 23:32:09-743522 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:09-743548 test_testing_2dtorus-10215 DEBUG Peer KJI3 FSM is in phase 4. Nov 08 23:32:09-743563 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//1//gnunet-testing-configK203xN -L DEBUG -s'. Nov 08 23:32:09-758532 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:09-758565 test_testing_2dtorus-10215 DEBUG Peer FC74 FSM is in phase 4. Nov 08 23:32:09-758580 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//0//gnunet-testing-configFUhkPf -L DEBUG -s'. Nov 08 23:32:09-768973 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:09-898694 test_testing_2dtorus-10215 DEBUG Peer KPST FSM is in phase 3. Nov 08 23:32:09-898730 test_testing_2dtorus-10215 DEBUG Peer JP32 FSM is in phase 3. Nov 08 23:32:09-898746 test_testing_2dtorus-10215 DEBUG Peer BMEP FSM is in phase 3. Nov 08 23:32:09-898760 test_testing_2dtorus-10215 DEBUG Peer CHLG FSM is in phase 3. Nov 08 23:32:09-898775 test_testing_2dtorus-10215 DEBUG Peer UOOE FSM is in phase 3. Nov 08 23:32:09-898789 test_testing_2dtorus-10215 DEBUG Peer 9393 FSM is in phase 3. Nov 08 23:32:09-899872 test_testing_2dtorus-10215 DEBUG Peer B3CP FSM is in phase 5. Nov 08 23:32:09-899921 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:09-899934 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:09-899965 test_testing_2dtorus-10215 DEBUG Connected to transport service `B3CP', getting HELLO Nov 08 23:32:09-900026 test_testing_2dtorus-10215 DEBUG Started daemon 1 out of 16 Peers started Progress: [0%Nov 08 23:32:09-900057 test_testing_2dtorus-10215 DEBUG Peer 2TTQ FSM is in phase 5. Nov 08 23:32:09-900082 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:09-900093 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:09-900107 test_testing_2dtorus-10215 DEBUG Connected to transport service `2TTQ', getting HELLO Nov 08 23:32:09-900162 test_testing_2dtorus-10215 DEBUG Started daemon 2 out of 16 .Nov 08 23:32:09-909527 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer B3CP Nov 08 23:32:09-912433 test_testing_2dtorus-10215 DEBUG Peer OH4T FSM is in phase 5. Nov 08 23:32:09-912473 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:09-912487 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:09-912503 test_testing_2dtorus-10215 DEBUG Connected to transport service `OH4T', getting HELLO Nov 08 23:32:09-912545 test_testing_2dtorus-10215 DEBUG Started daemon 3 out of 16 .Nov 08 23:32:09-913648 test_testing_2dtorus-10215 DEBUG Peer GHT1 FSM is in phase 5. Nov 08 23:32:09-913679 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:09-913692 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:09-913707 test_testing_2dtorus-10215 DEBUG Connected to transport service `GHT1', getting HELLO Nov 08 23:32:09-913744 test_testing_2dtorus-10215 DEBUG Started daemon 4 out of 16 .Nov 08 23:32:09-923859 test_testing_2dtorus-10215 DEBUG Peer 2GDS FSM is in phase 5. Nov 08 23:32:09-923906 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:09-923921 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:09-923940 test_testing_2dtorus-10215 DEBUG Connected to transport service `2GDS', getting HELLO Nov 08 23:32:09-923986 test_testing_2dtorus-10215 DEBUG Started daemon 5 out of 16 25%Nov 08 23:32:09-927514 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer GHT1 Nov 08 23:32:09-929613 test_testing_2dtorus-10215 DEBUG Peer 2UVH FSM is in phase 5. Nov 08 23:32:09-929650 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:09-929664 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:09-929681 test_testing_2dtorus-10215 DEBUG Connected to transport service `2UVH', getting HELLO Nov 08 23:32:09-929724 test_testing_2dtorus-10215 DEBUG Started daemon 6 out of 16 .Nov 08 23:32:09-932495 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer 2UVH Nov 08 23:32:09-933591 test_testing_2dtorus-10215 DEBUG Peer 4Q99 FSM is in phase 5. Nov 08 23:32:09-933626 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:09-933641 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:09-933658 test_testing_2dtorus-10215 DEBUG Connected to transport service `4Q99', getting HELLO Nov 08 23:32:09-933700 test_testing_2dtorus-10215 DEBUG Started daemon 7 out of 16 .Nov 08 23:32:09-943824 test_testing_2dtorus-10215 DEBUG Peer 9P6V FSM is in phase 5. Nov 08 23:32:09-943875 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:09-943890 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:09-943908 test_testing_2dtorus-10215 DEBUG Connected to transport service `9P6V', getting HELLO Nov 08 23:32:09-943955 test_testing_2dtorus-10215 DEBUG Started daemon 8 out of 16 .Nov 08 23:32:09-950343 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer 2TTQ Nov 08 23:32:09-958412 test_testing_2dtorus-10215 DEBUG Peer KJI3 FSM is in phase 5. Nov 08 23:32:09-958456 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:09-958470 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:09-958487 test_testing_2dtorus-10215 DEBUG Connected to transport service `KJI3', getting HELLO Nov 08 23:32:09-958531 test_testing_2dtorus-10215 DEBUG Started daemon 9 out of 16 50%Nov 08 23:32:09-968655 test_testing_2dtorus-10215 DEBUG Peer FC74 FSM is in phase 5. Nov 08 23:32:09-968703 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:09-968717 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:09-968735 test_testing_2dtorus-10215 DEBUG Connected to transport service `FC74', getting HELLO Nov 08 23:32:09-968781 test_testing_2dtorus-10215 DEBUG Started daemon 10 out of 16 .Nov 08 23:32:09-970158 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer OH4T Nov 08 23:32:09-995450 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer 2GDS Nov 08 23:32:10-003619 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer FC74 Nov 08 23:32:10-005911 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer 9P6V Nov 08 23:32:10-005993 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer KJI3 Nov 08 23:32:10-019262 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer 4Q99 Nov 08 23:32:10-098442 test_testing_2dtorus-10215 DEBUG Peer 9393 FSM is in phase 4. Nov 08 23:32:10-098501 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//15//gnunet-testing-configHkkfMp -L DEBUG -s'. Nov 08 23:32:10-110254 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:10-110300 test_testing_2dtorus-10215 DEBUG Peer UOOE FSM is in phase 4. Nov 08 23:32:10-110332 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//14//gnunet-testing-configlgZt2R -L DEBUG -s'. Nov 08 23:32:10-111345 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:10-111410 test_testing_2dtorus-10215 DEBUG Peer CHLG FSM is in phase 4. Nov 08 23:32:10-111441 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//13//gnunet-testing-configcdUMik -L DEBUG -s'. Nov 08 23:32:10-120220 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:10-120242 test_testing_2dtorus-10215 DEBUG Peer BMEP FSM is in phase 4. Nov 08 23:32:10-120256 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//12//gnunet-testing-configmIcazM -L DEBUG -s'. Nov 08 23:32:10-125515 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:10-125537 test_testing_2dtorus-10215 DEBUG Peer JP32 FSM is in phase 4. Nov 08 23:32:10-125553 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//11//gnunet-testing-configQW2BPe -L DEBUG -s'. Nov 08 23:32:10-130519 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:10-130541 test_testing_2dtorus-10215 DEBUG Peer KPST FSM is in phase 4. Nov 08 23:32:10-130556 test_testing_2dtorus-10215 DEBUG Starting `gnunet-arm', with command `gnunet-arm -c /tmp/test_testing_small//10//gnunet-testing-configO0975G -L DEBUG -s'. Nov 08 23:32:10-134527 test_testing_2dtorus-10215 DEBUG Started `gnunet-arm', waiting for `gnunet-service-core' to be up. Nov 08 23:32:10-310810 test_testing_2dtorus-10215 DEBUG Peer 9393 FSM is in phase 5. Nov 08 23:32:10-310888 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:10-310916 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:10-310951 test_testing_2dtorus-10215 DEBUG Connected to transport service `9393', getting HELLO Nov 08 23:32:10-311044 test_testing_2dtorus-10215 DEBUG Started daemon 11 out of 16 .Nov 08 23:32:10-311092 test_testing_2dtorus-10215 DEBUG Peer UOOE FSM is in phase 5. Nov 08 23:32:10-311130 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:10-311154 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:10-311182 test_testing_2dtorus-10215 DEBUG Connected to transport service `UOOE', getting HELLO Nov 08 23:32:10-311275 test_testing_2dtorus-10215 DEBUG Started daemon 12 out of 16 .Nov 08 23:32:10-311957 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer 9393 Nov 08 23:32:10-313580 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer UOOE Nov 08 23:32:10-320712 test_testing_2dtorus-10215 DEBUG Peer CHLG FSM is in phase 5. Nov 08 23:32:10-320765 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:10-320791 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:10-320822 test_testing_2dtorus-10215 DEBUG Connected to transport service `CHLG', getting HELLO Nov 08 23:32:10-320913 test_testing_2dtorus-10215 DEBUG Started daemon 13 out of 16 75%Nov 08 23:32:10-324678 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer CHLG Nov 08 23:32:10-325808 test_testing_2dtorus-10215 DEBUG Peer BMEP FSM is in phase 5. Nov 08 23:32:10-325857 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:10-325881 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:10-325911 test_testing_2dtorus-10215 DEBUG Connected to transport service `BMEP', getting HELLO Nov 08 23:32:10-325992 test_testing_2dtorus-10215 DEBUG Started daemon 14 out of 16 .Nov 08 23:32:10-326719 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer BMEP Nov 08 23:32:10-330849 test_testing_2dtorus-10215 DEBUG Peer JP32 FSM is in phase 5. Nov 08 23:32:10-330901 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:10-330926 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:10-330956 test_testing_2dtorus-10215 DEBUG Connected to transport service `JP32', getting HELLO Nov 08 23:32:10-331036 test_testing_2dtorus-10215 DEBUG Started daemon 15 out of 16 .Nov 08 23:32:10-331448 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer JP32 Nov 08 23:32:10-334578 test_testing_2dtorus-10215 DEBUG Peer KPST FSM is in phase 5. Nov 08 23:32:10-334630 test_testing_2dtorus-10215 DEBUG Successfully started `gnunet-arm'. Nov 08 23:32:10-334655 test_testing_2dtorus-10215 DEBUG Calling CORE_connect Nov 08 23:32:10-334686 test_testing_2dtorus-10215 DEBUG Connected to transport service `KPST', getting HELLO Nov 08 23:32:10-334768 test_testing_2dtorus-10215 DEBUG Started daemon 16 out of 16 .100%] Nov 08 23:32:10-334798 test_testing_2dtorus-10215 DEBUG All 16 daemons started, now connecting peers! Nov 08 23:32:10-334842 test_testing_2dtorus-10215 DEBUG Creating 2d torus CONNECT topology | ||||
| Steps To Reproduce | Unknown, sometime happens on my laptop, almost always happens on some buildbots (sparc, i386, freebsd), never happens on other buildbots, (powerpc, amd64) or my desktop. | ||||
| Additional Information | Problem 1: Nov 08 23:32:10-313580 test_testing_2dtorus-10215 DEBUG Skipping empty HELLO address of peer UOOE For every peer in the group. Problem 2: For some reason at reattempt connection the transport get hello is not triggered again. | ||||
| Tags | No tags attached. | ||||
|
|
Problem might be in nat.c code |
|
|
After fix in r18057 and activating kvm on builbot's VM, problem might actually be in performance. Changed timeout for slow_machines to 1h, to see if sparc can pass. |
|
|
Observe sparc buildbot with new test timeout, if now connections happen (even if multicast test fails), means it's fixed with just r18057. |
|
|
Sparcbot now does not completely fail (succeeds 46/64 connections). Even longer timeouts might be needed, after transport stops crashing. |
|
|
Timeout issue on slow buildbots. |
| Date Modified | Username | Field | Change |
|---|---|---|---|
| 2011-11-09 00:07 | Bart Polot | New Issue | |
| 2011-11-09 00:07 | Bart Polot | Status | new => assigned |
| 2011-11-09 00:07 | Bart Polot | Assigned To | => Bart Polot |
| 2011-11-09 17:15 | Bart Polot | Note Added: 0004894 | |
| 2011-11-12 01:56 | Bart Polot | Note Added: 0004903 | |
| 2011-11-12 01:58 | Bart Polot | Note Added: 0004904 | |
| 2011-11-12 01:58 | Bart Polot | Status | assigned => feedback |
| 2011-11-14 23:30 | Christian Grothoff | Target Version | => 0.9.0 |
| 2011-11-21 14:31 | Bart Polot | Note Added: 0004956 | |
| 2011-11-21 14:31 | Bart Polot | Status | feedback => assigned |
| 2011-11-24 17:15 | Christian Grothoff | Target Version | 0.9.0 => |
| 2011-12-01 09:57 | Christian Grothoff | Target Version | => 0.9.1 |
| 2011-12-23 10:59 | Christian Grothoff | Product Version | Git master => 0.9.0 |
| 2011-12-23 10:59 | Christian Grothoff | Target Version | 0.9.1 => |
| 2012-02-21 21:59 | Christian Grothoff | Target Version | => 0.9.3 |
| 2012-02-24 15:49 | Bart Polot | Note Added: 0005501 | |
| 2012-02-24 15:49 | Bart Polot | Status | assigned => resolved |
| 2012-02-24 15:49 | Bart Polot | Fixed in Version | => 0.9.2 |
| 2012-02-24 15:49 | Bart Polot | Resolution | open => fixed |
| 2012-02-24 20:40 | Christian Grothoff | Target Version | 0.9.3 => 0.9.2 |
| 2012-02-28 11:05 | Christian Grothoff | Status | resolved => closed |
