[support] mipv6 error

Tue May 19 11:15:35 JST 2009

Hello,

I am not sure about the origin of the error "Address already in use" but
I don't believe this is the source of your problem. In my understanding,
here is what happens in your testbed:

(all daemons started)
MN moves to foreign net.
MN wants to send the BU
No SA exist => an ACQUIRE message is generated in the MN.
iked catches this message and initiates the IKEv2 exchange.
MN -> HA: IKE_SA_INIT
HA -> MN: IKE_SA_INIT
MN -> HA: IKE_AUTH
HA -> MN: *error (invalid TS... why???)*

Therefore no SA is created, and MN is not able to send the BU (the error
you see later on your MN).

The reason why the IKEv2 exchange fails is not clear to me. The IKE_AUTH
message seems fine. I may miss something important though, sorry, it has
been a long time since I worked on this topic. Anyway, this is
definitely the place I would look for and try to debug. I hope this
helps you a little. Unfortunately, I don't have the resource nor the
time to re-build a testbed and look for this issue here.

Best regards,
Sebastien.

qhtf126 a ¨¦crit :
> hi,
> I followed the order HA: smpd iked mip6d MN: smpd iked mip6d
> this is iked log in mn . After i starting mip6d in mn , it shows :
> 2009-05-18 19:33:56 [INTERNAL_ERR]:
> isakmp.c:522:isakmp_open_address(): bind(3ffe:0:0:2::2[500]): Address
> already in use
> but i didn't have any daemon running before . please help me to solve
> the problem and thank you very much .
> best regards ,
> qhtf .
> *******************************************************************************************
> iked log in mn :
> 2009-05-18 19:33:38 [DEBUG]: ike_pfkey.c:178:sadb_init(): pfkey_socket: 3
> 2009-05-18 19:33:38 [DEBUG]:
> ike_conf.c:4170:ike_conf_check_consistency(): checking configuration
> 2009-05-18 19:33:38 [INTERNAL_WARN]:
> ike_conf.c:3812:ike_conf_check_ikev2(): remote (default) ikev2
> ipsec_sa_nego_time_limit configuration field support is unimplemented,
> ignored
> 2009-05-18 19:33:38 [INTERNAL_WARN]:
> ike_conf.c:4262:ike_conf_check_consistency(): configuration errors: 0,
> warnings: 1
> 2009-05-18 19:33:38 [DEBUG]: netlink.c:58:rtsock_init():
> rtnetlink_socket: 4
> 2009-05-18 19:33:38 [DEBUG]: if_spmd.c:359: spmd I/F connection ok:
> 220 77082316C8E51C1EBEC27F36F04670430B177F01
> 2009-05-18 19:33:38 [DEBUG]: cfsetup.c:3881: read 16 bytes
> 2009-05-18 19:33:38 [DEBUG]: if_spmd.c:421: spmd LOGIN ok: 250 OK
> 2009-05-18 19:33:38 [DEBUG]: ike_spmif.c:69:ike_spmif_init():
> spmif_socket: 5
> 2009-05-18 19:33:38 [DEBUG]: isakmp.c:530:isakmp_open_address():
> socket 6 bind 127.0.0.1[500]
> 2009-05-18 19:33:38 [DEBUG]: isakmp.c:530:isakmp_open_address():
> socket 7 bind 169.254.10.229[500]
> 2009-05-18 19:33:38 [DEBUG]: isakmp.c:530:isakmp_open_address():
> socket 8 bind ::1[500]
> 2009-05-18 19:33:38 [DEBUG]: isakmp.c:530:isakmp_open_address():
> socket 9 bind 3ffe::2:215:ff:fe4d:7046[500]
> 2009-05-18 19:33:38 [DEBUG]: isakmp.c:530:isakmp_open_address():
> socket 10 bind 3ffe:0:0:2::2[500]
> 2009-05-18 19:33:38 [DEBUG]: isakmp.c:530:isakmp_open_address():
> socket 11 bind fe80::215:ff:fe4d:7046%eth1[500]
> 2009-05-18 19:33:38 [INFO]: main.c:433:main(): starting iked for
> racoon2 20090327c
> 2009-05-18 19:33:40 [DEBUG]: netlink.c:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:40 [DEBUG]: netlink.c:89:rtsock_process(): len 64
> 2009-05-18 19:33:40 [DEBUG]: netlink.c:111:rtsock_process(): type 20
> 2009-05-18 19:33:44 [DEBUG]: netlink.c:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:44 [DEBUG]: netlink.c:89:rtsock_process(): len 64
> 2009-05-18 19:33:44 [DEBUG]: netlink.c:111:rtsock_process(): type 20
> 2009-05-18 19:33:47 [DEBUG]: netlink.c:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:47 [DEBUG]: netlink.c:89:rtsock_process(): len 64
> 2009-05-18 19:33:47 [DEBUG]: netlinkc:111:rtsock_process(): type 20
> 2009-05-18 19:33:50 [DEBUG]: netlink.c:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:50 [DEBUG]: netlink.c:89:rtsock_process(): len 64
> 2009-05-18 19:33:50 [DEBUG]: netlink.c:111:rtsock_process(): type 20
> 2009-05-18 19:33:54 [DEBUG]: netlinkc:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:54 [DEBUG]: netlink.c:89:rtsock_process(): len 64
> 2009-05-18 19:33:54 [DEBUG]: netlink.c:111:rtsock_process(): type 20
> 2009-05-18 19:33:56 [DEBUG]: netlink.c:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:56 [DEBUG]: netlink.c:89:rtsock_process(): len 64
> 2009-05-18 19:33:56 [DEBUG]: netlink.c:111:rtsock_process(): type 20
> 2009-05-18 19:33:56 [DEBUG]: isakmp.c:530:isakmp_open_address():
> socket 12 bind fe80::215:ff:fe4d:7046%ip6tnl1[500]
> 2009-05-18 19:33:56 [DEBUG]: netlink.c:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:56 [DEBUG]: netlink.c:89:rtsock_process(): len 656
> 2009-05-18 19:33:56 [DEBUG]: netlink.c:111:rtsock_process(): type 16
> 2009-05-18 19:33:56 [DEBUG]: netlink.c:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:56 [DEBUG]: netlink.c:89:rtsock_process(): len 64
> 2009-05-18 19:33:56 [DEBUG]: netlink.c:111:rtsock_process(): type 20
> 2009-05-18 19:33:56 [INTERNAL_ERR]:
> isakmp.c:522:isakmp_open_address(): bind(3ffe:0:0:2::2[500]): Address
> already in use
> 2009-05-18 19:33:57 [INTERNAL_ERR]:
> isakmp.c:522:isakmp_open_address(): bind(3ffe:0:0:2::2[500]): Address
> already in use
> 2009-05-18 19:33:57 [DEBUG]: netlink.c:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:57 [DEBUG]: netlink.c:89:rtsock_process(): len 64
> 2009-05-18 19:33:57 [DEBUG]: netlink.c:111:rtsock_process(): type 20
> 2009-05-18 19:33:57 [DEBUG]: netlink.c:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:57 [DEBUG]: netlink.c:89:rtsock_process(): len 64
> 2009-05-18 19:33:57 [DEBUG]: netlink.c:111:rtsock_process(): type 21
> 2009-05-18 19:33:57 [DEBUG]: netlink.c:87:rtsock_process(): reading
> netlink socket
> 2009-05-18 19:33:57 [DEBUG]: netlink.c:89:rtsock_process(): len 64
> ..............................................................................................................
> mip6d log in mn :
> mip6d[6203]: MIPL Mobile IPv6 for Linux v2.0.2-umip-0.4 started
> (Mobile Node)
> Mon May 18 19:33:56 main: MIPL Mobile IPv6 for Linux started in debug
> mode, not detaching from terminal
> Mon May 18 19:33:56 conf_show: config_file =
> /root/Desktop/final/mn/common/with-ipsec/mn-mip6d-setkey.conf
> Mon May 18 19:33:56 conf_show: vt_hostname = localhost
> Mon May 18 19:33:56 conf_show: vt_service = 7777
> Mon May 18 19:33:56 conf_show: mip6_entity = 1
> Mon May 18 19:33:56 conf_show: debug_level = 10
> Mon May 18 19:33:56 conf_show: debug_log_file = stderr
> Mon May 18 19:33:56 conf_show: PolicyModulePath = [internal]
> Mon May 18 19:33:56 conf_show: DefaultBindingAclPolicy = 0
> Mon May 18 19:33:56 conf_show: NonVolatileBindingCache = disabled
> Mon May 18 19:33:56 conf_show: KeyMngMobCapability = enabled
> Mon May 18 19:33:56 conf_show: UseMnHaIPsec = enabled
> Mon May 18 19:33:56 conf_show: MnMaxHaBindingLife = 262140
> Mon May 18 19:33:56 conf_show: MnMaxCnBindingLife = 420
> Mon May 18 19:33:56 conf_show: MnRouterProbes = 1
> Mon May 18 19:33:56 conf_show: MnRouterProbeTimeout = 0.000000
> Mon May 18 19:33:56 conf_show: InitialBindackTimeoutFirstReg = 1.500000
> Mon May 18 19:33:56 conf_show: InitialBindackTimeoutReReg = 1.000000
> Mon May 18 19:33:56 conf_show: UseCnBuAck = enabled
> Mon May 18 19:33:56 conf_show: DoRouteOptimizationMN = disabled
> Mon May 18 19:33:56 conf_show: MnUseAllInterfaces = disabled
> Mon May 18 19:33:56 conf_show: MnDiscardHaParamProb = enabled
> Mon May 18 19:33:56 conf_show: SendMobPfxSols = enabled
> Mon May 18 19:33:56 conf_show: OptimisticHandoff = disabled
> Mon May 18 19:33:56 conf_show: SendMobPfxAdvs = enabled
> Mon May 18 19:33:56 conf_show: SendUnsolMobPfxAdvs = enabled
> Mon May 18 19:33:56 conf_show: MaxMobPfxAdvInterval = 86400
> Mon May 18 19:33:56 conf_show: MinMobPfxAdvInterval = 600
> Mon May 18 19:33:56 conf_show: HaMaxBindingLife = 262140
> Mon May 18 19:33:56 conf_show: DoRouteOptimizationCN = enabled
> Mon May 18 19:33:56 xfrm_cn_init: Adding policies and states for CN
> Mon May 18 19:33:56 xfrm_mn_init: Adding policies and states for MN
> Mon May 18 19:33:56 conf_home_addr_info: HoA address 3ffe:0:0:2:0:0:0:4
> Mon May 18 19:33:56 conf_home_addr_info: HA address 3ffe:0:0:2:0:0:0:1
> Mon May 18 19:33:56 __tunnel_add: created tunnel ip6tnl1 (5) from
> 3ffe:0:0:2:0:0:0:4 to 3ffe:0:0:2:0:0:0:1 user count 1
> Mon May 18 19:33:56 conf_home_addr_info: Home address 3ffe:0:0:2:0:0:0:4
> Mon May 18 19:33:56 flag_hoa: set HoA 3ffe:0:0:2:0:0:0:4/128 iif 5
> flags 12 preferred_time 4294967295 valid_time 4294967295
> Mon May 18 19:33:56 conf_home_addr_info: Added new home_addr_info
> successfully
> Mon May 18 19:33:56 __md_discover_router: discover link on iface eth1 (4)
> Mon May 18 19:33:57 md_change_default_router: have entered change
> default router
> Mon May 18 19:33:57 md_change_default_router: if = 0
> Mon May 18 19:33:57 md_change_default_router: add new router
> fe80:0:0:0:211:5bff:fe2f:5cb7 on interface eth1 (4)
> Mon May 18 19:33:57 md_update_router_stats: add coa
> 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
> Mon May 18 19:33:58 mn_addr_do_dad: DAD succeeded!
> Mon May 18 19:33:58 mn_addr_do_dad: address = 3ffe:0:0:2:0:0:0:4
> Mon May 18 19:33:58 mn_move: 1578
> Mon May 18 19:33:58 mn_move: in home net
> Mon May 18 19:33:58 mv_hoa: move HoA 3ffe:0:0:2:0:0:0:4/64 from iface
> 5 to 4
> Mon May 18 19:34:00 md_update_router_stats: add coa
> 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
> Mon May 18 19:34:03 md_update_router_stats: add coa
> 3ffe:0:0:2:215:ff:fe4d:7046 on interface (4)
>
>
>
> ------------------------------------------------------------------------
> ´©Ô½µØÕð´ø ¼ÍÄîãë´¨µØÕðÒ»ÖÜÄê
> <http://512.mail.163.com/mailstamp/stamp/dz/activity.do?from=footer>
> ------------------------------------------------------------------------
>
> _______________________________________________
> Support mailing list
> Support at ml.nautilus6.org
> http://ml.nautilus6.org/mailman/listinfo/support
>   