Feb 18 21:40:14-127784 cadet-22259 INFO CADET started for peer RH83 Feb 18 21:40:14-157003 cadet-api-22263 DEBUG GNUNET_CADET_connect() 0x179de90 Feb 18 21:40:14-162323 cadet-api-22263 DEBUG Connecting to CADET Feb 18 21:40:14-163478 cadet-22259 DEBUG Client(0) connected Feb 18 21:40:14-167144 cadet-api-22269 DEBUG GNUNET_CADET_connecT() Feb 18 21:40:14-167471 cadet-22259 DEBUG Client(1) connected Feb 18 21:40:14-170184 cadet-22259 DEBUG Open port X4QX93K5 requested by Client(1) Feb 18 21:40:14-180126 cadet-hll-22259 DEBUG Hello for A10V (472 bytes), expires on Sun Feb 19 09:39:26 2017 Feb 18 21:40:14-180181 cadet-per-22259 DEBUG Creating peer P(A10V) Feb 18 21:40:14-180202 cadet-per-22259 DEBUG Got 472 byte HELLO for peer P(A10V) Feb 18 21:40:14-210029 cadet-cor-22259 DEBUG CORE connection to peer RH83 was established. Feb 18 21:40:14-210078 cadet-per-22259 DEBUG Creating peer P(RH83) Feb 18 21:40:14-210100 cadet-per-22259 DEBUG Message queue for peer P(RH83) is now 0x6f5610 Feb 18 21:40:14-210122 cadet-per-22259 DEBUG Updating peer P(RH83) activation state (0 connections) with CORE link Feb 18 21:40:14-213642 nat-22280 ERROR UPnP enabled in configuration, but UPnP client `upnpc` command not found, disabling UPnP Feb 18 21:40:14-257254 cadet-hll-22259 DEBUG Hello for A10V (472 bytes), expires on Sun Feb 19 09:40:14 2017 Feb 18 21:40:14-257294 cadet-per-22259 DEBUG Got 472 byte HELLO for peer P(A10V) Feb 18 21:40:14-258115 cadet-hll-22259 DEBUG Hello for A10V (472 bytes), expires on Sun Feb 19 09:40:14 2017 Feb 18 21:40:14-258141 cadet-per-22259 DEBUG Got 472 byte HELLO for peer P(A10V) Feb 18 21:40:14-303173 cadet-hll-22259 DEBUG Hello for A10V (472 bytes), expires on Sun Feb 19 09:40:14 2017 Feb 18 21:40:14-303214 cadet-per-22259 DEBUG Got 472 byte HELLO for peer P(A10V) Feb 18 21:40:14-305121 cadet-hll-22259 DEBUG Hello for A10V (472 bytes), expires on Sun Feb 19 09:40:14 2017 Feb 18 21:40:14-305150 cadet-per-22259 DEBUG Got 472 byte HELLO for peer P(A10V) Feb 18 21:40:14-319051 cadet-dht-22259 DEBUG Announcing my HELLO (472 bytes) in the DHT Feb 18 21:40:15-285483 cadet-22259 DEBUG Client(2) connected Feb 18 21:40:15-285812 cadet-22259 DEBUG Open port PR96BHN5 requested by Client(2) Feb 18 21:40:40-343517 cadet-cor-22259 DEBUG CORE connection to peer A10V was established. Feb 18 21:40:40-343586 cadet-per-22259 DEBUG Message queue for peer P(A10V) is now 0x6f6160 Feb 18 21:40:40-343607 cadet-per-22259 DEBUG Updating peer P(A10V) activation state (0 connections) with CORE link Feb 18 21:40:40-388802 cadet-cor-22259 DEBUG Received CADET_CONNECTION_CREATE message from P(A10V) for connection T3JWZ3, building inverse path Feb 18 21:40:40-388839 cadet-per-22259 DEBUG Asked to look for paths at distance 0, but maximum for me is < 0 Feb 18 21:40:40-388864 cadet-per-22259 DEBUG Discovered that peer P(A10V) is on path A10V(0x6e3e90) at offset 0 Feb 18 21:40:40-388888 cadet-pat-22259 DEBUG Created new path A10V(0x6e3e90) to create inverse for incoming connection Feb 18 21:40:40-388910 cadet-per-22259 DEBUG Attaching path A10V(0x6e3e90) to peer P(A10V) (forced) Feb 18 21:40:40-388932 cadet-tun-22259 DEBUG Creating new ephemeral ratchet key (DHRs) Feb 18 21:40:40-397013 cadet-per-22259 DEBUG Updating peer P(A10V) activation state (0 connections) with tunnel with CORE link Feb 18 21:40:40-401625 cadet-con-22259 DEBUG Creating Connection T3JWZ3 (Tunnel A10V) using path A10V(0x6e3e90) Feb 18 21:40:40-401672 cadet-pat-22259 DEBUG Adding connection Connection T3JWZ3 (Tunnel A10V) to path A10V(0x6e3e90) at offset 0 Feb 18 21:40:40-401719 cadet-per-22259 DEBUG Creating MQM 0x6f6820 for peer P(A10V) Feb 18 21:40:40-401761 cadet-con-22259 DEBUG Core MQ for Connection T3JWZ3 (Tunnel A10V) became available in state 3 Feb 18 21:40:40-401809 cadet-tun-22259 DEBUG Tunnel A10V has new Connection T3JWZ3 (Tunnel A10V) Feb 18 21:40:40-402005 cadet-cor-22259 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection T3JWZ3 Feb 18 21:40:40-402062 cadet-con-22259 DEBUG Got duplicate CREATE for Connection T3JWZ3 (Tunnel A10V), scheduling another ACK (MQM ready) Feb 18 21:40:40-402111 cadet-tun-22259 DEBUG Performing connection maintenance for Tunnel A10V. Feb 18 21:40:40-402155 cadet-per-22259 DEBUG Iterating over paths to peer P(A10V) including direct link Feb 18 21:40:40-402200 cadet-pat-22259 DEBUG check_match found match with path A10V(0x6e3e90) Feb 18 21:40:40-402237 cadet-pat-22259 DEBUG Returning existing path A10V(0x6e3e90) as inverse for incoming connection Feb 18 21:40:40-402276 cadet-tun-22259 DEBUG Ignoring duplicate path A10V(0x6e3e90). Feb 18 21:40:40-402313 cadet-tun-22259 DEBUG Ignoring duplicate path A10V(0x6e3e90). Feb 18 21:40:40-402366 cadet-cor-22259 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection T3JWZ3 Feb 18 21:40:40-402404 cadet-con-22259 DEBUG Got duplicate CREATE for Connection T3JWZ3 (Tunnel A10V), scheduling another ACK (MQM ready) Feb 18 21:40:40-402458 cadet-cor-22259 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection T3JWZ3 Feb 18 21:40:40-402495 cadet-con-22259 DEBUG Got duplicate CREATE for Connection T3JWZ3 (Tunnel A10V), scheduling another ACK (MQM ready) Feb 18 21:40:40-402550 cadet-con-22259 DEBUG Sending CONNECTION_CREATE_ACK message for Connection T3JWZ3 (Tunnel A10V) Feb 18 21:40:40-402589 cadet-per-22259 DEBUG Queueing message to peer P(A10V) in MQM 0x6f6820 Feb 18 21:40:40-402632 cadet-per-22259 DEBUG Sending to peer P(A10V) from MQM 0x6f6820 Feb 18 21:40:40-402679 cadet-tun-22259 DEBUG Connection T3JWZ3 (Tunnel A10V) now ready for Tunnel A10V in state CADET_TUNNEL_KEY_UNINITIALIZED Feb 18 21:40:40-402719 cadet-con-22259 DEBUG Core MQ for Connection T3JWZ3 (Tunnel A10V) became available in state 4 Feb 18 21:40:40-402767 cadet-con-22259 DEBUG Scheduling keepalive for Connection T3JWZ3 (Tunnel A10V) in 5 m Feb 18 21:40:40-402973 cadet-per-22259 DEBUG Sending to peer P(A10V) completed Feb 18 21:40:40-420207 cadet-cor-22259 DEBUG Received duplicate CADET_CONNECTION_CREATE message on connection T3JWZ3 Feb 18 21:40:40-420269 cadet-con-22259 DEBUG Got duplicate CREATE for Connection T3JWZ3 (Tunnel A10V), scheduling another ACK (MQM ready) Feb 18 21:40:40-420321 cadet-tun-22259 DEBUG Connection T3JWZ3 (Tunnel A10V) no longer ready for Tunnel A10V Feb 18 21:40:40-420376 cadet-con-22259 DEBUG Sending CONNECTION_CREATE_ACK message for Connection T3JWZ3 (Tunnel A10V) Feb 18 21:40:40-420414 cadet-per-22259 DEBUG Queueing message to peer P(A10V) in MQM 0x6f6820 Feb 18 21:40:40-420452 cadet-per-22259 DEBUG Sending to peer P(A10V) from MQM 0x6f6820 Feb 18 21:40:40-420493 cadet-tun-22259 DEBUG Connection T3JWZ3 (Tunnel A10V) now ready for Tunnel A10V in state CADET_TUNNEL_KEY_UNINITIALIZED Feb 18 21:40:40-420537 cadet-con-22259 DEBUG Core MQ for Connection T3JWZ3 (Tunnel A10V) became available in state 4 Feb 18 21:40:40-420576 cadet-con-22259 DEBUG Scheduling keepalive for Connection T3JWZ3 (Tunnel A10V) in 5 m Feb 18 21:40:40-420750 cadet-per-22259 DEBUG Sending to peer P(A10V) completed Feb 18 21:40:40-434357 cadet-tun-22259 DEBUG Tunnel A10V received 188 bytes of encrypted data in state 0 Feb 18 21:40:40-434422 cadet-22259 WARNING External protocol violation detected at gnunet-service-cadet-new_tunnels.c:3012. Feb 18 21:40:40-949425 cadet-tun-22259 DEBUG Tunnel A10V received 188 bytes of encrypted data in state 0 Feb 18 21:40:40-949720 cadet-22259 WARNING External protocol violation detected at gnunet-service-cadet-new_tunnels.c:3012. Feb 18 21:40:41-345506 cadet-22259 DEBUG New channel to A10V at port X4QX93K5 requested by Client(1) Feb 18 21:40:41-345822 cadet-tun-22259 DEBUG Adding Channel A10V:X4QX93K5 ctn:0(80000000/0) to Tunnel A10V Feb 18 21:40:41-345921 cadet-chn-22259 DEBUG Created channel to port X4QX93K5 at peer P(A10V) for Client(1) using Tunnel A10V Feb 18 21:40:41-946281 cadet-tun-22259 DEBUG Tunnel A10V received 188 bytes of encrypted data in state 0 Feb 18 21:40:41-946414 cadet-22259 WARNING External protocol violation detected at gnunet-service-cadet-new_tunnels.c:3012. Feb 18 21:40:43-957755 cadet-tun-22259 DEBUG Tunnel A10V received 188 bytes of encrypted data in state 0 Feb 18 21:40:43-957823 cadet-22259 WARNING External protocol violation detected at gnunet-service-cadet-new_tunnels.c:3012. Feb 18 21:40:47-964606 cadet-tun-22259 DEBUG Tunnel A10V received 188 bytes of encrypted data in state 0 Feb 18 21:40:47-964685 cadet-22259 WARNING External protocol violation detected at gnunet-service-cadet-new_tunnels.c:3012. Feb 18 21:40:55-975870 cadet-tun-22259 DEBUG Tunnel A10V received 188 bytes of encrypted data in state 0 Feb 18 21:40:55-975971 cadet-22259 WARNING External protocol violation detected at gnunet-service-cadet-new_tunnels.c:3012. Feb 18 21:41:11-991726 cadet-tun-22259 DEBUG Tunnel A10V received 188 bytes of encrypted data in state 0 Feb 18 21:41:11-991799 cadet-22259 WARNING External protocol violation detected at gnunet-service-cadet-new_tunnels.c:3012. Feb 18 21:41:40-414242 cadet-tun-22259 DEBUG Performing connection maintenance for Tunnel A10V. Feb 18 21:41:40-414329 cadet-per-22259 DEBUG Iterating over paths to peer P(A10V) including direct link Feb 18 21:41:40-414353 cadet-pat-22259 DEBUG check_match found match with path A10V(0x6e3e90) Feb 18 21:41:40-414374 cadet-pat-22259 DEBUG Returning existing path A10V(0x6e3e90) as inverse for incoming connection Feb 18 21:41:40-414394 cadet-tun-22259 DEBUG Ignoring duplicate path A10V(0x6e3e90). Feb 18 21:41:40-414412 cadet-tun-22259 DEBUG Ignoring duplicate path A10V(0x6e3e90). Feb 18 21:41:44-024733 cadet-tun-22259 DEBUG Tunnel A10V received 188 bytes of encrypted data in state 0 Feb 18 21:41:44-024795 cadet-22259 WARNING External protocol violation detected at gnunet-service-cadet-new_tunnels.c:3012. Feb 18 21:42:40-429218 cadet-tun-22259 DEBUG Performing connection maintenance for Tunnel A10V. Feb 18 21:42:40-429327 cadet-per-22259 DEBUG Iterating over paths to peer P(A10V) including direct link Feb 18 21:42:40-429365 cadet-pat-22259 DEBUG check_match found match with path A10V(0x6e3e90) Feb 18 21:42:40-429400 cadet-pat-22259 DEBUG Returning existing path A10V(0x6e3e90) as inverse for incoming connection Feb 18 21:42:40-429433 cadet-tun-22259 DEBUG Ignoring duplicate path A10V(0x6e3e90). Feb 18 21:42:40-429464 cadet-tun-22259 DEBUG Ignoring duplicate path A10V(0x6e3e90). Feb 18 21:42:48-096207 cadet-tun-22259 DEBUG Tunnel A10V received 188 bytes of encrypted data in state 0 Feb 18 21:42:48-096291 cadet-22259 WARNING External protocol violation detected at gnunet-service-cadet-new_tunnels.c:3012. Feb 18 21:43:40-478578 cadet-tun-22259 DEBUG Performing connection maintenance for Tunnel A10V. Feb 18 21:43:40-478666 cadet-per-22259 DEBUG Iterating over paths to peer P(A10V) including direct link Feb 18 21:43:40-478691 cadet-pat-22259 DEBUG check_match found match with path A10V(0x6e3e90) Feb 18 21:43:40-478713 cadet-pat-22259 DEBUG Returning existing path A10V(0x6e3e90) as inverse for incoming connection Feb 18 21:43:40-478739 cadet-tun-22259 DEBUG Ignoring duplicate path A10V(0x6e3e90). Feb 18 21:43:40-478758 cadet-tun-22259 DEBUG Ignoring duplicate path A10V(0x6e3e90). gnunet-arm -e * Lost NATed local address 10.11.10.1, stopping NAT server Feb 18 21:44:03-671372 cadet-22259 DEBUG Client(2) is disconnecting. Feb 18 21:44:03-671419 cadet-22259 DEBUG Closing port PR96BHN5 due to Client(2) disconnect. Feb 18 21:44:03-671455 cadet-22259 DEBUG Client(1) is disconnecting. Feb 18 21:44:03-671479 cadet-22259 DEBUG Destroying Channel A10V:X4QX93K5 ctn:8(80000000/0), due to Client(1) disconnecting. Feb 18 21:44:03-671504 cadet-chn-22259 DEBUG Client(1) asks for destruction of Channel A10V:X4QX93K5 ctn:8(80000000/0) Feb 18 21:44:03-671529 cadet-tun-22259 DEBUG Removing Channel A10V:X4QX93K5 ctn:8(0/0) from Tunnel A10V Feb 18 21:44:03-671553 cadet-22259 DEBUG Closing port X4QX93K5 due to Client(1) disconnect. Feb 18 21:44:03-671576 cadet-22259 DEBUG Client(0) is disconnecting. Feb 18 21:44:03-671603 cadet-22259 DEBUG Shutting down Feb 18 21:44:03-671629 cadet-cor-22259 DEBUG CORE connection to peer RH83 went down. Feb 18 21:44:03-671648 cadet-per-22259 DEBUG Message queue for peer P(RH83) is now (nil) Feb 18 21:44:03-671672 cadet-cor-22259 DEBUG CORE connection to peer A10V went down. Feb 18 21:44:03-671689 cadet-per-22259 DEBUG Message queue for peer P(A10V) is now (nil) Feb 18 21:44:03-671712 cadet-con-22259 DEBUG Core MQ for Connection T3JWZ3 (Tunnel A10V) went down Feb 18 21:44:03-671732 cadet-tun-22259 DEBUG Connection T3JWZ3 (Tunnel A10V) no longer ready for Tunnel A10V Feb 18 21:44:03-671752 cadet-per-22259 DEBUG Updating peer P(A10V) activation state (0 connections) with tunnel Feb 18 21:44:03-671783 cadet-dht-22259 DEBUG Starting DHT GET for peer A10V (0x6f6950) Feb 18 21:44:03-671834 cadet-22259 DEBUG Cleaning all up Feb 18 21:44:03-671868 cadet-tun-22259 DEBUG Destroying idle Tunnel A10V Feb 18 21:44:03-671890 cadet-per-22259 DEBUG Destroying MQM 0x6f6820 for peer P(A10V) with last ditch transmission Feb 18 21:44:03-671910 cadet-con-22259 DEBUG Destroying Connection T3JWZ3 Feb 18 21:44:03-671931 cadet-pat-22259 DEBUG Removing connection Connection T3JWZ3 to path A10V(0x6e3e90) at offset 0 Feb 18 21:44:03-671954 cadet-per-22259 DEBUG Dropping tunnel Tunnel A10V to peer P(A10V) Feb 18 21:44:03-671980 cadet-per-22259 DEBUG Destroying all paths to P(A10V) Feb 18 21:44:03-671954 cadet-api-22263 DEBUG CADET DISCONNECT Feb 18 21:44:03-672002 cadet-pat-22259 DEBUG Owner releases path A10V(0x6e3e90) Feb 18 21:44:03-672024 cadet-per-22259 DEBUG Removing knowledge about peer P(A10V) beging on path A10V(0x6e3e90) at offset 0 Feb 18 21:44:03-672042 cadet-pat-22259 DEBUG Destroying path (0x6e3e90) Feb 18 21:44:03-672060 cadet-per-22259 DEBUG Destroying all paths to P(RH83) Feb 18 21:44:03-672078 cadet-per-22259 DEBUG Destroying all peers now Feb 18 21:44:03-672097 cadet-per-22259 DEBUG Destroying state about peer P(A10V) Feb 18 21:44:03-672116 cadet-dht-22259 DEBUG Stopping DHT GET 0x6f6950 Feb 18 21:44:03-672139 cadet-per-22259 DEBUG Destroying state about peer P(RH83) Feb 18 21:44:03-673990 cadet-api-22269 DEBUG Destroying channel 80 of 0xfd2290