Feb 18 18:05:47-434599 cadet-21550 INFO CADET started for peer W7ZF Feb 18 18:05:47-463041 cadet-api-21556 DEBUG GNUNET_CADET_connect() 0x16c3e90 Feb 18 18:05:47-467689 cadet-api-21556 DEBUG Connecting to CADET Feb 18 18:05:47-469686 cadet-21550 DEBUG Client(0) connected Feb 18 18:05:47-471102 cadet-api-21560 DEBUG GNUNET_CADET_connecT() Feb 18 18:05:47-472770 cadet-21550 DEBUG Client(1) connected Feb 18 18:05:47-474323 cadet-21550 DEBUG Open port X4QX93K5 requested by Client(1) Feb 18 18:05:47-488239 cadet-hll-21550 DEBUG Hello for 6SMN (472 bytes), expires on Sun Feb 19 05:58:00 2017 Feb 18 18:05:47-488338 cadet-per-21550 DEBUG Creating peer P(6SMN) Feb 18 18:05:47-488381 cadet-per-21550 DEBUG Got 472 byte HELLO for peer P(6SMN) Feb 18 18:05:47-510572 cadet-cor-21550 DEBUG CORE connection to peer W7ZF was established. Feb 18 18:05:47-510637 cadet-per-21550 DEBUG Creating peer P(W7ZF) Feb 18 18:05:47-510680 cadet-per-21550 DEBUG Message queue for peer P(W7ZF) is now 0x2084610 Feb 18 18:05:47-510723 cadet-per-21550 DEBUG Updating peer P(W7ZF) activation state (0 connections) with CORE link Feb 18 18:05:47-519875 nat-21571 ERROR UPnP enabled in configuration, but UPnP client `upnpc` command not found, disabling UPnP Feb 18 18:05:47-626948 cadet-dht-21550 DEBUG Announcing my HELLO (472 bytes) in the DHT Feb 18 18:05:50-188965 transport-21563 WARNING External protocol violation detected at plugin_transport_udp.c:1275. Feb 18 18:05:51-270695 cadet-21550 DEBUG Client(2) connected Feb 18 18:05:51-271155 cadet-21550 DEBUG Open port PR96BHN5 requested by Client(2) Feb 18 18:05:53-068215 cadet-cor-21550 DEBUG CORE connection to peer 6SMN was established. Feb 18 18:05:53-068319 cadet-per-21550 DEBUG Message queue for peer P(6SMN) is now 0x2085060 Feb 18 18:05:53-068353 cadet-per-21550 DEBUG Updating peer P(6SMN) activation state (0 connections) with CORE link Feb 18 18:05:54-070476 cadet-21550 DEBUG New channel to 6SMN at port X4QX93K5 requested by Client(1) Feb 18 18:05:54-070564 cadet-tun-21550 DEBUG Creating new ephemeral ratchet key (DHRs) Feb 18 18:05:54-079751 cadet-per-21550 DEBUG Updating peer P(6SMN) activation state (0 connections) with tunnel with CORE link Feb 18 18:05:54-079824 cadet-tun-21550 DEBUG Adding Channel 6SMN:X4QX93K5 ctn:0(80000000/0) to Tunnel 6SMN Feb 18 18:05:54-079860 cadet-chn-21550 DEBUG Created channel to port X4QX93K5 at peer P(6SMN) for Client(1) using Tunnel 6SMN Feb 18 18:05:54-081072 cadet-tun-21550 DEBUG Performing connection maintenance for Tunnel 6SMN. Feb 18 18:05:54-081104 cadet-per-21550 DEBUG Iterating over paths to peer P(6SMN) including direct link Feb 18 18:05:54-081128 cadet-per-21550 DEBUG Asked to look for paths at distance 0, but maximum for me is < 0 Feb 18 18:05:54-081157 cadet-per-21550 DEBUG Discovered that peer P(6SMN) is on path 6SMN(0x206dad0) at offset 0 Feb 18 18:05:54-081199 cadet-con-21550 DEBUG Creating Connection W4DHGD (Tunnel 6SMN) using path 6SMN(0x206dad0) Feb 18 18:05:54-081227 cadet-pat-21550 DEBUG Adding connection Connection W4DHGD (Tunnel 6SMN) to path 6SMN(0x206dad0) at offset 0 Feb 18 18:05:54-081251 cadet-per-21550 DEBUG Creating MQM 0x2085f40 for peer P(6SMN) Feb 18 18:05:54-081277 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 0 Feb 18 18:05:54-081307 cadet-tun-21550 DEBUG Found interesting path 6SMN(0x206dad0) for Tunnel 6SMN, created Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:54-081333 cadet-pat-21550 DEBUG Created new path 6SMN(0x206dad0) to create inverse for incoming connection Feb 18 18:05:54-081358 cadet-per-21550 DEBUG Attaching path 6SMN(0x206dad0) to peer P(6SMN) (forced) Feb 18 18:05:54-081385 cadet-tun-21550 DEBUG Ignoring duplicate path 6SMN(0x206dad0). Feb 18 18:05:54-081407 cadet-tun-21550 DEBUG Ignoring duplicate path 6SMN(0x206dad0). Feb 18 18:05:54-081446 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:54-081471 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:54-081497 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:54-081553 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:54-083696 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:54-083735 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:56-055879 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE message from P(6SMN) for connection M1AG3J, building inverse path Feb 18 18:05:56-055991 cadet-pat-21550 DEBUG check_match found match with path 6SMN(0x206dad0) Feb 18 18:05:56-056035 cadet-pat-21550 DEBUG Returning existing path 6SMN(0x206dad0) as inverse for incoming connection Feb 18 18:05:56-056089 cadet-con-21550 DEBUG Got two connections on 6SMN(0x206dad0), keeping my existing Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-056130 cadet-tun-21550 DEBUG Tunnel 6SMN refused inbound Connection(NULL) (duplicate) Feb 18 18:05:56-056174 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE from P(6SMN) for M1AG3J, but 6SMN(0x206dad0) already has a connection. Sending BROKEN Feb 18 18:05:56-056216 cadet-per-21550 DEBUG Sending message to P(6SMN) out of management Feb 18 18:05:56-056708 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE message from P(6SMN) for connection M1AG3J, building inverse path Feb 18 18:05:56-056795 cadet-pat-21550 DEBUG check_match found match with path 6SMN(0x206dad0) Feb 18 18:05:56-056820 cadet-pat-21550 DEBUG Returning existing path 6SMN(0x206dad0) as inverse for incoming connection Feb 18 18:05:56-056849 cadet-con-21550 DEBUG Got two connections on 6SMN(0x206dad0), keeping my existing Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-056872 cadet-tun-21550 DEBUG Tunnel 6SMN refused inbound Connection(NULL) (duplicate) Feb 18 18:05:56-056896 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE from P(6SMN) for M1AG3J, but 6SMN(0x206dad0) already has a connection. Sending BROKEN Feb 18 18:05:56-056919 cadet-per-21550 DEBUG Sending message to P(6SMN) out of management Feb 18 18:05:56-059367 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE message from P(6SMN) for connection M1AG3J, building inverse path Feb 18 18:05:56-059406 cadet-pat-21550 DEBUG check_match found match with path 6SMN(0x206dad0) Feb 18 18:05:56-059429 cadet-pat-21550 DEBUG Returning existing path 6SMN(0x206dad0) as inverse for incoming connection Feb 18 18:05:56-059456 cadet-con-21550 DEBUG Got two connections on 6SMN(0x206dad0), keeping my existing Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-059478 cadet-tun-21550 DEBUG Tunnel 6SMN refused inbound Connection(NULL) (duplicate) Feb 18 18:05:56-059501 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE from P(6SMN) for M1AG3J, but 6SMN(0x206dad0) already has a connection. Sending BROKEN Feb 18 18:05:56-059523 cadet-per-21550 DEBUG Sending message to P(6SMN) out of management Feb 18 18:05:56-071197 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE message from P(6SMN) for connection M1AG3J, building inverse path Feb 18 18:05:56-071235 cadet-pat-21550 DEBUG check_match found match with path 6SMN(0x206dad0) Feb 18 18:05:56-071258 cadet-pat-21550 DEBUG Returning existing path 6SMN(0x206dad0) as inverse for incoming connection Feb 18 18:05:56-071284 cadet-con-21550 DEBUG Got two connections on 6SMN(0x206dad0), keeping my existing Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-071306 cadet-tun-21550 DEBUG Tunnel 6SMN refused inbound Connection(NULL) (duplicate) Feb 18 18:05:56-071329 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE from P(6SMN) for M1AG3J, but 6SMN(0x206dad0) already has a connection. Sending BROKEN Feb 18 18:05:56-071351 cadet-per-21550 DEBUG Sending message to P(6SMN) out of management Feb 18 18:05:56-081773 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE message from P(6SMN) for connection M1AG3J, building inverse path Feb 18 18:05:56-081811 cadet-pat-21550 DEBUG check_match found match with path 6SMN(0x206dad0) Feb 18 18:05:56-081835 cadet-pat-21550 DEBUG Returning existing path 6SMN(0x206dad0) as inverse for incoming connection Feb 18 18:05:56-081886 cadet-con-21550 DEBUG Got two connections on 6SMN(0x206dad0), keeping my existing Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-081942 cadet-tun-21550 DEBUG Tunnel 6SMN refused inbound Connection(NULL) (duplicate) Feb 18 18:05:56-081965 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE from P(6SMN) for M1AG3J, but 6SMN(0x206dad0) already has a connection. Sending BROKEN Feb 18 18:05:56-081987 cadet-per-21550 DEBUG Sending message to P(6SMN) out of management Feb 18 18:05:56-119989 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE message from P(6SMN) for connection M1AG3J, building inverse path Feb 18 18:05:56-120058 cadet-pat-21550 DEBUG check_match found match with path 6SMN(0x206dad0) Feb 18 18:05:56-120082 cadet-pat-21550 DEBUG Returning existing path 6SMN(0x206dad0) as inverse for incoming connection Feb 18 18:05:56-120111 cadet-con-21550 DEBUG Got two connections on 6SMN(0x206dad0), keeping my existing Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-120133 cadet-tun-21550 DEBUG Tunnel 6SMN refused inbound Connection(NULL) (duplicate) Feb 18 18:05:56-120157 cadet-cor-21550 DEBUG Received CADET_CONNECTION_CREATE from P(6SMN) for M1AG3J, but 6SMN(0x206dad0) already has a connection. Sending BROKEN Feb 18 18:05:56-120179 cadet-per-21550 DEBUG Sending message to P(6SMN) out of management Feb 18 18:05:56-147563 core-21554 WARNING Client waited 3078 ms for permission to transmit to `6SMN' (priority 1) Feb 18 18:05:56-147925 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:05:56-147978 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:56-148021 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:56-149687 core-21554 WARNING Client waited 3080 ms for transmission of 348 bytes to `6SMN' (uncorked) Feb 18 18:05:56-152176 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-152234 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:56-159404 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:05:56-159461 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:56-159502 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:56-160942 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:05:56-167690 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-167755 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:56-167794 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:56-167839 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:56-168099 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:05:56-176314 cadet-cor-21550 DEBUG Received CONNECTION_DESTROY for connection M1AG3J. Destroying route. Feb 18 18:05:56-176387 cadet-cor-21550 DEBUG Failed to route message of type 1003 from P(6SMN) on connection M1AG3J: no route Feb 18 18:05:56-184027 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-184072 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:56-184096 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:56-184123 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:56-184280 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:05:56-216276 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-216312 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:56-216335 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:56-216361 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:56-216539 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:05:56-280536 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-280581 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:56-280604 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:56-280630 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:56-280797 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:05:56-408865 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-408908 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:56-408932 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:56-408958 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:56-409120 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:05:56-665387 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:56-665496 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:56-665536 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:56-665584 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:56-665951 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:05:57-178294 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:57-178413 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:57-178456 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:57-178505 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:57-178825 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:05:58-203689 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:05:58-203787 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:05:58-203820 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:05:58-203859 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:05:58-204238 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:06:00-254069 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:06:00-254168 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:06:00-254195 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:06:00-254228 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:06:00-254481 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:06:04-354052 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:06:04-354174 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:06:04-354217 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:06:04-354270 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:06:04-354651 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:06:12-554654 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:06:12-554776 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:06:12-554816 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:06:12-554864 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:06:12-555185 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed Feb 18 18:06:28-955401 cadet-con-21550 DEBUG Sending CADET_CONNECTION_CREATE message for Connection W4DHGD (Tunnel 6SMN) Feb 18 18:06:28-955469 cadet-per-21550 DEBUG Queueing message to peer P(6SMN) in MQM 0x2085f40 Feb 18 18:06:28-955496 cadet-per-21550 DEBUG Sending to peer P(6SMN) from MQM 0x2085f40 Feb 18 18:06:28-955528 cadet-con-21550 DEBUG Core MQ for Connection W4DHGD (Tunnel 6SMN) became available in state 2 Feb 18 18:06:28-955757 cadet-per-21550 DEBUG Sending to peer P(6SMN) completed