Bug 782175 - NetworkManager floods system log with Activation messages
Summary: NetworkManager floods system log with Activation messages
Keywords:
Status: CLOSED WONTFIX
Alias: None
Product: Fedora
Classification: Fedora
Component: NetworkManager
Version: 16
Hardware: x86_64
OS: Linux
unspecified
medium
Target Milestone: ---
Assignee: Dan Williams
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
: 761313 (view as bug list)
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2012-01-16 18:35 UTC by Steve Snyder
Modified: 2013-02-13 13:51 UTC (History)
4 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2013-02-13 13:51:13 UTC
Type: ---


Attachments (Terms of Use)

Description Steve Snyder 2012-01-16 18:35:02 UTC
Description of problem:

NetworkManager writes Activation message to the system log *very* often.

Version-Release number of selected component (if applicable):

NetworkManager-0.9.2-1.fc16.x86_64

How reproducible:

Always

Steps to Reproduce:
1. Use NetworkManager
2.
3.
  
Actual results:

System log is flooded with messages, 12 lines at roughly 12 - 18 second intervals  An instance is shown below.


Expected results:

NetworkManager should only log significant networking events.

Additional info:

This is the set of messages that is periodically written to the system log.  This machine has a wireless networking device, but I have another Fedora16/x86_64 machine with no wireless devices and the same flooding of the system log occurs on that machine too.

Jan 16 13:19:02 earth NetworkManager[880]: <info> Activation (p7p1) Stage 4 of 5 (IP6 Configure Get) scheduled...
Jan 16 13:19:02 earth NetworkManager[880]: <info> Activation (p7p1) Stage 4 of 5 (IP6 Configure Get) started...
Jan 16 13:19:02 earth NetworkManager[880]: NetworkManager[880]: <info> Activation (p7p1) Stage 4 of 5 (IP6 Configure Get) scheduled...
Jan 16 13:19:02 earth NetworkManager[880]: NetworkManager[880]: <info> Activation (p7p1) Stage 4 of 5 (IP6 Configure Get) started...
Jan 16 13:19:02 earth NetworkManager[880]: <info> Activation (p7p1) Stage 5 of 5 (IP Configure Commit) scheduled...
Jan 16 13:19:02 earth NetworkManager[880]: <info> Activation (p7p1) Stage 4 of 5 (IP6 Configure Get) complete.
Jan 16 13:19:02 earth NetworkManager[880]: <info> Activation (p7p1) Stage 5 of 5 (IP Configure Commit) started...
Jan 16 13:19:02 earth NetworkManager[880]: NetworkManager[880]: <info> Activation (p7p1) Stage 5 of 5 (IP Configure Commit) scheduled...
Jan 16 13:19:02 earth NetworkManager[880]: <info> Activation (p7p1) Stage 5 of 5 (IP Configure Commit) complete.
Jan 16 13:19:02 earth NetworkManager[880]: NetworkManager[880]: <info> Activation (p7p1) Stage 4 of 5 (IP6 Configure Get) complete.
Jan 16 13:19:02 earth NetworkManager[880]: NetworkManager[880]: <info> Activation (p7p1) Stage 5 of 5 (IP Configure Commit) started...
Jan 16 13:19:02 earth NetworkManager[880]: NetworkManager[880]: <info> Activation (p7p1) Stage 5 of 5 (IP Configure Commit) complete.

Comment 1 Jirka Klimes 2012-01-30 11:44:26 UTC
You may add this to /etc/NetworkManager/NetworkManager.conf to make logging less verbose:

[logging]
level=WARN

See 'man NetworkManager.conf' for more information.

Comment 2 Jeff Layton 2012-02-02 00:18:13 UTC
This is a symptom of a larger problem I think...

The issue is that NetworkManager is continually thrashing -- disconnecting and reconnecting the network interfaces. It has made the latest version of NM completely unusable for me.

My suspicion is that it has something to do with IPv6 configuration or more people would be complaining about it.

Comment 3 Steve Snyder 2012-02-02 02:12:32 UTC
Well, I don't know if it's IPv6-related or not, but both of my F16/x86_64 machines are showing this behavior and both have IPv6 connectivity.  Only one of the machines has a wireless network device, so that rules out wireless as the cause of the constant logging.

The IPv6 addresses are handed out by the radvd daemon running on the network server to which both machine are connected.

Let me know if I can provide any more config info.

Comment 4 Jeff Layton 2012-02-02 22:23:27 UTC
One of my co-workers pointed out a workaround. I had set up IPv6 connectivity on the network connection to "Automatic". Setting it to "Ignore" seems to have calmed things down.

Even with that setting, the kernel still picks up router advertisements and I
get an IPv6 address. It apparently just makes NM get out of the way. The one
thing that it noticeable is that it doesn't set the IPv6 address of the DNS
server in /etc/resolv.conf, but I suppose I can live with that until this is
properly fixed.

Comment 5 Jeff Layton 2012-02-02 22:24:44 UTC
BTW, his conjecture was that NM was timing out the IPv6 configuration on an
interval much smaller than the router advertisement interval. Increasing the
broadcast frequency on the router might also help here.

Comment 6 Jeff Layton 2012-02-02 23:36:07 UTC
*** Bug 761313 has been marked as a duplicate of this bug. ***

Comment 7 Jeff Layton 2012-02-02 23:40:29 UTC
In fact...this may be a duplicate of bug 675226. I'm not certain of that but
the suggested workaround is the same.

Comment 8 John Haxby 2012-04-08 10:37:12 UTC
Bug 761313 suggests a more serious problem that just flooding the log with messages.   I'm seeing both problems on two machines.

The more serious problem is that on one machine the frequent reconfiguration causes breaks in other services.  On one machine where I have forked-daapd this means that music playing over the network to an airport express will randomly stop.   I can live with that ...

However, on another machine a VPN connection using openconnect will randomly fail every few minutes.  Reconnecting wouldn't be so bad except that the VPN server assigns a different IP address every time so any connections I have open will eventually timeout.   This can happen, and has happened, half way though a subversion commit, a git push, sending a mail message, buying books from Amazon.  For me, this makes this bug rather serious.

Comment 9 Fedora End Of Life 2013-01-16 13:10:02 UTC
This message is a reminder that Fedora 16 is nearing its end of life.
Approximately 4 (four) weeks from now Fedora will stop maintaining
and issuing updates for Fedora 16. It is Fedora's policy to close all
bug reports from releases that are no longer maintained. At that time
this bug will be closed as WONTFIX if it remains open with a Fedora 
'version' of '16'.

Package Maintainer: If you wish for this bug to remain open because you
plan to fix it in a currently maintained version, simply change the 'version' 
to a later Fedora version prior to Fedora 16's end of life.

Bug Reporter: Thank you for reporting this issue and we are sorry that 
we may not be able to fix it before Fedora 16 is end of life. If you 
would still like to see this bug fixed and are able to reproduce it 
against a later version of Fedora, you are encouraged to click on 
"Clone This Bug" and open it against that version of Fedora.

Although we aim to fix as many bugs as possible during every release's 
lifetime, sometimes those efforts are overtaken by events. Often a 
more recent Fedora release includes newer upstream software that fixes 
bugs or makes them obsolete.

The process we are following is described here: 
http://fedoraproject.org/wiki/BugZappers/HouseKeeping

Comment 10 Fedora End Of Life 2013-02-13 13:51:16 UTC
Fedora 16 changed to end-of-life (EOL) status on 2013-02-12. Fedora 16 is 
no longer maintained, which means that it will not receive any further 
security or bug fix updates. As a result we are closing this bug.

If you can reproduce this bug against a currently maintained version of 
Fedora please feel free to reopen this bug against that version.

Thank you for reporting this bug and we are sorry it could not be fixed.


Note You need to log in before you can comment on or make changes to this bug.