mobility and echo request problem

Bug #795231 reported by Miika Komu
12
This bug affects 2 people
Affects Status Importance Assigned to Milestone
HIPL
Fix Released
High
René Hummen

Bug Description

As noted in bug report #789327, mobility code suffers from a strange echo request problem. In the first handover, the mobile node seems to forget to include an echo request to the UPDATE packet and the correspondent node gives the error below. Correspondent node fails to set up SAs correctly until few minutes retransmissions after which there's probably a successful handover.

Stefan tested the problem with IPv4 and I tested it with IPv6. Here's my log from the CN:

debug(modules/heartbeat/hipd/heartbeat.c:180@hip_send_icmp): Sent heartbeat to: 2001:0019:11ac:e3af:2367:11a4:1a36:36ec
debug(modules/heartbeat/hipd/heartbeat.c:379@hip_send_heartbeat: heartbeat_counter: 1
debug(hipd/hip_socket.c:69@hip_handle_raw_input_v6): received on: hip_raw_sock_input_v6
debug(lib/core/message.c:672@hip_read_control_msg_v6): Receiving a message on raw HIP from IPv6/HIP socket (file descriptor: 9).
debug(lib/core/message.c:562@hip_read_control_msg_all): hip_read_control_msg_all() invoked.
debug(lib/core/builder.c:1920@hip_verify_network_header): Received a connection to HIT: 2001:0015:e156:8a78:3226:dbaa:f2ff:ed06
debug(lib/core/builder.c:1924@hip_verify_network_header): dst port is 0
debug(lib/tool/checksum.c:278@hip_checksum_packet): Checksumming 304 bytes of data.
debug(lib/core/message.c:652@hip_read_control_msg_all): src: 3ffe:0000:0000:0000:0000:0000:0000:0012
debug(lib/core/message.c:653@hip_read_control_msg_all): dst: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(hipd/hidb.c:326@hip_get_hostid_entry_by_lhi_and_algo): Failed to find a host ID entry.
info(hipd/input.c:530@hip_receive_control_packet): Src IP: 3ffe:0000:0000:0000:0000:0000:0000:0012
info(hipd/input.c:531@hip_receive_control_packet): Dst IP: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(hipd/input.c:532@hip_receive_control_packet): Src port: 0
debug(hipd/input.c:533@hip_receive_control_packet): Dst port: 0
debug(hipd/input.c:534@hip_receive_control_packet): dump:
debug(lib/core/builder.c:1263@hip_dump_msg): --------------- MSG START ------------------
debug(lib/core/builder.c:1264@hip_dump_msg): HIT Sender : 2001:0019:11ac:e3af:2367:11a4:1a36:36ec
debug(lib/core/builder.c:1265@hip_dump_msg): HIT Receiver: 2001:0015:e156:8a78:3226:dbaa:f2ff:ed06
debug(lib/core/builder.c:1268@hip_dump_msg): Msg type : HIP_UPDATE (16)
debug(lib/core/builder.c:1269@hip_dump_msg): Msg length: 304
debug(lib/core/builder.c:1270@hip_dump_msg): Msg err: 31599
debug(lib/core/builder.c:1271@hip_dump_msg): Msg controls: 0x0000
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_ESP_INFO (65). Total length: 16 (4 type+length, 12 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x00000090CFEF6E53CFEF6E53
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_LOCATOR (193). Total length: 80 (4 type+length, 72 content, 4 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x000104000000000000000000000000000000FFFFC0A800A500010400000000003FFE000000000000000000000000001200010400000000002001000053AA064C14E53F92AD4A59A7
debug(lib/core/builder.c:1288@hip_dump_msg): Padding:0x00000000
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_SEQ (385). Total length: 8 (4 type+length, 4 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x00000004
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_HMAC (61505). Total length: 24 (4 type+length, 20 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0xD9BBA76DDDCBDFE033624CC6B3381857DCB87327
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_HIP_SIGNATURE (61697). Total length: 136 (4 type+length, 129 content, 3 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x058AB5B82DE33567F5107BD08C21B6D14B27EC808AE3655205D98CEDE5D7E8163FFDD867269F70B32E710A0C1ED87F3A5BB16E2A3BE6EE4A9F2C49E22D2BA434DEB918F1FFBA6C4561BCC48E4A73B1E58804B94865C697CCBAE5A5528211EC115D98A1673AEC6BF9DD8C89DF08249F70E2C9D4DA8CF396105538BC38D5CD911064
debug(lib/core/builder.c:1288@hip_dump_msg): Padding:0x000000
debug(lib/core/builder.c:1290@hip_dump_msg): ---------------- MSG END --------------------
debug(hipd/hadb.c:249@hip_hadb_find_byhits): Local HIT: 2001:0019:11ac:e3af:2367:11a4:1a36:36ec
debug(hipd/hadb.c:250@hip_hadb_find_byhits): Remote HIT: 2001:0015:e156:8a78:3226:dbaa:f2ff:ed06
debug(hipd/input.c:565@hip_receive_control_packet): HIP association state ESTABLISHED
debug(hipd/input.c:573@hip_receive_control_packet): handle relay to failed, continue the bex handler
debug(modules/update/hipd/update.c:190@hip_check_update_freshne: previous incoming update id=2
debug(modules/update/hipd/update.c:192@hip_check_update_freshne: previous outgoing update id=2
debug(modules/update/hipd/update.c:198@hip_check_update_freshne: SEQ parameter found with Update ID 4.
debug(hipd/input.c:144@hip_verify_packet_hmac_general): hip_verify_packet_hmac() invoked.
debug(hipd/input.c:111@hip_verify_hmac): HMAC data0x3B111011000000002001001911ACE3AF236711A41A3636EC20010015E1568A783226DBAAF2FFED060041000C00000090CFEF6E53CFEF6E5300C10048000104000000000000000000000000000000FFFFC0A800A500010400000000003FFE000000000000000000000000001200010400000000002001000053AA064C14E53F92AD4A59A7000000000181000400000004
debug(lib/core/crypto.c:378@hip_write_hmac): HMAC key:0xBBC7D71516BEAB599DF37DB0D690F0323BEFB76B
debug(lib/core/crypto.c:379@hip_write_hmac): HMAC in:0x3B111011000000002001001911ACE3AF236711A41A3636EC20010015E1568A783226DBAAF2FFED060041000C00000090CFEF6E53CFEF6E5300C10048000104000000000000000000000000000000FFFFC0A800A500010400000000003FFE000000000000000000000000001200010400000000002001000053AA064C14E53F92AD4A59A7000000000181000400000004
debug(lib/core/crypto.c:380@hip_write_hmac): HMAC out:0xD9BBA76DDDCBDFE033624CC6B3381857DCB87327
debug(hipd/input.c:117@hip_verify_hmac): HMAC0xD9BBA76DDDCBDFE033624CC6B3381857DCB87327
debug(modules/update/hipd/update_param_handling.c:342@hip_handl: LOCATOR has 3 address(es), loc param len=80
debug(modules/update/hipd/update_param_handling.c:349@hip_handl: hip_get_state_item returned localstate: 0x21fa290
debug(modules/update/hipd/update_param_handling.c:363@hip_handl: Comparing: 3ffe:0000:0000:0000:0000:0000:0000:0012
debug(modules/update/hipd/update_param_handling.c:364@hip_handl: to : 192.168.0.165
debug(modules/update/hipd/update_param_handling.c:363@hip_handl: Comparing: 3ffe:0000:0000:0000:0000:0000:0000:0012
debug(modules/update/hipd/update_param_handling.c:364@hip_handl: to : 3ffe:0000:0000:0000:0000:0000:0000:0012
debug(modules/update/hipd/update_param_handling.c:363@hip_handl: Comparing: 3ffe:0000:0000:0000:0000:0000:0000:0012
debug(modules/update/hipd/update_param_handling.c:364@hip_handl: to : 2001:0000:53aa:064c:14e5:3f92:ad4a:59a7
debug(modules/update/hipd/update_param_handling.c:85@hip_print_: Addresses to send update:
debug(modules/update/hipd/update_param_handling.c:87@hip_print_: : 192.168.0.165
debug(modules/update/hipd/update_param_handling.c:87@hip_print_: : 3ffe:0000:0000:0000:0000:0000:0000:0012
debug(modules/update/hipd/update_param_handling.c:87@hip_print_: : 2001:0000:53aa:064c:14e5:3f92:ad4a:59a7
debug(modules/update/hipd/update_param_handling.c:178@hip_add_s: outgoing UPDATE ID=3
debug(modules/update/hipd/update_param_handling.c:259@hip_add_e: ECHO_REQUEST in the host association0x479820C7
error(modules/update/hipd/update_param_handling.c:293@hip_handl: ECHO_REQUEST parameter not found!
debug(lib/core/crypto.c:378@hip_write_hmac): HMAC key:0x7C4E5670709642AC20AE3806E8DB7473217CD036
debug(lib/core/crypto.c:379@hip_write_hmac): HMAC in:0x3B0910110000000020010015E1568A783226DBAAF2FFED062001001911ACE3AF236711A41A3636EC0041000C00000090D5F95E22D5F95E22018100040000000301C100040000000403810004479820C7
debug(lib/core/crypto.c:380@hip_write_hmac): HMAC out:0x502D543C55343C2E15630F9A66DA204409BF01DF
debug(modules/update/hipd/update.c:307@hip_send_update_packet): Sending echo requests from: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(modules/update/hipd/update.c:308@hip_send_update_packet): to: 3ffe:0000:0000:0000:0000:0000:0000:0012
debug(hipd/output.c:1200@hip_send_raw_from_one_src): Sending HIP_UPDATE packet
debug(hipd/output.c:1201@hip_send_raw_from_one_src): hip_send_raw(): local_addr: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(hipd/output.c:1202@hip_send_raw_from_one_src): hip_send_raw(): peer_addr: 3ffe:0000:0000:0000:0000:0000:0000:0012
debug(hipd/output.c:1203@hip_send_raw_from_one_src): Source port=0, destination port=0
debug(hipd/output.c:1204@hip_send_raw_from_one_src): dump:
debug(lib/core/builder.c:1263@hip_dump_msg): --------------- MSG START ------------------
debug(lib/core/builder.c:1264@hip_dump_msg): HIT Sender : 2001:0015:e156:8a78:3226:dbaa:f2ff:ed06
debug(lib/core/builder.c:1265@hip_dump_msg): HIT Receiver: 2001:0019:11ac:e3af:2367:11a4:1a36:36ec
debug(lib/core/builder.c:1268@hip_dump_msg): Msg type : HIP_UPDATE (16)
debug(lib/core/builder.c:1269@hip_dump_msg): Msg length: 240
debug(lib/core/builder.c:1270@hip_dump_msg): Msg err: 0
debug(lib/core/builder.c:1271@hip_dump_msg): Msg controls: 0x0000
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_ESP_INFO (65). Total length: 16 (4 type+length, 12 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x00000090D5F95E22D5F95E22
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_SEQ (385). Total length: 8 (4 type+length, 4 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x00000003
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_ACK (449). Total length: 8 (4 type+length, 4 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x00000004
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_ECHO_REQUEST_SIGN (897). Total length: 8 (4 type+length, 4 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x479820C7
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_HMAC (61505). Total length: 24 (4 type+length, 20 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x502D543C55343C2E15630F9A66DA204409BF01DF
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_HIP_SIGNATURE (61697). Total length: 136 (4 type+length, 129 content, 3 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x0593FA2C2ED22BFB5B9A9A0C871597BAFD3309C66F348454A60549DEF87C5697C58DB3E05069E1225CCD94D5475CF696CC4626F35873A664FB53D2F4D762D041511FDF15D113B754367E37A22F6239E8A7B45E27B151F9F691DCB5329E601ECD6354D5334FAA2885FBDD452554C48CC89BA8120281A0DC8FF694B924DC35B39569
debug(lib/core/builder.c:1288@hip_dump_msg): Padding:0x000000
debug(lib/core/builder.c:1290@hip_dump_msg): ---------------- MSG END --------------------
debug(hipd/output.c:1233@hip_send_raw_from_one_src): Using IPv6 raw socket
debug(hipd/output.c:1239@hip_send_raw_from_one_src): local address given
debug(hipd/output.c:1256@hip_send_raw_from_one_src): src6: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(hipd/output.c:1267@hip_send_raw_from_one_src): dst6: 3ffe:0000:0000:0000:0000:0000:0000:0012
debug(lib/tool/checksum.c:278@hip_checksum_packet): Checksumming 240 bytes of data.
debug(hipd/output.c:1344@hip_send_raw_from_one_src): sent=240/240 ipv4=0
debug(hipd/output.c:1345@hip_send_raw_from_one_src): Packet sent ok
debug(modules/update/hipd/update.c:307@hip_send_update_packet): Sending echo requests from: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(modules/update/hipd/update.c:308@hip_send_update_packet): to: 2001:0000:53aa:064c:14e5:3f92:ad4a:59a7
debug(hipd/output.c:1200@hip_send_raw_from_one_src): Sending HIP_UPDATE packet
debug(hipd/output.c:1201@hip_send_raw_from_one_src): hip_send_raw(): local_addr: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(hipd/output.c:1202@hip_send_raw_from_one_src): hip_send_raw(): peer_addr: 2001:0000:53aa:064c:14e5:3f92:ad4a:59a7
debug(hipd/output.c:1203@hip_send_raw_from_one_src): Source port=0, destination port=0
debug(hipd/output.c:1204@hip_send_raw_from_one_src): dump:
debug(lib/core/builder.c:1263@hip_dump_msg): --------------- MSG START ------------------
debug(lib/core/builder.c:1264@hip_dump_msg): HIT Sender : 2001:0015:e156:8a78:3226:dbaa:f2ff:ed06
debug(lib/core/builder.c:1265@hip_dump_msg): HIT Receiver: 2001:0019:11ac:e3af:2367:11a4:1a36:36ec
debug(lib/core/builder.c:1268@hip_dump_msg): Msg type : HIP_UPDATE (16)
debug(lib/core/builder.c:1269@hip_dump_msg): Msg length: 240
debug(lib/core/builder.c:1270@hip_dump_msg): Msg err: 6590
debug(lib/core/builder.c:1271@hip_dump_msg): Msg controls: 0x0000
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_ESP_INFO (65). Total length: 16 (4 type+length, 12 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x00000090D5F95E22D5F95E22
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_SEQ (385). Total length: 8 (4 type+length, 4 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x00000003
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_ACK (449). Total length: 8 (4 type+length, 4 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x00000004
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_ECHO_REQUEST_SIGN (897). Total length: 8 (4 type+length, 4 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x479820C7
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_HMAC (61505). Total length: 24 (4 type+length, 20 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x502D543C55343C2E15630F9A66DA204409BF01DF
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_HIP_SIGNATURE (61697). Total length: 136 (4 type+length, 129 content, 3 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x0593FA2C2ED22BFB5B9A9A0C871597BAFD3309C66F348454A60549DEF87C5697C58DB3E05069E1225CCD94D5475CF696CC4626F35873A664FB53D2F4D762D041511FDF15D113B754367E37A22F6239E8A7B45E27B151F9F691DCB5329E601ECD6354D5334FAA2885FBDD452554C48CC89BA8120281A0DC8FF694B924DC35B39569
debug(lib/core/builder.c:1288@hip_dump_msg): Padding:0x000000
debug(lib/core/builder.c:1290@hip_dump_msg): ---------------- MSG END --------------------
debug(hipd/output.c:1233@hip_send_raw_from_one_src): Using IPv6 raw socket
debug(hipd/output.c:1239@hip_send_raw_from_one_src): local address given
debug(hipd/output.c:1256@hip_send_raw_from_one_src): src6: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(hipd/output.c:1267@hip_send_raw_from_one_src): dst6: 2001:0000:53aa:064c:14e5:3f92:ad4a:59a7
debug(lib/tool/checksum.c:278@hip_checksum_packet): Checksumming 240 bytes of data.
debug(hipd/output.c:1344@hip_send_raw_from_one_src): sent=240/240 ipv4=0
debug(hipd/output.c:1345@hip_send_raw_from_one_src): Packet sent ok
debug(hipd/input.c:591@hip_receive_control_packet): Done with control packet, err is 0.
debug(hipd/hip_socket.c:308@hip_run_socket_handles): result: 0
debug(modules/heartbeat/hipd/heartbeat.c:180@hip_send_icmp): Sent heartbeat to: 2001:0019:11ac:e3af:2367:11a4:1a36:36ec
debug(modules/heartbeat/hipd/heartbeat.c:379@hip_send_heartbeat: heartbeat_counter: 2
^Cerror(hipd/init.c:996@hip_close): Caught signal: 2
debug(hipd/close.c:172@hip_send_close): msg=(nil)
debug(hipd/close.c:83@hip_xmit_close): Peer HIT to be closed: 0000:0000:0000:0000:0000:0000:0000:0000
debug(hipd/close.c:106@hip_xmit_close): State is ESTABLISHED in current host association, sending CLOSE message to peer.
debug(hipd/hidb.c:326@hip_get_hostid_entry_by_lhi_and_algo): Failed to find a host ID entry.
debug(hipd/maintenance.c:374@hip_firewall_set_bex_data): BEX DATA Send to firewall OK.
debug(lib/core/crypto.c:378@hip_write_hmac): HMAC key:0x7C4E5670709642AC20AE3806E8DB7473217CD036
debug(lib/core/crypto.c:379@hip_write_hmac): HMAC in:0x3B0512110000000020010015E1568A783226DBAAF2FFED062001001911ACE3AF236711A41A3636EC038100044FBF41C7
debug(lib/core/crypto.c:380@hip_write_hmac): HMAC out:0xBA8C40354F0FDCA733C52DA225BFD1BE0F84FD38
debug(hipd/output.c:1200@hip_send_raw_from_one_src): Sending HIP_CLOSE packet
debug(lib/core/debug.c:741@hip_print_hit): hip_send_raw(): local_addr: NULL
debug(hipd/output.c:1202@hip_send_raw_from_one_src): hip_send_raw(): peer_addr: 3ffe:0000:0000:0000:0000:0000:0000:0011
debug(hipd/output.c:1203@hip_send_raw_from_one_src): Source port=0, destination port=0
debug(hipd/output.c:1204@hip_send_raw_from_one_src): dump:
debug(lib/core/builder.c:1263@hip_dump_msg): --------------- MSG START ------------------
debug(lib/core/builder.c:1264@hip_dump_msg): HIT Sender : 2001:0015:e156:8a78:3226:dbaa:f2ff:ed06
debug(lib/core/builder.c:1265@hip_dump_msg): HIT Receiver: 2001:0019:11ac:e3af:2367:11a4:1a36:36ec
debug(lib/core/builder.c:1268@hip_dump_msg): Msg type : HIP_CLOSE (18)
debug(lib/core/builder.c:1269@hip_dump_msg): Msg length: 208
debug(lib/core/builder.c:1270@hip_dump_msg): Msg err: 0
debug(lib/core/builder.c:1271@hip_dump_msg): Msg controls: 0x0000
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_ECHO_REQUEST_SIGN (897). Total length: 8 (4 type+length, 4 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x4FBF41C7
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_HMAC (61505). Total length: 24 (4 type+length, 20 content, 0 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0xBA8C40354F0FDCA733C52DA225BFD1BE0F84FD38
debug(lib/core/builder.c:1286@hip_dump_msg): Parameter type:HIP_PARAM_HIP_SIGNATURE (61697). Total length: 136 (4 type+length, 129 content, 3 padding).
debug(lib/core/builder.c:1287@hip_dump_msg): Contents:0x051FCD73A5979207D77B3193B7F8FAB65ED576CB24A549BFF6978A559538C32D5D209795E9D015B58A35B3D1C89FC22A1E1BC4829C40C06881F0DED3169F26FD264305EB7C032F25BD02F1BCE18F6386B779FF9D1E84AA18422E64D198CFD6A7232E63F8A9FF6EB04DA4346FA7C67F087A0A5DEF401EA5D787C8C22585D8EF0B75
debug(lib/core/builder.c:1288@hip_dump_msg): Padding:0x000000
debug(lib/core/builder.c:1290@hip_dump_msg): ---------------- MSG END --------------------
debug(hipd/output.c:1233@hip_send_raw_from_one_src): Using IPv6 raw socket
debug(hipd/output.c:1242@hip_send_raw_from_one_src): no local address, selecting one
debug(hipd/netdev.c:1404@hip_select_source_address): dst: 3ffe:0000:0000:0000:0000:0000:0000:0011
debug(lib/tool/nlink.c:1109@hip_iproute_get): Getting route for destination address: 3ffe:0000:0000:0000:0000:0000:0000:0011
error(lib/tool/nlink.c:882@parse_rtattr): Deficit len 28, rta_len=0
debug(hipd/netdev.c:1432@hip_select_source_address): src: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(hipd/output.c:1256@hip_send_raw_from_one_src): src6: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(hipd/output.c:1267@hip_send_raw_from_one_src): dst6: 3ffe:0000:0000:0000:0000:0000:0000:0011
debug(lib/tool/checksum.c:278@hip_checksum_packet): Checksumming 208 bytes of data.
debug(hipd/output.c:1344@hip_send_raw_from_one_src): sent=208/208 ipv4=0
debug(hipd/output.c:1345@hip_send_raw_from_one_src): Packet sent ok
debug(hipd/user.c:228@hip_sendto_user): Sending msg type 98
debug(hipd/close.c:210@hip_send_close): resetof firewall db ok (sent 40 bytes)
debug(hipd/init.c:1003@hip_close): Starting to close HIP daemon...
error(hipd/hipd.c:396@hipd_main): select() error: Interrupted system call.
error(hipd/hipd.c:409@hipd_main): Error (1) ignoring. Interrupted system call
error(hipd/hipd.c:409@hipd_main): Error (1) ignoring. Interrupted system call
error(hipd/hipd.c:409@hipd_main): Error (1) ignoring. Interrupted system call
error(hipd/hipd.c:409@hipd_main): Error (1) ignoring. Interrupted system call
error(hipd/hipd.c:409@hipd_main): Error (1) ignoring. Interrupted system call
debug(hipd/hadb.c:1159@hip_delete_all_sp):
debug(hipd/hadb.c:1161@hip_delete_all_sp): DEBUG: DUMP SPI LISTS
debug(hipd/hadb.c:1163@hip_delete_all_sp): DELETING HA HT
debug(hipd/netdev.c:513@hip_delete_all_addresses): address to be deleted
: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(hipd/netdev.c:513@hip_delete_all_addresses): address to be deleted
: 2001:0000:53aa:064c:248a:3aa6:ad4a:59a7
debug(hipd/netdev.c:513@hip_delete_all_addresses): address to be deleted
: 192.168.0.2
error(lib/tool/nlink.c:1325@do_chflags): SIOCSIFFLAGSCannot assign requested address
debug(hipd/hidb.c:710@hip_for_each_hi): Found HIT: 2001:0015:e156:8a78:3226:dbaa:f2ff:ed06
debug(hipd/hidb.c:711@hip_for_each_hi): Found LSI: 1.0.0.1
debug(hipd/netdev.c:1294@hip_manage_iface_local_hit): Removing HIT: 2001:0015:e156:8a78:3226:dbaa:f2ff:ed06/28
debug(lib/tool/nlink.c:1253@hip_ipaddr_modify): IFA INDEX IS 6
debug(lib/tool/nlink.c:1257@hip_ipaddr_modify): value exit function netlink_talk 0
info(hipd/init.c:911@hip_exit): Uninitializing RVS / HIP relay database and whitelist.
debug(hipd/hiprelay.c:613@hip_relwl_hit_free_doall): HIT deleted from the relay whitelist.
info(hipd/init.c:916@hip_exit): hip_raw_sock_input_v6
info(hipd/init.c:921@hip_exit): hip_raw_sock_output_v6
info(hipd/init.c:926@hip_exit): hip_raw_sock_input_v4
info(hipd/init.c:931@hip_exit): hip_raw_sock_output_v4
info(hipd/init.c:936@hip_exit): hip_nat_sock_input_udp
info(hipd/init.c:941@hip_exit): hip_nat_sock_output_udp
debug(hipd/hadb.c:927@hip_del_peer_info_entry): our HIT: 2001:0015:e156:8a78:3226:dbaa:f2ff:ed06
debug(hipd/hadb.c:928@hip_del_peer_info_entry): peer HIT: 2001:0019:11ac:e3af:2367:11a4:1a36:36ec
debug(hipd/hadb.c:1483@hip_delete_security_associations_and_sp): Previous SPI out =0xcfef6e53
debug(hipd/hadb.c:1484@hip_delete_security_associations_and_sp): Previous SPI in =0xd5f95e22
debug(hipd/hadb.c:1486@hip_delete_security_associations_and_sp): Our current active addr: 3ffe:0000:0000:0000:0000:0000:0000:0001
debug(hipd/hadb.c:1487@hip_delete_security_associations_and_sp): Peer's current active addr: 3ffe:0000:0000:0000:0000:0000:0000:0011
debug(lib/tool/xfrmapi.c:543@hip_xfrm_state_delete): IPV6 SA deletion
debug(lib/tool/xfrmapi.c:548@hip_xfrm_state_delete): sport 0, dport 0
debug(lib/tool/xfrmapi.c:567@hip_xfrm_state_delete): deleting xfrm state with spi 0xcfef6e53
debug(lib/tool/xfrmapi.c:568@hip_xfrm_state_delete): SA peer addr: 0x3FFE0000000000000000000000000011
debug(lib/tool/xfrmapi.c:678@hip_delete_sa): outbound IPsec SA deleted
debug(lib/tool/xfrmapi.c:543@hip_xfrm_state_delete): IPV6 SA deletion
debug(lib/tool/xfrmapi.c:548@hip_xfrm_state_delete): sport 0, dport 0
debug(lib/tool/xfrmapi.c:567@hip_xfrm_state_delete): deleting xfrm state with spi 0xd5f95e22
debug(lib/tool/xfrmapi.c:568@hip_xfrm_state_delete): SA peer addr: 0x3FFE0000000000000000000000000001
debug(lib/tool/xfrmapi.c:685@hip_delete_sa): inbound IPsec SA deleted
debug(hipd/hadb.c:866@hip_hadb_delete_state): ha=0x0x21f8890

Miika Komu (miika-iki)
Changed in hipl:
importance: Undecided → High
Revision history for this message
René Hummen (rene-hummen) wrote :

I'll look into it as soon as I got some time. I won't be available next week though.

Changed in hipl:
assignee: nobody → René Hummen (rene-hummen)
Changed in hipl:
status: New → Confirmed
Revision history for this message
Miika Komu (miika-iki) wrote :

On my LAN, the additional delay seems to be roughly 90 seconds. This will have drastic effect on TCP connectivity.

Revision history for this message
Miika Komu (miika-iki) wrote :

Any progress? Baris is trying to get started with the code again.

Revision history for this message
René Hummen (rene-hummen) wrote :

Please check if trunk revision 6007 is doing the trick for you.

Changed in hipl:
status: Confirmed → Fix Committed
Revision history for this message
Miika Komu (miika-iki) wrote :

It does. Thanks a lot Rene!

Changed in hipl:
status: Fix Committed → Fix Released
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.