View Issue Details
| ID | Project | Category | View Status | Date Submitted | Last Update |
|---|---|---|---|---|---|
| 0002905 | GNUnet | obsolete | public | 2013-06-25 16:08 | 2024-05-03 13:49 |
| Reporter | Bart Polot | Assigned To | Matthias Wachs | ||
| Priority | normal | Severity | major | Reproducibility | always |
| Status | closed | Resolution | no change required | ||
| Product Version | Git master | ||||
| Target Version | 0.10.0 | Fixed in Version | 0.10.0 | ||
| Summary | 0002905: Ats/Transport ERROR breaks communication | ||||
| Description | Mesh testcase fails as the traffic goes A->B->C->D->E but E complains that is not directly connected to D to send the response. At boot time, there are a lot of messages like this: Jun 25 16:01:15-927905 transport-8448 ERROR Update for unknown address for peer `P565', plugin `tcp', session 0x1464ec0 id 0 Jun 25 16:01:15-927947 transport-8448 ERROR Assertion failed at ats_api_scheduling.c:1292. | ||||
| Steps To Reproduce | Run test_mesh2_small_forward. Still happens after a bootstrap/configure/make install. | ||||
| Additional Information | [bart@ranger ~/g/src/mesh]$ ./test_mesh2_small_forward 2>&1 | tee log Jun 25 16:01:14-488825 test_mesh2_small-8415 DEBUG Starting HELPER process `/tmp/gnunet/lib/gnunet/libexec/gnunet-helper-testbed' Jun 25 16:01:14-504162 test_mesh2_small-8415 DEBUG Got 3252 bytes from helper `/tmp/gnunet/lib/gnunet/libexec/gnunet-helper-testbed' ==8441== Memcheck, a memory error detector ==8442== Memcheck, a memory error detector ==8442== Copyright (C) 2002-2012, and GNU GPL'd, by Julian Seward et al. ==8442== Using Valgrind-3.8.1 and LibVEX; rerun with -h for copyright info ==8442== Command: /tmp/gnunet/lib/gnunet/libexec/gnunet-service-mesh-new -c /tmp/testbed3SWb0L/3/config ==8442== ==8439== Memcheck, a memory error detector ==8441== Copyright (C) 2002-2012, and GNU GPL'd, by Julian Seward et al. ==8441== Using Valgrind-3.8.1 and LibVEX; rerun with -h for copyright info ==8441== Command: /tmp/gnunet/lib/gnunet/libexec/gnunet-service-mesh-new -c /tmp/testbed3SWb0L/2/config ==8441== ==8444== Memcheck, a memory error detector ==8444== Copyright (C) 2002-2012, and GNU GPL'd, by Julian Seward et al. ==8444== Using Valgrind-3.8.1 and LibVEX; rerun with -h for copyright info ==8444== Command: /tmp/gnunet/lib/gnunet/libexec/gnunet-service-mesh-new -c /tmp/testbed3SWb0L/1/config ==8444== ==8439== Copyright (C) 2002-2012, and GNU GPL'd, by Julian Seward et al. ==8439== Using Valgrind-3.8.1 and LibVEX; rerun with -h for copyright info ==8439== Command: /tmp/gnunet/lib/gnunet/libexec/gnunet-service-mesh-new -c /tmp/testbed3SWb0L/0/config ==8439== ==8446== Memcheck, a memory error detector ==8446== Copyright (C) 2002-2012, and GNU GPL'd, by Julian Seward et al. ==8446== Using Valgrind-3.8.1 and LibVEX; rerun with -h for copyright info ==8446== Command: /tmp/gnunet/lib/gnunet/libexec/gnunet-service-mesh-new -c /tmp/testbed3SWb0L/4/config ==8446== Jun 25 16:01:14-844967 transport-8447 ERROR Update for unknown address for peer `AG2P', plugin `tcp', session 0x1d68210 id 0 Jun 25 16:01:14-845381 transport-8447 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:14-892288 transport-8447 ERROR Update for unknown address for peer `AG2P', plugin `tcp', session 0x1d68210 id 0 Jun 25 16:01:14-905699 transport-8447 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:14-944514 transport-8448 ERROR Update for unknown address for peer `5MBM', plugin `tcp', session 0x1462730 id 0 Jun 25 16:01:14-950628 transport-8448 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:14-951001 transport-8448 ERROR Update for unknown address for peer `5MBM', plugin `tcp', session 0x14637e0 id 0 Jun 25 16:01:14-951182 transport-8448 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:14-990834 transport-8448 ERROR Update for unknown address for peer `5MBM', plugin `tcp', session 0x14637e0 id 0 Jun 25 16:01:15-006817 transport-8448 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:15-105238 transport-8447 ERROR Update for unknown address for peer `AG2P', plugin `tcp', session 0x1d68a60 id 0 Jun 25 16:01:15-119399 transport-8447 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:15-927905 transport-8448 ERROR Update for unknown address for peer `P565', plugin `tcp', session 0x1464ec0 id 0 Jun 25 16:01:15-927947 transport-8448 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:15-927992 transport-8448 ERROR Update for unknown address for peer `P565', plugin `tcp', session 0x1464ec0 id 0 Jun 25 16:01:15-928006 transport-8448 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:15-929700 transport-8460 ERROR Update for unknown address for peer `AG2P', plugin `tcp', session 0x1f234d0 id 0 Jun 25 16:01:15-929800 transport-8460 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:15-936787 transport-8460 ERROR Update for unknown address for peer `AG2P', plugin `tcp', session 0x1f234d0 id 0 Jun 25 16:01:15-939104 transport-8460 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:15-971475 transport-8460 ERROR Update for unknown address for peer `AG2P', plugin `tcp', session 0x1f22fc0 id 0 Jun 25 16:01:15-971757 transport-8460 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:15-997988 transport-8448 ERROR Update for unknown address for peer `P565', plugin `tcp', session 0x1465a80 id 0 Jun 25 16:01:15-998270 transport-8448 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:16-513763 transport-8460 ERROR Update for unknown address for peer `HT3H', plugin `tcp', session 0x1f233f0 id 0 Jun 25 16:01:16-513804 transport-8460 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:16-513848 transport-8460 ERROR Update for unknown address for peer `HT3H', plugin `tcp', session 0x1f233f0 id 0 Jun 25 16:01:16-513862 transport-8460 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:16-515617 transport-8456 ERROR Update for unknown address for peer `P565', plugin `tcp', session 0x1078370 id 0 Jun 25 16:01:16-515714 transport-8456 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:16-522706 transport-8456 ERROR Update for unknown address for peer `P565', plugin `tcp', session 0x1078370 id 0 Jun 25 16:01:16-527725 transport-8456 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:16-553918 transport-8456 ERROR Update for unknown address for peer `P565', plugin `tcp', session 0x1077ed0 id 0 Jun 25 16:01:16-560647 transport-8456 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:16-587833 transport-8460 ERROR Update for unknown address for peer `HT3H', plugin `tcp', session 0x1f24d20 id 0 Jun 25 16:01:16-588128 transport-8460 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:16-732977 mesh-8444 DEBUG starting to run Jun 25 16:01:17-106607 transport-8456 ERROR Update for unknown address for peer `RG08', plugin `tcp', session 0x1079680 id 0 Jun 25 16:01:17-106649 transport-8456 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:17-106695 transport-8456 ERROR Update for unknown address for peer `RG08', plugin `tcp', session 0x1079680 id 0 Jun 25 16:01:17-106708 transport-8456 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:17-108438 transport-8449 ERROR Update for unknown address for peer `HT3H', plugin `tcp', session 0x1b45390 id 0 Jun 25 16:01:17-108536 transport-8449 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:17-115625 transport-8449 ERROR Update for unknown address for peer `HT3H', plugin `tcp', session 0x1b45390 id 0 Jun 25 16:01:17-118661 transport-8449 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:17-153046 transport-8449 ERROR Update for unknown address for peer `HT3H', plugin `tcp', session 0x1b44e40 id 0 Jun 25 16:01:17-153342 transport-8449 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:17-175172 transport-8456 ERROR Update for unknown address for peer `RG08', plugin `tcp', session 0x1079cf0 id 0 Jun 25 16:01:17-183948 transport-8456 ERROR Assertion failed at ats_api_scheduling.c:1292. Jun 25 16:01:17-731048 mesh2-api-8415 DEBUG GNUNET_MESH_connect() Jun 25 16:01:17-731104 mesh2-api-8415 DEBUG addr 0x8c39b0 Jun 25 16:01:17-731173 mesh2-api-8415 DEBUG port 1 Jun 25 16:01:17-731195 mesh2-api-8415 DEBUG Sending 8 bytes long message with 1 ports Jun 25 16:01:17-731213 mesh2-api-8415 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT Jun 25 16:01:17-731232 mesh2-api-8415 DEBUG queued Jun 25 16:01:17-731245 mesh2-api-8415 DEBUG calling ntfy tmt rdy for 8 bytes Jun 25 16:01:17-731263 mesh2-api-8415 DEBUG GNUNET_MESH_connect() END Jun 25 16:01:17-735116 mesh2-api-8415 DEBUG GNUNET_MESH_connect() Jun 25 16:01:17-740957 mesh2-api-8415 DEBUG addr 0x8d2ea0 Jun 25 16:01:17-741058 mesh2-api-8415 DEBUG port 1 Jun 25 16:01:17-741099 mesh2-api-8415 DEBUG Sending 8 bytes long message with 1 ports Jun 25 16:01:17-741138 mesh2-api-8415 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT Jun 25 16:01:17-741177 mesh2-api-8415 DEBUG queued Jun 25 16:01:17-741214 mesh2-api-8415 DEBUG calling ntfy tmt rdy for 8 bytes Jun 25 16:01:17-741254 mesh2-api-8415 DEBUG GNUNET_MESH_connect() END Jun 25 16:01:17-741320 mesh2-api-8415 DEBUG Jun 25 16:01:17-741376 mesh2-api-8415 DEBUG # Send packet() Buffer 8 Jun 25 16:01:17-741416 mesh2-api-8415 DEBUG # message internal Jun 25 16:01:17-741455 mesh2-api-8415 DEBUG # mesh traffic, type GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT Jun 25 16:01:17-741501 mesh2-api-8415 DEBUG # total size: 8 Jun 25 16:01:17-741540 mesh2-api-8415 DEBUG # nothing left to transmit Jun 25 16:01:17-741578 mesh2-api-8415 DEBUG # start receiving from service Jun 25 16:01:17-741620 mesh2-api-8415 DEBUG # Send packet() END Jun 25 16:01:17-742602 mesh2-api-8415 DEBUG GNUNET_MESH_connect() Jun 25 16:01:17-742647 mesh2-api-8415 DEBUG addr 0x8df480 Jun 25 16:01:17-743293 mesh2-api-8415 DEBUG port 1 Jun 25 16:01:17-743336 mesh2-api-8415 DEBUG Sending 8 bytes long message with 1 ports Jun 25 16:01:17-743376 mesh2-api-8415 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT Jun 25 16:01:17-743415 mesh2-api-8415 DEBUG queued Jun 25 16:01:17-743451 mesh2-api-8415 DEBUG calling ntfy tmt rdy for 8 bytes Jun 25 16:01:17-743971 mesh2-api-8415 DEBUG GNUNET_MESH_connect() END Jun 25 16:01:17-744974 mesh2-api-8415 DEBUG GNUNET_MESH_connect() Jun 25 16:01:17-752679 mesh2-api-8415 DEBUG addr 0x8ebb10 Jun 25 16:01:17-752793 mesh2-api-8415 DEBUG port 1 Jun 25 16:01:17-752835 mesh2-api-8415 DEBUG Sending 8 bytes long message with 1 ports Jun 25 16:01:17-752874 mesh2-api-8415 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT Jun 25 16:01:17-752913 mesh2-api-8415 DEBUG queued Jun 25 16:01:17-752950 mesh2-api-8415 DEBUG calling ntfy tmt rdy for 8 bytes Jun 25 16:01:17-752991 mesh2-api-8415 DEBUG GNUNET_MESH_connect() END Jun 25 16:01:17-753903 mesh2-api-8415 DEBUG GNUNET_MESH_connect() Jun 25 16:01:17-753969 mesh2-api-8415 DEBUG addr 0x8f8dc0 Jun 25 16:01:17-754029 mesh2-api-8415 DEBUG port 1 Jun 25 16:01:17-754069 mesh2-api-8415 DEBUG Sending 8 bytes long message with 1 ports Jun 25 16:01:17-754107 mesh2-api-8415 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT Jun 25 16:01:17-754149 mesh2-api-8415 DEBUG queued Jun 25 16:01:17-754186 mesh2-api-8415 DEBUG calling ntfy tmt rdy for 8 bytes Jun 25 16:01:17-754226 mesh2-api-8415 DEBUG GNUNET_MESH_connect() END Jun 25 16:01:17-755124 test_mesh2_small-8415 DEBUG test main Jun 25 16:01:17-756222 test_mesh2_small-8415 DEBUG requested peer ids Jun 25 16:01:17-764238 test_mesh2_small-8415 DEBUG id callback for 0 Jun 25 16:01:17-766061 test_mesh2_small-8415 DEBUG id: 5MBM Jun 25 16:01:17-766170 test_mesh2_small-8415 DEBUG id callback for 1 Jun 25 16:01:17-766312 test_mesh2_small-8415 DEBUG id: RG08 Jun 25 16:01:17-766354 test_mesh2_small-8415 DEBUG Got all IDs, starting test Jun 25 16:01:17-965490 mesh-8444 INFO Mesh for peer [AG2P] starting Jun 25 16:01:18-005505 mesh-8444 DEBUG adding path [1] to peer AG2P Jun 25 16:01:18-011744 mesh-8444 DEBUG Mesh service running Jun 25 16:01:18-089959 mesh-8444 DEBUG Core init Jun 25 16:01:18-146558 mesh-8444 DEBUG new client connected Jun 25 16:01:18-148849 mesh-8444 DEBUG client id 0 Jun 25 16:01:18-149965 mesh-8444 DEBUG client has 1 ports Jun 25 16:01:18-152334 mesh-8444 DEBUG port: 1 Jun 25 16:01:18-178256 mesh-8444 DEBUG new client processed Jun 25 16:01:18-767309 test_mesh2_small-8415 DEBUG test_task Jun 25 16:01:18-767354 test_mesh2_small-8415 DEBUG add peer 2 Jun 25 16:01:18-767368 test_mesh2_small-8415 DEBUG schedule timeout in TIMEOUT Jun 25 16:01:18-767393 mesh2-api-8415 DEBUG Creating new tunnel Jun 25 16:01:18-767408 mesh2-api-8415 DEBUG at 0x8fb620 Jun 25 16:01:18-767421 mesh2-api-8415 DEBUG number 80000000 Jun 25 16:01:18-767452 mesh2-api-8415 DEBUG Sending message to service: GNUNET_MESSAGE_TYPE_MESH_LOCAL_TUNNEL_CREATE Jun 25 16:01:18-767465 mesh2-api-8415 DEBUG queued Jun 25 16:01:18-767480 test_mesh2_small-8415 DEBUG Sending data initializer... Jun 25 16:01:18-767498 mesh2-api-8415 DEBUG MESH NOTIFY TRANSMIT READY Jun 25 16:01:18-767511 mesh2-api-8415 DEBUG on tunnel 80000000 Jun 25 16:01:18-767523 mesh2-api-8415 DEBUG to destination Jun 25 16:01:18-767535 mesh2-api-8415 DEBUG payload size 8 Jun 25 16:01:18-767549 mesh2-api-8415 DEBUG total size 88 Jun 25 16:01:19-620721 mesh-8439 DEBUG starting to run Jun 25 16:01:19-796191 mesh2-api-8415 DEBUG Jun 25 16:01:19-796233 mesh2-api-8415 DEBUG # Send packet() Buffer 8 Jun 25 16:01:19-796247 mesh2-api-8415 DEBUG # message internal Jun 25 16:01:19-796259 mesh2-api-8415 DEBUG # mesh traffic, type GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT Jun 25 16:01:19-796271 mesh2-api-8415 DEBUG # message internal Jun 25 16:01:19-796282 mesh2-api-8415 DEBUG # total size: 8 Jun 25 16:01:19-796293 mesh2-api-8415 DEBUG # message internal Jun 25 16:01:19-796306 mesh2-api-8415 DEBUG # next size: 76 Jun 25 16:01:19-796318 mesh2-api-8415 DEBUG # start receiving from service Jun 25 16:01:19-796331 mesh2-api-8415 DEBUG # Send packet() END Jun 25 16:01:19-796374 mesh2-api-8415 DEBUG Jun 25 16:01:19-796391 mesh2-api-8415 DEBUG # Send packet() Buffer 76 Jun 25 16:01:19-796403 mesh2-api-8415 DEBUG # message internal Jun 25 16:01:19-796414 mesh2-api-8415 DEBUG # mesh traffic, type GNUNET_MESSAGE_TYPE_MESH_LOCAL_TUNNEL_CREATE Jun 25 16:01:19-796430 mesh2-api-8415 DEBUG # total size: 76 Jun 25 16:01:19-796443 mesh2-api-8415 DEBUG # can't transmit any more Jun 25 16:01:19-796454 mesh2-api-8415 DEBUG # Send packet() END Jun 25 16:01:19-612916 mesh-8441 DEBUG starting to run Jun 25 16:01:19-809243 mesh2-api-8415 DEBUG Jun 25 16:01:19-809285 mesh2-api-8415 DEBUG # Send packet() Buffer 8 Jun 25 16:01:19-809299 mesh2-api-8415 DEBUG # message internal Jun 25 16:01:19-809311 mesh2-api-8415 DEBUG # mesh traffic, type GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT Jun 25 16:01:19-809323 mesh2-api-8415 DEBUG # total size: 8 Jun 25 16:01:19-809334 mesh2-api-8415 DEBUG # nothing left to transmit Jun 25 16:01:19-809344 mesh2-api-8415 DEBUG # start receiving from service Jun 25 16:01:19-809356 mesh2-api-8415 DEBUG # Send packet() END Jun 25 16:01:19-823765 mesh2-api-8415 DEBUG Jun 25 16:01:19-823799 mesh2-api-8415 DEBUG # Send packet() Buffer 8 Jun 25 16:01:19-823811 mesh2-api-8415 DEBUG # message internal Jun 25 16:01:19-823824 mesh2-api-8415 DEBUG # mesh traffic, type GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT Jun 25 16:01:19-823836 mesh2-api-8415 DEBUG # total size: 8 Jun 25 16:01:19-823847 mesh2-api-8415 DEBUG # nothing left to transmit Jun 25 16:01:19-823857 mesh2-api-8415 DEBUG # start receiving from service Jun 25 16:01:19-823869 mesh2-api-8415 DEBUG # Send packet() END Jun 25 16:01:19-823901 mesh2-api-8415 DEBUG Jun 25 16:01:19-823914 mesh2-api-8415 DEBUG # Send packet() Buffer 8 Jun 25 16:01:19-823961 mesh2-api-8415 DEBUG # message internal Jun 25 16:01:19-823974 mesh2-api-8415 DEBUG # mesh traffic, type GNUNET_MESSAGE_TYPE_MESH_LOCAL_CONNECT Jun 25 16:01:19-823986 mesh2-api-8415 DEBUG # total size: 8 Jun 25 16:01:19-823997 mesh2-api-8415 DEBUG # nothing left to transmit Jun 25 16:01:19-824007 mesh2-api-8415 DEBUG # start receiving from service Jun 25 16:01:19-824019 mesh2-api-8415 DEBUG # Send packet() END Jun 25 16:01:19-635953 mesh-8442 DEBUG starting to run Jun 25 16:01:19-657172 mesh-8446 DEBUG starting to run Jun 25 16:01:21-555863 mesh-8439 INFO Mesh for peer [5MBM] starting Jun 25 16:01:21-553558 mesh-8441 INFO Mesh for peer [P565] starting Jun 25 16:01:21-633321 mesh-8439 DEBUG adding path [1] to peer 5MBM Jun 25 16:01:21-636319 mesh-8441 DEBUG adding path [1] to peer P565 Jun 25 16:01:21-642215 mesh-8439 DEBUG Mesh service running Jun 25 16:01:21-648990 mesh-8441 DEBUG Mesh service running Jun 25 16:01:21-715496 mesh-8444 DEBUG adding path [2] to peer 5MBM Jun 25 16:01:21-724525 mesh-8444 DEBUG adding path [2] to peer P565 Jun 25 16:01:21-810995 mesh-8439 DEBUG Core init Jun 25 16:01:21-815167 mesh-8441 DEBUG Core init Jun 25 16:01:21-819518 mesh-8442 INFO Mesh for peer [HT3H] starting Jun 25 16:01:21-835683 mesh-8446 INFO Mesh for peer [RG08] starting Jun 25 16:01:21-889732 mesh-8442 DEBUG adding path [1] to peer HT3H Jun 25 16:01:21-901431 mesh-8442 DEBUG Mesh service running Jun 25 16:01:21-908235 mesh-8446 DEBUG adding path [1] to peer RG08 Jun 25 16:01:21-909243 mesh-8439 DEBUG adding path [2] to peer AG2P Jun 25 16:01:21-912542 mesh-8441 DEBUG adding path [2] to peer AG2P Jun 25 16:01:21-912628 mesh-8446 DEBUG Mesh service running Jun 25 16:01:21-976780 mesh-8439 DEBUG new client connected Jun 25 16:01:21-979562 mesh-8441 DEBUG new client connected Jun 25 16:01:21-981721 mesh-8439 DEBUG client id 0 Jun 25 16:01:21-982840 mesh-8439 DEBUG client has 1 ports Jun 25 16:01:21-984926 mesh-8441 DEBUG client id 0 Jun 25 16:01:21-988085 mesh-8441 DEBUG client has 1 ports Jun 25 16:01:21-988429 mesh-8439 DEBUG port: 1 Jun 25 16:01:21-990797 mesh-8441 DEBUG port: 1 Jun 25 16:01:22-029397 mesh-8441 DEBUG new client processed Jun 25 16:01:22-026372 mesh-8439 DEBUG new client processed Jun 25 16:01:22-049117 mesh-8442 DEBUG Core init Jun 25 16:01:22-059067 mesh-8439 DEBUG new tunnel requested Jun 25 16:01:22-060912 mesh-8439 DEBUG by client 0 Jun 25 16:01:22-064604 mesh-8439 DEBUG towards RG08 Jun 25 16:01:22-067488 mesh-8441 DEBUG client disconnected Jun 25 16:01:22-068936 mesh-8441 DEBUG ... searching Jun 25 16:01:22-068247 mesh-8446 DEBUG Core init Jun 25 16:01:22-074423 mesh-8441 DEBUG done! Jun 25 16:01:22-093150 mesh-8439 DEBUG CREATED TUNNEL 5MBM[0]:1 (80000000) Jun 25 16:01:22-113745 mesh-8439 DEBUG Starting DHT GET for peer RG08 Jun 25 16:01:22-138923 mesh-8441 DEBUG adding path [2] to peer HT3H Jun 25 16:01:22-145073 mesh-8439 DEBUG client disconnected Jun 25 16:01:22-146627 mesh-8439 DEBUG ... searching Jun 25 16:01:22-147866 mesh-8439 DEBUG done! Jun 25 16:01:22-182886 mesh-8442 DEBUG adding path [2] to peer P565 Jun 25 16:01:22-207155 mesh-8446 DEBUG adding path [2] to peer HT3H Jun 25 16:01:22-237856 mesh-8439 DEBUG Got results from DHT! Jun 25 16:01:22-239405 mesh-8439 DEBUG for RG08 Jun 25 16:01:22-241488 mesh-8439 DEBUG GET has 1 hops. Jun 25 16:01:22-243729 mesh-8439 DEBUG Adding from GET: AG2P. Jun 25 16:01:22-245807 mesh-8442 DEBUG new client connected Jun 25 16:01:22-245857 mesh-8439 DEBUG PUT has 3 hops. Jun 25 16:01:22-247861 mesh-8442 DEBUG client id 0 Jun 25 16:01:22-248724 mesh-8439 DEBUG Adding from PUT: P565. Jun 25 16:01:22-248821 mesh-8442 DEBUG client has 1 ports Jun 25 16:01:22-250116 mesh-8439 DEBUG Adding from PUT: HT3H. Jun 25 16:01:22-250476 mesh-8439 DEBUG Adding from PUT: RG08. Jun 25 16:01:22-251034 mesh-8442 DEBUG port: 1 Jun 25 16:01:22-251964 mesh-8439 DEBUG (first of GET: AG2P) Jun 25 16:01:22-253279 mesh-8439 DEBUG (first of PUT: RG08) Jun 25 16:01:22-254427 mesh-8439 DEBUG In total: 5 hops Jun 25 16:01:22-256302 mesh-8439 DEBUG 1: 5MBM Jun 25 16:01:22-256985 mesh-8439 DEBUG 2: AG2P Jun 25 16:01:22-257358 mesh-8439 DEBUG 4: P565 Jun 25 16:01:22-257702 mesh-8439 DEBUG 5: HT3H Jun 25 16:01:22-258056 mesh-8439 DEBUG 3: RG08 Jun 25 16:01:22-260698 mesh-8446 DEBUG new client connected Jun 25 16:01:22-263552 mesh-8439 DEBUG adding path [3] to peer P565 Jun 25 16:01:22-266191 mesh-8439 DEBUG adding path [4] to peer HT3H Jun 25 16:01:22-266631 mesh-8439 DEBUG adding path [5] to peer RG08 Jun 25 16:01:22-275246 mesh-8446 DEBUG client id 0 Jun 25 16:01:22-276256 mesh-8446 DEBUG client has 1 ports Jun 25 16:01:22-285178 mesh-8446 DEBUG port: 1 Jun 25 16:01:22-294133 mesh-8442 DEBUG new client processed Jun 25 16:01:22-306196 mesh-8446 DEBUG new client processed Jun 25 16:01:22-312190 mesh-8439 DEBUG * Queue send Jun 25 16:01:22-314868 mesh-8439 DEBUG * selecting message Jun 25 16:01:22-315294 mesh-8442 DEBUG client disconnected Jun 25 16:01:22-316618 mesh-8442 DEBUG ... searching Jun 25 16:01:22-317360 mesh-8439 DEBUG * GNUNET_MESSAGE_TYPE_MESH_PATH_CREATE Jun 25 16:01:22-318187 mesh-8442 DEBUG done! Jun 25 16:01:22-319081 mesh-8439 DEBUG * OK! Jun 25 16:01:22-320391 mesh-8439 DEBUG * not empty Jun 25 16:01:22-321785 mesh-8439 DEBUG * towards AG2P Jun 25 16:01:22-322976 mesh-8439 DEBUG * size ok Jun 25 16:01:22-324773 mesh-8439 DEBUG * path create Jun 25 16:01:22-326199 mesh-8439 DEBUG CREATE PATH sending... Jun 25 16:01:22-329743 mesh-8439 DEBUG CREATE PATH (336 bytes long) sent! Jun 25 16:01:22-333129 mesh-8446 DEBUG client disconnected Jun 25 16:01:22-333715 mesh-8439 DEBUG * selecting message Jun 25 16:01:22-334826 mesh-8439 DEBUG * nothing found Jun 25 16:01:22-336267 mesh-8439 DEBUG * Return 336 Jun 25 16:01:22-337817 mesh-8446 DEBUG ... searching Jun 25 16:01:22-338870 mesh-8446 DEBUG done! Jun 25 16:01:22-346891 mesh-8444 DEBUG Received a path create msg [AG2P] Jun 25 16:01:22-346958 mesh-8442 DEBUG adding path [2] to peer RG08 Jun 25 16:01:22-357733 mesh-8444 DEBUG path has 5 hops. Jun 25 16:01:22-359878 mesh-8444 DEBUG path is for tunnel 5MBM[0]. Jun 25 16:01:22-365355 mesh-8444 DEBUG Creating tunnel Jun 25 16:01:22-373778 mesh-8444 DEBUG nobuffer:0 Jun 25 16:01:22-375947 mesh-8444 DEBUG Creating PeerInfo for destination. Jun 25 16:01:22-378069 mesh-8444 DEBUG Creating path... Jun 25 16:01:22-381200 mesh-8444 DEBUG ... adding 5MBM Jun 25 16:01:22-382455 mesh-8444 DEBUG ... adding AG2P Jun 25 16:01:22-382993 mesh-8444 DEBUG ... adding P565 Jun 25 16:01:22-383268 mesh-8444 DEBUG ... adding HT3H Jun 25 16:01:22-383549 mesh-8444 DEBUG ... adding RG08 Jun 25 16:01:22-384608 mesh-8444 DEBUG Own position: 1 Jun 25 16:01:22-389484 mesh-8444 DEBUG shortening path by 1 Jun 25 16:01:22-391198 mesh-8444 DEBUG adding path [2] to peer P565 Jun 25 16:01:22-391810 mesh-8444 DEBUG shortening path by 1 Jun 25 16:01:22-392085 mesh-8444 DEBUG adding path [3] to peer HT3H Jun 25 16:01:22-393646 mesh-8444 DEBUG shortening path by 1 Jun 25 16:01:22-393920 mesh-8444 DEBUG adding path [4] to peer RG08 Jun 25 16:01:22-398922 mesh-8444 DEBUG Retransmitting. Jun 25 16:01:22-399757 mesh-8444 DEBUG shortening path by 1 Jun 25 16:01:22-400032 mesh-8444 DEBUG adding path [4] to peer RG08 Jun 25 16:01:22-403490 mesh-8444 DEBUG shortening path by 3 Jun 25 16:01:22-403753 mesh-8444 DEBUG adding path [2] to peer 5MBM Jun 25 16:01:22-427609 mesh-8444 DEBUG * Queue send Jun 25 16:01:22-428716 mesh-8444 DEBUG * selecting message Jun 25 16:01:22-430342 mesh-8444 DEBUG * GNUNET_MESSAGE_TYPE_MESH_PATH_CREATE Jun 25 16:01:22-431738 mesh-8444 DEBUG * OK! Jun 25 16:01:22-432858 mesh-8444 DEBUG * not empty Jun 25 16:01:22-433999 mesh-8444 DEBUG * towards P565 Jun 25 16:01:22-434980 mesh-8444 DEBUG * size ok Jun 25 16:01:22-436352 mesh-8444 DEBUG * path create Jun 25 16:01:22-437526 mesh-8444 DEBUG CREATE PATH sending... Jun 25 16:01:22-440487 mesh-8444 DEBUG CREATE PATH (336 bytes long) sent! Jun 25 16:01:22-443590 mesh-8444 DEBUG * selecting message Jun 25 16:01:22-444496 mesh-8444 DEBUG * nothing found Jun 25 16:01:22-445662 mesh-8444 DEBUG * Return 336 Jun 25 16:01:22-454510 mesh-8441 DEBUG Received a path create msg [P565] Jun 25 16:01:22-456063 mesh-8441 DEBUG path has 5 hops. Jun 25 16:01:22-458045 mesh-8441 DEBUG path is for tunnel 5MBM[0]. Jun 25 16:01:22-461878 mesh-8441 DEBUG Creating tunnel Jun 25 16:01:22-469083 mesh-8441 DEBUG nobuffer:0 Jun 25 16:01:22-471142 mesh-8441 DEBUG Creating PeerInfo for destination. Jun 25 16:01:22-473084 mesh-8441 DEBUG Creating PeerInfo for origin. Jun 25 16:01:22-474567 mesh-8441 DEBUG Creating path... Jun 25 16:01:22-476219 mesh-8441 DEBUG ... adding 5MBM Jun 25 16:01:22-477397 mesh-8441 DEBUG ... adding AG2P Jun 25 16:01:22-477630 mesh-8441 DEBUG ... adding P565 Jun 25 16:01:22-478115 mesh-8441 DEBUG ... adding HT3H Jun 25 16:01:22-478339 mesh-8441 DEBUG ... adding RG08 Jun 25 16:01:22-479335 mesh-8441 DEBUG Own position: 2 Jun 25 16:01:22-484916 mesh-8441 DEBUG shortening path by 2 Jun 25 16:01:22-486869 mesh-8441 DEBUG adding path [2] to peer HT3H Jun 25 16:01:22-487513 mesh-8441 DEBUG shortening path by 2 Jun 25 16:01:22-487761 mesh-8441 DEBUG adding path [3] to peer RG08 Jun 25 16:01:22-494743 mesh-8441 DEBUG Retransmitting. Jun 25 16:01:22-495583 mesh-8441 DEBUG shortening path by 2 Jun 25 16:01:22-495787 mesh-8441 DEBUG adding path [3] to peer RG08 Jun 25 16:01:22-499138 mesh-8441 DEBUG shortening path by 2 Jun 25 16:01:22-499369 mesh-8441 DEBUG adding path [3] to peer 5MBM Jun 25 16:01:22-522359 mesh-8441 DEBUG * Queue send Jun 25 16:01:22-523392 mesh-8441 DEBUG * selecting message Jun 25 16:01:22-524932 mesh-8441 DEBUG * GNUNET_MESSAGE_TYPE_MESH_PATH_CREATE Jun 25 16:01:22-526199 mesh-8441 DEBUG * OK! Jun 25 16:01:22-527250 mesh-8441 DEBUG * not empty Jun 25 16:01:22-528467 mesh-8441 DEBUG * towards HT3H Jun 25 16:01:22-529425 mesh-8441 DEBUG * size ok Jun 25 16:01:22-530808 mesh-8441 DEBUG * path create Jun 25 16:01:22-531960 mesh-8441 DEBUG CREATE PATH sending... Jun 25 16:01:22-534928 mesh-8441 DEBUG CREATE PATH (336 bytes long) sent! Jun 25 16:01:22-538096 mesh-8441 DEBUG * selecting message Jun 25 16:01:22-538941 mesh-8441 DEBUG * nothing found Jun 25 16:01:22-540106 mesh-8441 DEBUG * Return 336 Jun 25 16:01:22-548960 mesh-8442 DEBUG Received a path create msg [HT3H] Jun 25 16:01:22-550477 mesh-8442 DEBUG path has 5 hops. Jun 25 16:01:22-552346 mesh-8442 DEBUG path is for tunnel 5MBM[0]. Jun 25 16:01:22-555531 mesh-8442 DEBUG Creating tunnel Jun 25 16:01:22-561752 mesh-8442 DEBUG nobuffer:0 Jun 25 16:01:22-563996 mesh-8442 DEBUG Creating PeerInfo for origin. Jun 25 16:01:22-565478 mesh-8442 DEBUG Creating path... Jun 25 16:01:22-567135 mesh-8442 DEBUG ... adding 5MBM Jun 25 16:01:22-568303 mesh-8442 DEBUG ... adding AG2P Jun 25 16:01:22-568541 mesh-8442 DEBUG ... adding P565 Jun 25 16:01:22-568759 mesh-8442 DEBUG ... adding HT3H Jun 25 16:01:22-569241 mesh-8442 DEBUG ... adding RG08 Jun 25 16:01:22-570241 mesh-8442 DEBUG Own position: 3 Jun 25 16:01:22-575042 mesh-8442 DEBUG shortening path by 3 Jun 25 16:01:22-576755 mesh-8442 DEBUG adding path [2] to peer RG08 Jun 25 16:01:22-581985 mesh-8442 DEBUG Retransmitting. Jun 25 16:01:22-582848 mesh-8442 DEBUG shortening path by 3 Jun 25 16:01:22-583055 mesh-8442 DEBUG adding path [2] to peer RG08 Jun 25 16:01:22-585243 mesh-8442 DEBUG shortening path by 1 Jun 25 16:01:22-585466 mesh-8442 DEBUG adding path [4] to peer 5MBM Jun 25 16:01:22-610252 mesh-8442 DEBUG * Queue send Jun 25 16:01:22-611296 mesh-8442 DEBUG * selecting message Jun 25 16:01:22-612856 mesh-8442 DEBUG * GNUNET_MESSAGE_TYPE_MESH_PATH_CREATE Jun 25 16:01:22-614128 mesh-8442 DEBUG * OK! Jun 25 16:01:22-615180 mesh-8442 DEBUG * not empty Jun 25 16:01:22-616301 mesh-8442 DEBUG * towards RG08 Jun 25 16:01:22-617249 mesh-8442 DEBUG * size ok Jun 25 16:01:22-618641 mesh-8442 DEBUG * path create Jun 25 16:01:22-619792 mesh-8442 DEBUG CREATE PATH sending... Jun 25 16:01:22-622806 mesh-8442 DEBUG CREATE PATH (336 bytes long) sent! Jun 25 16:01:22-625872 mesh-8442 DEBUG * selecting message Jun 25 16:01:22-626745 mesh-8442 DEBUG * nothing found Jun 25 16:01:22-627933 mesh-8442 DEBUG * Return 336 Jun 25 16:01:22-636623 mesh-8446 DEBUG Received a path create msg [RG08] Jun 25 16:01:22-638200 mesh-8446 DEBUG path has 5 hops. Jun 25 16:01:22-640133 mesh-8446 DEBUG path is for tunnel 5MBM[0]. Jun 25 16:01:22-643280 mesh-8446 DEBUG Creating tunnel Jun 25 16:01:22-649427 mesh-8446 DEBUG nobuffer:0 Jun 25 16:01:22-651620 mesh-8446 DEBUG Creating PeerInfo for origin. Jun 25 16:01:22-653095 mesh-8446 DEBUG Creating path... Jun 25 16:01:22-654767 mesh-8446 DEBUG ... adding 5MBM Jun 25 16:01:22-655914 mesh-8446 DEBUG ... adding AG2P Jun 25 16:01:22-656152 mesh-8446 DEBUG ... adding P565 Jun 25 16:01:22-656379 mesh-8446 DEBUG ... adding HT3H Jun 25 16:01:22-656595 mesh-8446 DEBUG ... adding RG08 Jun 25 16:01:22-657861 mesh-8446 DEBUG Own position: 4 Jun 25 16:01:22-665290 mesh-8446 DEBUG It's for us! Jun 25 16:01:22-667370 mesh-8446 DEBUG adding path [5] to peer 5MBM Jun 25 16:01:22-690774 mesh2-api-8415 DEBUG Jun 25 16:01:22-690820 mesh2-api-8415 DEBUG Received a message: GNUNET_MESSAGE_TYPE_MESH_LOCAL_TUNNEL_CREATE Jun 25 16:01:22-690854 mesh2-api-8415 DEBUG Creating incoming tunnel B0000000 Jun 25 16:01:22-690871 mesh2-api-8415 DEBUG created tunnel 0x8f9020 Jun 25 16:01:22-690888 test_mesh2_small-8415 INFO Incoming tunnel from 5MBM to peer 4 Jun 25 16:01:22-690901 test_mesh2_small-8415 INFO ok: 1 Jun 25 16:01:22-690916 mesh2-api-8415 DEBUG User notified Jun 25 16:01:22-690929 mesh2-api-8415 DEBUG message processed Jun 25 16:01:22-705166 mesh-8446 DEBUG * Queue send Jun 25 16:01:22-706375 mesh-8446 DEBUG * selecting message Jun 25 16:01:22-708002 mesh-8446 DEBUG * GNUNET_MESSAGE_TYPE_MESH_PATH_ACK Jun 25 16:01:22-709264 mesh-8446 DEBUG * OK! Jun 25 16:01:22-710315 mesh-8446 DEBUG * not empty Jun 25 16:01:22-711442 mesh-8446 DEBUG * towards HT3H Jun 25 16:01:22-712393 mesh-8446 DEBUG * size ok Jun 25 16:01:22-713768 mesh-8446 DEBUG * path ack Jun 25 16:01:22-716461 mesh-8446 DEBUG PATH ACK sent! Jun 25 16:01:22-719246 mesh-8446 DEBUG * selecting message Jun 25 16:01:22-720085 mesh-8446 DEBUG * nothing found Jun 25 16:01:22-721283 mesh-8446 DEBUG * Return 140 Jun 25 16:01:22-727236 mesh-8442 DEBUG Received a path ACK msg [HT3H] Jun 25 16:01:22-729803 mesh-8442 DEBUG on tunnel 5MBM [0] Jun 25 16:01:22-731138 mesh-8442 DEBUG by peer RG08 Jun 25 16:01:22-732139 mesh-8442 DEBUG via peer RG08 Jun 25 16:01:22-732737 mesh-8442 DEBUG shortening path by 3 Jun 25 16:01:22-732936 mesh-8442 DEBUG adding path [2] to peer RG08 Jun 25 16:01:22-734558 mesh-8442 DEBUG not for us, retransmitting... Jun 25 16:01:22-737644 mesh-8442 DEBUG P565 IS NOT DIRECTLY CONNECTED Jun 25 16:01:22-738652 mesh-8442 DEBUG PATHS TO P565: Jun 25 16:01:22-739898 mesh-8442 ERROR Assertion failed at gnunet-service-mesh-new.c:1187. Jun 25 16:01:22-741104 mesh-8442 WARNING no direct connection to P565 | ||||
| Tags | No tags attached. | ||||
|
|
In fact, the traffic goes A->B->C->D->E and E->D, but it's D->C that fails. IDs are: "SYN": 5MBM -> AG2P -> P565 -> HT3H -> RG08 "SYN-ACK": RG08 -> HT3H --X P565 |
|
|
Apparently, it was a regression in mesh that just happened to surface after a svn up. |
| Date Modified | Username | Field | Change |
|---|---|---|---|
| 2013-06-25 16:08 | Bart Polot | New Issue | |
| 2013-06-25 16:08 | Bart Polot | Status | new => assigned |
| 2013-06-25 16:08 | Bart Polot | Assigned To | => Matthias Wachs |
| 2013-06-25 16:12 | Bart Polot | Note Added: 0007173 | |
| 2013-06-26 02:12 | Bart Polot | Note Added: 0007186 | |
| 2013-06-26 02:12 | Bart Polot | Status | assigned => resolved |
| 2013-06-26 02:12 | Bart Polot | Fixed in Version | => Git master |
| 2013-06-26 02:12 | Bart Polot | Resolution | open => no change required |
| 2013-06-26 09:51 | Christian Grothoff | Fixed in Version | Git master => 0.10.0 |
| 2013-06-26 09:51 | Christian Grothoff | Target Version | => 0.10.0 |
| 2013-12-24 20:54 | Christian Grothoff | Status | resolved => closed |
| 2024-05-03 13:49 | Christian Grothoff | Category | ATS service => obsolete |
