Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

FS#1628 - odhcpd: excessive logging of RA lifetime #6560

Closed
openwrt-bot opened this issue Jul 3, 2018 · 3 comments
Closed

FS#1628 - odhcpd: excessive logging of RA lifetime #6560

openwrt-bot opened this issue Jul 3, 2018 · 3 comments
Labels

Comments

@openwrt-bot
Copy link

pleasantone:

This is master as of June 29th, 2018.

Enable and run ohdpcd on a few lan interfaces...

Mon Jul 2 22:25:28 2018 daemon.info odhcpd[1139]: Using a RA lifetime of 1800 seconds on br-lan
Mon Jul 2 22:25:28 2018 daemon.info odhcpd[1139]: Using a RA lifetime of 1800 seconds on br-guest
Mon Jul 2 22:25:38 2018 daemon.info odhcpd[1139]: Using a RA lifetime of 1800 seconds on br-lan
Mon Jul 2 22:25:38 2018 daemon.info odhcpd[1139]: Using a RA lifetime of 1800 seconds on br-guest
Mon Jul 2 22:25:42 2018 daemon.info odhcpd[1139]: Using a RA lifetime of 1800 seconds on br-lan
Mon Jul 2 22:25:42 2018 daemon.info odhcpd[1139]: Using a RA lifetime of 1800 seconds on br-guest

Should not be repeating, should not be at daemon.info either. Don't pollute the log if nothing has changed.

@openwrt-bot
Copy link
Author

mherbert:

Reproduced with release 18.06.2

The odhcpd logging is quite usefull for trouble shooting;

Yeah sure, pretty much every log statement is useful for troubleshooting; that's the essence of a log statement. Yet this one is the only INFO statement filling up the logs like clockwork in the default configuration, no other INFO statement does that.

(One of the very first action before troubleshooting is to increase the log level)

particular related to this trace it's not only about the lifetime being announced but also about the rate at which RA messages are sent.

Setting aside for the spam question for a minute: what is an expected / "normal" frequency range for these events? On my system "Using a RA lifetime of 1800 seconds" shows up about 3 times per minute: is that something to be concerned about?

If you want to make odhcpd less verbose you can set the loglevel uci parameter in the dhcp.odhcpd section to a lower value than 4 which is the current default

Thanks. What is the default loglevel of other components? Seem to be INFO, correct?

@openwrt-bot
Copy link
Author

mherbert:

If you want to make odhcpd less verbose you can set the loglevel uci parameter in the dhcp.odhcpd section to a lower value than 4 which is the current default

The default odhcp loglevel in release 18.06.1 is not 4, it's 6 or higher. I know that because the "daemon.info odhcpd RA lifetime" spam is present at level 6 and not present at level 5.

Now trying to figure out why odhcpd sends about 3 Router Advertisements/minute by default...

@openwrt-bot
Copy link
Author

mherbert:

Now trying to figure out why odhcpd sends about 3 Router Advertisements/minute by default...

I did some reading and packet traces on both WAN and LAN and this message is printed for every default, unsolicited RA on the LAN. No correlation to anything else going on anywhere. So it gets printed for a regular, totally non-event not worth the INFO level. The fix for this bug is just to move this statement from the INFO to the DEBUG level.

I tried to change the interval with ra_maxinterval and ra_mininterval but it made no difference (changing ra_lifetime in the same section does make a difference)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

1 participant