View Issue Details
| ID | Project | Category | View Status | Date Submitted | Last Update |
|---|---|---|---|---|---|
| 0002977 | GNUnet | core service | public | 2013-08-08 11:27 | 2013-08-11 21:43 |
| Reporter | Matthias Wachs | Assigned To | Christian Grothoff | ||
| Priority | none | Severity | minor | Reproducibility | random |
| Status | closed | Resolution | open | ||
| Product Version | Git master | ||||
| Target Version | Git master | Fixed in Version | Git master | ||
| Summary | 0002977: Ephemeral key message rejected | ||||
| Description | Every ~ minute Aug 08 11:20:32-512257 core-27103 WARNING Ephemeral key message rejected as its validity range does not match our system time (1375953632512 not in [1375955651454,1375999151454]). | ||||
| Additional Information | Is this an issue? | ||||
| Tags | No tags attached. | ||||
|
|
FYI: there is an active invalid peer with this generic CAKN id uses if hostkey was not deleted since crypto was changing |
|
|
Full log: mwachs@fulcrum:~/coding/gnunet/src/transport$ Aug 08 11:29:16-766698 transport-27322 INFO My identity is `CDTU' Aug 08 11:29:16-766938 transport-27322 INFO Starting transport plugins `tcp http_server http_client https_client https_server' Aug 08 11:29:16-766989 transport-27322 INFO Loading `tcp' transport plugin Aug 08 11:29:16-767014 transport-27322 INFO Loading `http_server' transport plugin Aug 08 11:29:16-767036 transport-27322 INFO Loading `http_client' transport plugin Aug 08 11:29:16-767053 transport-27322 INFO Loading `https_client' transport plugin Aug 08 11:29:16-767076 transport-27322 INFO Loading `https_server' transport plugin Aug 08 11:29:16-769072 transport-https_server-27322 INFO Using port 4433 Aug 08 11:29:16-774303 transport-http_server-27322 INFO Using port 1080 Aug 08 11:29:16-774628 transport-tcp-27322 INFO Binding to address `0.0.0.0:2086' Aug 08 11:29:16-774653 transport-tcp-27322 INFO Binding to address `[::]:2086' Aug 08 11:29:16-774718 transport-tcp-27322 INFO TCP transport listening on port 2086 Aug 08 11:29:16-774961 transport-tcp-27322 INFO NAT notification to add address `131.159.20.52:2086' Aug 08 11:29:16-775006 transport-tcp-27322 INFO NAT notification to add address `[2001:4ca0:2001:11:226:b9ff:fe7d:84ed]:2086' Aug 08 11:29:17-648271 transport-27322 INFO We are now connected to peer `KFNI' and 1 peers in total Aug 08 11:29:18-113231 transport-27322 INFO We are now connected to peer `HIJN' and 2 peers in total Aug 08 11:29:19-009986 fs-27321 WARNING External protocol violation detected at gnunet-service-fs_pr.c:1644. Aug 08 11:29:21-193877 transport-27322 INFO We are now connected to peer `CAKN' and 3 peers in total Aug 08 11:29:36-417499 transport-27322 INFO Peer `CAKN' disconnected and we are connected to 2 peers Aug 08 11:29:46-573715 transport-27322 INFO We are now connected to peer `8D01' and 3 peers in total Aug 08 11:29:47-056648 transport-27322 INFO We are now connected to peer `CAKN' and 4 peers in total Aug 08 11:29:59-443275 transport-27322 INFO We are now connected to peer `BVT8' and 5 peers in total Aug 08 11:30:01-834758 nse-27319 WARNING External protocol violation detected at gnunet-service-nse.c:960. Aug 08 11:30:01-834840 nse-27319 WARNING External protocol violation detected at gnunet-service-nse.c:1161. Aug 08 11:30:02-027723 nse-27319 WARNING External protocol violation detected at gnunet-service-nse.c:960. Aug 08 11:30:02-027757 nse-27319 WARNING External protocol violation detected at gnunet-service-nse.c:1161. Aug 08 11:30:02-068406 transport-27322 INFO Peer `CAKN' disconnected and we are connected to 4 peers Aug 08 11:30:02-070805 transport-27322 INFO We are now connected to peer `CAKN' and 5 peers in total Aug 08 11:30:04-455113 nse-27319 WARNING External protocol violation detected at gnunet-service-nse.c:960. Aug 08 11:30:04-455142 nse-27319 WARNING External protocol violation detected at gnunet-service-nse.c:1161. Aug 08 11:30:04-554358 nse-27319 WARNING External protocol violation detected at gnunet-service-nse.c:960. Aug 08 11:30:04-554388 nse-27319 WARNING External protocol violation detected at gnunet-service-nse.c:1161. Aug 08 11:30:17-082007 transport-27322 INFO Peer `CAKN' disconnected and we are connected to 4 peers Aug 08 11:30:17-085678 transport-27322 INFO We are now connected to peer `CAKN' and 5 peers in total Aug 08 11:30:34-711427 transport-27322 INFO Peer `CAKN' disconnected and we are connected to 4 peers Aug 08 11:30:40-541942 transport-27322 INFO We are now connected to peer `CAKN' and 5 peers in total Aug 08 11:30:51-818745 transport-27322 INFO We are now connected to peer `M1RG' and 6 peers in total Aug 08 11:30:51-832411 core-27323 WARNING Ephemeral key message rejected as its validity range does not match our system time (1375954251832 not in [1375956450355,1375999950355]). Aug 08 11:31:01-832443 core-27323 WARNING Ephemeral key message rejected as its validity range does not match our system time (1375954261832 not in [1375956450355,1375999950355]). Aug 08 11:31:15-558987 transport-27322 INFO Peer `CAKN' disconnected and we are connected to 5 peers .... |
|
|
It is *expected* if the clocks of the two peers are not close enough. Are they properly synchronized? |
|
|
We need more descriptive error messages! Assertion cannot be distinguished between breaks and assertions and for this error message we at least need the respective peer id! |
|
|
Well, first of all, this was a 'warning', not an 'error', which hints at the fact that this may happen without there being a bug. Also, the warning is actually reasonably clear; sure, we might add the peer ID, but overall I think this is far from the worst message we have ;-). |
|
|
Got peer id: Aug 08 11:54:39-497997 transport-3686 INFO We are now connected to peer `M1RG' and 8 peers in total Aug 08 11:54:39-541935 core-3687 WARNING Ephemeral key message from peer `M1RG' rejected as its validity range does not match our system time (1375955679541 not in [1375955994324,1375999494325]). Aug 08 11:54:42-667341 transport-3686 INFO Peer `M1RG' disconnected and we are connected to 7 peers |
|
|
Can't fix peers with bad system time. This is expected behavior as far as I can see it. |
| Date Modified | Username | Field | Change |
|---|---|---|---|
| 2013-08-08 11:27 | Matthias Wachs | New Issue | |
| 2013-08-08 11:27 | Matthias Wachs | Status | new => assigned |
| 2013-08-08 11:27 | Matthias Wachs | Assigned To | => Christian Grothoff |
| 2013-08-08 11:32 | Matthias Wachs | Note Added: 0007315 | |
| 2013-08-08 11:33 | Matthias Wachs | Note Added: 0007316 | |
| 2013-08-08 11:39 | Christian Grothoff | Note Added: 0007317 | |
| 2013-08-08 11:47 | Matthias Wachs | Note Added: 0007322 | |
| 2013-08-08 11:52 | Christian Grothoff | Note Added: 0007323 | |
| 2013-08-08 11:55 | Matthias Wachs | Note Added: 0007324 | |
| 2013-08-11 21:43 | Christian Grothoff | Note Added: 0007333 | |
| 2013-08-11 21:43 | Christian Grothoff | Priority | normal => none |
| 2013-08-11 21:43 | Christian Grothoff | Status | assigned => closed |
| 2013-08-11 21:43 | Christian Grothoff | Fixed in Version | => Git master |
| 2013-08-11 21:43 | Christian Grothoff | Target Version | => Git master |
