Feb 20 16:32:59-323949 cadet-23397 DEBUG Client(3) connected Feb 20 16:32:59-324251 cadet-23397 DEBUG New channel to W4JC at port PR96BHN5 requested by Client(3) Feb 20 16:32:59-324287 cadet-tun-23397 DEBUG Adding Channel W4JC:PR96BHN5 ctn:0(80000000/0) to Tunnel W4JC Feb 20 16:32:59-324309 cadet-chn-23397 DEBUG Tunnel up, sending CHANNEL_OPEN on Channel W4JC:PR96BHN5 ctn:0(80000000/0) now Feb 20 16:32:59-324338 cadet-chn-23397 DEBUG Created channel to port PR96BHN5 at peer P(W4JC) for Client(3) using Tunnel W4JC Feb 20 16:32:59-324382 cadet-chn-23397 DEBUG Sending CHANNEL_OPEN message for Channel W4JC:PR96BHN5 ctn:2000000(80000000/0) Feb 20 16:32:59-324401 cadet-tun-23397 DEBUG Encrypting 76 bytes for Tunnel W4JC Feb 20 16:32:59-328234 cadet-tun-23397 DEBUG Sending payload of Tunnel W4JC on Connection D0HEMY (Tunnel W4JC) Feb 20 16:32:59-328289 cadet-con-23397 DEBUG Scheduling message for transmission on Connection D0HEMY (Tunnel W4JC) Feb 20 16:32:59-328331 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0910 Feb 20 16:32:59-328372 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0910 Feb 20 16:32:59-328417 cadet-tun-23397 DEBUG Connection D0HEMY (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:32:59-328459 cadet-tun-23397 DEBUG Not sending payload of Tunnel W4JC on ready Connection D0HEMY (Tunnel W4JC) (nothing pending) Feb 20 16:32:59-328500 cadet-con-23397 DEBUG Core MQ for Connection D0HEMY (Tunnel W4JC) became available in state 4 Feb 20 16:32:59-328543 cadet-con-23397 DEBUG Scheduling keepalive for Connection D0HEMY (Tunnel W4JC) in 5 m Feb 20 16:32:59-328588 cadet-chn-23397 DEBUG Sent CADET_CHANNEL_OPEN on Channel W4JC:PR96BHN5 ctn:2000000(80000000/0), retrying in 500 ms Feb 20 16:32:59-328784 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:32:59-829222 cadet-chn-23397 DEBUG Sending CHANNEL_OPEN message for Channel W4JC:PR96BHN5 ctn:2000000(80000000/0) Feb 20 16:32:59-829533 cadet-tun-23397 DEBUG Encrypting 76 bytes for Tunnel W4JC Feb 20 16:32:59-834801 cadet-tun-23397 DEBUG Sending payload of Tunnel W4JC on Connection D0HEMY (Tunnel W4JC) Feb 20 16:32:59-835001 cadet-con-23397 DEBUG Scheduling message for transmission on Connection D0HEMY (Tunnel W4JC) Feb 20 16:32:59-835188 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0910 Feb 20 16:32:59-835410 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0910 Feb 20 16:32:59-835580 cadet-tun-23397 DEBUG Connection D0HEMY (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:32:59-835750 cadet-tun-23397 DEBUG Not sending payload of Tunnel W4JC on ready Connection D0HEMY (Tunnel W4JC) (nothing pending) Feb 20 16:32:59-835918 cadet-con-23397 DEBUG Core MQ for Connection D0HEMY (Tunnel W4JC) became available in state 4 Feb 20 16:32:59-836086 cadet-con-23397 DEBUG Scheduling keepalive for Connection D0HEMY (Tunnel W4JC) in 5 m Feb 20 16:32:59-836259 cadet-chn-23397 DEBUG Sent CADET_CHANNEL_OPEN on Channel W4JC:PR96BHN5 ctn:2000000(80000000/0), retrying in 1 s Feb 20 16:32:59-836593 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:33:00-836649 cadet-chn-23397 DEBUG Sending CHANNEL_OPEN message for Channel W4JC:PR96BHN5 ctn:2000000(80000000/0) Feb 20 16:33:00-836747 cadet-tun-23397 DEBUG Encrypting 76 bytes for Tunnel W4JC Feb 20 16:33:00-843142 cadet-tun-23397 DEBUG Sending payload of Tunnel W4JC on Connection D0HEMY (Tunnel W4JC) Feb 20 16:33:00-843181 cadet-con-23397 DEBUG Scheduling message for transmission on Connection D0HEMY (Tunnel W4JC) Feb 20 16:33:00-843219 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0910 Feb 20 16:33:00-843291 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0910 Feb 20 16:33:00-843411 cadet-tun-23397 DEBUG Connection D0HEMY (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:33:00-843482 cadet-tun-23397 DEBUG Not sending payload of Tunnel W4JC on ready Connection D0HEMY (Tunnel W4JC) (nothing pending) Feb 20 16:33:00-843548 cadet-con-23397 DEBUG Core MQ for Connection D0HEMY (Tunnel W4JC) became available in state 4 Feb 20 16:33:00-843621 cadet-con-23397 DEBUG Scheduling keepalive for Connection D0HEMY (Tunnel W4JC) in 5 m Feb 20 16:33:00-843696 cadet-chn-23397 DEBUG Sent CADET_CHANNEL_OPEN on Channel W4JC:PR96BHN5 ctn:2000000(80000000/0), retrying in 2 s Feb 20 16:33:00-844015 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:33:02-845908 cadet-chn-23397 DEBUG Sending CHANNEL_OPEN message for Channel W4JC:PR96BHN5 ctn:2000000(80000000/0) Feb 20 16:33:02-846065 cadet-tun-23397 DEBUG Encrypting 76 bytes for Tunnel W4JC Feb 20 16:33:02-852725 cadet-tun-23397 DEBUG Sending payload of Tunnel W4JC on Connection D0HEMY (Tunnel W4JC) Feb 20 16:33:02-852814 cadet-con-23397 DEBUG Scheduling message for transmission on Connection D0HEMY (Tunnel W4JC) Feb 20 16:33:02-852888 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0910 Feb 20 16:33:02-852957 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0910 Feb 20 16:33:02-853044 cadet-tun-23397 DEBUG Connection D0HEMY (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:33:02-853116 cadet-tun-23397 DEBUG Not sending payload of Tunnel W4JC on ready Connection D0HEMY (Tunnel W4JC) (nothing pending) Feb 20 16:33:02-853186 cadet-con-23397 DEBUG Core MQ for Connection D0HEMY (Tunnel W4JC) became available in state 4 Feb 20 16:33:02-853267 cadet-con-23397 DEBUG Scheduling keepalive for Connection D0HEMY (Tunnel W4JC) in 5 m Feb 20 16:33:02-853346 cadet-chn-23397 DEBUG Sent CADET_CHANNEL_OPEN on Channel W4JC:PR96BHN5 ctn:2000000(80000000/0), retrying in 4 s Feb 20 16:33:02-853679 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:33:06-857532 cadet-chn-23397 DEBUG Sending CHANNEL_OPEN message for Channel W4JC:PR96BHN5 ctn:2000000(80000000/0) Feb 20 16:33:06-857611 cadet-tun-23397 DEBUG Encrypting 76 bytes for Tunnel W4JC Feb 20 16:33:06-861482 cadet-tun-23397 DEBUG Sending payload of Tunnel W4JC on Connection D0HEMY (Tunnel W4JC) Feb 20 16:33:06-861506 cadet-con-23397 DEBUG Scheduling message for transmission on Connection D0HEMY (Tunnel W4JC) Feb 20 16:33:06-861529 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0910 Feb 20 16:33:06-861548 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0910 Feb 20 16:33:06-861573 cadet-tun-23397 DEBUG Connection D0HEMY (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:33:06-861595 cadet-tun-23397 DEBUG Not sending payload of Tunnel W4JC on ready Connection D0HEMY (Tunnel W4JC) (nothing pending) Feb 20 16:33:06-861614 cadet-con-23397 DEBUG Core MQ for Connection D0HEMY (Tunnel W4JC) became available in state 4 Feb 20 16:33:06-861635 cadet-con-23397 DEBUG Scheduling keepalive for Connection D0HEMY (Tunnel W4JC) in 5 m Feb 20 16:33:06-861659 cadet-chn-23397 DEBUG Sent CADET_CHANNEL_OPEN on Channel W4JC:PR96BHN5 ctn:2000000(80000000/0), retrying in 8 s Feb 20 16:33:06-862029 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:33:14-867420 cadet-chn-23397 DEBUG Sending CHANNEL_OPEN message for Channel W4JC:PR96BHN5 ctn:2000000(80000000/0) Feb 20 16:33:14-867522 cadet-tun-23397 DEBUG Encrypting 76 bytes for Tunnel W4JC Feb 20 16:33:14-873973 cadet-tun-23397 DEBUG Sending payload of Tunnel W4JC on Connection D0HEMY (Tunnel W4JC) Feb 20 16:33:14-874014 cadet-con-23397 DEBUG Scheduling message for transmission on Connection D0HEMY (Tunnel W4JC) Feb 20 16:33:14-874054 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0910 Feb 20 16:33:14-874085 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0910 Feb 20 16:33:14-874126 cadet-tun-23397 DEBUG Connection D0HEMY (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:33:14-874169 cadet-tun-23397 DEBUG Not sending payload of Tunnel W4JC on ready Connection D0HEMY (Tunnel W4JC) (nothing pending) Feb 20 16:33:14-874200 cadet-con-23397 DEBUG Core MQ for Connection D0HEMY (Tunnel W4JC) became available in state 4 Feb 20 16:33:14-874238 cadet-con-23397 DEBUG Scheduling keepalive for Connection D0HEMY (Tunnel W4JC) in 5 m Feb 20 16:33:14-874280 cadet-chn-23397 DEBUG Sent CADET_CHANNEL_OPEN on Channel W4JC:PR96BHN5 ctn:2000000(80000000/0), retrying in 16 s Feb 20 16:33:14-874757 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:33:14-892424 cadet-cor-23397 DEBUG Received CONNECTION_BROKEN for connection D0HEMY. Destroying it. Feb 20 16:33:14-892746 cadet-con-23397 DEBUG Destroying Connection D0HEMY Feb 20 16:33:14-893017 cadet-per-23397 DEBUG Destroying MQM 0x1ee0910 for peer P(W4JC) Feb 20 16:33:14-893287 cadet-pat-23397 DEBUG Removing connection Connection D0HEMY to path W4JC(0x1ee0050) at offset 0 Feb 20 16:33:30-890380 cadet-chn-23397 DEBUG Sending CHANNEL_OPEN message for Channel W4JC:PR96BHN5 ctn:2000000(80000000/0) Feb 20 16:33:30-890656 cadet-tun-23397 DEBUG Encrypting 76 bytes for Tunnel W4JC Feb 20 16:33:47-869124 cadet-tun-23397 DEBUG Performing connection maintenance for Tunnel W4JC. Feb 20 16:33:47-869230 cadet-per-23397 DEBUG Iterating over paths to peer P(W4JC) including direct link Feb 20 16:33:47-869279 cadet-pat-23397 DEBUG check_match found match with path W4JC(0x1ee0050) Feb 20 16:33:47-869325 cadet-pat-23397 DEBUG Returning existing path W4JC(0x1ee0050) as inverse for incoming connection Feb 20 16:33:47-869380 cadet-con-23397 DEBUG Creating Connection Z6RT7C (Tunnel W4JC) using path W4JC(0x1ee0050) Feb 20 16:33:47-869415 cadet-pat-23397 DEBUG Adding connection Connection Z6RT7C (Tunnel W4JC) to path W4JC(0x1ee0050) at offset 0 Feb 20 16:33:47-869449 cadet-per-23397 DEBUG Creating MQM 0x1ee0870 for peer P(W4JC) Feb 20 16:33:47-869482 cadet-con-23397 DEBUG Core MQ for Connection Z6RT7C (Tunnel W4JC) became available in state 0 Feb 20 16:33:47-869524 cadet-tun-23397 DEBUG Found interesting path W4JC(0x1ee0050) for Tunnel W4JC, created Connection Z6RT7C (Tunnel W4JC) Feb 20 16:33:47-869556 cadet-tun-23397 DEBUG Ignoring duplicate path W4JC(0x1ee0050). Feb 20 16:33:47-869614 cadet-con-23397 DEBUG Sending CADET_CONNECTION_CREATE message for Connection Z6RT7C (Tunnel W4JC) Feb 20 16:33:47-869646 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0870 Feb 20 16:33:47-869677 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0870 Feb 20 16:33:47-869714 cadet-con-23397 DEBUG Core MQ for Connection Z6RT7C (Tunnel W4JC) became available in state 2 Feb 20 16:33:47-870076 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:33:47-871864 cadet-con-23397 DEBUG Sending CADET_CONNECTION_CREATE message for Connection Z6RT7C (Tunnel W4JC) Feb 20 16:33:47-871913 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0870 Feb 20 16:33:47-871944 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0870 Feb 20 16:33:47-871979 cadet-con-23397 DEBUG Core MQ for Connection Z6RT7C (Tunnel W4JC) became available in state 2 Feb 20 16:33:47-872279 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:33:47-876633 cadet-con-23397 DEBUG Sending CADET_CONNECTION_CREATE message for Connection Z6RT7C (Tunnel W4JC) Feb 20 16:33:47-876681 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0870 Feb 20 16:33:47-876711 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0870 Feb 20 16:33:47-876747 cadet-con-23397 DEBUG Core MQ for Connection Z6RT7C (Tunnel W4JC) became available in state 2 Feb 20 16:33:47-877037 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:33:47-884944 cadet-con-23397 DEBUG Sending CADET_CONNECTION_CREATE message for Connection Z6RT7C (Tunnel W4JC) Feb 20 16:33:47-885003 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0870 Feb 20 16:33:47-885035 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0870 Feb 20 16:33:47-885072 cadet-con-23397 DEBUG Core MQ for Connection Z6RT7C (Tunnel W4JC) became available in state 2 Feb 20 16:33:47-885382 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:33:47-896905 cadet-cor-23397 DEBUG Received CONNECTION_CREATE_ACK for connection Z6RT7C. Feb 20 16:33:47-897219 cadet-con-23397 DEBUG Received CADET_CONNECTION_CREATE_ACK for Connection Z6RT7C (Tunnel W4JC) in state 2 (MQM ready) Feb 20 16:33:47-897411 cadet-tun-23397 DEBUG Connection Z6RT7C (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:33:47-897482 cadet-tun-23397 DEBUG Sending payload of Tunnel W4JC on Connection Z6RT7C (Tunnel W4JC) Feb 20 16:33:47-897553 cadet-con-23397 DEBUG Scheduling message for transmission on Connection Z6RT7C (Tunnel W4JC) Feb 20 16:33:47-897624 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0870 Feb 20 16:33:47-897687 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0870 Feb 20 16:33:47-897812 cadet-tun-23397 DEBUG Connection Z6RT7C (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:33:47-897884 cadet-tun-23397 DEBUG Not sending payload of Tunnel W4JC on ready Connection Z6RT7C (Tunnel W4JC) (nothing pending) Feb 20 16:33:47-897961 cadet-con-23397 DEBUG Core MQ for Connection Z6RT7C (Tunnel W4JC) became available in state 4 Feb 20 16:33:47-898030 cadet-con-23397 DEBUG Scheduling keepalive for Connection Z6RT7C (Tunnel W4JC) in 5 m Feb 20 16:33:47-898107 cadet-chn-23397 DEBUG Sent CADET_CHANNEL_OPEN on Channel W4JC:PR96BHN5 ctn:2000000(80000000/0), retrying in 32 s Feb 20 16:33:47-898492 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:33:47-901842 cadet-cor-23397 DEBUG Received CONNECTION_CREATE_ACK for connection Z6RT7C. Feb 20 16:33:47-902131 cadet-con-23397 DEBUG Received CADET_CONNECTION_CREATE_ACK for Connection Z6RT7C (Tunnel W4JC) in state 4 (MQM ready) Feb 20 16:33:47-911228 cadet-cor-23397 DEBUG Received CONNECTION_CREATE_ACK for connection Z6RT7C. Feb 20 16:33:47-911576 cadet-con-23397 DEBUG Received CADET_CONNECTION_CREATE_ACK for Connection Z6RT7C (Tunnel W4JC) in state 4 (MQM ready) Feb 20 16:33:47-923158 cadet-tun-23397 DEBUG Creating fresh unverified KX for Tunnel W4JC. Feb 20 16:33:48-435795 cadet-tun-23397 DEBUG Got duplicate unverified KX on Tunnel W4JC. Fire back KX_AUTH again. Feb 20 16:33:48-435903 cadet-tun-23397 DEBUG Sending KX_AUTH on Tunnel W4JC using Connection Z6RT7C (Tunnel W4JC) Feb 20 16:33:48-445261 cadet-con-23397 DEBUG Scheduling message for transmission on Connection Z6RT7C (Tunnel W4JC) Feb 20 16:33:48-445345 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0870 Feb 20 16:33:48-445409 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0870 Feb 20 16:33:48-445480 cadet-tun-23397 DEBUG Connection Z6RT7C (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:33:48-445548 cadet-tun-23397 DEBUG Not sending payload of Tunnel W4JC on ready Connection Z6RT7C (Tunnel W4JC) (nothing pending) Feb 20 16:33:48-445613 cadet-con-23397 DEBUG Core MQ for Connection Z6RT7C (Tunnel W4JC) became available in state 4 Feb 20 16:33:48-445680 cadet-con-23397 DEBUG Scheduling keepalive for Connection Z6RT7C (Tunnel W4JC) in 5 m Feb 20 16:33:48-445950 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:34:19-913518 cadet-chn-23397 DEBUG Sending CHANNEL_OPEN message for Channel W4JC:PR96BHN5 ctn:2000000(80000000/0) Feb 20 16:34:19-913586 cadet-tun-23397 DEBUG Encrypting 76 bytes for Tunnel W4JC Feb 20 16:34:19-917381 cadet-tun-23397 DEBUG Sending payload of Tunnel W4JC on Connection Z6RT7C (Tunnel W4JC) Feb 20 16:34:19-917402 cadet-con-23397 DEBUG Scheduling message for transmission on Connection Z6RT7C (Tunnel W4JC) Feb 20 16:34:19-917428 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0870 Feb 20 16:34:19-917446 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0870 Feb 20 16:34:19-917472 cadet-tun-23397 DEBUG Connection Z6RT7C (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:34:19-917493 cadet-tun-23397 DEBUG Not sending payload of Tunnel W4JC on ready Connection Z6RT7C (Tunnel W4JC) (nothing pending) Feb 20 16:34:19-917513 cadet-con-23397 DEBUG Core MQ for Connection Z6RT7C (Tunnel W4JC) became available in state 4 Feb 20 16:34:19-917536 cadet-con-23397 DEBUG Scheduling keepalive for Connection Z6RT7C (Tunnel W4JC) in 5 m Feb 20 16:34:19-917560 cadet-chn-23397 DEBUG Sent CADET_CHANNEL_OPEN on Channel W4JC:PR96BHN5 ctn:2000000(80000000/0), retrying in 64 s Feb 20 16:34:19-917894 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:34:47-873374 cadet-tun-23397 DEBUG Performing connection maintenance for Tunnel W4JC. Feb 20 16:34:47-873488 cadet-per-23397 DEBUG Iterating over paths to peer P(W4JC) including direct link Feb 20 16:34:47-873526 cadet-pat-23397 DEBUG check_match found match with path W4JC(0x1ee0050) Feb 20 16:34:47-873560 cadet-pat-23397 DEBUG Returning existing path W4JC(0x1ee0050) as inverse for incoming connection Feb 20 16:34:47-873592 cadet-tun-23397 DEBUG Ignoring duplicate path W4JC(0x1ee0050). Feb 20 16:34:47-873623 cadet-tun-23397 DEBUG Ignoring duplicate path W4JC(0x1ee0050). Feb 20 16:35:23-932508 cadet-chn-23397 DEBUG Sending CHANNEL_OPEN message for Channel W4JC:PR96BHN5 ctn:2000000(80000000/0) Feb 20 16:35:23-932583 cadet-tun-23397 DEBUG Encrypting 76 bytes for Tunnel W4JC Feb 20 16:35:23-936485 cadet-tun-23397 DEBUG Sending payload of Tunnel W4JC on Connection Z6RT7C (Tunnel W4JC) Feb 20 16:35:23-936509 cadet-con-23397 DEBUG Scheduling message for transmission on Connection Z6RT7C (Tunnel W4JC) Feb 20 16:35:23-936533 cadet-per-23397 DEBUG Queueing message to peer P(W4JC) in MQM 0x1ee0870 Feb 20 16:35:23-936553 cadet-per-23397 DEBUG Sending to peer P(W4JC) from MQM 0x1ee0870 Feb 20 16:35:23-936579 cadet-tun-23397 DEBUG Connection Z6RT7C (Tunnel W4JC) now ready for Tunnel W4JC in state CADET_TUNNEL_KEY_OK Feb 20 16:35:23-936600 cadet-tun-23397 DEBUG Not sending payload of Tunnel W4JC on ready Connection Z6RT7C (Tunnel W4JC) (nothing pending) Feb 20 16:35:23-936620 cadet-con-23397 DEBUG Core MQ for Connection Z6RT7C (Tunnel W4JC) became available in state 4 Feb 20 16:35:23-936643 cadet-con-23397 DEBUG Scheduling keepalive for Connection Z6RT7C (Tunnel W4JC) in 5 m Feb 20 16:35:23-936668 cadet-chn-23397 DEBUG Sent CADET_CHANNEL_OPEN on Channel W4JC:PR96BHN5 ctn:2000000(80000000/0), retrying in 128 s Feb 20 16:35:23-937000 cadet-per-23397 DEBUG Sending to peer P(W4JC) completed Feb 20 16:35:47-895363 cadet-tun-23397 DEBUG Performing connection maintenance for Tunnel W4JC. Feb 20 16:35:47-895449 cadet-per-23397 DEBUG Iterating over paths to peer P(W4JC) including direct link Feb 20 16:35:47-895473 cadet-pat-23397 DEBUG check_match found match with path W4JC(0x1ee0050) Feb 20 16:35:47-895491 cadet-pat-23397 DEBUG Returning existing path W4JC(0x1ee0050) as inverse for incoming connection Feb 20 16:35:47-895513 cadet-tun-23397 DEBUG Ignoring duplicate path W4JC(0x1ee0050). Feb 20 16:35:47-895530 cadet-tun-23397 DEBUG Ignoring duplicate path W4JC(0x1ee0050). Feb 20 16:36:45-770974 cadet-hll-23397 DEBUG Hello for W4JC (472 bytes), expires on Tue Feb 21 04:36:45 2017 Feb 20 16:36:45-771048 cadet-per-23397 DEBUG Got 472 byte HELLO for peer P(W4JC) Feb 20 16:36:47-897867 cadet-tun-23397 DEBUG Performing connection maintenance for Tunnel W4JC. Feb 20 16:36:47-897991 cadet-per-23397 DEBUG Iterating over paths to peer P(W4JC) including direct link Feb 20 16:36:47-898032 cadet-pat-23397 DEBUG check_match found match with path W4JC(0x1ee0050) Feb 20 16:36:47-898069 cadet-pat-23397 DEBUG Returning existing path W4JC(0x1ee0050) as inverse for incoming connection Feb 20 16:36:47-898104 cadet-tun-23397 DEBUG Ignoring duplicate path W4JC(0x1ee0050). Feb 20 16:36:47-898137 cadet-tun-23397 DEBUG Ignoring duplicate path W4JC(0x1ee0050).