View Issue Details

Jump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0002321GNUnetcore servicepublic2012-05-07 16:322013-12-24 20:55
ReporterChristian Grothoff Assigned ToChristian Grothoff  
PriorityurgentSeveritymajorReproducibilityhave not tried
Status closedResolutionfixed 
Product VersionGit master 
Target Version0.10.0Fixed in Version0.10.0 
Summary0002321: External protocol violation detected at gnunet-service-core_kx.c:1555.
DescriptionPeer connection Progress: [0%.......25%......May 07 14:51:39-048236 core-28253 WARNING External protocol violation detected at crypto_aes.c:64.
May 07 14:51:39-048287 core-28253 WARNING External protocol violation detected at gnunet-service-core_kx.c:880.
.50%.......75%....May 07 14:51:48-169134 core-28253 WARNING External protocol violation detected at crypto_aes.c:64.
May 07 14:51:48-169156 core-28253 WARNING External protocol violation detected at gnunet-service-core_kx.c:880.
.May 07 14:52:08-173145 core-28253 WARNING External protocol violation detected at crypto_aes.c:64.
May 07 14:52:08-173171 core-28253 WARNING External protocol violation detected at gnunet-service-core_kx.c:880.
May 07 14:52:48-173259 core-28253 WARNING External protocol violation detected at crypto_aes.c:64.
May 07 14:52:48-173287 core-28253 WARNING External protocol violation detected at gnunet-service-core_kx.c:880.
.May 07 14:54:08-174113 core-28253 WARNING External protocol violation detected at crypto_aes.c:64.
May 07 14:54:08-174140 core-28253 WARNING External protocol violation detected at gnunet-service-core_kx.c:880.
May 07 14:56:48-175895 core-28253 WARNING External protocol violation detected at crypto_aes.c:64.
May 07 14:56:48-175928 core-28253 WARNING External protocol violation detected at gnunet-service-core_kx.c:880.
May 07 15:02:08-175699 core-28253 WARNING External protocol violation detected at crypto_aes.c:64.
May 07 15:02:08-175726 core-28253 WARNING External protocol violation detected at gnunet-service-core_kx.c:880.
.100%]
May 07 15:06:37-497507 test_mesh_2dtorus-28207 ERROR test: 2 connections have FAILED!
May 07 15:06:38-003420 test_mesh_2dtorus-28207 WARNING test: FAILED!
Steps To Reproduce$ while true; do ./test_mesh_2dtorus || break; done

Had to run it like this for quite a while...
TagsNo tags attached.

Relationships

related to 0002563 closedChristian Grothoff core should use DH for PFS 

Activities

Christian Grothoff

2012-05-10 00:14

manager   ~0005844

This is also odd:

May 09 16:35:44-130153 core-7081 ERROR Invalid key b83523d6 decrypted by AC70 from message 1271719722 (origin: 7SDPG7P9)

Given that there is no (recent) message about either the receiver (AC70) nor the sender (7SPD) of this message, it is not surprising that the key is also invalid. But we don't have any other messages from process 7081, so maybe the log is just the tail and the message was hanging in the queues for >2s!?

Christian Grothoff

2012-05-10 00:25

manager   ~0005846

Filed the 740 assertion as 0002338, further discussion on the 740 assertion should be done there. This bug should only be used for the kx key decryption failure issue.

Christian Grothoff

2012-05-10 14:59

manager   ~0005854

With SVN 21408, I now ONLY get May 10 14:51:00-613393 core-7774 WARNING External protocol violation detected at gnunet-service-core_kx.c:1555.
(and that once after running the loop for like 2-3h).

Bart Polot

2012-05-10 18:35

manager   ~0005858

Last edited: 2012-05-10 18:36

 CPU is at 100%, of which 80-85% in *SYSTEM* mode.

There keep appearing ~ 5 messages/s like above.

Bart Polot

2012-05-10 18:50

manager   ~0005859

"killall gnunet-service-transport" seemed to help the cpu usage and error messages, but now all estimates are ~ 4 instead of ~ 4k.

Christian Grothoff

2012-05-10 19:03

manager   ~0005860

1556 is a checksum failure of the HMAC for encrypted traffic...

Christian Grothoff

2012-05-11 00:39

manager   ~0005869

We need a better way to trigger this...

Matthias Wachs

2012-05-14 15:31

manager   ~0005893

Confirmed on my machine May 14 15:27:17-054413 core-31764 WARNING External protocol violation detected at crypto_aes.c:64.
May 14 15:27:17-054453 core-31764 ERROR Invalid key 63b4184f decrypted by FC74 from message 3468445679 (origin: IVT2QK5U)
May 14 15:27:17-054462 core-31764 WARNING External protocol violation detected at gnunet-service-core_kx.c:886.
May 14 15:27:27-024077 core-31764 WARNING External protocol violation detected at crypto_aes.c:64.
May 14 15:27:27-024099 core-31764 ERROR Invalid key 63b4184f decrypted by FC74 from message 3468445679 (origin: IVT2QK5U)
May 14 15:27:27-024106 core-31764 WARNING External protocol violation detected at gnunet-service-core_kx.c:886.
May 14 15:27:47-044139 core-31764 WARNING External protocol violation detected at crypto_aes.c:64.
May 14 15:27:47-044160 core-31764 ERROR Invalid key 63b4184f decrypted by FC74 from message 3468445679 (origin: IVT2QK5U)
May 14 15:27:47-044166 core-31764 WARNING External protocol violation detected at gnunet-service-core_kx.c:886.
May 14 15:28:27-116819 core-31764 WARNING External protocol violation detected at crypto_aes.c:64.
May 14 15:28:27-116844 core-31764 ERROR Invalid key 63b4184f decrypted by FC74 from message 3468445679 (origin: IVT2QK5U)
May 14 15:28:27-116850 core-31764 WARNING External protocol violation detected at gnunet-service-core_kx.c:886.
May 14 15:29:47-114827 core-31764 WARNING External protocol violation detected at crypto_aes.c:64.
May 14 15:29:47-114848 core-31764 ERROR Invalid key 63b4184f decrypted by FC74 from message 3468445679 (origin: IVT2QK5U)
May 14 15:29:47-114855 core-31764 WARNING External protocol violation detected at gnunet-service-core_kx.c:886.

Christian Grothoff

2012-06-14 09:18

manager   ~0006069

Still there:
Jun 12 14:51:08-441346 core-31681 WARNING Message `External protocol violation detected at gnunet-service-core_kx.c:1556.' repeated 19 times in the last 8 m

Christian Grothoff

2012-06-14 09:20

manager   ~0006070

Also got (possibly related):
Jun 12 17:17:36-905121 core-31681 WARNING External protocol violation detected at gnunet-service-core_kx.c:1012.

Christian Grothoff

2012-10-07 13:58

manager   ~0006419

Still need more data on how to reproduce this in a controlled manner. Very tricky issue...

Christian Grothoff

2013-03-18 14:59

manager   ~0006994

Seems gone with ECC-based KX code.

Issue History

Date Modified Username Field Change
2012-05-07 16:32 Christian Grothoff New Issue
2012-05-07 16:32 Christian Grothoff Status new => assigned
2012-05-07 16:32 Christian Grothoff Assigned To => Christian Grothoff
2012-05-10 00:14 Christian Grothoff Note Added: 0005844
2012-05-10 00:23 Christian Grothoff Priority high => urgent
2012-05-10 00:25 Christian Grothoff Note Added: 0005846
2012-05-10 00:26 Christian Grothoff Priority urgent => high
2012-05-10 14:59 Christian Grothoff Note Added: 0005854
2012-05-10 15:00 Christian Grothoff Summary crypto failure is back... => External protocol violation detected at gnunet-service-core_kx.c:1555.
2012-05-10 18:35 Bart Polot Note Added: 0005858
2012-05-10 18:36 Bart Polot Note Edited: 0005858
2012-05-10 18:50 Bart Polot Note Added: 0005859
2012-05-10 18:56 Christian Grothoff Priority high => immediate
2012-05-10 18:56 Christian Grothoff Target Version => 0.9.3
2012-05-10 19:03 Christian Grothoff Note Added: 0005860
2012-05-10 21:08 Christian Grothoff Target Version 0.9.3 =>
2012-05-11 00:39 Christian Grothoff Note Added: 0005869
2012-05-11 00:39 Christian Grothoff Priority immediate => high
2012-05-11 00:39 Christian Grothoff Status assigned => feedback
2012-05-14 15:31 Matthias Wachs Note Added: 0005893
2012-06-11 14:11 Christian Grothoff Priority high => low
2012-06-14 09:18 Christian Grothoff Note Added: 0006069
2012-06-14 09:18 Christian Grothoff Status feedback => assigned
2012-06-14 09:20 Christian Grothoff Note Added: 0006070
2012-09-29 21:20 Christian Grothoff Relationship added related to 0002563
2012-10-07 13:58 Christian Grothoff Note Added: 0006419
2012-10-07 13:58 Christian Grothoff Status assigned => feedback
2012-11-10 14:12 Christian Grothoff Target Version => 0.10.0
2012-12-21 20:31 Christian Grothoff Priority low => urgent
2013-03-18 14:59 Christian Grothoff Note Added: 0006994
2013-03-18 14:59 Christian Grothoff Status feedback => assigned
2013-03-18 14:59 Christian Grothoff Status assigned => resolved
2013-03-18 14:59 Christian Grothoff Fixed in Version => 0.10.0
2013-03-18 14:59 Christian Grothoff Resolution open => fixed
2013-12-24 20:55 Christian Grothoff Status resolved => closed