[Xorp-users] FATAL xorp_fea ERROR

Mon Jul 17 15:38:14 PDT 2006

>   I had sent an earlier email about trying to get PIM working on
> single-interface machines to see if and how PIM works in an ad-hoc setup.
> 
> Notwithstanding the fact that the only thing I have gotten it to do is send
> PIM Registers from the Node/Router to the RP, I get a crash in the fea
> . Additionally, the MFC entry addition events do not trigger jp_timer events
> which would ordinarily have sent a PIM join.

The MFC entry addition is probably bogus (see my reply to your
earlier email). The real issue is why the PIM-SM Join message wasn't
generated when the receiver joined.

> I have attached the log on the offending machine which was running a sender as
> well as a receiver
> 
> Why does the fea error occur ?

First, could you double-check that HAVE_IF_INDEXTONAME inside
xorp/config.h is defined:

/* Define to 1 if you have the `if_indextoname' function. */
#define HAVE_IF_INDEXTONAME 1

If yes, could you send the output of the following programs (after
XORP is started):

UNIX:   cat /proc/net/ip_mr_vif 
Xorpsh: show interfaces
        show mfea interface
        show pim interface

Also, do you always get this XLOG_FATAL() message right after the
PIM Register message is transmitted:

[ 2006/07/17 16:01:31 TRACE xorp_pimsm4 PIM ] RX WHOLEPKT signal from MFEA_4: vif_index = 1 src = 192.168.100.14 dst = 224.5.6.7 len = 49
[ 2006/07/17 16:01:31 TRACE xorp_pimsm4 PIM ] TX PIM_REGISTER from 192.168.100.14 to 192.168.100.21 on vif register_vif

[ 2006/07/17 16:01:32  FATAL xorp_fea:14932 FEA +298 netlink_socket_utils.cc nlm_get_to_fte_cfg ] Could not find interface corresponding to index 17

> Why does the first mention of the join only occur after this fatal crash ?
> 
> [ 2006/07/17 16:01:32 WARNING xorp_pimsm4 PIM ] JoinDesired(*,G) = false: upstream neighbor for RP 192.168.100.21 for group 224.5.6.7: not found
> [ 2006/07/17 16:01:32 WARNING xorp_pimsm4 PIM ] JoinDesired(S,G) = false: upstream neighbor for source 192.168.100.14 and group 224.5.6.7: not found

For some reason, XORP probably wanted to send Prune messages
toward the RP and toward S, but it couldn't because there was no
upstream PIM-SM neighor router. Given that those messages were
generated right after the FEA crashed, all bets are off about which
particular event triggered them.

> Why does joining the group not create an immediate PIM Join when the
> router/node has been configured to act on only IGMP messages from itself and
> each router/node is a DR ? This should ordinarily have worked in sending a PIM
> join towards the next upstream router in direction of the RP ?

This is the question we need to answer. Hopefully, the debug info I
asked about in my earlier email will help us finding the answer.

Regards,
Pavlin

> 
> Any help will be welcome
> 
> regards..
> Vikram
> [ 2006/07/17 16:01:30 TRACE xorp_fea MFEA ] RX kernel signal: message_type = 3 vif_index = 1 src = 192.168.100.14 dst = 224.5.6.7
> [ 2006/07/17 16:01:30 TRACE xorp_pimsm4 PIM ] RX WHOLEPKT signal from MFEA_4: vif_index = 1 src = 192.168.100.14 dst = 224.5.6.7 len = 49
> [ 2006/07/17 16:01:30 TRACE xorp_pimsm4 PIM ] TX PIM_REGISTER from 192.168.100.14 to 192.168.100.21 on vif register_vif
> [ 2006/07/17 16:01:31 TRACE xorp_fea MFEA ] RX kernel signal: message_type = 3 vif_index = 1 src = 192.168.100.14 dst = 224.5.6.7
> [ 2006/07/17 16:01:31 TRACE xorp_pimsm4 PIM ] RX WHOLEPKT signal from MFEA_4: vif_index = 1 src = 192.168.100.14 dst = 224.5.6.7 len = 49
> [ 2006/07/17 16:01:31 TRACE xorp_pimsm4 PIM ] TX PIM_REGISTER from 192.168.100.14 to 192.168.100.21 on vif register_vif
> 
> [ 2006/07/17 16:01:32  FATAL xorp_fea:14932 FEA +298 netlink_socket_utils.cc nlm_get_to_fte_cfg ] Could not find interface corresponding to index 17
> 
> [ 2006/07/17 16:01:32 TRACE xorp_igmp MLD6IGMP ] RX IGMP_V2_LEAVE_GROUP from 192.168.100.14 to 224.0.0.2 on vif vf0
> [ 2006/07/17 16:01:32 TRACE xorp_igmp MLD6IGMP ] TX IGMP_MEMBERSHIP_QUERY from 192.168.100.14 to 224.5.6.7
> [ 2006/07/17 16:01:32 TRACE xorp_igmp MLD6IGMP ] RX IGMP_V2_LEAVE_GROUP from 192.168.100.14 to 224.0.0.2 on vif vf0
> [ 2006/07/17 16:01:32 TRACE xorp_igmp MLD6IGMP ] TX IGMP_MEMBERSHIP_QUERY from 192.168.100.14 to 224.0.0.13
> 
> [ 2006/07/17 16:01:32  ERROR xorp_fib2mrib:14980 FIB2MRIB +1569 xrl_fib2mrib_node.cc finder_event_observer_0_1_xrl_target_death ] FEA (instance fea-5fec59042f3942eaebcc96b857a25b9e at 127.0.0.1) has died, shutting down.
> [ 2006/07/17 16:01:32  ERROR xorp_rtrmgr:14921 RTRMGR +750 module_manager.cc done_cb ] Command "/extra/xorp/fea/xorp_fea": terminated with signal 6.
> [ 2006/07/17 16:01:32  INFO xorp_rtrmgr:14921 RTRMGR +297 module_manager.cc module_exited ] Module abnormally killed: fea
> [ 2006/07/17 16:01:32  INFO xorp_rtrmgr:14921 RTRMGR +297 module_manager.cc module_exited ] Module abnormally killed: interfaces
> [ 2006/07/17 16:01:32  INFO xorp_rtrmgr:14921 RTRMGR +297 module_manager.cc module_exited ] Module abnormally killed: mfea4
> [ 2006/07/17 16:01:32  ERROR xorp_fib2mrib:14980 LIBXORP +213 buffered_asyncio.cc io_event ] read error 104
> [ 2006/07/17 16:01:32  ERROR xorp_fib2mrib:14980 XRL +159 xrl_pf_stcp.cc read_event ] Read failed (error = 104)
> [ 2006/07/17 16:01:32  ERROR xorp_fib2mrib:14980 XRL +339 xrl_pf_stcp.cc die ] STCPRequestHandler died: read error
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 LIBXORP +213 buffered_asyncio.cc io_event ] read error 104
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 XRL +783 xrl_pf_stcp.cc read_event ] Read failed (error = 104)
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 XRL +636 xrl_pf_stcp.cc die ] XrlPFSTCPSender died: read error
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 MLD6IGMP +1534 xrl_mld6igmp_node.cc mfea_client_send_protocol_message_cb ] Cannot send a protocol message: 210 Transport failed
> [ 2006/07/17 16:01:32 INFO xorp_igmp XRL ] Sender died (protocol = "stcp", address = "127.0.0.1:33162")
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 LIBXORP +213 buffered_asyncio.cc io_event ] read error 104
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 XRL +159 xrl_pf_stcp.cc read_event ] Read failed (error = 104)
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 XRL +339 xrl_pf_stcp.cc die ] STCPRequestHandler died: read error
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 LIBXORP +213 buffered_asyncio.cc io_event ] read error 111
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 XRL +783 xrl_pf_stcp.cc read_event ] Read failed (error = 111)
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 XRL +636 xrl_pf_stcp.cc die ] XrlPFSTCPSender died: read error
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 MLD6IGMP +1534 xrl_mld6igmp_node.cc mfea_client_send_protocol_message_cb ] Cannot send a protocol message: 210 Transport failed
> [ 2006/07/17 16:01:32  INFO xorp_rtrmgr:14921 RTRMGR +278 module_manager.cc module_exited ] Module normal exit: fib2mrib
> [ 2006/07/17 16:01:32  ERROR xorp_igmp:14989 MLD6IGMP +1834 xrl_mld6igmp_node.cc finder_event_observer_0_1_xrl_target_death ] MFEA (instance MFEA_4-1eda973b04dd50efd6db560643e10159 at 127.0.0.1) has died, shutting down.
> [ 2006/07/17 16:01:32 INFO xorp_igmp MLD6IGMP ] Interface stopped: Vif[vf0] pif_index: 0 vif_index: 0 addr: 192.168.100.14 subnet: 192.168.100.0/24 broadcast: 192.168.100.255 peer: 0.0.0.0 Flags: MULTICAST BROADCAST UNDERLYING_VIF_UP DOWN IPv4 ENABLED
> [ 2006/07/17 16:01:32  ERROR xorp_pimsm4:15069 PIM +2949 xrl_pim_node.cc finder_event_observer_0_1_xrl_target_death ] MFEA (instance MFEA_4-1eda973b04dd50efd6db560643e10159 at 127.0.0.1) has died, shutting down.
> 
> [ 2006/07/17 16:01:32 TRACE xorp_pimsm4 PIM ] TX PIM_HELLO from 192.168.100.14 to 224.0.0.13 on vif vf0
> [ 2006/07/17 16:01:32 WARNING xorp_pimsm4 PIM ] JoinDesired(*,G) = false: upstream neighbor for RP 192.168.100.21 for group 224.5.6.7: not found
> [ 2006/07/17 16:01:32 WARNING xorp_pimsm4 PIM ] JoinDesired(S,G) = false: upstream neighbor for source 192.168.100.14 and group 224.5.6.7: not found
> [ 2006/07/17 16:01:32 TRACE xorp_pimsm4 PIM ] Delete MFC entry: (192.168.100.14, 224.5.6.7) iif = 0 olist = .O
> [ 2006/07/17 16:01:32 INFO xorp_pimsm4 PIM ] Interface stopped: Vif[register_vif] pif_index: 0 vif_index: 1 addr: 192.168.100.14 subnet: 192.168.100.14/32 broadcast: 192.168.100.14 peer: 0.0.0.0 Flags: PIM_REGISTER UNDERLYING_VIF_UP DOWN IPv4 ENABLED
> [ 2006/07/17 16:01:32 TRACE xorp_pimsm4 PIM ] TX PIM_HELLO from 192.168.100.14 to 224.0.0.13 on vif vf0
> [ 2006/07/17 16:01:32 INFO xorp_pimsm4 PIM ] Interface stopped: Vif[vf0] pif_index: 0 vif_index: 0 addr: 192.168.100.14 subnet: 192.168.100.0/24 broadcast: 192.168.100.255 peer: 0.0.0.0 Flags: MULTICAST BROADCAST UNDERLYING_VIF_UP DOWN IPv4 ENABLED
> 
> [ 2006/07/17 16:01:32  WARNING xorp_rtrmgr:14921 XrlFinderTarget +406 ../xrl/targets/finder_base.cc handle_finder_0_2_resolve_xrl ] Handling method for finder/0.2/resolve_xrl failed: XrlCmdError 102 Command failed Target "MFEA_4" does not exist or is not enabled.
> [ 2006/07/17 16:01:32 INFO xorp_igmp MLD6IGMP ] CLI stopped
> [ 2006/07/17 16:01:32 INFO xorp_igmp MLD6IGMP ] CLI stopped
> [ 2006/07/17 16:01:32 INFO xorp_igmp MLD6IGMP ] Interface deleted: vf0
> [ 2006/07/17 16:01:32 INFO xorp_pimsm4 XRL ] Sender died (protocol = "stcp", address = "127.0.0.1:33192")
> [ 2006/07/17 16:01:32  ERROR xorp_pimsm4:15069 LIBXORP +213 buffered_asyncio.cc io_event ] read error 111
> [ 2006/07/17 16:01:32  ERROR xorp_pimsm4:15069 XRL +783 xrl_pf_stcp.cc read_event ] Read failed (error = 111)
> [ 2006/07/17 16:01:32  ERROR xorp_pimsm4:15069 XRL +636 xrl_pf_stcp.cc die ] XrlPFSTCPSender died: read error
> [ 2006/07/17 16:01:32  INFO xorp_rtrmgr:14921 RTRMGR +278 module_manager.cc module_exited ] Module normal exit: igmp
> [ 2006/07/17 16:01:32  WARNING xorp_rtrmgr:14921 XrlFinderTarget +406 ../xrl/targets/finder_base.cc handle_finder_0_2_resolve_xrl ] Handling method for finder/0.2/resolve_xrl failed: XrlCmdError 102 Command failed Target "MFEA_4" does not exist or is not enabled.
> [ 2006/07/17 16:01:32  ERROR xorp_pimsm4:15069 PIM +2638 xrl_pim_node.cc mfea_client_send_protocol_message_cb ] Cannot send a protocol message: 201 Resolve failed
> [ 2006/07/17 16:01:32  WARNING xorp_rtrmgr:14921 XrlFinderTarget +406 ../xrl/targets/finder_base.cc handle_finder_0_2_resolve_xrl ] Handling method for finder/0.2/resolve_xrl failed: XrlCmdError 102 Command failed Target "MFEA_4" does not exist or is not enabled.
> [ 2006/07/17 16:01:32  ERROR xorp_pimsm4:15069 PIM +1868 xrl_pim_node.cc mfea_client_send_add_delete_mfc_cb ] XRL communication error: 201 Resolve failed
> 
> _______________________________________________
> Xorp-users mailing list
> Xorp-users at xorp.org
> http://mailman.ICSI.Berkeley.EDU/mailman/listinfo/xorp-users