[hipl-users] Re: Hi3: Successful BaseExch, but no connection setup

  • From: Miika Komu <miika@xxxxxx>
  • To: hipl-users@xxxxxxxxxxxxx
  • Date: Thu, 31 Jan 2008 09:51:26 +0200 (EET)

On Thu, 31 Jan 2008, Jeab wrote:

Hi Jeab,

can you show "ip xfrm state" output on both sides? The hosts are in the same network, so they should be able to ping each other, right?


Hello,

I am trying to test the connection with Hi3. So far I found the base exchange 
is successfully setup, but never received response by sending ping or 
conntest-client message.

Kernel 2.6.21.3, HIPL patch 310
@INIT > 3ffe::1/64, 192.168.0.8
@RESP >3ffe::2/64, 192.168.0.9

After wait until Trigger is inserted on both machines and test without command 
hipconf add map <HIT_RESP> 3ffe::2,
# test/conntest-client-gai <RESP> tcp 1111
Initiator mode

name=@RESP service='1111'

The name is not an IPv4 or IPv6 address, resolve name (!AI_NUMERICHOST)

Asking serving gateway info from daemon...

waiting to receive daemon info

bytes= 72  hdr_size = 40

72 bytes received

DHT is not in use

** match on line 6 **

HIP_TRANSPARENT_API: AI_HIP set: strictly HITs are returned

HIT: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5

IP: 192.168.0.9

waiting to receive daemon info

bytes= 88  hdr_size = 40

88 bytes received

HIT: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5

IP: 3ffe:0000:0000:0000:0000:0000:0000:0002

waiting to receive daemon info

bytes= 88  hdr_size = 40

88 bytes received

HIP: AI_HIP set or HITs were found: removing IP addresses



a->addr: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5



a->addr: 192.168.0.9

freeing IP address



a->addr: 3ffe:0000:0000:0000:0000:0000:0000:0002

freeing IP address

Input some text, press enter and ctrl+d

kleoel

Addr given to connect: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5

!!!! conntest.c Connecting...

ai_flags 2048

ai_family 10

ai_socktype 1

ai_protocol6

ai_addrlen 28

ai_canonname (null)

local addr: 0000:0000:0000:0000:0000:0000:0000:0000

Trying to connect to IPv6: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5

---------------------------------------------------------------------------

debug(hipd.c:646@hipd_main): netlink receive
debug(nlink.c:56@hip_netlink_receive): Received a netlink message
debug(netdev.c:829@hip_netdev_event): handling msg type 23 ifindex=0
debug(netdev.c:656@hip_netdev_handle_acquire): Acquire: sending I1 (pid: 0)
debug(debug.c:671@hip_print_hit): src HIT: 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:671@hip_print_hit): dst HIT: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :3
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :3
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :5
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :5
debug(debug.c:671@hip_print_hit): HIT1: 2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:671@hip_print_hit): HIT2: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(netdev.c:756@hip_netdev_handle_acquire): State is 1, sending i1
debug(debug.c:671@hip_print_hit): our hit: 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:671@hip_print_hit): peer hit: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:649@hip_print_lsi): peer locator: 192.168.0.9
debug(debug.c:649@hip_print_lsi): our locator: 192.168.0.8
debug(netdev.c:786@hip_netdev_handle_acquire): acq->sel.ifindex=0
debug(debug.c:649@hip_print_lsi): Trying to find addr: 192.168.0.8
debug(netdev.c:316@hip_netdev_find_if): n family 10, addr family 10
debug(debug.c:671@hip_print_hit): n addr : 
3ffe:0000:0000:0000:0000:0000:0000:0001
debug(netdev.c:318@hip_netdev_find_if): index 2
debug(netdev.c:316@hip_netdev_find_if): n family 10, addr family 10
debug(debug.c:649@hip_print_lsi): n addr : 192.168.0.8
debug(netdev.c:318@hip_netdev_find_if): index 2
debug(netdev.c:324@hip_netdev_find_if): index 2
debug(netdev.c:793@hip_netdev_handle_acquire): Using ifindex 2
debug(output.c:35@hip_send_i1):
debug(debug.c:671@hip_print_hit): src_hit: 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :5, algo 
value of id entry :3
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :5, algo 
value of id entry :3
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :5, algo 
value of id entry :5
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :5, algo 
value of id entry :5
debug(hidb.c:614@hip_get_rsa_public_key): dilen: 5
debug(hidb.c:623@hip_get_rsa_public_key): Host ID len after cut-off: 152
info(output.c:73@hip_send_i1): HIT source0x20010014359451e7148a19515b664a02
info(output.c:74@hip_send_i1): HIT dest0x20010015fc77357cecb72de3723366c5
debug(debug.c:649@hip_print_lsi): entry def addr: 192.168.0.9
debug(output.c:951@hip_send_udp): Sending HIP_I1 packet on UDP.
debug(debug.c:649@hip_print_lsi): hip_send_udp(): local_addr: 192.168.0.8
debug(debug.c:649@hip_print_lsi): hip_send_udp(): peer_addr: 192.168.0.9
debug(output.c:955@hip_send_udp): Source port: 50500, destination port: 50500.
info(output.c:956@hip_send_udp):  dump:
debug(builder.c:902@hip_dump_msg): --------------- MSG START ------------------
debug(builder.c:906@hip_dump_msg): Msg type :      HIP_I1 (1)
debug(builder.c:907@hip_dump_msg): Msg length:     40
debug(builder.c:908@hip_dump_msg): Msg err:        0
debug(builder.c:909@hip_dump_msg): Msg controls: 0x0000
debug(builder.c:929@hip_dump_msg): ---------------- MSG END --------------------
debug(debug.c:649@hip_print_lsi): Local address is given: 192.168.0.8
debug(output.c:1009@hip_send_udp): Trying to send 40 bytes on UDP with source 
port: 50500 and destination port: 50500.
debug(output.c:1043@hip_send_udp): Packet sent successfully over UDP, 
characters sent: 40, packet length: 40.
debug(output.c:106@hip_send_i1): err after sending: 0.
debug(ping_thread.c:112@send_npings): Sending echo request to 140.247.60.123
debug(hipd.c:527@hipd_main): Receiving a message on UDP from NAT socket (file 
descriptor: 8).
debug(message.c:224@hip_read_control_msg_all): hip_read_control_msg_all() 
invoked.
debug(message.c:38@hip_peek_recv_total_len): bytes= 696  hdr_size = 40
debug(message.c:278@hip_read_control_msg_all): hip_read_control_msg_all() 
source port = 50500
debug(builder.c:1706@hip_verify_network_header): Received HIT is ours or we are 
RVS
debug(builder.c:1720@hip_verify_network_header): HIP IPv4 UDP packet: ignoring 
HIP checksum
debug(debug.c:649@hip_print_lsi): src: 192.168.0.9
debug(debug.c:649@hip_print_lsi): dst: 192.168.0.8
debug(debug.c:671@hip_print_hit): HIT1: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:671@hip_print_hit): HIT2: 2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:649@hip_print_lsi): Source IP: 192.168.0.9
debug(debug.c:649@hip_print_lsi): Destination IP: 192.168.0.8
debug(debug.c:671@hip_print_hit): HIT Sender: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:671@hip_print_hit): HIT Receiver: 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(input.c:412@hip_receive_control_packet): source port: 50500, destination 
port: 50500
info(input.c:413@hip_receive_control_packet):  dump:
debug(builder.c:902@hip_dump_msg): --------------- MSG START ------------------
debug(builder.c:906@hip_dump_msg): Msg type :      HIP_R1 (2)
debug(builder.c:907@hip_dump_msg): Msg length:     696
debug(builder.c:908@hip_dump_msg): Msg err:        0
debug(builder.c:909@hip_dump_msg): Msg controls: 0x0000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_LOCATOR (193). 
Total length: 64 (4 type+length, 56 content, 4 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x00000400000000003ffe000000000000000000000000000200000000000004000000000000000000000000000000ffffc0a8000900000000
info(builder.c:927@hip_dump_msg): Padding:0x00000000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_PUZZLE (257). Total 
length: 16 (4 type+length, 12 content, 0 padding).
info(builder.c:926@hip_dump_msg): Contents:0x0a2a48498c19213ed34fbe30
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_DIFFIE_HELLMAN 
(513). Total length: 256 (4 type+length, 246 content, 6 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x0300c070fda99515b319b2524340beabb8e20e64c764c899366557e6dc8d82b72c5e15e6250872df45968f81e4a42123b5d1ff0f6ac021d9a3760f0846fc5f5c9741bbde3d5eb7f5f9eb776f9410f9584061bcf3cf9fc0521ef51d108e26007ddaf268824b524cf63afbd123a04433fa7036c5349a1b98cc24da8434594a308a8bd45dc74998f2b8a27cb75ef17f0dd1abebee94a363e5116ab282638ae7ef1f7eac43deef80b15dcc118203f5637c3aa44bf935cbc7203603d685ea748987f90c930701003097dbbe7b7a4f77bf61a67e977748455db1a57cdda004c1eca9bd92f322822264e169e6d4115a3dbe6137b77f51d2c11c
info(builder.c:927@hip_dump_msg): Padding:0x000000000000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_HIP_TRANSFORM 
(577). Total length: 16 (4 type+length, 6 content, 6 padding).
info(builder.c:926@hip_dump_msg): Contents:0x000100020005
info(builder.c:927@hip_dump_msg): Padding:0x000000000000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_ESP_TRANSFORM 
(4095). Total length: 16 (4 type+length, 8 content, 4 padding).
info(builder.c:926@hip_dump_msg): Contents:0x0000000100020005
info(builder.c:927@hip_dump_msg): Padding:0x00000000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_HOST_ID (705). 
Total length: 152 (4 type+length, 144 content, 4 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x008810040202ff0503010001dea314e9f2b9a667893f92273f64cd6ffd53c57ebc332a0a016fb3a7bf80a27e40f4fd2174cadb7df329cd8fb8276cf738fa9bb671972ac1a44f2eec07b8af2aa3c9ed4677b0b7761921ef00cf7326d947841a1a6e110d5e2ba0e65348066bcda4bc9c1f272b7b0ae4589c1ecd2a2e8f43af6f05cf7432d82efe599bf56ceae76b696d00
info(builder.c:927@hip_dump_msg): Padding:0x00000000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_HIP_SIGNATURE2 
(61633). Total length: 136 (4 type+length, 129 content, 3 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x054ea6af5bad6df286f6b9a32c0fbe7f2ea8e104a3b393cd65feebbffc3c6f519f2ce8273c3873a4f50791213cbea6ab82e1bba3553765d922ac5b175252c38965c5d42013973fcbddd0644dc2fdaa5b681da6a9f21829d899db8f4c922c37d4f8cb6f7278459af053a27e37ae550778b1a63da13cdfb0d0ec8e535d3dcd73e6ae
info(builder.c:927@hip_dump_msg): Padding:0x000000
debug(builder.c:929@hip_dump_msg): ---------------- MSG END --------------------
debug(debug.c:671@hip_print_hit): HIT1: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:671@hip_print_hit): HIT2: 2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(input.c:451@hip_receive_control_packet): Filtering packet trough agent 
now (packet is R1).
debug(input.c:1268@hip_receive_r1): hip_receive_r1() invoked.
debug(oppipdb.c:238@hip_oppipdb_find_byip): The ip was not present in oppipdb. 
Peer HIP capable.
debug(debug.c:649@hip_print_lsi): entry def addr: 192.168.0.9
debug(input.c:1310@hip_receive_r1): Received R1 in state I1-SENT
debug(input.c:1012@hip_handle_r1): Not a retransmission
debug(debug.c:671@hip_print_hit): peer's hit: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:671@hip_print_hit): entry's hit: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(crypto.c:700@impl_rsa_verify): RSA verify: 1
info(cookie.c:184@hip_solve_puzzle): puzzle0x0101000c0a2a48498c19213ed34fbe30
debug(cookie.c:198@hip_solve_puzzle): (u->pz.I: 0x30be4fd33e21198c
debug(cookie.c:216@hip_solve_puzzle): K=10, maxtries (with k+2)=8192
debug(misc.c:420@hip_select_hip_transform): Chose HIP transform: 1
debug(misc.c:471@hip_select_esp_transform): Took ESP transform 1
debug(input.c:217@hip_produce_keying_material): transform lengths: hip=16, 
hmac=20, esp=16, auth=20
debug(input.c:219@hip_produce_keying_material): I=0x30be4fd33e21198c 
J=0x90998b915dea30fc
debug(input.c:251@hip_produce_keying_material): keymat_len_min=144 
keymat_len=160
debug(builder.c:329@hip_dh_select_key): Multiple DHF public values received
debug(input.c:274@hip_produce_keying_material): dh_shared_len=192
info(input.c:275@hip_produce_keying_material): DH SHARED 
PARAM0x0fff0008000000010002000500000000
info(input.c:276@hip_produce_keying_material): DH SHARED 
KEY0xf83ddafae0ffdfbf73b998db6b4eec7757fd0aca3a46f33446dc331d7b18f219bc283c827f0e2d01577033ba043bba7f051bd75d16e2c744d58d9462a41bf821caae5a7b4226cd92844bf986789ef05efe6b7c4737f18769cf99e24aa4c28bc655b45d6a7cfa9dcb32d8ede0532498492f74160f200c2a15f0f99b34608c2e43706d775c6b536d9423752853e3be142a72501d721a52aaf7193374d1567b9cf2e1fc3d629b66cf526f8e7eb30e5e99b913e52a988a7c1635374515e009503481
debug(keymat.c:90@hip_make_keymat):
debug(keymat.c:21@hip_create_keymat_buffer):
debug(input.c:333@hip_produce_keying_material): we are HITl
info(input.c:353@hip_produce_keying_material): HIP-gl 
encryption:0x007e1c44fbf22baebbbad794c29e6d6c
info(input.c:355@hip_produce_keying_material): HIP-gl integrity (HMAC) 
key:0x178c1fba70cb52baa80fc14b7d0f72a04f0ef586
info(input.c:357@hip_produce_keying_material): HIP-lg 
encryption:0x638d382bf5c18b59b22c7777ec775607
info(input.c:358@hip_produce_keying_material): HIP-lg integrity (HMAC) 
key:0xba1d3c0217a572d1a8831c2e95dcbc3ff6f878cc
info(input.c:359@hip_produce_keying_material): SA-gl ESP encryption 
key:0xfbb8bd4fca96b0112739ab626fc33775
info(input.c:360@hip_produce_keying_material): SA-gl ESP authentication 
key:0xa7fd09cbbb49148bb3e65d861d8ded3c0511ab1e
info(input.c:361@hip_produce_keying_material): SA-lg ESP encryption 
key:0x707bfcf2e76c4e496322a81c73b29da1
info(input.c:362@hip_produce_keying_material): SA-lg ESP authentication 
key:0xe75e39a672058eea528745fee4fd429a0a534d22
debug(input.c:1240@hip_handle_r1): Identity type: FQDN, Length: 4, Name: kim
debug(hadb.c:2353@hip_hadb_delete_inbound_spi): SPI=0x0
debug(hadb.c:2400@hip_hadb_delete_outbound_spi): entry=0x0x80e40c0 SPI=0x0
debug(input.c:668@hip_create_i2): Build normal I2
debug(input.c:696@hip_create_i2): Building LOCATOR parameter
debug(misc.c:420@hip_select_hip_transform): Chose HIP transform: 1
debug(input.c:733@hip_create_i2): HIP transform: 1
debug(misc.c:471@hip_select_esp_transform): Took ESP transform 1
debug(builder.c:1768@hip_build_param_encrypted_aes_sha1): Adjusting param size 
to AES block size
info(input.c:783@hip_create_i2): 
enc(host_id)0x02c10091008810050202ff050301000199479e91dfb4f9b811a8c25f5ffc00b8b623743fd0f7501ec9d9fbc562d68142f3a46fff3716bbad738c33be6673452cdb6d538a731292e89350687cbd24b43148d9dd5758a48492cd7ad494baee8006a5c27b4c917a73b8374c818624731cf283153b3e7285da473b25a2c7912aac3abbcd4f54bcc75fccb1b8f95507c35f6b6a61636b00000000
debug(input.c:794@hip_create_i2): Remainder 8 (for AES)
info(input.c:803@hip_create_i2): enc 
key0x007e1c44fbf22baebbbad794c29e6d6c00000000000000000000000000000000
debug(input.c:806@hip_create_i2): host id type: 5
info(crypto.c:365@hip_crypto_encrypted): hip_crypto_encrypted encrypt 
data0x02c10091008810050202ff050301000199479e91dfb4f9b811a8c25f5ffc00b8b623743fd0f7501ec9d9fbc562d68142f3a46fff3716bbad738c33be6673452cdb6d538a731292e89350687cbd24b43148d9dd5758a48492cd7ad494baee8006a5c27b4c917a73b8374c818624731cf283153b3e7285da473b25a2c7912aac3abbcd4f54bcc75fccb1b8f95507c35f6b6a61636b000000000808080808080808
debug(crypto.c:367@hip_crypto_encrypted): d1
debug(crypto.c:371@hip_crypto_encrypted): d2
debug(crypto.c:375@hip_crypto_encrypted): d3
info(crypto.c:378@hip_crypto_encrypted): AES key for OpenSSL: 
0x007e1c44fbf22baebbbad794c29e6d6c0a424c61f1b067cf4a0ab05b8894dd372a83d6a5db33b16a9139013119addc06bb05b9716036081bf10f092ae8a2d52c8906c8eae930c0f1183fc9dbf09d1cf7c79aa0662eaa60973695a94cc608b5bbd74f4ad2f9e52a45cf708309097836b22b4a7dd3d2af57961ddfd49f14a7e22df7d2a529257df2bf38a226202c05c40d87ce7258a2b380e79a11a6c7b61462ca4b640616e9d786f173c62036c5d242fc0000000000000000000000000000000000000000000000000000000007000000100000005dea30fcc84099bf8740080803000000cfc30b08260300007cc90b08
info(crypto.c:379@hip_crypto_encrypted): AES IV: 
0xfb2313584c75dd3db0e02c15a4c6d5d9
debug(input.c:824@hip_create_i2): src 50500, dst 50500
debug(input.c:843@hip_create_i2): Blind is OFF
debug(debug.c:671@hip_print_hit): hit our: 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:671@hip_print_hit): hit peer: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(xfrmapi.c:474@hip_add_sa): adding new SA
debug(debug.c:671@hip_print_hit): src_hit: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:671@hip_print_hit): dst_hit: 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:649@hip_print_lsi): saddr: 192.168.0.9
debug(debug.c:649@hip_print_lsi): daddr: 192.168.0.8
debug(xfrmapi.c:281@hip_xfrm_state_modify): hip_xfrm_state_modify() invoked.
debug(xfrmapi.c:282@hip_xfrm_state_modify): sport 50500, dport 50500
debug(debug.c:649@hip_print_lsi): saddr in sa: 192.168.0.9
debug(debug.c:649@hip_print_lsi): daddr in sa: 192.168.0.8
debug(input.c:856@hip_create_i2): set up inbound IPsec SA, SPI=0x8721ce32 (host)
debug(xfrmapi.c:102@hip_xfrm_policy_modify): IPv4 address found in tmpl policy
debug(xfrmapi.c:102@hip_xfrm_policy_modify): IPv4 address found in tmpl policy
debug(reg.c:807@hip_get_incomplete_registrations):
op:1
local_controls: 0000000000000000 (0x0000), peer_controls: 0000000000000000 
(0x0000)
info(crypto.c:310@hip_write_hmac): Dumping 
key0x178c1fba70cb52baa80fc14b7d0f72a04f0ef586
info(crypto.c:332@hip_write_hmac): HMAC 
key0x178c1fba70cb52baa80fc14b7d0f72a04f0ef586
info(crypto.c:333@hip_write_hmac): hmac 
in0x3b4303110000000020010014359451e7148a19515b664a0220010015fc77357cecb72de3723366c50041000c00000048000000008721ce3200c1003800000400000000003ffe000000000000000000000000000100000000000004000000000000000000000000000000ffffc0a800080000000000000000014100140a0048498c19213ed34fbe30fc30ea5d918b9990020100c30300c0a8553c62956df1824231bff07049af28ce3114d9a638509aeafe741149bb76ff2ef50d4307ee33b48fb50f80608fcf9b0fd23fc52557fd37c38681c3cbcc5f51a430774a2c1d245c3a98009e0e2b99b389384332c2b8b9b9dda808d1da020e23d5033ecfb3f6976b64fecf3b53562a8b377ba368bb18dd4bc82f53aeac99e6d5b3cfd1232bb1cde91ddfa8d08f495a99547eea1b15c5774a8a7f32a5158fb6ed9ceda2624d1f7c37a8250cc6bc8bc887aac38c3d54d6bec90049ac2bd0b5f5170002410002000100000fff000400000001028100b400000000fb2313584c75dd3db0e02c15a4c6d5d90c73072450551d65ec5ca641444f6a6e4fdc59b54cfcaed0f112830a7451a57f3e751863ec9d258e65015764e97015584abae1e4fb347f6155c63d47663ba0d2c9c007b58564a94f4c521618bc21b70135b6ff96166e5498
56d2cb1f6d5d6754cea8943ddd4ee8facef82e97995d2e3bdd2b5f4ae938d5e7cf9f81ebd0171e71bea2143b9933cf77bd3ed2e11f4ab794cf133382b18365a1f60b4f513c9edf70
info(crypto.c:334@hip_write_hmac): hmac 
out0xe409035a7ba9da102b7055150eeb28db30820912
debug(debug.c:649@hip_print_lsi): Trying to find addr: 192.168.0.8
debug(netdev.c:316@hip_netdev_find_if): n family 10, addr family 10
debug(debug.c:671@hip_print_hit): n addr : 
3ffe:0000:0000:0000:0000:0000:0000:0001
debug(netdev.c:318@hip_netdev_find_if): index 2
debug(netdev.c:316@hip_netdev_find_if): n family 10, addr family 10
debug(debug.c:649@hip_print_lsi): n addr : 192.168.0.8
debug(netdev.c:318@hip_netdev_find_if): index 2
debug(netdev.c:324@hip_netdev_find_if): index 2
debug(hadb.c:853@hip_hadb_add_inbound_spi): added SPI 0x8721ce32 to the inbound 
SPI list
debug(input.c:960@hip_create_i2): Saving base exchange encryption data to entry
debug(debug.c:671@hip_print_hit): our_hit: : 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:671@hip_print_hit): peer_hit: : 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:649@hip_print_lsi): entry def addr: 192.168.0.9
debug(output.c:951@hip_send_udp): Sending HIP_I2 packet on UDP.
debug(debug.c:649@hip_print_lsi): hip_send_udp(): local_addr: 192.168.0.8
debug(debug.c:649@hip_print_lsi): hip_send_udp(): peer_addr: 192.168.0.9
debug(output.c:955@hip_send_udp): Source port: 50500, destination port: 50500.
info(output.c:956@hip_send_udp):  dump:
debug(builder.c:902@hip_dump_msg): --------------- MSG START ------------------
debug(builder.c:906@hip_dump_msg): Msg type :      HIP_I2 (3)
debug(builder.c:907@hip_dump_msg): Msg length:     704
debug(builder.c:908@hip_dump_msg): Msg err:        0
debug(builder.c:909@hip_dump_msg): Msg controls: 0x0000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_ESP_INFO (65). 
Total length: 16 (4 type+length, 12 content, 0 padding).
info(builder.c:926@hip_dump_msg): Contents:0x00000048000000008721ce32
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_LOCATOR (193). 
Total length: 64 (4 type+length, 56 content, 4 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x00000400000000003ffe000000000000000000000000000100000000000004000000000000000000000000000000ffffc0a8000800000000
info(builder.c:927@hip_dump_msg): Padding:0x00000000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_SOLUTION (321). 
Total length: 24 (4 type+length, 20 content, 0 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x0a0048498c19213ed34fbe30fc30ea5d918b9990
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_DIFFIE_HELLMAN 
(513). Total length: 200 (4 type+length, 195 content, 1 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x0300c0a8553c62956df1824231bff07049af28ce3114d9a638509aeafe741149bb76ff2ef50d4307ee33b48fb50f80608fcf9b0fd23fc52557fd37c38681c3cbcc5f51a430774a2c1d245c3a98009e0e2b99b389384332c2b8b9b9dda808d1da020e23d5033ecfb3f6976b64fecf3b53562a8b377ba368bb18dd4bc82f53aeac99e6d5b3cfd1232bb1cde91ddfa8d08f495a99547eea1b15c5774a8a7f32a5158fb6ed9ceda2624d1f7c37a8250cc6bc8bc887aac38c3d54d6bec90049ac2bd0b5f517
info(builder.c:927@hip_dump_msg): Padding:0x00
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_HIP_TRANSFORM 
(577). Total length: 8 (4 type+length, 2 content, 2 padding).
info(builder.c:926@hip_dump_msg): Contents:0x0001
info(builder.c:927@hip_dump_msg): Padding:0x0000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_ESP_TRANSFORM 
(4095). Total length: 8 (4 type+length, 4 content, 0 padding).
info(builder.c:926@hip_dump_msg): Contents:0x00000001
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_ENCRYPTED (641). 
Total length: 184 (4 type+length, 180 content, 0 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x00000000fb2313584c75dd3db0e02c15a4c6d5d90c73072450551d65ec5ca641444f6a6e4fdc59b54cfcaed0f112830a7451a57f3e751863ec9d258e65015764e97015584abae1e4fb347f6155c63d47663ba0d2c9c007b58564a94f4c521618bc21b70135b6ff96166e549856d2cb1f6d5d6754cea8943ddd4ee8facef82e97995d2e3bdd2b5f4ae938d5e7cf9f81ebd0171e71bea2143b9933cf77bd3ed2e11f4ab794cf133382b18365a1f60b4f513c9edf70
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_HMAC (61505). Total 
length: 24 (4 type+length, 20 content, 0 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0xe409035a7ba9da102b7055150eeb28db30820912
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_HIP_SIGNATURE 
(61697). Total length: 136 (4 type+length, 129 content, 3 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x05795d4353183644c265535c014ffd41d7265dbf0febd729fb4f13a931d42c3527e241edac81940c9aa94121dc0b2261448d90726198f40ce5a4c8fc811333a1aa95be132184cd7bb349533cbb4751a1d361b069d1205154dba76ce93879dac58cc3c3ffafcd6836dae54996a956fb7af003aef8f480e5116f9253195f49d007d0
info(builder.c:927@hip_dump_msg): Padding:0x000000
debug(builder.c:929@hip_dump_msg): ---------------- MSG END --------------------
debug(debug.c:649@hip_print_lsi): Local address is given: 192.168.0.8
debug(output.c:1009@hip_send_udp): Trying to send 704 bytes on UDP with source 
port: 50500 and destination port: 50500.
debug(output.c:1043@hip_send_udp): Packet sent successfully over UDP, 
characters sent: 704, packet length: 704.
debug(input.c:572@hip_receive_control_packet): Done with control packet, err is 
0.
debug(hipd.c:646@hipd_main): netlink receive
debug(nlink.c:56@hip_netlink_receive): Received a netlink message
debug(netdev.c:829@hip_netdev_event): handling msg type 23 ifindex=0
debug(netdev.c:656@hip_netdev_handle_acquire): Acquire: sending I1 (pid: 0)
debug(debug.c:671@hip_print_hit): src HIT: 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:671@hip_print_hit): dst HIT: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :3
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :3
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :5
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :5
debug(debug.c:671@hip_print_hit): HIT1: 2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:671@hip_print_hit): HIT2: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(netdev.c:760@hip_netdev_handle_acquire): I1 was already sent, ignoring
debug(hipd.c:646@hipd_main): netlink receive
debug(nlink.c:56@hip_netlink_receive): Received a netlink message
debug(netdev.c:829@hip_netdev_event): handling msg type 23 ifindex=0
debug(netdev.c:656@hip_netdev_handle_acquire): Acquire: sending I1 (pid: 0)
debug(debug.c:671@hip_print_hit): src HIT: 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:671@hip_print_hit): dst HIT: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :3
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :3
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :5
debug(hidb.c:97@hip_get_hostid_entry_by_lhi_and_algo): ALGO VALUE :-1, algo 
value of id entry :5
debug(debug.c:671@hip_print_hit): HIT1: 2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:671@hip_print_hit): HIT2: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(netdev.c:760@hip_netdev_handle_acquire): I1 was already sent, ignoring
debug(hipd.c:527@hipd_main): Receiving a message on UDP from NAT socket (file 
descriptor: 8).
debug(message.c:224@hip_read_control_msg_all): hip_read_control_msg_all() 
invoked.
debug(message.c:38@hip_peek_recv_total_len): bytes= 216  hdr_size = 40
debug(message.c:278@hip_read_control_msg_all): hip_read_control_msg_all() 
source port = 50500
debug(builder.c:1706@hip_verify_network_header): Received HIT is ours or we are 
RVS
debug(builder.c:1720@hip_verify_network_header): HIP IPv4 UDP packet: ignoring 
HIP checksum
debug(debug.c:649@hip_print_lsi): src: 192.168.0.9
debug(debug.c:649@hip_print_lsi): dst: 192.168.0.8
debug(debug.c:671@hip_print_hit): HIT1: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:671@hip_print_hit): HIT2: 2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:649@hip_print_lsi): Source IP: 192.168.0.9
debug(debug.c:649@hip_print_lsi): Destination IP: 192.168.0.8
debug(debug.c:671@hip_print_hit): HIT Sender: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:671@hip_print_hit): HIT Receiver: 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(input.c:412@hip_receive_control_packet): source port: 50500, destination 
port: 50500
info(input.c:413@hip_receive_control_packet):  dump:
debug(builder.c:902@hip_dump_msg): --------------- MSG START ------------------
debug(builder.c:906@hip_dump_msg): Msg type :      HIP_R2 (4)
debug(builder.c:907@hip_dump_msg): Msg length:     216
debug(builder.c:908@hip_dump_msg): Msg err:        0
debug(builder.c:909@hip_dump_msg): Msg controls: 0x0000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_ESP_INFO (65). 
Total length: 16 (4 type+length, 12 content, 0 padding).
info(builder.c:926@hip_dump_msg): Contents:0x0000004800000000ed0d384f
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_HMAC2 (61569). 
Total length: 24 (4 type+length, 20 content, 0 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x704b2109e426c43f113903030f9f063ac5cad0e3
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_HIP_SIGNATURE 
(61697). Total length: 136 (4 type+length, 129 content, 3 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x058928eaf4b186996565ba1af10656e314652ba5d002ed4588afa607573f5583511f8300814313d9a0508731b4e806706cfc93772aaac335ed665cc957487f0ec2fb4b1e8552eb9e34969e4ee4ca8b867ba2688d472e4a857378b0411ac900f3441392a75137fc7609d3cae1d0eb6cd2c8024e7ccf0a819f5931548fd1c620565e
info(builder.c:927@hip_dump_msg): Padding:0x000000
debug(builder.c:929@hip_dump_msg): ---------------- MSG END --------------------
debug(debug.c:671@hip_print_hit): HIT1: 2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:671@hip_print_hit): HIT2: 2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(input.c:2508@hip_receive_r2): Received R2 in state I2-SENT
debug(input.c:2176@hip_handle_r2): Not a retransmission
info(input.c:160@hip_verify_packet_hmac2): HMAC 
data0x3b1904110000000020010015fc77357cecb72de3723366c520010014359451e7148a19515b664a020041000c0000004800000000ed0d384f02c10090008810040202ff0503010001dea314e9f2b9a667893f92273f64cd6ffd53c57ebc332a0a016fb3a7bf80a27e40f4fd2174cadb7df329cd8fb8276cf738fa9bb671972ac1a44f2eec07b8af2aa3c9ed4677b0b7761921ef00cf7326d947841a1a6e110d5e2ba0e65348066bcda4bc9c1f272b7b0ae4589c1ecd2a2e8f43af6f05cf7432d82efe599bf56ceae76b696d0000000000
info(crypto.c:310@hip_write_hmac): Dumping 
key0xba1d3c0217a572d1a8831c2e95dcbc3ff6f878cc
info(crypto.c:332@hip_write_hmac): HMAC 
key0xba1d3c0217a572d1a8831c2e95dcbc3ff6f878cc
info(crypto.c:333@hip_write_hmac): hmac 
in0x3b1904110000000020010015fc77357cecb72de3723366c520010014359451e7148a19515b664a020041000c0000004800000000ed0d384f02c10090008810040202ff0503010001dea314e9f2b9a667893f92273f64cd6ffd53c57ebc332a0a016fb3a7bf80a27e40f4fd2174cadb7df329cd8fb8276cf738fa9bb671972ac1a44f2eec07b8af2aa3c9ed4677b0b7761921ef00cf7326d947841a1a6e110d5e2ba0e65348066bcda4bc9c1f272b7b0ae4589c1ecd2a2e8f43af6f05cf7432d82efe599bf56ceae76b696d0000000000
info(crypto.c:334@hip_write_hmac): hmac 
out0x704b2109e426c43f113903030f9f063ac5cad0e3
debug(crypto.c:700@impl_rsa_verify): RSA verify: 1
debug(hadb.c:900@hip_hadb_add_outbound_spi): added SPI 0xed0d384f to the 
outbound SPI list
debug(input.c:2218@hip_handle_r2): entry should have only one spi_in now, test
debug(input.c:2222@hip_handle_r2): src 50500, dst 50500
debug(input.c:2233@hip_handle_r2): entry->hip_transform:
debug(xfrmapi.c:474@hip_add_sa): adding new SA
debug(debug.c:671@hip_print_hit): src_hit: 
2001:0014:3594:51e7:148a:1951:5b66:4a02
debug(debug.c:671@hip_print_hit): dst_hit: 
2001:0015:fc77:357c:ecb7:2de3:7233:66c5
debug(debug.c:649@hip_print_lsi): saddr: 192.168.0.8
debug(debug.c:649@hip_print_lsi): daddr: 192.168.0.9
debug(xfrmapi.c:281@hip_xfrm_state_modify): hip_xfrm_state_modify() invoked.
debug(xfrmapi.c:282@hip_xfrm_state_modify): sport 50500, dport 50500
debug(debug.c:649@hip_print_lsi): saddr in sa: 192.168.0.8
debug(debug.c:649@hip_print_lsi): daddr in sa: 192.168.0.9
debug(input.c:2256@hip_handle_r2): set up outbound IPsec SA, SPI=0xed0d384f 
(host)
debug(hadb.c:1611@hip_hadb_add_addr_to_spi): spi=0xed0d384f is_preferred_addr=1
debug(hadb.c:1637@hip_hadb_add_addr_to_spi): create new addr item to SPI list
debug(hadb.c:1687@hip_hadb_add_addr_to_spi): address is base exchange address, 
setting state to ACTIVE
debug(hadb.c:1689@hip_hadb_add_addr_to_spi): setting bex addr as preferred 
address
debug(hadb.c:1709@hip_hadb_add_addr_to_spi): adding new addr to SPI list
debug(hadb.c:1714@hip_hadb_add_addr_to_spi): returning, err=0
debug(input.c:2274@hip_handle_r2): set default SPI out=0xed0d384f
debug(debug.c:649@hip_print_lsi): Trying to find addr: 192.168.0.8
debug(netdev.c:316@hip_netdev_find_if): n family 10, addr family 10
debug(debug.c:671@hip_print_hit): n addr : 
3ffe:0000:0000:0000:0000:0000:0000:0001
debug(netdev.c:318@hip_netdev_find_if): index 2
debug(netdev.c:316@hip_netdev_find_if): n family 10, addr family 10
debug(debug.c:649@hip_print_lsi): n addr : 192.168.0.8
debug(netdev.c:318@hip_netdev_find_if): index 2
debug(netdev.c:324@hip_netdev_find_if): index 2
debug(input.c:2281@hip_handle_r2): ifindex=2
debug(hadb.c:932@hip_hadb_set_spi_ifindex): SPI=0x8721ce32 ifindex=2
debug(hadb.c:939@hip_hadb_set_spi_ifindex): found updated spi-ifindex mapping
debug(update.c:335@hip_update_handle_locator_parameter): LOCATOR SPI 
old=0xed0d384f new=0xed0d384f
debug(update.c:56@hip_for_each_locator_addr_item): LOCATOR has 2 address(es), 
loc param len=64
error(update.c:63@hip_for_each_locator_addr_item): Locator handler function 
returned error
debug(update.c:382@hip_update_handle_locator_parameter): LOCATOR contained same 
family members as local_address
debug(update.c:389@hip_update_handle_locator_parameter): Did not find any 
address of same family
debug(update.c:56@hip_for_each_locator_addr_item): LOCATOR has 2 address(es), 
loc param len=64
debug(debug.c:671@hip_print_hit): LOCATOR address: 
3ffe:0000:0000:0000:0000:0000:0000:0002
debug(update.c:215@hip_update_add_peer_addr_item):  address: is_pref=no 
reserved=0x0 lifetime=0x0
debug(netdev.c:52@filter_address): ifindex=-1, address family=10
info(netdev.c:53@filter_address): testing 
address=0x3ffe0000000000000000000000000002
debug(netdev.c:62@filter_address): IPv6 addr: 3ffe::2
debug(hadb.c:1611@hip_hadb_add_addr_to_spi): spi=0xed0d384f is_preferred_addr=0
debug(hadb.c:1637@hip_hadb_add_addr_to_spi): create new addr item to SPI list
debug(hadb.c:1693@hip_hadb_add_addr_to_spi): address's state is set in state 
UNVERIFIED
debug(debug.c:671@hip_print_hit): new addr to check: 
3ffe:0000:0000:0000:0000:0000:0000:0002
debug(update.c:951@hip_build_verification_pkt): building verification packet
info(crypto.c:310@hip_write_hmac): Dumping 
key0x178c1fba70cb52baa80fc14b7d0f72a04f0ef586
info(crypto.c:332@hip_write_hmac): HMAC 
key0x178c1fba70cb52baa80fc14b7d0f72a04f0ef586
info(crypto.c:333@hip_write_hmac): hmac 
in0x3b0810110000000020010014359451e7148a19515b664a0220010015fc77357cecb72de3723366c50041000c000000900000000000000000018100040000000101c1000401000000
info(crypto.c:334@hip_write_hmac): hmac 
out0x782e4e17386d43d3f932725cd3b50ed6a2756dd4
info(update.c:990@hip_build_verification_pkt): ECHO_REQUEST in LOCATOR addr 
check0x37c2a1b3
debug(update.c:994@hip_build_verification_pkt): sending addr verify pkt
debug(update.c:999@hip_build_verification_pkt): end, err=0
debug(output.c:951@hip_send_udp): Sending HIP_UPDATE packet on UDP.
debug(debug.c:671@hip_print_hit): hip_send_udp(): local_addr: 
3ffe:0000:0000:0000:0000:0000:0000:0001
debug(debug.c:671@hip_print_hit): hip_send_udp(): peer_addr: 
3ffe:0000:0000:0000:0000:0000:0000:0002
debug(output.c:955@hip_send_udp): Source port: 50500, destination port: 50500.
info(output.c:956@hip_send_udp):  dump:
debug(builder.c:902@hip_dump_msg): --------------- MSG START ------------------
debug(builder.c:906@hip_dump_msg): Msg type :      HIP_UPDATE (16)
debug(builder.c:907@hip_dump_msg): Msg length:     240
debug(builder.c:908@hip_dump_msg): Msg err:        0
debug(builder.c:909@hip_dump_msg): Msg controls: 0x0000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_ESP_INFO (65). 
Total length: 16 (4 type+length, 12 content, 0 padding).
info(builder.c:926@hip_dump_msg): Contents:0x000000900000000000000000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_SEQ (385). Total 
length: 8 (4 type+length, 4 content, 0 padding).
info(builder.c:926@hip_dump_msg): Contents:0x00000001
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_ACK (449). Total 
length: 8 (4 type+length, 4 content, 0 padding).
info(builder.c:926@hip_dump_msg): Contents:0x01000000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_HMAC (61505). Total 
length: 24 (4 type+length, 20 content, 0 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x782e4e17386d43d3f932725cd3b50ed6a2756dd4
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_HIP_SIGNATURE 
(61697). Total length: 136 (4 type+length, 129 content, 3 padding).
info(builder.c:926@hip_dump_msg): 
Contents:0x0505616fc0beca6c42915b48c8b5502539d3de02cf015fb5a7ab6e779e45ec12669a2b55d96319a0942598670033055695acdf7f1fb67fba37dc0f64ec2183e8096176c2369ff5e99e5952c4e1ebb90e8d38c94e37a02508683927aa6a6611a111ddf05cf290599f28f7bc2fd0e267fa83c4591d1abddebe83536ee9cecb9b3dec
info(builder.c:927@hip_dump_msg): Padding:0x000000
debug(builder.c:925@hip_dump_msg): Parameter type:HIP_PARAM_ECHO_REQUEST 
(63661). Total length: 8 (4 type+length, 4 content, 0 padding).
info(builder.c:926@hip_dump_msg): Contents:0x37c2a1b3
debug(builder.c:929@hip_dump_msg): ---------------- MSG END --------------------
debug(debug.c:671@hip_print_hit): Local address is given: 
3ffe:0000:0000:0000:0000:0000:0000:0001
error(output.c:967@hip_send_udp): Local address is pure IPv6 address, IPv6 
address family is currently not supported on UDP/HIP.
error(hadb.c:1521@hip_update_send_echo): Sending UPDATE packet with echo data 
failed.
debug(hadb.c:1709@hip_hadb_add_addr_to_spi): adding new addr to SPI list
debug(hadb.c:1714@hip_hadb_add_addr_to_spi): returning, err=0
debug(oppipdb.c:238@hip_oppipdb_find_byip): The ip was not present in oppipdb. 
Peer HIP capable.
debug(debug.c:649@hip_print_lsi): LOCATOR address: 192.168.0.9
debug(update.c:215@hip_update_add_peer_addr_item):  address: is_pref=no 
reserved=0x0 lifetime=0x0
debug(netdev.c:52@filter_address): ifindex=-1, address family=2
info(netdev.c:53@filter_address): testing address=0xc0a80009
debug(netdev.c:102@filter_address): IPv4 addr: 192.168.0.9
debug(hadb.c:1611@hip_hadb_add_addr_to_spi): spi=0xed0d384f is_preferred_addr=1
debug(hadb.c:1647@hip_hadb_add_addr_to_spi): update old addr item
debug(hadb.c:1693@hip_hadb_add_addr_to_spi): address's state is set in state 
UNVERIFIED
debug(debug.c:649@hip_print_lsi): new addr to check: 192.168.0.9
debug(update.c:951@hip_build_verification_pkt): building verification packet
info(crypto.c:310@hip_write_hmac): Dumping 
key0x178c1fba70cb52baa80fc14b7d0f72a04f0ef586
info(crypto.c:332@hip_write_hmac): HMAC 
key0x178c1fba70cb52baa80fc14b7d0f72a04f0ef586
info(crypto.c:333@hip_write_hmac): hmac 
in0x3b0810110000000020010014359451e7148a19515b664a0220010015fc77357cecb72de3723366c50041000c000000900000000000000000018100040000000201c1000402000000
info(crypto.c:334@hip_write_hmac): hmac 
out0xcf951f0c53bba05a5ab838675f7057a9c56e56bf
info(update.c:990@hip_build_verification_pkt): ECHO_REQUEST in LOCATOR addr 
check0xe09d6abc
debug(update.c:994@hip_build_verification_pkt): sending addr verify pkt
debug(update.c:999@hip_build_verification_pkt): end, err=0
debug(output.c:951@hip_send_udp): Sending HIP_UPDATE packet on UDP.
debug(debug.c:649@hip_print_lsi): hip_send_udp(): local_addr: 192.168.0.8
debug(debug.c:649@hip_print_lsi): hip_send_udp(): peer_addr: 192.168.0.9
debug(output.c:955@hip_send_udp): Source port: 50500, destination port: 50500.
info(output.c:956@hip_send_udp):  dump:

---------------------------------------------------------------------------------------

I also tested with # hipconf nat on/off. The difference between on/off is the 
traffic captured by tcpdump.

# hipconf nat off
--no packet is filtered with ip-proto 253-- (but the debugging log shows base 
exchange is successful).

# hipconf nat on
# tcpdump -n -i any esp or proto 253 or port 50500
tcpdump: WARNING: Promiscuous mode not supported on the "any" device
tcpdump: verbose output suppressed, use -v or -vv for full protocol decode
listening on any, link-type LINUX_SLL (Linux cooked), capture size 96 bytes
16:22:05.683030 IP 192.168.0.8.50500 > 192.168.0.9.50500: UDP, length 40
16:22:06.215241 IP 192.168.0.9.50500 > 192.168.0.8.50500: UDP, length 696
16:22:06.389867 IP 192.168.0.8.50500 > 192.168.0.9.50500: UDP, length 704
16:22:06.487483 IP 192.168.0.9.50500 > 192.168.0.8.50500: UDP, length 216
16:22:06.509997 IP 192.168.0.9.50500 > 192.168.0.8.50500: UDP, length 240

Please...if you have any idea how to make this connection setup over Hi3 
successful. I do appreciate for any advise.

Best Regards,
Jeab



_________________________________________________________________
Express yourself instantly with MSN Messenger! Download today it's FREE!
http://messenger.msn.click-url.com/go/onm00200471ave/direct/01/

--
Miika Komu                                       http://www.iki.fi/miika/

Other related posts: