Feb 16 15:44:55-231634 cadet-18038 INFO CADET started for peer 2VJ5 Feb 16 15:44:55-273633 cadet-api-18047 DEBUG GNUNET_CADET_connect() 0x2308570 Feb 16 15:44:55-285493 cadet-api-18047 DEBUG Connecting to CADET Feb 16 15:44:55-286205 cadet-18038 DEBUG Client(0) connected Feb 16 15:44:55-290679 cadet-18038 DEBUG Open port X4QX93K5 requested by Client(0) Feb 16 15:44:55-280749 cadet-api-18044 DEBUG GNUNET_CADET_connect() 0x215fe90 Feb 16 15:44:55-302289 cadet-api-18044 DEBUG Connecting to CADET Feb 16 15:44:55-304362 cadet-18038 DEBUG Client(1) connected Feb 16 15:44:55-311428 cadet-hll-18038 DEBUG Hello for PRPZ (472 bytes), expires on Fri Feb 17 03:44:04 2017 Feb 16 15:44:55-311485 cadet-per-18038 DEBUG Creating peer P(PRPZ) Feb 16 15:44:55-311522 cadet-per-18038 DEBUG Got 472 byte HELLO for peer P(PRPZ) Feb 16 15:44:55-383939 cadet-cor-18038 DEBUG CORE connection to peer 2VJ5 was established. Feb 16 15:44:55-384015 cadet-per-18038 DEBUG Creating peer P(2VJ5) Feb 16 15:44:55-384052 cadet-per-18038 DEBUG Message queue for peer P(2VJ5) is now 0xebc690 Feb 16 15:44:55-384091 cadet-per-18038 DEBUG Updating peer P(2VJ5) activation state (0 connections) with CORE link Feb 16 15:44:55-392742 nat-18057 ERROR UPnP enabled in configuration, but UPnP client `upnpc` command not found, disabling UPnP Feb 16 15:44:55-501712 cadet-dht-18038 DEBUG Announcing my HELLO (472 bytes) in the DHT Feb 16 15:44:56-836487 transport-18051 WARNING External protocol violation detected at plugin_transport_udp.c:1275. Feb 16 15:44:58-812320 cadet-18038 DEBUG Client(2) connected Feb 16 15:44:58-812807 cadet-18038 DEBUG Open port PR96BHN5 requested by Client(2) Feb 16 15:44:59-020445 cadet-cor-18038 DEBUG CORE connection to peer PRPZ was established. Feb 16 15:44:59-020523 cadet-per-18038 DEBUG Message queue for peer P(PRPZ) is now 0xebd070 Feb 16 15:44:59-020561 cadet-per-18038 DEBUG Updating peer P(PRPZ) activation state (0 connections) with CORE link Feb 16 15:45:00-022624 cadet-api-18047 DEBUG Creating new channel to PRPZ:3861448488 at 0x2322e70 number 80000000 Feb 16 15:45:00-022889 cadet-api-18047 DEBUG CADET NOTIFY TRANSMIT READY on channel 80000000 allow_send is 0 to origin with 76 bytes Feb 16 15:45:00-023314 cadet-18038 DEBUG New channel to PRPZ at port X4QX93K5 requested by Client(0) Feb 16 15:45:00-023360 cadet-tun-18038 DEBUG Creating new ephemeral ratchet key (DHRs) Feb 16 15:45:00-031731 cadet-per-18038 DEBUG Updating peer P(PRPZ) activation state (0 connections) with tunnel with CORE link Feb 16 15:45:00-031824 cadet-tun-18038 DEBUG Adding Channel PRPZ:X4QX93K5 ctn:0(80000000/0) to Tunnel PRPZ Feb 16 15:45:00-031886 cadet-chn-18038 DEBUG Created channel to port X4QX93K5 at peer P(PRPZ) for Client(0) using Tunnel PRPZ Feb 16 15:45:00-032750 cadet-tun-18038 DEBUG Performing connection maintenance for Tunnel PRPZ. Feb 16 15:45:00-032802 cadet-per-18038 DEBUG Iterating over paths to peer P(PRPZ) including direct link Feb 16 15:45:00-032843 cadet-per-18038 DEBUG Asked to look for paths at distance 0, but maximum for me is < 0 Feb 16 15:45:00-032887 cadet-per-18038 DEBUG Discovered that peer P(PRPZ) is on path PRPZ(0xea5b90) at offset 0 Feb 16 15:45:00-032951 cadet-con-18038 DEBUG Creating Connection JX3TTW (Tunnel PRPZ) using path PRPZ(0xea5b90) Feb 16 15:45:00-033007 cadet-pat-18038 DEBUG Adding connection Connection JX3TTW (Tunnel PRPZ) to path PRPZ(0xea5b90) at offset 0 Feb 16 15:45:00-033047 cadet-per-18038 DEBUG Creating MQM 0xebdee0 for peer P(PRPZ) Feb 16 15:45:00-033091 cadet-con-18038 DEBUG Core MQ for Connection JX3TTW (Tunnel PRPZ) became available in state 0 Feb 16 15:45:00-033146 cadet-tun-18038 DEBUG Found interesting path PRPZ(0xea5b90) for Tunnel PRPZ, created Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:00-033189 cadet-pat-18038 DEBUG Created new path PRPZ(0xea5b90) to create inverse for incoming connection Feb 16 15:45:00-033230 cadet-per-18038 DEBUG Attaching path PRPZ(0xea5b90) to peer P(PRPZ) (forced) Feb 16 15:45:00-033272 cadet-tun-18038 DEBUG Ignoring duplicate path PRPZ(0xea5b90). Feb 16 15:45:00-033316 cadet-tun-18038 DEBUG Ignoring duplicate path PRPZ(0xea5b90). Feb 16 15:45:00-033374 cadet-con-18038 DEBUG Sending CADET_CONNECTION_CREATE message for Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:00-033422 cadet-per-18038 DEBUG Queueing message to peer P(PRPZ) in MQM 0xebdee0 Feb 16 15:45:00-033464 cadet-per-18038 DEBUG Sending to peer P(PRPZ) from MQM 0xebdee0 Feb 16 15:45:00-033514 cadet-con-18038 DEBUG Core MQ for Connection JX3TTW (Tunnel PRPZ) became available in state 2 Feb 16 15:45:00-035682 cadet-con-18038 DEBUG Sending CADET_CONNECTION_CREATE message for Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:00-035739 cadet-per-18038 DEBUG Queueing message to peer P(PRPZ) in MQM 0xebdee0 Feb 16 15:45:01-878374 cadet-per-18038 DEBUG Sending to peer P(PRPZ) completed Feb 16 15:45:01-878471 cadet-per-18038 DEBUG Sending to peer P(PRPZ) from MQM 0xebdee0 Feb 16 15:45:01-878516 cadet-con-18038 DEBUG Core MQ for Connection JX3TTW (Tunnel PRPZ) became available in state 2 Feb 16 15:45:01-879921 cadet-per-18038 DEBUG Sending to peer P(PRPZ) completed Feb 16 15:45:01-882676 cadet-con-18038 DEBUG Sending CADET_CONNECTION_CREATE message for Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:01-882741 cadet-per-18038 DEBUG Queueing message to peer P(PRPZ) in MQM 0xebdee0 Feb 16 15:45:01-882782 cadet-per-18038 DEBUG Sending to peer P(PRPZ) from MQM 0xebdee0 Feb 16 15:45:01-882827 cadet-con-18038 DEBUG Core MQ for Connection JX3TTW (Tunnel PRPZ) became available in state 2 Feb 16 15:45:01-883011 cadet-per-18038 DEBUG Sending to peer P(PRPZ) completed Feb 16 15:45:01-891006 cadet-con-18038 DEBUG Sending CADET_CONNECTION_CREATE message for Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:01-891073 cadet-per-18038 DEBUG Queueing message to peer P(PRPZ) in MQM 0xebdee0 Feb 16 15:45:01-891114 cadet-per-18038 DEBUG Sending to peer P(PRPZ) from MQM 0xebdee0 Feb 16 15:45:01-891162 cadet-con-18038 DEBUG Core MQ for Connection JX3TTW (Tunnel PRPZ) became available in state 2 Feb 16 15:45:01-891341 cadet-per-18038 DEBUG Sending to peer P(PRPZ) completed Feb 16 15:45:01-907323 cadet-con-18038 DEBUG Sending CADET_CONNECTION_CREATE message for Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:01-907391 cadet-per-18038 DEBUG Queueing message to peer P(PRPZ) in MQM 0xebdee0 Feb 16 15:45:01-907434 cadet-per-18038 DEBUG Sending to peer P(PRPZ) from MQM 0xebdee0 Feb 16 15:45:01-907479 cadet-con-18038 DEBUG Core MQ for Connection JX3TTW (Tunnel PRPZ) became available in state 2 Feb 16 15:45:01-907656 cadet-per-18038 DEBUG Sending to peer P(PRPZ) completed Feb 16 15:45:01-939640 cadet-con-18038 DEBUG Sending CADET_CONNECTION_CREATE message for Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:01-939708 cadet-per-18038 DEBUG Queueing message to peer P(PRPZ) in MQM 0xebdee0 Feb 16 15:45:01-939753 cadet-per-18038 DEBUG Sending to peer P(PRPZ) from MQM 0xebdee0 Feb 16 15:45:01-939796 cadet-con-18038 DEBUG Core MQ for Connection JX3TTW (Tunnel PRPZ) became available in state 2 Feb 16 15:45:01-939977 cadet-per-18038 DEBUG Sending to peer P(PRPZ) completed Feb 16 15:45:02-003976 cadet-con-18038 DEBUG Sending CADET_CONNECTION_CREATE message for Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:02-004039 cadet-per-18038 DEBUG Queueing message to peer P(PRPZ) in MQM 0xebdee0 Feb 16 15:45:02-004083 cadet-per-18038 DEBUG Sending to peer P(PRPZ) from MQM 0xebdee0 Feb 16 15:45:02-004126 cadet-con-18038 DEBUG Core MQ for Connection JX3TTW (Tunnel PRPZ) became available in state 2 Feb 16 15:45:02-004309 cadet-per-18038 DEBUG Sending to peer P(PRPZ) completed Feb 16 15:45:02-132385 cadet-con-18038 DEBUG Sending CADET_CONNECTION_CREATE message for Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:02-132461 cadet-per-18038 DEBUG Queueing message to peer P(PRPZ) in MQM 0xebdee0 Feb 16 15:45:02-132501 cadet-per-18038 DEBUG Sending to peer P(PRPZ) from MQM 0xebdee0 Feb 16 15:45:02-132550 cadet-con-18038 DEBUG Core MQ for Connection JX3TTW (Tunnel PRPZ) became available in state 2 Feb 16 15:45:02-132743 cadet-per-18038 DEBUG Sending to peer P(PRPZ) completed Feb 16 15:45:02-373585 cadet-cor-18038 DEBUG Received CADET_CONNECTION_CREATE message from P(PRPZ) for connection 4XEANC, building inverse path Feb 16 15:45:02-374266 cadet-pat-18038 DEBUG check_match found match with path PRPZ(0xea5b90) Feb 16 15:45:02-374353 cadet-pat-18038 DEBUG Returning existing path PRPZ(0xea5b90) as inverse for incoming connection Feb 16 15:45:02-375228 cadet-con-18038 DEBUG Got two connections on PRPZ(0xea5b90), keeping my existing Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:02-375309 cadet-tun-18038 DEBUG Tunnel PRPZ refused inbound Connection(NULL) (duplicate) Feb 16 15:45:02-375391 cadet-cor-18038 DEBUG Received CADET_CONNECTION_CREATE from P(PRPZ) for 4XEANC, but PRPZ(0xea5b90) already has a connection. Sending BROKEN Feb 16 15:45:02-375470 cadet-per-18038 DEBUG Sending message to P(PRPZ) out of management Feb 16 15:45:02-375600 cadet-cor-18038 DEBUG Received CONNECTION_DESTROY for connection 4XEANC. Destroying route. Feb 16 15:45:02-375681 cadet-cor-18038 DEBUG Failed to route message of type 1003 from P(PRPZ) on connection 4XEANC: no route Feb 16 15:45:02-375754 cadet-per-18038 DEBUG Sending message to P(PRPZ) out of management Feb 16 15:45:02-375864 cadet-cor-18038 DEBUG Received CONNECTION_CREATE_ACK for connection JX3TTW. Feb 16 15:45:02-375945 cadet-con-18038 DEBUG Received CADET_CONNECTION_CREATE_ACK for Connection JX3TTW (Tunnel PRPZ) in state 2 (MQM ready) Feb 16 15:45:02-376032 cadet-tun-18038 DEBUG Connection JX3TTW (Tunnel PRPZ) now ready for Tunnel PRPZ in state CADET_TUNNEL_KEY_UNINITIALIZED Feb 16 15:45:02-376120 cadet-tun-18038 DEBUG Sending KX on Tunnel PRPZ via Connection JX3TTW (Tunnel PRPZ) using CADET_TUNNEL_KEY_UNINITIALIZED in state �� Feb 16 15:45:02-385406 cadet-tun-18038 DEBUG Tunnel PRPZ estate changed from CADET_TUNNEL_KEY_UNINITIALIZED to CADET_TUNNEL_KEY_AX_SENT Feb 16 15:45:02-385500 cadet-con-18038 DEBUG Scheduling message for transmission on Connection JX3TTW (Tunnel PRPZ) Feb 16 15:45:02-385570 cadet-per-18038 DEBUG Queueing message to peer P(PRPZ) in MQM 0xebdee0 Feb 16 15:45:02-385690 cadet-tun-18038 DEBUG Creating fresh unverified KX for Tunnel PRPZ. Feb 16 15:45:02-400300 cadet-tun-18038 DEBUG Tunnel PRPZ estate changed from CADET_TUNNEL_KEY_AX_SENT to CADET_TUNNEL_KEY_AX_SENT_AND_RECV Feb 16 15:45:02-400428 cadet-tun-18038 DEBUG Trying to make KX progress on Tunnel PRPZ in state CADET_TUNNEL_KEY_AX_SENT_AND_RECV Feb 16 15:45:02-400515 cadet-tun-18038 DEBUG Wanted to send KX_AUTH on Tunnel PRPZ, but no connection is ready, deferring Feb 16 15:45:02-608573 cadet-cor-18038 DEBUG Failed to route message of type 1002 from P(PRPZ) on connection 4XEANC: no route Feb 16 15:45:02-608643 cadet-per-18038 DEBUG Sending message to P(PRPZ) out of management Feb 16 15:45:02-612408 cadet-cor-18038 DEBUG Failed to route message of type 1002 from P(PRPZ) on connection 4XEANC: no route Feb 16 15:45:02-612459 cadet-per-18038 DEBUG Sending message to P(PRPZ) out of management Feb 16 15:45:02-815732 cadet-cor-18038 DEBUG Failed to route message of type 1002 from P(PRPZ) on connection 4XEANC: no route Feb 16 15:45:02-815809 cadet-per-18038 DEBUG Sending message to P(PRPZ) out of management Feb 16 15:45:02-820551 cadet-cor-18038 DEBUG Failed to route message of type 1002 from P(PRPZ) on connection 4XEANC: no route Feb 16 15:45:02-820595 cadet-per-18038 DEBUG Sending message to P(PRPZ) out of management Feb 16 15:45:02-880701 cadet-tun-18038 DEBUG Got duplicate unverified KX on Tunnel PRPZ. Fire back KX_AUTH again. Feb 16 15:45:02-880746 cadet-tun-18038 DEBUG Wanted to send KX_AUTH on Tunnel PRPZ, but no connection is ready, deferring