Modify

Opened 7 years ago

Last modified 4 years ago

#9879 reopened defect

Radvd related errors in Backfire after update to radvd 1.8

Reported by: hnyman Owned by: developers
Priority: normal Milestone: Chaos Calmer 15.05
Component: packages Version: Backfire 10.03.1 RC5
Keywords: radvd 6in4 ipv6 Cc: hannu.nyman@…

Description

Backfire's system log seems to get radvd-related error messages since radvd in Backfire was updated to 1.8 a few weeks ago with r27679. (I first thought that the error might be due to the new hotplug introduced at the same time, but the same errors materialize also with my own old now-removed hotplug script.)

I have not seen them earlier, before the summer, when Backfire still used radvd 1.7.

The following errors materialize in the system log it the 6in4 tunnel interface comes up (either at system boot, reconnect of the interface, or some settings change), as the radvd probably tries to restart.

These are from system startup of self-built Backfire 27885:

Aug  3 21:32:44 OpenWrt user.notice 6in4-update: Re-establishing tunnel due to change on wan (eth1)
vvvvvvvvvvvv
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: attempting to reread config file
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: resuming normal operation
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: attempting to reread config file
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: resuming normal operation
Aug  3 21:32:45 OpenWrt user.info firewall: removing sixxs (6in4-sixxs) from zone wan
Aug  3 21:32:45 OpenWrt user.info kernel: 6in4-sixxs: Disabled Privacy Extensions
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: attempting to reread config file
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: resuming normal operation
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: attempting to reread config file
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: resuming normal operation
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: attempting to reread config file
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: resuming normal operation
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: attempting to reread config file
Aug  3 21:32:45 OpenWrt daemon.info radvd[2338]: resuming normal operation
Aug  3 21:32:46 OpenWrt daemon.err radvd[2338]: poll error: Interrupted system call
Aug  3 21:32:46 OpenWrt daemon.info radvd[2338]: attempting to reread config file
Aug  3 21:32:46 OpenWrt daemon.info radvd[2338]: resuming normal operation
^^^^^^^^^^^^
Aug  3 21:32:46 OpenWrt user.notice ifup: Enabling Router Solicitations on sixxs (6in4-sixxs)
Aug  3 21:32:46 OpenWrt user.notice root: stopping ntpclient
Aug  3 21:32:47 OpenWrt user.info firewall: adding sixxs (6in4-sixxs) to zone wan

It is always the same errors, so it is rather consistent. (But the amount of "attempting to reread config file" & "resuming normal operation" pairs varies.)

As far as I see, IPv6 connectivity still works, so this is mostly cosmetics, but still annoying.

Attachments (0)

Change History (9)

comment:1 Changed 7 years ago by anonymous

Maybe it has something to do with netlink support being added
https://github.com/reubenhwk/radvd/commit/ca01b6ecf1af583dac7e6006cfb69c428bfc3695

comment:2 Changed 7 years ago by hnyman

I tested it also with trunk (Attitude Adjustment / trunk (r27893), and although there are no error messages related to the original start, there are the same litany of errors, if the tunnel interface is reconnected:

Aug  4 17:18:21 OpenWrt daemon.info radvd[2471]: attempting to reread config file
Aug  4 17:18:21 OpenWrt daemon.info radvd[2471]: resuming normal operation
Aug  4 17:18:21 OpenWrt daemon.info radvd[2471]: attempting to reread config file
Aug  4 17:18:21 OpenWrt daemon.info radvd[2471]: resuming normal operation
Aug  4 17:18:21 OpenWrt user.info firewall: removing sixxs (6in4-sixxs) from zone wan
Aug  4 17:18:21 OpenWrt daemon.info radvd[2471]: attempting to reread config file
Aug  4 17:18:21 OpenWrt daemon.info radvd[2471]: resuming normal operation
Aug  4 17:18:21 OpenWrt daemon.info radvd[2471]: attempting to reread config file
Aug  4 17:18:21 OpenWrt daemon.info radvd[2471]: resuming normal operation
Aug  4 17:18:21 OpenWrt daemon.info radvd[2471]: attempting to reread config file
Aug  4 17:18:21 OpenWrt daemon.info radvd[2471]: resuming normal operation
Aug  4 17:18:22 OpenWrt daemon.err radvd[2471]: poll error: Interrupted system call
Aug  4 17:18:22 OpenWrt daemon.info radvd[2471]: attempting to reread config file
Aug  4 17:18:22 OpenWrt daemon.info radvd[2471]: resuming normal operation}}}

So, the Milestone & Version in the ticket might be updated to concern also trunk.

comment:3 Changed 6 years ago by SimonIremonger

I am finding that "radvd" crashes/quits, now that I am using self-built backfire 10.3.1-rc (r27838) with a radvd 1.8 version, on ar7 architecture.
I didn't have this trouble with trunk r25675 that included radvd_1.7-1.

comment:4 Changed 6 years ago by reubenhwk@…

Can you verify radvd-1.8 is crashing?

From looking at the log and the code, it seems that radvd is continually getting HUP signals... Something else must still be sending them. The new netlink code will automatically reload the config file when an interface comes up. HUP isn't needed.

comment:5 Changed 6 years ago by jow

  • Resolution set to worksforme
  • Status changed from new to closed

There are no HUP signals anymore, those messages might happen if the LuCI ui spawns temporary interfaces to gather wireless information, each poll will in turn trigger a radvd reload through netlink. The latest package version has a patch to make implicit reloads silent.

comment:6 Changed 5 years ago by kra4ol4e

  • Resolution worksforme deleted
  • Status changed from closed to reopened

The same problem appeared again with recent trunk image - r34578.

Dec  9 17:53:06 DIR-825 daemon.debug radvd[6797]: attempting to reread config file
Dec  9 17:53:06 DIR-825 daemon.debug radvd[6797]: resuming normal operation
Dec  9 17:53:06 DIR-825 daemon.debug radvd[6797]: attempting to reread config file
Dec  9 17:53:06 DIR-825 daemon.debug radvd[6797]: resuming normal operation
Dec  9 17:53:06 DIR-825 daemon.debug radvd[6797]: attempting to reread config file
Dec  9 17:53:06 DIR-825 daemon.debug radvd[6797]: resuming normal operation
Dec  9 17:53:06 DIR-825 daemon.debug radvd[6797]: attempting to reread config file
Dec  9 17:53:06 DIR-825 daemon.debug radvd[6797]: resuming normal operation

Other than that everything seems fine.

comment:7 Changed 5 years ago by kra4ol4e

After deleting my browser's cache the annoying message has disappeared. I guess everithing should be fine then and this ticket can be closed. (r35412)

comment:8 Changed 5 years ago by nlh

This is the same problem I was seeing (using trunk); I didn't go searching for a ticket at the time though. The link below has a cut at a patch that I've been using for a while. I haven't noticed any bad side effects, but then I might not be stressing it either. I have tried sending the problem description and patch to the RADVD mailing list, but the list appears to be dead. The project is being maintained on github now, so I'll try submitting it there.

See: https://lists.openwrt.org/pipermail/openwrt-devel/2013-January/018132.html

comment:9 Changed 4 years ago by jow

  • Milestone changed from Backfire 10.03.2 to Chaos Calmer (trunk)

Milestone Backfire 10.03.2 deleted

Add Comment

Modify Ticket

Action
as reopened .
Author


E-mail address and user name can be saved in the Preferences.

 
Note: See TracTickets for help on using tickets.