Feb 18 18:05:50-114359 cadet-4030 INFO CADET started for peer 6SMN Feb 18 18:05:50-141871 cadet-api-4037 DEBUG GNUNET_CADET_connect() 0x1ecae90 Feb 18 18:05:50-121763 cadet-api-4035 DEBUG GNUNET_CADET_connecT() Feb 18 18:05:50-147414 cadet-api-4037 DEBUG Connecting to CADET Feb 18 18:05:50-147756 cadet-4030 DEBUG Client(0) connected Feb 18 18:05:50-148781 cadet-4030 DEBUG Client(1) connected Feb 18 18:05:50-148861 cadet-4030 DEBUG Open port X4QX93K5 requested by Client(0) Feb 18 18:05:50-178105 cadet-hll-4030 DEBUG Hello for W7ZF (472 bytes), expires on Sun Feb 19 06:05:32 2017 Feb 18 18:05:50-178159 cadet-per-4030 DEBUG Creating peer P(W7ZF) Feb 18 18:05:50-178181 cadet-per-4030 DEBUG Got 472 byte HELLO for peer P(W7ZF) Feb 18 18:05:50-187684 cadet-cor-4030 DEBUG CORE connection to peer 6SMN was established. Feb 18 18:05:50-187733 cadet-per-4030 DEBUG Creating peer P(6SMN) Feb 18 18:05:50-187754 cadet-per-4030 DEBUG Message queue for peer P(6SMN) is now 0x2091700 Feb 18 18:05:50-187775 cadet-per-4030 DEBUG Updating peer P(6SMN) activation state (0 connections) with CORE link Feb 18 18:05:50-207577 nat-4048 ERROR UPnP enabled in configuration, but UPnP client `upnpc` command not found, disabling UPnP Feb 18 18:05:50-228979 cadet-hll-4030 DEBUG Hello for W7ZF (472 bytes), expires on Sun Feb 19 06:05:50 2017 Feb 18 18:05:50-229017 cadet-per-4030 DEBUG Got 472 byte HELLO for peer P(W7ZF) Feb 18 18:05:50-246240 cadet-hll-4030 DEBUG Hello for W7ZF (472 bytes), expires on Sun Feb 19 06:05:50 2017 Feb 18 18:05:50-246269 cadet-per-4030 DEBUG Got 472 byte HELLO for peer P(W7ZF) Feb 18 18:05:50-270286 cadet-hll-4030 DEBUG Hello for W7ZF (472 bytes), expires on Sun Feb 19 06:05:50 2017 Feb 18 18:05:50-270316 cadet-per-4030 DEBUG Got 472 byte HELLO for peer P(W7ZF) Feb 18 18:05:50-290061 cadet-hll-4030 DEBUG Hello for W7ZF (472 bytes), expires on Sun Feb 19 06:05:50 2017 Feb 18 18:05:50-290090 cadet-per-4030 DEBUG Got 472 byte HELLO for peer P(W7ZF) Feb 18 18:05:50-311323 cadet-dht-4030 DEBUG Announcing my HELLO (472 bytes) in the DHT Feb 18 18:05:51-904677 cadet-4030 DEBUG Client(2) connected Feb 18 18:05:51-905271 cadet-4030 DEBUG New channel to W7ZF at port PR96BHN5 requested by Client(2) Feb 18 18:05:51-905325 cadet-tun-4030 DEBUG Creating new ephemeral ratchet key (DHRs) Feb 18 18:05:51-919190 cadet-per-4030 DEBUG Updating peer P(W7ZF) activation state (0 connections) with tunnel Feb 18 18:05:51-919533 cadet-dht-4030 DEBUG Starting DHT GET for peer W7ZF (0x20915c0) Feb 18 18:05:51-919836 cadet-tun-4030 DEBUG Adding Channel W7ZF:PR96BHN5 ctn:0(80000000/0) to Tunnel W7ZF Feb 18 18:05:51-920110 cadet-chn-4030 DEBUG Created channel to port PR96BHN5 at peer P(W7ZF) for Client(2) using Tunnel W7ZF Feb 18 18:05:51-925763 cadet-tun-4030 DEBUG Performing connection maintenance for Tunnel W7ZF. Feb 18 18:05:51-926032 cadet-per-4030 DEBUG Iterating over paths to peer P(W7ZF) Feb 18 18:05:53-063540 cadet-cor-4030 DEBUG CORE connection to peer W7ZF was established. Feb 18 18:05:53-063613 cadet-per-4030 DEBUG Message queue for peer P(W7ZF) is now 0x2093610 Feb 18 18:05:53-063637 cadet-per-4030 DEBUG Updating peer P(W7ZF) activation state (0 connections) with tunnel with CORE link Feb 18 18:05:53-063660 cadet-dht-4030 DEBUG Stopping DHT GET 0x20915c0 Feb 18 18:05:53-063700 cadet-per-4030 DEBUG Asked to look for paths at distance 0, but maximum for me is < 0 Feb 18 18:05:53-063724 cadet-per-4030 DEBUG Discovered that peer P(W7ZF) is on path W7ZF(0x207aad0) at offset 0 Feb 18 18:05:53-063767 cadet-con-4030 DEBUG Creating Connection M1AG3J (Tunnel W7ZF) using path W7ZF(0x207aad0) Feb 18 18:05:53-063789 cadet-pat-4030 DEBUG Adding connection Connection M1AG3J (Tunnel W7ZF) to path W7ZF(0x207aad0) at offset 0 Feb 18 18:05:53-063812 cadet-per-4030 DEBUG Creating MQM 0x2090dd0 for peer P(W7ZF) Feb 18 18:05:53-063833 cadet-con-4030 DEBUG Core MQ for Connection M1AG3J (Tunnel W7ZF) became available in state 0 Feb 18 18:05:53-063859 cadet-tun-4030 DEBUG Found interesting path W7ZF(0x207aad0) for Tunnel W7ZF, created Connection M1AG3J (Tunnel W7ZF) Feb 18 18:05:53-063883 cadet-pat-4030 DEBUG Created new path W7ZF(0x207aad0) to create inverse for incoming connection Feb 18 18:05:53-063906 cadet-per-4030 DEBUG Attaching path W7ZF(0x207aad0) to peer P(W7ZF) (forced) Feb 18 18:05:53-063928 cadet-tun-4030 DEBUG Ignoring duplicate path W7ZF(0x207aad0). Feb 18 18:05:53-063983 cadet-con-4030 DEBUG Sending CADET_CONNECTION_CREATE message for Connection M1AG3J (Tunnel W7ZF) Feb 18 18:05:53-064003 cadet-per-4030 DEBUG Queueing message to peer P(W7ZF) in MQM 0x2090dd0 Feb 18 18:05:53-064026 cadet-per-4030 DEBUG Sending to peer P(W7ZF) from MQM 0x2090dd0 Feb 18 18:05:53-064053 cadet-con-4030 DEBUG Core MQ for Connection M1AG3J (Tunnel W7ZF) became available in state 2 Feb 18 18:05:53-066185 cadet-con-4030 DEBUG Sending CADET_CONNECTION_CREATE message for Connection M1AG3J (Tunnel W7ZF) Feb 18 18:05:53-066220 cadet-per-4030 DEBUG Queueing message to peer P(W7ZF) in MQM 0x2090dd0 Feb 18 18:05:56-035353 cadet-per-4030 DEBUG Sending to peer P(W7ZF) completed Feb 18 18:05:56-035428 cadet-per-4030 DEBUG Sending to peer P(W7ZF) from MQM 0x2090dd0 Feb 18 18:05:56-035454 cadet-con-4030 DEBUG Core MQ for Connection M1AG3J (Tunnel W7ZF) became available in state 2 Feb 18 18:05:56-036359 cadet-per-4030 DEBUG Sending to peer P(W7ZF) completed Feb 18 18:05:56-039601 cadet-con-4030 DEBUG Sending CADET_CONNECTION_CREATE message for Connection M1AG3J (Tunnel W7ZF) Feb 18 18:05:56-039631 cadet-per-4030 DEBUG Queueing message to peer P(W7ZF) in MQM 0x2090dd0 Feb 18 18:05:56-039650 cadet-per-4030 DEBUG Sending to peer P(W7ZF) from MQM 0x2090dd0 Feb 18 18:05:56-039672 cadet-con-4030 DEBUG Core MQ for Connection M1AG3J (Tunnel W7ZF) became available in state 2 Feb 18 18:05:56-039884 cadet-per-4030 DEBUG Sending to peer P(W7ZF) completed Feb 18 18:05:56-047819 cadet-con-4030 DEBUG Sending CADET_CONNECTION_CREATE message for Connection M1AG3J (Tunnel W7ZF) Feb 18 18:05:56-047855 cadet-per-4030 DEBUG Queueing message to peer P(W7ZF) in MQM 0x2090dd0 Feb 18 18:05:56-047875 cadet-per-4030 DEBUG Sending to peer P(W7ZF) from MQM 0x2090dd0 Feb 18 18:05:56-047898 cadet-con-4030 DEBUG Core MQ for Connection M1AG3J (Tunnel W7ZF) became available in state 2 Feb 18 18:05:56-048135 cadet-per-4030 DEBUG Sending to peer P(W7ZF) completed Feb 18 18:05:56-064036 cadet-con-4030 DEBUG Sending CADET_CONNECTION_CREATE message for Connection M1AG3J (Tunnel W7ZF) Feb 18 18:05:56-064072 cadet-per-4030 DEBUG Queueing message to peer P(W7ZF) in MQM 0x2090dd0 Feb 18 18:05:56-064092 cadet-per-4030 DEBUG Sending to peer P(W7ZF) from MQM 0x2090dd0 Feb 18 18:05:56-064114 cadet-con-4030 DEBUG Core MQ for Connection M1AG3J (Tunnel W7ZF) became available in state 2 Feb 18 18:05:56-064328 cadet-per-4030 DEBUG Sending to peer P(W7ZF) completed Feb 18 18:05:56-096336 cadet-con-4030 DEBUG Sending CADET_CONNECTION_CREATE message for Connection M1AG3J (Tunnel W7ZF) Feb 18 18:05:56-096417 cadet-per-4030 DEBUG Queueing message to peer P(W7ZF) in MQM 0x2090dd0 Feb 18 18:05:56-096439 cadet-per-4030 DEBUG Sending to peer P(W7ZF) from MQM 0x2090dd0 Feb 18 18:05:56-096466 cadet-con-4030 DEBUG Core MQ for Connection M1AG3J (Tunnel W7ZF) became available in state 2 Feb 18 18:05:56-096834 cadet-per-4030 DEBUG Sending to peer P(W7ZF) completed Feb 18 18:05:56-159840 cadet-cor-4030 DEBUG Received CADET_CONNECTION_CREATE message from P(W7ZF) for connection W4DHGD, building inverse path Feb 18 18:05:56-159912 cadet-pat-4030 DEBUG check_match found match with path W7ZF(0x207aad0) Feb 18 18:05:56-159952 cadet-pat-4030 DEBUG Returning existing path W7ZF(0x207aad0) as inverse for incoming connection Feb 18 18:05:56-160002 cadet-con-4030 DEBUG Got two connections on W7ZF(0x207aad0), dropping my existing Connection M1AG3J (Tunnel W7ZF) Feb 18 18:05:56-160042 cadet-per-4030 DEBUG Destroying MQM 0x2090dd0 for peer P(W7ZF) with last ditch transmission Feb 18 18:05:56-160085 cadet-con-4030 DEBUG Destroying Connection M1AG3J Feb 18 18:05:56-160126 cadet-pat-4030 DEBUG Removing connection Connection M1AG3J to path W7ZF(0x207aad0) at offset 0 Feb 18 18:05:56-160169 cadet-con-4030 DEBUG Creating Connection W4DHGD (Tunnel W7ZF) using path W7ZF(0x207aad0) Feb 18 18:05:56-160208 cadet-pat-4030 DEBUG Adding connection Connection W4DHGD (Tunnel W7ZF) to path W7ZF(0x207aad0) at offset 0 Feb 18 18:05:56-160245 cadet-per-4030 DEBUG Creating MQM 0x2090dd0 for peer P(W7ZF) Feb 18 18:05:56-160282 cadet-con-4030 DEBUG Core MQ for Connection W4DHGD (Tunnel W7ZF) became available in state 3 Feb 18 18:05:56-160330 cadet-tun-4030 DEBUG Tunnel W7ZF has new Connection W4DHGD (Tunnel W7ZF) Feb 18 18:05:56-160405 cadet-con-4030 DEBUG Sending CONNECTION_CREATE_ACK message for Connection W4DHGD (Tunnel W7ZF) Feb 18 18:05:56-160442 cadet-per-4030 DEBUG Queueing message to peer P(W7ZF) in MQM 0x2090dd0 Feb 18 18:05:56-171123 cadet-cor-4030 DEBUG Failed to route message of type 1002 from P(W7ZF) on connection M1AG3J: no route Feb 18 18:05:56-176356 cadet-cor-4030 DEBUG Failed to route message of type 1002 from P(W7ZF) on connection M1AG3J: no route Feb 18 18:05:56-180477 cadet-cor-4030 DEBUG Failed to route message of type 1002 from P(W7ZF) on connection M1AG3J: no route Feb 18 18:05:56-181007 cadet-cor-4030 DEBUG Failed to route message of type 1002 from P(W7ZF) on connection M1AG3J: no route Feb 18 18:05:56-181281 cadet-cor-4030 DEBUG Failed to route message of type 1002 from P(W7ZF) on connection M1AG3J: no route Feb 18 18:05:56-181761 cadet-cor-4030 DEBUG Failed to route message of type 1002 from P(W7ZF) on connection M1AG3J: no route Feb 18 18:05:56-197189 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:05:56-197258 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:05:56-197441 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:05:56-197486 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:05:56-197691 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:05:56-197735 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:05:56-206974 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:05:56-207074 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:05:56-232675 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:05:56-232735 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:05:56-303360 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:05:56-303432 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:05:56-428215 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:05:56-428280 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:05:56-684564 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:05:56-684646 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:05:57-198342 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:05:57-198454 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:05:58-219720 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:05:58-219825 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:06:00-277384 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:06:00-277491 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:06:04-370248 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:06:04-370324 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:06:12-577371 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:06:12-577460 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK Feb 18 18:06:28-979689 cadet-cor-4030 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection W4DHGD Feb 18 18:06:28-979767 cadet-con-4030 DEBUG Got duplicate CREATE for Connection W4DHGD (Tunnel W7ZF). MQ is busy, not queueing another ACK