[support] "md_expire_router" problem in MR

Mon Nov 19 10:07:07 JST 2007

Hello all,
I am implementing NEMO test bed according to the latest how-to. When I connect MR from HL to FL1, it successfully hands off and connection between LFN and CN remains uninterrupted. However, after some time of handoff, MR gets a periodic "md_expire_router" in its wireless interface. and sends BU to HA. Getting this BU, HA deletes the existing tunnel. Again, after some time, MR gets "md_expire_router" in its interface and sends another BU to HA. This time HA creates a tunnel between HA and MR and connection restores. This toggle happens periodically.

To resolve this problem, I made AdvIntervalOpt in routers radvd.conf turned off but it didn't help.

Before using wireless interfaces in the test bed, I didn't get this problem, that means MR never reported "md_expire_router" in its log and tunnel was never deleted. But using wireless interfaces in the test bed introduces this problem.

I got another issue as well. When MnMaxHaBindingLife (suppose it is 60 sec)expires in MR, it sends refresh BU to HA, but getting this refresh BU, HA deletes the tunnel. Is this a expected behavior or I have some problem in my test bed?

I have also attached HA and MR output herewith this mail for "md_expire_router" problem.

I would appreciate any help regarding these problems.

Best Regards,
Md Sazzadur Rahman
Graduate Student,
School of Computer Science,
University of Oklahoma,
Norman, Oklahoma, USA

MR output:

Fri Nov 16 13:23:00 md_expire_router: expiring router fe80:0:0:0:214:22ff:feac:355e on iface wlan0 (5)
Fri Nov 16 13:23:01 __md_discover_router: discover link on iface wlan0 (5)
Fri Nov 16 13:23:01 md_change_default_router: add new router fe80:0:0:0:214:22ff:feac:355e on interface wlan0 (5)
Fri Nov 16 13:23:01 md_update_router_stats: add coa 2001:a:c:1:290:5bff:feb2:dd0 on interface (5)Fri Nov 16 13:23:01 md_expire_router: expiring router fe80:0:0:0:214:22ff:feac:355e on iface wlan0 (5)
Fri Nov 16 13:23:01 __md_discover_router: discover link on iface wlan0 (5)
Fri Nov 16 13:23:01 ndisc_send_unspec: sendmsg: Cannot assign requested address
Fri Nov 16 13:23:05 __md_discover_router: discover link on iface wlan0 (5)
Fri Nov 16 13:23:05 md_change_default_router: add new router fe80:0:0:0:214:22ff:feac:355e on interface wlan0 (5)
Fri Nov 16 13:23:05 md_update_router_stats: add coa 2001:a:c:1:290:5bff:feb2:dd0 on interface (5)Fri Nov 16 13:23:05 mn_move: 1731
Fri Nov 16 13:23:05 mn_move: in foreign net
Fri Nov 16 13:23:05 mn_block_rule_add: blackhole is already set.
Fri Nov 16 13:23:05 mn_send_home_bu: 783
Fri Nov 16 13:23:05 mn_get_home_lifetime: CoA lifetime 2591999 s, HoA lifetime 2591804 s, BU lifetime 1800 s
Fri Nov 16 13:23:05 mn_send_home_bu: Moved to foreign network
Fri Nov 16 13:23:05 mn_send_home_bu: Bule for HA exists. Updating it.
Fri Nov 16 13:23:05 mh_send: sending MH type 5
from 2001:a:b:0:0:0:0:1
to 2001:a:b:0:0:0:0:1000
Fri Nov 16 13:23:05 mh_send: local CoA 2001:a:c:1:290:5bff:feb2:dd0
Fri Nov 16 13:23:05 bul_update_timer: Updating timer
== BUL_ENTRY ==
Home address    2001:a:b:0:0:0:0:1
Care-of address 2001:a:c:1:290:5bff:feb2:dd0
CN address      2001:a:b:0:0:0:0:1000
 lifetime = 1800,  delay = 1000
 flags: IP6_MH_BU_HOME IP6_MH_BU_ACK
Fri Nov 16 13:23:05 tunnel_mod: modifying tunnel 6 end points with from 2001:a:c:1:290:5bff:feb2:dd0 to 2001:a:b:0:0:0:0:1000
Fri Nov 16 13:23:05 mn_recv_ba: 1029
Fri Nov 16 13:23:05 mn_recv_ba: Got BA from 2001:a:b:0:0:0:0:1000 to home address 2001:a:b:0:0:0:0:1 with coa 2001:a:c:1:290:5bff:feb2:dd0 and status 141
Fri Nov 16 13:23:05 mn_recv_ba: Dumping corresponding BULE
== BUL_ENTRY ==
Home address    2001:a:b:0:0:0:0:1
Care-of address 2001:a:c:1:290:5bff:feb2:dd0
CN address      2001:a:b:0:0:0:0:1000
 lifetime = 1800,  delay = 1000
 flags: IP6_MH_BU_HOME IP6_MH_BU_ACK
mip6d[3231]: Unable to register with HA, deleting entry
Fri Nov 16 13:23:05 bule_invalidate: HA unable to handle binding, changing BUL entry to NON_MIP_CN_ENTRY
Fri Nov 16 13:23:05 mn_pol_ext_cleanup:
Fri Nov 16 13:23:05 mpd_cancel_mps: canceling MPS
Fri Nov 16 13:23:05 bul_delete: Deleting bule
== BUL_ENTRY ==
Home address    2001:a:b:0:0:0:0:1
Care-of address 2001:a:b:0:0:0:0:1
CN address      2001:a:b:0:0:0:0:1000
 lifetime = 1800,  delay = 1000
 flags: IP6_MH_BU_HOME IP6_MH_BU_ACK
Fri Nov 16 13:23:05 bul_add: Adding bule
== NON_MIP_CN_ENTRY ==
Home address    2001:a:b:0:0:0:0:1
Care-of address 0:0:0:0:0:0:0:0
CN address      2001:a:b:0:0:0:0:1000
 lifetime = 420,  delay = 420000
 flags: IP6_MH_BU_HOME IP6_MH_BU_ACK

HA output:
^[OFmip6d[2886]: MIPL Mobile IPv6 for Linux v2.0.2-umip-0.4 started (Home Agent)
Tue Oct 30 17:46:55 main: MIPL Mobile IPv6 for Linux started in debug mode, not detaching from terminal
Tue Oct 30 17:46:55 conf_show: config_file = /usr/local/etc/mip6d.conf
Tue Oct 30 17:46:55 conf_show: vt_hostname = localhost
Tue Oct 30 17:46:55 conf_show: vt_service = 7777
Tue Oct 30 17:46:55 conf_show: mip6_entity = 2
Tue Oct 30 17:46:55 conf_show: debug_level = 10
Tue Oct 30 17:46:55 conf_show: debug_log_file = stderr
Tue Oct 30 17:46:55 conf_show: PolicyModulePath = [internal]
Tue Oct 30 17:46:55 conf_show: DefaultBindingAclPolicy = 129
Tue Oct 30 17:46:55 conf_show: NonVolatileBindingCache = disabled
Tue Oct 30 17:46:55 conf_show: KeyMngMobCapability = disabled
Tue Oct 30 17:46:55 conf_show: UseMnHaIPsec = disabled
Tue Oct 30 17:46:55 conf_show: MnMaxHaBindingLife = 262140
Tue Oct 30 17:46:55 conf_show: MnMaxCnBindingLife = 420
Tue Oct 30 17:46:55 conf_show: MnRouterProbes = 0
Tue Oct 30 17:46:55 conf_show: MnRouterProbeTimeout = 0.000000
Tue Oct 30 17:46:55 conf_show: InitialBindackTimeoutFirstReg = 1.500000
Tue Oct 30 17:46:55 conf_show: InitialBindackTimeoutReReg = 1.000000
Tue Oct 30 17:46:55 conf_show: UseCnBuAck = disabled
Tue Oct 30 17:46:55 conf_show: DoRouteOptimizationMN = enabled
Tue Oct 30 17:46:55 conf_show: MnUseAllInterfaces = disabled
Tue Oct 30 17:46:55 conf_show: MnDiscardHaParamProb = disabled
Tue Oct 30 17:46:55 conf_show: SendMobPfxSols = enabled
Tue Oct 30 17:46:55 conf_show: OptimisticHandoff = disabled
Tue Oct 30 17:46:55 conf_show: SendMobPfxAdvs = enabled
Tue Oct 30 17:46:55 conf_show: SendUnsolMobPfxAdvs = enabled
Tue Oct 30 17:46:55 conf_show: MaxMobPfxAdvInterval = 86400
Tue Oct 30 17:46:55 conf_show: MinMobPfxAdvInterval = 600
Tue Oct 30 17:46:55 conf_show: HaMaxBindingLife = 262140
Tue Oct 30 17:46:55 conf_show: DoRouteOptimizationCN = enabled
Tue Oct 30 17:46:55 xfrm_cn_init: Adding policies and states for CN
Tue Oct 30 17:46:55 xfrm_ha_init: Adding policies and states for HA
Tue Oct 30 17:46:55 ha_if_addr_setup: Joined anycast group 2001:a:b:0:fdff:ffff:ffff:fffe on iface 4
Tue Oct 30 17:50:48 mh_bu_parse: Binding Update Received
Tue Oct 30 17:50:49 ndisc_do_dad: Dad success
Tue Oct 30 17:50:49 __tunnel_add: created tunnel ip6tnl1 (5) from 2001:a:b:0:0:0:0:1000 to 2001:a:c:1:250:4ff:fe6f:ba14 user count 1
Tue Oct 30 17:50:49 mh_send_ba: status 0
Tue Oct 30 17:50:49 mh_send: sending MH type 6
from 2001:a:b:0:0:0:0:1000
to 2001:a:b:0:0:0:0:1
Tue Oct 30 17:50:49 mh_send: remote CoA 2001:a:c:1:250:4ff:fe6f:ba14
Tue Oct 30 17:51:45 mh_bu_parse: Binding Update Received
Tue Oct 30 17:51:45 tunnel_mod: modifying tunnel 5 end points with from 2001:a:b:0:0:0:0:1000 to 2001:a:c:1:250:4ff:fe6f:ba14Tue Oct 30 17:51:45 tunnel_mod: ext_tunnel_ops failed
Tue Oct 30 17:51:45 __tunnel_del: tunnel ip6tnl1 (5) from 2001:a:b:0:0:0:0:1000 to 2001:a:c:1:250:4ff:fe6f:ba14 user count decreased to 0
Tue Oct 30 17:51:45 __tunnel_del: tunnel deleted
Tue Oct 30 17:51:45 mh_send_ba: status 141
Tue Oct 30 17:51:45 mh_send: sending MH type 6
from 2001:a:b:0:0:0:0:1000
to 2001:a:b:0:0:0:0:1
Tue Oct 30 17:51:45 mh_send: remote CoA 2001:a:c:1:250:4ff:fe6f:ba14
Tue Oct 30 17:58:47 mh_bu_parse: Binding Update Received
Tue Oct 30 17:58:48 ndisc_do_dad: Dad success
Tue Oct 30 17:58:48 __tunnel_add: created tunnel ip6tnl1 (6) from 2001:a:b:0:0:0:0:1000 to 2001:a:c:1:250:4ff:fe6f:ba14 user count 1
Tue Oct 30 17:58:48 mh_send_ba: status 0
Tue Oct 30 17:58:48 mh_send: sending MH type 6
from 2001:a:b:0:0:0:0:1000
to 2001:a:b:0:0:0:0:1
Tue Oct 30 17:58:48 mh_send: remote CoA 2001:a:c:1:250:4ff:fe6f:ba14
Tue Oct 30 17:59:44 mh_bu_parse: Binding Update Received
Tue Oct 30 17:59:44 tunnel_mod: modifying tunnel 6 end points with from 2001:a:b:0:0:0:0:1000 to 2001:a:c:1:250:4ff:fe6f:ba14Tue Oct 30 17:59:44 tunnel_mod: ext_tunnel_ops failed
Tue Oct 30 17:59:44 __tunnel_del: tunnel ip6tnl1 (6) from 2001:a:b:0:0:0:0:1000 to 2001:a:c:1:250:4ff:fe6f:ba14 user count decreased to 0
Tue Oct 30 17:59:44 __tunnel_del: tunnel deleted
Tue Oct 30 17:59:44 mh_send_ba: status 141
Tue Oct 30 17:59:44 mh_send: sending MH type 6
from 2001:a:b:0:0:0:0:1000
to 2001:a:b:0:0:0:0:1
Tue Oct 30 17:59:44 mh_send: remote CoA 2001:a:c:1:250:4ff:fe6f:ba14
Tue Oct 30 18:06:45 mh_bu_parse: Binding Update Received
Tue Oct 30 18:06:46 ndisc_do_dad: Dad success
Tue Oct 30 18:06:46 __tunnel_add: created tunnel ip6tnl1 (7) from 2001:a:b:0:0:0:0:1000 to 2001:a:c:1:250:4ff:fe6f:ba14 user count 1
Tue Oct 30 18:06:46 mh_send_ba: status 0
Tue Oct 30 18:06:46 mh_send: sending MH type 6
from 2001:a:b:0:0:0:0:1000
to 2001:a:b:0:0:0:0:1
Tue Oct 30 18:06:46 mh_send: remote CoA 2001:a:c:1:250:4ff:fe6f:ba14
Tue Oct 30 18:07:42 mh_bu_parse: Binding Update Received
Tue Oct 30 18:07:42 tunnel_mod: modifying tunnel 7 end points with from 2001:a:b:0:0:0:0:1000 to 2001:a:c:1:250:4ff:fe6f:ba14Tue Oct 30 18:07:42 tunnel_mod: ext_tunnel_ops failed
Tue Oct 30 18:07:42 __tunnel_del: tunnel ip6tnl1 (7) from 2001:a:b:0:0:0:0:1000 to 2001:a:c:1:250:4ff:fe6f:ba14 user count decreased to 0
Tue Oct 30 18:07:42 __tunnel_del: tunnel deleted
Tue Oct 30 18:07:42 mh_send_ba: status 141
Tue Oct 30 18:07:42 mh_send: sending MH type 6
from 2001:a:b:0:0:0:0:1000
to 2001:a:b:0:0:0:0:1
Tue Oct 30 18:07:42 mh_send: remote CoA 2001:a:c:1:250:4ff:fe6f:ba14

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://ml.nautilus6.org/pipermail/support/attachments/20071118/9b7fd75e/attachment-0001.htm