activation stage already scheduled

2014-11-19 Thread D.S. Ljungmark
So, I have some small arm boxes out in the world, and at least one of
them is... misbehaving.

It's rotated all journals, so it's hard to see what started this, however:

root@modio:~# journalctl -u NetworkManager  |grep 'error' |wc -l
17051
The errors persisted until I killed NetworkManager and then everything
came up as it should. uncertain what happened. And I hope I can't
reproduce it.

NetworkManager --version
0.9.10.0
uname -a
Linux modio 3.17.2-bone5 #1 Thu Oct 30 19:55:17 UTC 2014 armv7l GNU/Linux

Debian packages from Jessie.


snippet from part of that -second- below:

Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.279189]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.310462]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.325419]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.327484]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.338799]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.351628]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.365748]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.375169]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.377461]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.391430]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.394526]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.409356]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.420293]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.432451]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.447322]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.462336]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.464379]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.478861]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.481982]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.495960]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.505470]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.507762]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.521718]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.523950]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.538990]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.553859]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.557044]
[devices/nm-device.c:2026] activation_source_schedule(): activation
stage already scheduled
Nov 18 21:35:38 modio NetworkManager[286]: error 

Re: activation stage already scheduled

2014-11-19 Thread Dan Williams
On Wed, 2014-11-19 at 18:24 +0100, D.S. Ljungmark wrote:
 So, I have some small arm boxes out in the world, and at least one of
 them is... misbehaving.
 
 It's rotated all journals, so it's hard to see what started this, however:
 
 root@modio:~# journalctl -u NetworkManager  |grep 'error' |wc -l
 17051
 The errors persisted until I killed NetworkManager and then everything
 came up as it should. uncertain what happened. And I hope I can't
 reproduce it.
 
 NetworkManager --version
 0.9.10.0
 uname -a
 Linux modio 3.17.2-bone5 #1 Thu Oct 30 19:55:17 UTC 2014 armv7l GNU/Linux
 
 Debian packages from Jessie.
 
 
 snippet from part of that -second- below:

I can't quite tell because the log messages that would tell me are long
gone, but I think the cause of this is IPv6 router advertisement timeout
mishandling.  I believe we have fixed that in NM 0.9.10.x which we hope
to release soon.  Relevant patch is (I think):

http://cgit.freedesktop.org/NetworkManager/NetworkManager/commit/?h=nm-0-9-10id=3351e3a6aeb1ab1222802b8857c757e590f5a0d7

you'll also probably want the following if IPv6 is used at all.

http://cgit.freedesktop.org/NetworkManager/NetworkManager/commit/?h=nm-0-9-10id=1e96e68e36ad0fb158e3a91e00c7fdc1d109f69d

Or, you could set the ipv6.method=ignore for the time being in the
connection configuration for that interface.

Does that help at all?

Dan


 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.279189]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.310462]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.325419]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.327484]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.338799]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.351628]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.365748]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.375169]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.377461]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.391430]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.394526]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.409356]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.420293]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.432451]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.447322]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.462336]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.464379]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.478861]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.481982]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.495960]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.505470]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.507762]
 [devices/nm-device.c:2026] activation_source_schedule(): activation
 stage already scheduled
 Nov 18 

Re: activation stage already scheduled

2014-11-19 Thread D.S. Ljungmark
That night help. We so use ipv6 so disabling it would be temporary if so.

Do you know what causes it? If I cab change something in the network setups?

Otherwise, is there more negative impact of this other than eating all my
log space?
On 19 Nov 2014 20:28, Dan Williams d...@redhat.com wrote:

 On Wed, 2014-11-19 at 18:24 +0100, D.S. Ljungmark wrote:
  So, I have some small arm boxes out in the world, and at least one of
  them is... misbehaving.
 
  It's rotated all journals, so it's hard to see what started this,
 however:
 
  root@modio:~# journalctl -u NetworkManager  |grep 'error' |wc -l
  17051
  The errors persisted until I killed NetworkManager and then everything
  came up as it should. uncertain what happened. And I hope I can't
  reproduce it.
 
  NetworkManager --version
  0.9.10.0
  uname -a
  Linux modio 3.17.2-bone5 #1 Thu Oct 30 19:55:17 UTC 2014 armv7l GNU/Linux
 
  Debian packages from Jessie.
 
 
  snippet from part of that -second- below:

 I can't quite tell because the log messages that would tell me are long
 gone, but I think the cause of this is IPv6 router advertisement timeout
 mishandling.  I believe we have fixed that in NM 0.9.10.x which we hope
 to release soon.  Relevant patch is (I think):


 http://cgit.freedesktop.org/NetworkManager/NetworkManager/commit/?h=nm-0-9-10id=3351e3a6aeb1ab1222802b8857c757e590f5a0d7

 you'll also probably want the following if IPv6 is used at all.


 http://cgit.freedesktop.org/NetworkManager/NetworkManager/commit/?h=nm-0-9-10id=1e96e68e36ad0fb158e3a91e00c7fdc1d109f69d

 Or, you could set the ipv6.method=ignore for the time being in the
 connection configuration for that interface.

 Does that help at all?

 Dan


  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.279189]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.310462]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.325419]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.327484]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.338799]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.351628]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.365748]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.375169]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.377461]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.391430]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.394526]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.409356]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.420293]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.432451]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.447322]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.462336]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.464379]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.478861]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.481982]
  [devices/nm-device.c:2026] activation_source_schedule(): activation
  stage already scheduled
  Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.495960]
  [devices/nm-device.c:2026] 

Re: activation stage already scheduled

2014-11-19 Thread Dan Williams
On Wed, 2014-11-19 at 21:22 +0100, D.S. Ljungmark wrote:
 That night help. We so use ipv6 so disabling it would be temporary if so.
 
 Do you know what causes it? If I cab change something in the network setups?

I think it's a result of NM mishandling the lifetime on the rdnss/dnssd
options.  If you can live without those, then perhaps turning them off
would work.  But that would  mean statically configured IPv6 DNS.

Dan

 Otherwise, is there more negative impact of this other than eating all my
 log space?
 On 19 Nov 2014 20:28, Dan Williams d...@redhat.com wrote:
 
  On Wed, 2014-11-19 at 18:24 +0100, D.S. Ljungmark wrote:
   So, I have some small arm boxes out in the world, and at least one of
   them is... misbehaving.
  
   It's rotated all journals, so it's hard to see what started this,
  however:
  
   root@modio:~# journalctl -u NetworkManager  |grep 'error' |wc -l
   17051
   The errors persisted until I killed NetworkManager and then everything
   came up as it should. uncertain what happened. And I hope I can't
   reproduce it.
  
   NetworkManager --version
   0.9.10.0
   uname -a
   Linux modio 3.17.2-bone5 #1 Thu Oct 30 19:55:17 UTC 2014 armv7l GNU/Linux
  
   Debian packages from Jessie.
  
  
   snippet from part of that -second- below:
 
  I can't quite tell because the log messages that would tell me are long
  gone, but I think the cause of this is IPv6 router advertisement timeout
  mishandling.  I believe we have fixed that in NM 0.9.10.x which we hope
  to release soon.  Relevant patch is (I think):
 
 
  http://cgit.freedesktop.org/NetworkManager/NetworkManager/commit/?h=nm-0-9-10id=3351e3a6aeb1ab1222802b8857c757e590f5a0d7
 
  you'll also probably want the following if IPv6 is used at all.
 
 
  http://cgit.freedesktop.org/NetworkManager/NetworkManager/commit/?h=nm-0-9-10id=1e96e68e36ad0fb158e3a91e00c7fdc1d109f69d
 
  Or, you could set the ipv6.method=ignore for the time being in the
  connection configuration for that interface.
 
  Does that help at all?
 
  Dan
 
 
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.279189]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.310462]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.325419]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.327484]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.338799]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.351628]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.365748]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.375169]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.377461]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.391430]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.394526]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.409356]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.420293]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.432451]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.447322]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.462336]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.464379]
   [devices/nm-device.c:2026] activation_source_schedule(): activation
   stage already scheduled
   Nov 18 21:35:38 modio NetworkManager[286]: error [1416346538.478861]