#28419 – etcnet brings an iface correctly up; but then NetworkManager brings it down

Bug 28419 - etcnet brings an iface correctly up; but then NetworkManager brings it down

Summary: etcnet brings an iface correctly up; but then NetworkManager brings it down

Status:	CLOSED WORKSFORME

Alias:	None

Product:	Branch p6
Classification:	Distributions
Component:	NetworkManager (show other bugs)
Version:	не указана
Hardware:	x86 Linux

Importance:	P3 normal
Assignee:	Andrey Cherepanov
QA Contact:	QA p6

URL:
Keywords:

Depends on:	18138
Blocks:
	Show dependency tree

Reported:	2013-01-22 16:15 MSK by Ivan Zakharyaschev
Modified:	2022-08-30 15:54 MSK (History)
CC List:	3 users (show)

See Also:

Attachments
strace NetworkManager (118.48 KB, application/octet-stream) 2013-04-13 23:08 MSK, Ivan Zakharyaschev	no flags	Details
strace -fF -e trace=all NetworkManager --no-daemon 2> nm-strace2 (494.03 KB, application/octet-stream) 2013-04-13 23:30 MSK, Ivan Zakharyaschev	no flags	Details
View All Add an attachment (proposed patch, testcase, etc.)

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

Description

Ivan Zakharyaschev 2013-01-22 16:15:08 MSK

(В ответ на комментарий №8)
> NM_CONTROLLED=no должно быть достаточно.
> Покажите что пишет NM в /var/log/messages.

После запуска service network интерфейс нормально поднят:

Jan 21 13:50:06 linux network: Computing interface groups: 
Jan 21 13:50:06 linux network: . 
Jan 21 13:50:06 linux avahi-daemon[4969]: Successfully called chroot().
Jan 21 13:50:06 linux avahi-daemon[4969]: Successfully dropped remaining
capabilities.
Jan 21 13:50:06 linux network: . 
Jan 21 13:50:06 linux gdmgreeter[4987]: WARNING: unknown escape code %R in text 
Jan 21 13:50:06 linux network:  2 interfaces found 
Jan 21 13:50:06 linux network: Starting group 0/virtual (1 interfaces) 
Jan 21 13:50:06 linux network: ^IStarting lo: 
Jan 21 13:50:06 linux network: . 
Jan 21 13:50:06 linux network: . 
Jan 21 13:50:06 linux avahi-daemon[4969]: Loading service file
/services/ssh.service.
Jan 21 13:50:06 linux network: . 
Jan 21 13:50:06 linux avahi-daemon[4969]: Loading service file
/services/udisks.service.
Jan 21 13:50:06 linux network: . 
Jan 21 13:50:06 linux network: OK 
Jan 21 13:50:06 linux network: Starting group 1/realphys (1 interfaces) 
Jan 21 13:50:06 linux network: ^IStarting eth0: 
Jan 21 13:50:06 linux network: . 
Jan 21 13:50:06 linux last message repeated 7 times
Jan 21 13:50:06 linux network: OK 
Jan 21 13:50:06 linux network: Processing /etc/net/vlantab: empty. 

А вот если я запущу service NetworkManager  , то интерфейс будет опущен:


Jan 21 13:51:36 linux NetworkManager: Setting network parameters: succeeded
Jan 21 13:51:36 linux NetworkManager[5590]: <info> NetworkManager (version
0.8.6) is starting...
Jan 21 13:51:36 linux NetworkManager[5590]: <info> Read config file
/etc/NetworkManager/NetworkManager.conf
Jan 21 13:51:36 linux NetworkManager: NetworkManager startup succeeded
Jan 21 13:51:36 linux NetworkManager[5590]: <info> VPN: loaded
org.freedesktop.NetworkManager.l2tp
Jan 21 13:51:36 linux NetworkManager[5590]: <info> VPN: loaded
org.freedesktop.NetworkManager.pptp
Jan 21 13:51:36 linux NetworkManager[5590]: <info> VPN: loaded
org.freedesktop.NetworkManager.vpnc
Jan 21 13:51:36 linux NetworkManager[5590]: <info> VPN: loaded
org.freedesktop.NetworkManager.openvpn
Jan 21 13:51:36 linux NetworkManager[5590]: <info> trying to start the modem
manager...
Jan 21 13:51:36 linux dbus[4094]: [system] Activating service
name='org.freedesktop.ModemManager' (using servicehelper)
Jan 21 13:51:36 linux NetworkManager[5590]: <info> monitoring kernel firmware
directory '/lib/firmware'.
Jan 21 13:51:36 linux NetworkManager[5590]:    etcnet-alt: Current network
profile: (none)
Jan 21 13:51:36 linux NetworkManager[5590]: <info> Loaded plugin etcnet-alt:
(c) 2008 Mikhail Efremov <sem@altlinux.org>.
Jan 21 13:51:36 linux modem-manager[5599]: <info>  ModemManager (version
0.5.2.0) starting... 
Jan 21 13:51:36 linux NetworkManager[5590]: <info> Loaded plugin keyfile: (c)
2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing
list.
Jan 21 13:51:36 linux dbus[4094]: [system] Successfully activated service
'org.freedesktop.ModemManager'
Jan 21 13:51:36 linux NetworkManager[5590]: <info> WiFi enabled by radio
killswitch; enabled by state file
Jan 21 13:51:36 linux NetworkManager[5590]: <info> WWAN enabled by radio
killswitch; enabled by state file
Jan 21 13:51:36 linux NetworkManager[5590]: <info> WiMAX enabled by radio
killswitch; enabled by state file
Jan 21 13:51:36 linux NetworkManager[5590]: <info> Networking is enabled by
state file
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin AnyData 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Generic 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Gobi 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Option
High-Speed 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Huawei 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Linktop 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Longcheer 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Ericsson MBM 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin MotoC 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Nokia 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Novatel 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Option 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Samsung 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Sierra 
Jan 21 13:51:36 linux NetworkManager[5590]: <info> (eth0): carrier is ON
Jan 21 13:51:36 linux NetworkManager[5590]: <info> (eth0): new Ethernet device
(driver: 'forcedeth' ifindex: 2)
Jan 21 13:51:36 linux NetworkManager[5590]: <info> (eth0): exported as
/org/freedesktop/NetworkManager/Devices/0
Jan 21 13:51:36 linux NetworkManager[5590]: <info> (eth0): now managed
Jan 21 13:51:36 linux NetworkManager[5590]: <info> (eth0): device state change:
1 -> 2 (reason 2)
Jan 21 13:51:36 linux NetworkManager[5590]: <info> (eth0): preparing device.
Jan 21 13:51:36 linux NetworkManager[5590]: <info> (eth0): deactivating device
(reason: 2).
Jan 21 13:51:36 linux avahi-daemon[4969]: Withdrawing address record for
10.11.7.106 on eth0.
Jan 21 13:51:36 linux avahi-daemon[4969]: Leaving mDNS multicast group on
interface eth0.IPv4 with address 10.11.7.106.
Jan 21 13:51:36 linux avahi-daemon[4969]: Interface eth0.IPv4 no longer
relevant for mDNS.
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin SimTech 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin Wavecom 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin X22X 
Jan 21 13:51:36 linux modem-manager[5599]: <info>  Loaded plugin ZTE 
Jan 21 13:51:36 linux NetworkManager[5590]: <info> modem-manager is now
available
Jan 21 13:51:36 linux NetworkManager[5590]: <warn> bluez error getting default
adapter: No such adapter
Jan 21 13:51:36 linux NetworkManager[5590]: <info> Trying to start the
supplicant...
Jan 21 13:51:36 linux dbus[4094]: [system] Activating service
name='fi.epitest.hostap.WPASupplicant' (using servicehelper)
Jan 21 13:51:36 linux NetworkManager[5590]: <info> (eth0): device state change:
2 -> 3 (reason 0)

До тех пор, пока кто-то не зайдёт в Xfce с апплетом:

Jan 21 13:59:25 linux gnome-keyring-daemon[6135]: GLib-GIO: Using the 'memory'
GSettings backend.  Your settings will not be saved or shared with other
applications.
Jan 21 13:59:25 linux gnome-keyring-daemon[6135]: couldn't set environment
variable in session: The name org.gnome.SessionManager was not provided by any
.service files
Jan 21 13:59:25 linux last message repeated 2 times
Jan 21 13:59:26 linux gnome-keyring-daemon[6135]: The GPG agent was already
initialized
Jan 21 13:59:26 linux gnome-keyring-daemon[6135]: The PKCS#11 component was
already initialized
Jan 21 13:59:26 linux gnome-keyring-daemon[6135]: The Secret Service was
already initialized
Jan 21 13:59:26 linux gnome-keyring-daemon[6135]: The SSH agent was already
initialized
Jan 21 13:59:27 linux dbus[4094]: [system] Activating service
name='org.freedesktop.UDisks' (using servicehelper)
Jan 21 13:59:27 linux dbus[4094]: [system] Successfully activated service
'org.freedesktop.UDisks'
Jan 21 13:59:31 linux dbus[4094]: [system] Activating service
name='org.freedesktop.UPower' (using servicehelper)
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) starting
connection 'п\220п╡я\202п╬п╪п╟я\202п╦я\207п╣я\201п╨п╬п╣ (Ethernet)'
Jan 21 13:59:31 linux NetworkManager[5590]: <info> (eth0): device state change:
3 -> 4 (reason 0)
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 1 of
5 (Device Prepare) scheduled...
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 1 of
5 (Device Prepare) started...
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 2 of
5 (Device Configure) scheduled...
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 1 of
5 (Device Prepare) complete.
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 2 of
5 (Device Configure) starting...
Jan 21 13:59:31 linux NetworkManager[5590]: <info> (eth0): device state change:
4 -> 5 (reason 0)
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 2 of
5 (Device Configure) successful.
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 3 of
5 (IP Configure Start) scheduled.
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 2 of
5 (Device Configure) complete.
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 3 of
5 (IP Configure Start) started...
Jan 21 13:59:31 linux NetworkManager[5590]: <info> (eth0): device state change:
5 -> 7 (reason 0)
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 4 of
5 (IP4 Configure Get) scheduled...
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 3 of
5 (IP Configure Start) complete.
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 4 of
5 (IP4 Configure Get) started...
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Scheduling stage 5
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 5 of
5 (IP Configure Commit) scheduled...
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Done scheduling stage 5
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 4 of
5 (IP4 Configure Get) complete.
Jan 21 13:59:31 linux NetworkManager[5590]: <info> Activation (eth0) Stage 5 of
5 (IP Configure Commit) started...
Jan 21 13:59:31 linux avahi-daemon[4969]: Joining mDNS multicast group on
interface eth0.IPv4 with address 10.11.7.106.
Jan 21 13:59:31 linux avahi-daemon[4969]: New relevant interface eth0.IPv4 for
mDNS.
Jan 21 13:59:31 linux avahi-daemon[4969]: Registering new address record for
10.11.7.106 on eth0.IPv4.
Jan 21 13:59:32 linux NetworkManager[5590]: <info> (eth0): writing resolv.conf
to /sbin/resolvconf
Jan 21 13:59:32 linux dbus[4094]: [system] Successfully activated service
'org.freedesktop.UPower'
Jan 21 13:59:33 linux NetworkManager[5590]: <info> (eth0): device state change:
7 -> 8 (reason 0)
Jan 21 13:59:33 linux NetworkManager[5590]: <info> Policy set
'п\220п╡я\202п╬п╪п╟я\202п╦я\207п╣я\201п╨п╬п╣ (Ethernet)' (eth0) as default for
IPv4 routing and DNS.
Jan 21 13:59:33 linux NetworkManager[5590]: <info> Activation (eth0)
successful, device activated.
Jan 21 13:59:33 linux NetworkManager[5590]: <info> Activation (eth0) Stage 5 of
5 (IP Configure Commit) complete.
Jan 21 13:59:33 linux dbus[4094]: [system] Activating service
name='org.freedesktop.nm_dispatcher' (using servicehelper)
Jan 21 13:59:33 linux dbus[4094]: [system] Successfully activated service
'org.freedesktop.nm_dispatcher'
Jan 21 13:59:42 linux pulseaudio[6403]: pid.c: Daemon already running.
Jan 21 13:59:42 linux pulseaudio[6404]: pid.c: Daemon already running.
Jan 21 13:59:46 linux dbus[4094]: [system] Activating service
name='org.blueman.Mechanism' (using servicehelper)
Jan 21 13:59:47 linux blueman-mechanism: Starting blueman-mechanism  
Jan 21 13:59:47 linux dbus[4094]: [system] Successfully activated service
'org.blueman.Mechanism'
Jan 21 13:59:47 linux blueman-mechanism: reload 0 0  
Jan 21 13:59:59 linux nmbd[5174]: [2013/01/21 13:59:59.556863,  0]
nmbd/nmbd_become_lmb.c:395(become_local_master_stage2) 
Jan 21 13:59:59 linux nmbd[5174]:   ***** 
Jan 21 13:59:59 linux nmbd[5174]:    
Jan 21 13:59:59 linux nmbd[5174]:   Samba name server LINUX is now a local
master browser for workgroup WORKGROUP on subnet 10.11.7.106 
Jan 21 13:59:59 linux nmbd[5174]:    
Jan 21 13:59:59 linux nmbd[5174]:   ***** 
Jan 21 14:00:17 linux blueman-mechanism: Exiting  

Такое поведение меня не устраивает -- что нет сети пока кто-то не залогинится.

При этом отключать NetworkManager не хочется, потому что он оказывается удобен
для подключения через телефон-модем.

Настройки:

/etc/net/ifaces/eth0/options :

BOOTPROTO=static
TYPE=eth
NM_CONTROLLED=no
DISABLED=no

-----------------------------------

/etc/sysconfig/network :

# When set to no, this may cause most daemons' initscripts skip starting.
NETWORKING=yes

# Used by hotplug/pcmcia/ifplugd scripts to detect current network config
# subsystem.
CONFMETHOD=etcnet

# Used by rc.sysinit to setup system hostname at boot.
HOSTNAME=linux.local

# This is used by ALTLinux ppp-common to decide if we want to install
# nameserver lines into /etc/resolv.conf or not.
RESOLV_MODS=yes
FORWARD_IPV4=false

---------------------------

/etc/sysconfig/NetworkManager :

# Settings for running NetworkManager daemon

# If defined network parameters will be applied from the
# specified file.
NM_SYSCTL_CONF=/etc/net/sysctl.conf

# This value will be pass to stop_daemon as
# --retry $NM_STOP_TIMEOUT.
# By defaul '4' will be assumed.
#NM_STOP_TIMEOUT=4

# If yes then a network interfaces which controlled by
# NetworkManager, but do not disabled for etcnet
# will be bringing down before NetworkManager start.
NM_DOWN_CONTROLLED=yes

# If yes kill ModemManager when NetworkManager is stopped.
NM_STOP_MODEMMANAGER=yes

# If defined, then there will be the delay while boot until
# a network connection will be established by NetworkManager.
# The value is a maximum waiting time specified in seconds.
# NOTE: It will work properly only if the network interfaces
# controlled by NetworkManager and configured in some
# system-wide way, by a configuration files in /etc/net or
# NetworkManager's keyfile plugin configs.
#NM_CONNECTION_WAIT=30

# Normally NetworkManager don't deconfigure and down ifaces on exit,
# and all dispatcher scripts are not executed.
# If defined it will disconnect all controlled ifaces.
NM_STOP_ONEXIT=yes

# If yes run efw for interface on up/down.
#NM_RUN_EFW=yes

# If yes run ifup-post/ifdown-post scripts from /etc/net/ for iface.
#NM_RUN_ETCNET_POST=yes

------------------------------------

~ $ rpm -qf /etc/sysconfig/NetworkManager
NetworkManager-0.8.6-alt3.M60P.1
~ $ rpm -V {rpm -qf /etc/sysconfig/NetworkManager}
отсутствует /var/log/NetworkManager
~ $

+++ This bug was initially created as a clone of Bug #18138 +++

По всей видимости игнорируестя NM_CONTROLLED=no

#cat /etc/net/ifaces/usb0/options
TYPE=usb
#MODULE=cdc_ether
ONBOOT=no
DISABLED=no
NM_CONTROLLED=no
BOOTPROTO=static

#cat /etc/net/ifaces/usb0/ipv4address
192.168.0.200/24

Сразу после подключения устройства:
#sudo ip a s usb0
29: usb0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UNKNOWN qlen 1000
    link/ether 56:a5:3f:49:bb:05 brd ff:ff:ff:ff:ff:ff
    inet 192.168.0.200/24 scope global usb0


Но через несколько секунд:
#sudo ip a s usb0
29: usb0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UNKNOWN qlen 1000
    link/ether 56:a5:3f:49:bb:05 brd ff:ff:ff:ff:ff:ff

С остановленным сервисом NetworkManager ситуация не воспроизводится.

Comment 1

Ivan Zakharyaschev 2013-01-22 16:16:26 MSK

Может, мне попробовать поставить NetworkManager (я на p6) из Sisyphus?

Comment 2

Mikhail Efremov 2013-01-22 18:04:52 MSK

> Может, мне попробовать поставить NetworkManager (я на p6) из Sisyphus?

Нет, этого делать точно не надо.

NM_CONTROLLED=no
Проверьте, нет ли в этой строчке лишних пробелов в конце.

Comment 3

Ivan Zakharyaschev 2013-01-22 19:06:02 MSK

(В ответ на комментарий №2)
> > Может, мне попробовать поставить NetworkManager (я на p6) из Sisyphus?
> 
> Нет, этого делать точно не надо.
> 
> NM_CONTROLLED=no
> Проверьте, нет ли в этой строчке лишних пробелов в конце.

Нет, насколько я вижу:

/sudo:root@linux.local:/etc/net/ifaces/eth0 $ hexdump options 
0000000 4f42 544f 5250 544f 3d4f 7473 7461 6369
0000010 540a 5059 3d45 7465 0a68 4d4e 435f 4e4f
0000020 5254 4c4f 454c 3d44 6f6e 440a 5349 4241
0000030 454c 3d44 6f6e 000a                    
0000037
/sudo:root@linux.local:/etc/net/ifaces/eth0 $ hexdump -C options 
00000000  42 4f 4f 54 50 52 4f 54  4f 3d 73 74 61 74 69 63  |BOOTPROTO=static|
00000010  0a 54 59 50 45 3d 65 74  68 0a 4e 4d 5f 43 4f 4e  |.TYPE=eth.NM_CON|
00000020  54 52 4f 4c 4c 45 44 3d  6e 6f 0a 44 49 53 41 42  |TROLLED=no.DISAB|
00000030  4c 45 44 3d 6e 6f 0a                              |LED=no.|
00000037
/sudo:root@linux.local:/etc/net/ifaces/eth0 $ hexdump -c options 
0000000   B   O   O   T   P   R   O   T   O   =   s   t   a   t   i   c
0000010  \n   T   Y   P   E   =   e   t   h  \n   N   M   _   C   O   N
0000020   T   R   O   L   L   E   D   =   n   o  \n   D   I   S   A   B
0000030   L   E   D   =   n   o  \n                                    
0000037
/sudo:root@linux.local:/etc/net/ifaces/eth0 $

Comment 4

Mikhail Efremov 2013-01-22 20:19:24 MSK

У меня не получается это воспроизвести.
Покажите /etc/NetworkManager/NetworkManager.conf.
Также попробуйте закомментировать строчку с NM_CONTROLLED, записать файл, потом опять расскоментрировать и записать.
Покажите что при этом NM пишет в /var/log/messages и вывод nmcli dev.

Comment 5

Ivan Zakharyaschev 2013-01-22 20:33:05 MSK

(В ответ на комментарий №4)
> У меня не получается это воспроизвести.
> Покажите /etc/NetworkManager/NetworkManager.conf.

~ $ cat /etc/NetworkManager/NetworkManager.conf
[main]
plugins=etcnet-alt,keyfile
dhcp=dhcpcd
~ $ 
> Также попробуйте закомментировать строчку с NM_CONTROLLED, записать файл, потом
> опять расскоментрировать и записать.
> Покажите что при этом NM пишет в /var/log/messages и вывод nmcli dev.

сейчас попробую

Comment 6

Ivan Zakharyaschev 2013-01-22 20:42:51 MSK

(В ответ на комментарий №4)

> Также попробуйте закомментировать строчку с NM_CONTROLLED, записать файл, потом
> опять расскоментрировать и записать.
> Покажите что при этом NM пишет в /var/log/messages и вывод nmcli dev.

Сначала строчка была как я показывал, потом я её закомментировал и сохранил, потом раскомментировал и сохранил. Вот:

~ $ nmcli dev
УСТРОЙСТВО ТИП               СТАТУС       
eth0       802-3-ethernet    подключен    
~ $ sudo tail -f /var/log/messages 
Jan 22 20:26:40 linux blueman-mechanism: Starting blueman-mechanism  
Jan 22 20:26:40 linux dbus[4037]: [system] Successfully activated service 'org.blueman.Mechanism'
Jan 22 20:26:40 linux blueman-mechanism: reload 0 0  
Jan 22 20:26:56 linux nmbd[5964]: [2013/01/22 20:26:56.850314,  0] nmbd/nmbd_become_lmb.c:395(become_local_master_stage2) 
Jan 22 20:26:56 linux nmbd[5964]:   ***** 
Jan 22 20:26:56 linux nmbd[5964]:    
Jan 22 20:26:56 linux nmbd[5964]:   Samba name server LINUX is now a local master browser for workgroup WORKGROUP on subnet 10.11.7.106 
Jan 22 20:26:56 linux nmbd[5964]:    
Jan 22 20:26:56 linux nmbd[5964]:   ***** 
Jan 22 20:27:10 linux blueman-mechanism: Exiting  
Jan 22 20:37:11 linux NetworkManager[4375]:    etcnet-alt: eth0: Readed connection "System eth0"
Jan 22 20:37:11 linux last message repeated 2 times
Jan 22 20:37:17 linux modem-manager[4453]: <info>  (ttyACM0) opening serial port... 
Jan 22 20:37:19 linux modem-manager[4453]: <info>  (ttyACM0) closing serial port... 
Jan 22 20:37:19 linux modem-manager[4453]: <info>  (ttyACM0) serial port closed 


завершено
~ $ nmcli dev
УСТРОЙСТВО ТИП               СТАТУС       
eth0       802-3-ethernet    подключен    
~ $ sudo tail -f /var/log/messages 
Jan 22 20:26:56 linux nmbd[5964]:    
Jan 22 20:26:56 linux nmbd[5964]:   Samba name server LINUX is now a local master browser for workgroup WORKGROUP on subnet 10.11.7.106 
Jan 22 20:26:56 linux nmbd[5964]:    
Jan 22 20:26:56 linux nmbd[5964]:   ***** 
Jan 22 20:27:10 linux blueman-mechanism: Exiting  
Jan 22 20:37:11 linux NetworkManager[4375]:    etcnet-alt: eth0: Readed connection "System eth0"
Jan 22 20:37:11 linux last message repeated 2 times
Jan 22 20:37:17 linux modem-manager[4453]: <info>  (ttyACM0) opening serial port... 
Jan 22 20:37:19 linux modem-manager[4453]: <info>  (ttyACM0) closing serial port... 
Jan 22 20:37:19 linux modem-manager[4453]: <info>  (ttyACM0) serial port closed 
завершено
~ $ nmcli dev
УСТРОЙСТВО ТИП               СТАТУС       
eth0       802-3-ethernet    подключен    
~ $ 

В общем, после раскомментирования NM ничего не писал в /var/log/messages.

Comment 7

Ivan Zakharyaschev 2013-01-22 23:21:49 MSK

На другой системе NetworkManager-0.8.6-alt1.M60P.2 и такого не наблюдается. 

Может быть, ошибка от этого зависит?

Вот changelog с тех пор:

 * Пнд Июл 16 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt3.M60P.1
 - Rollback to 0.8.6 release again (closes: #27362).
 - Add patches from upstream git:
     + ppp: change handling of lcp-echo-failure and lcp-echo-interval
       (bgo #663970).
     + core: check the iface index correctly.
     + core: handle N900 USB network interface again.
 * Чтв Апр 05 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt2.git20120208.M60P.1
 - upstream git snapshot (NM_0_8 branch):
     Bugfixes and improvements, including:
     + handle N900 USB network interface again (closes: #27160).
 * Чтв Мар 01 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt1.M60P.3
 - Don't try to unmount network filesystems (closes: #27013).
 * Птн Фев 24 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt1.M60P.2
 - netfs hook: Fix typo in service name.

Comment 8

Ivan Zakharyaschev 2013-01-23 02:05:47 MSK

(В ответ на комментарий №7)
> На другой системе NetworkManager-0.8.6-alt1.M60P.2 и такого не наблюдается. 
> 
> Может быть, ошибка от этого зависит?
> 
> Вот changelog с тех пор:
> 
>  * Пнд Июл 16 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt3.M60P.1
>  - Rollback to 0.8.6 release again (closes: #27362).
>  - Add patches from upstream git:
>      + ppp: change handling of lcp-echo-failure and lcp-echo-interval
>        (bgo #663970).
>      + core: check the iface index correctly.
>      + core: handle N900 USB network interface again.
>  * Чтв Апр 05 2012 Mikhail Efremov <sem@altlinux.org>
> 0.8.6-alt2.git20120208.M60P.1
>  - upstream git snapshot (NM_0_8 branch):
>      Bugfixes and improvements, including:
>      + handle N900 USB network interface again (closes: #27160).
>  * Чтв Мар 01 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt1.M60P.3
>  - Don't try to unmount network filesystems (closes: #27013).
>  * Птн Фев 24 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt1.M60P.2
>  - netfs hook: Fix typo in service name.

$ git --no-pager diff 0.8.6-alt1.M60P.2..0.8.6-alt3.M60P.1
diff --git a/10-netfs b/10-netfs
index 30c38df..c3ec515 100644
--- a/10-netfs
+++ b/10-netfs
@@ -11,8 +11,13 @@ case "$2" in
 			$SERVICE netfs reload >/dev/null 2>&1 ||:
 		;;
 	down)
-		[ $($IP route show | wc -l) -eq 0 ] &&
-			$SERVICE netfs status >/dev/null 2>&1 &&
-			$SERVICE netfs stop >/dev/null 2>&1 ||:
+# "down" action runs when interface already down,
+# so it is too late to unmount network filesystems at that time.
+# And there is no "pre-down" action yet.
+# See ALT bug #27013.
+
+#		[ $($IP route show | wc -l) -eq 0 ] &&
+#			$SERVICE netfs status >/dev/null 2>&1 &&
+#			$SERVICE netfs stop >/dev/null 2>&1 ||:
 		;;
 esac
diff --git a/NetworkManager.spec b/NetworkManager.spec
index c3f243c..537d315 100644
--- a/NetworkManager.spec
+++ b/NetworkManager.spec
@@ -1,4 +1,4 @@
-#define git_date .git20110415
+#define git_date .git20120208
 %define git_date %nil
 
 %define dbus_version 1.2.12-alt2
@@ -12,7 +12,7 @@
 
 Name: NetworkManager
 Version: 0.8.6
-Release: alt1%git_date.M60P.2
+Release: alt3%git_date.M60P.1
 License: %gpl2plus
 Group: System/Configuration/Networking
 Summary: Network Link Manager and User Applications
@@ -274,6 +274,22 @@ fi
 %exclude %_libdir/pppd/%ppp_version/*.la
 
 %changelog
+* Mon Jul 16 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt3.M60P.1
+- Rollback to 0.8.6 release again (closes: #27362).
+- Add patches from upstream git:
+    + ppp: change handling of lcp-echo-failure and lcp-echo-interval
+      (bgo #663970).
+    + core: check the iface index correctly.
+    + core: handle N900 USB network interface again.
+
+* Thu Apr 05 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt2.git20120208.M60P.1
+- upstream git snapshot (NM_0_8 branch):
+    Bugfixes and improvements, including:
+    + handle N900 USB network interface again (closes: #27160).
+
+* Thu Mar 01 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt1.M60P.3
+- Don't try to unmount network filesystems (closes: #27013).
+
 * Fri Feb 24 2012 Mikhail Efremov <sem@altlinux.org> 0.8.6-alt1.M60P.2
 - netfs hook: Fix typo in service name.
 
diff --git a/NetworkManager/src/nm-device.c b/NetworkManager/src/nm-device.c
index 02c2aaf..1754fe2 100644
--- a/NetworkManager/src/nm-device.c
+++ b/NetworkManager/src/nm-device.c
@@ -408,7 +408,7 @@ nm_device_set_ip_iface (NMDevice *self, const char *iface)
 	priv->ip_iface = g_strdup (iface);
 	if (priv->ip_iface) {
 		priv->ip_ifindex = nm_netlink_iface_to_index (priv->ip_iface);
-		if (!priv->ip_ifindex) {
+		if (priv->ip_ifindex < 0) {
 			nm_log_warn (LOGD_HW, "(%s): failed to look up interface index", iface);
 		}
 	}
diff --git a/NetworkManager/src/nm-udev-manager.c b/NetworkManager/src/nm-udev-manager.c
index 260703d..1b44294 100644
--- a/NetworkManager/src/nm-udev-manager.c
+++ b/NetworkManager/src/nm-udev-manager.c
@@ -447,18 +447,6 @@ net_add (NMUdevManager *self, GUdevDevice *device)
 		return;
 	}
 
-	/* Ignore Nokia cdc-ether interfaces in PC-Suite mode since we need to
-	 * talk phonet to use them, which ModemManager doesn't do yet.
-	 */
-	tmp = g_udev_device_get_property (device, "ID_VENDOR_ID");
-	if (g_strcmp0 (tmp, "0421") == 0) { /* Nokia vendor ID */
-		tmp = g_udev_device_get_property (device, "ID_MODEL");
-		if (tmp && (strstr (tmp, "PC-Suite") || strstr (tmp, "PC Suite"))) {
-			nm_log_dbg (LOGD_HW, "ignoring Nokia PC-Suite ethernet interface");
-			return;
-		}
-	}
-
 	g_signal_emit (self, signals[DEVICE_ADDED], 0, device, device_creator);
 }
 
diff --git a/NetworkManager/src/ppp-manager/nm-ppp-manager.c b/NetworkManager/src/ppp-manager/nm-ppp-manager.c
index 6a3af64..13e79f6 100644
--- a/NetworkManager/src/ppp-manager/nm-ppp-manager.c
+++ b/NetworkManager/src/ppp-manager/nm-ppp-manager.c
@@ -16,7 +16,7 @@
  * 51 Franklin Street, Fifth Floor, Boston, MA 02110-1301 USA.
  *
  * Copyright (C) 2008 Novell, Inc.
- * Copyright (C) 2008 - 2010 Red Hat, Inc.
+ * Copyright (C) 2008 - 2011 Red Hat, Inc.
  */
 
 #include <config.h>
@@ -871,12 +871,6 @@ pppoe_fill_defaults (NMSettingPPP *setting)
 	if (!nm_setting_ppp_get_mru (setting))
 		g_object_set (setting, NM_SETTING_PPP_MRU, (guint32) 1492, NULL);
 
-	if (!nm_setting_ppp_get_lcp_echo_interval (setting))
-		g_object_set (setting, NM_SETTING_PPP_LCP_ECHO_INTERVAL, (guint32) 20, NULL);
-
-	if (!nm_setting_ppp_get_lcp_echo_failure (setting))
-		g_object_set (setting, NM_SETTING_PPP_LCP_ECHO_FAILURE, (guint32) 3, NULL);
-
 	g_object_set (setting,
 			    NM_SETTING_PPP_NOAUTH, TRUE,
 			    NM_SETTING_PPP_NODEFLATE, TRUE,
$ 

Выглядит почти неподозрительно.

Попробую, может, ещё обновить NetworkManager и в этой системе.

Comment 9

Ivan Zakharyaschev 2013-01-23 09:58:38 MSK

(В ответ на комментарий №8)
> (В ответ на комментарий №7)
> > На другой системе NetworkManager-0.8.6-alt1.M60P.2 и такого не наблюдается. 

> Попробую, может, ещё обновить NetworkManager и в этой системе.

Нет, на другой системе не воспроизвелось...

Comment 10

Mikhail Efremov 2013-01-23 16:15:06 MSK

> В общем, после раскомментирования NM ничего не писал в /var/log/messages.

Это странно, плагин должен следить за файлом и реагировать на любое его изменение.
Причем судя по 
> Jan 22 20:37:11 linux NetworkManager[4375]:    etcnet-alt: eth0: Readed
> connection "System eth0"
настройки из etcnet читаются (и NM должен сразу подключаться в соответствии с ними если в options нет ONBOOT=no).
Попробуйте вообще поизменять файлы в /etc/net/ifaces/eth0, например указать BOOOTPROTO=dhcp, смотря в /var/log/messages (или остановить NM и запустить его руками на другой консоли как NetworkManager --no-daemon).
Должна быть реакция на изменение файла options как минимум (только не забывайте его сохранять).
Если реакции нет, то пробуйте перезапустить NM и посмотреть, будет ли реакция на измененный файл в этом случае (например, будет ли попытка подключения по dhcp при BOOTPROTO=dhcp).
Также посмотрите на всякий случай не завалялся ли в /etc/NetworkManager/nm-system-settings.conf.
У меня пока нет внятных мыслей из-за чего это все может быть и что еще посмотреть кроме как gdb. Подумаю.

Comment 11

Ivan Zakharyaschev 2013-02-03 17:58:56 MSK

(В ответ на комментарий №10)

> Попробуйте вообще поизменять файлы в /etc/net/ifaces/eth0, например указать
> BOOOTPROTO=dhcp, смотря в /var/log/messages (или остановить NM и запустить его
> руками на другой консоли как NetworkManager --no-daemon).
> Должна быть реакция на изменение файла options как минимум (только не забывайте
> его сохранять).

Запустил NetworkManager, потом поменял BOOTPROTO, сохранил -- реакции не было (это место отмечено пустой строчкой). Потом уже прервал NetworkManager:

[root@linux ifaces]# NetworkManager --no-daemon
NetworkManager[19223]: <info> NetworkManager (version 0.8.6) is starting...
NetworkManager[19223]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
NetworkManager[19223]: <info> VPN: loaded org.freedesktop.NetworkManager.l2tp
NetworkManager[19223]: <info> VPN: loaded org.freedesktop.NetworkManager.pptp
NetworkManager[19223]: <info> VPN: loaded org.freedesktop.NetworkManager.vpnc
NetworkManager[19223]: <info> VPN: loaded org.freedesktop.NetworkManager.openvpn
NetworkManager[19223]: <info> modem-manager is now available
NetworkManager[19223]: <info> monitoring kernel firmware directory '/lib/firmware'.
NetworkManager[19223]:    etcnet-alt: Current network profile: (none)
NetworkManager[19223]: <info> Loaded plugin etcnet-alt: (c) 2008 Mikhail Efremov <sem@altlinux.org>.
NetworkManager[19223]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
NetworkManager[19223]:    etcnet-alt: Couldn't access iface directory /etc/net/ifaces/eth2 .
NetworkManager[19223]:    etcnet-alt: eth2: Readed connection "System eth2"
NetworkManager[19223]: <info> WiFi enabled by radio killswitch; enabled by state file
NetworkManager[19223]: <info> WWAN enabled by radio killswitch; enabled by state file
NetworkManager[19223]: <info> WiMAX enabled by radio killswitch; enabled by state file
NetworkManager[19223]: <info> Networking is enabled by state file
NetworkManager[19223]: <info> (eth2): carrier is OFF
NetworkManager[19223]: <info> (eth2): new Ethernet device (driver: 'forcedeth' ifindex: 2)
NetworkManager[19223]: <info> (eth2): exported as /org/freedesktop/NetworkManager/Devices/0
NetworkManager[19223]: <info> (eth2): now managed
NetworkManager[19223]: <info> (eth2): device state change: 1 -> 2 (reason 2)
NetworkManager[19223]: <info> (eth2): bringing up device.
NetworkManager[19223]: <info> (eth2): preparing device.
NetworkManager[19223]: <info> (eth2): deactivating device (reason: 2).
NetworkManager[19223]: <info> (eth1): carrier is ON
NetworkManager[19223]: <info> (eth1): new Ethernet device (driver: '8139too' ifindex: 3)
NetworkManager[19223]: <info> (eth1): exported as /org/freedesktop/NetworkManager/Devices/1
NetworkManager[19223]: <info> (eth1): now managed
NetworkManager[19223]: <info> (eth1): device state change: 1 -> 2 (reason 2)
NetworkManager[19223]: <info> (eth1): preparing device.
NetworkManager[19223]: <info> (eth1): deactivating device (reason: 2).
NetworkManager[19223]: <warn> bluez error getting default adapter: No such adapter
NetworkManager[19223]: <info> (eth1): device state change: 2 -> 3 (reason 0)


^CNetworkManager[19223]: <info> caught signal 2, shutting down normally.
NetworkManager[19223]: <warn> quit request received, terminating...
NetworkManager[19223]: <info> (eth2): now unmanaged
NetworkManager[19223]: <info> (eth2): device state change: 2 -> 1 (reason 36)
NetworkManager[19223]: <info> (eth2): cleaning up...
NetworkManager[19223]: <info> (eth2): taking down device.
NetworkManager[19223]: <info> (eth1): now unmanaged
NetworkManager[19223]: <info> (eth1): device state change: 3 -> 1 (reason 36)
NetworkManager[19223]: <info> (eth1): cleaning up...
NetworkManager[19223]: <info> (eth1): taking down device.
NetworkManager[19223]: <info> ((null)): removing resolv.conf from /sbin/resolvconf
NetworkManager[19223]: <info> exiting (success)
[root@linux ifaces]# ip addr
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN 
    link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
    inet 127.0.0.1/8 scope host lo
2: eth2: <BROADCAST,MULTICAST> mtu 1500 qdisc pfifo_fast state DOWN qlen 1000
    link/ether 00:13:d4:cf:e8:53 brd ff:ff:ff:ff:ff:ff
3: eth1: <BROADCAST,MULTICAST> mtu 1500 qdisc pfifo_fast state DOWN qlen 1000
    link/ether 00:0d:0f:10:1a:bc brd ff:ff:ff:ff:ff:ff
[root@linux ifaces]# 

> Если реакции нет, то пробуйте перезапустить NM и посмотреть, будет ли реакция
> на измененный файл в этом случае (например, будет ли попытка подключения по
> dhcp при BOOTPROTO=dhcp).

Запустил заново. Нет попыток DHCP. (Потом уже дал команду service network restart):

[root@linux ifaces]# NetworkManager --no-daemon
NetworkManager[19479]: <info> NetworkManager (version 0.8.6) is starting...
NetworkManager[19479]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
NetworkManager[19479]: <info> VPN: loaded org.freedesktop.NetworkManager.l2tp
NetworkManager[19479]: <info> VPN: loaded org.freedesktop.NetworkManager.pptp
NetworkManager[19479]: <info> VPN: loaded org.freedesktop.NetworkManager.vpnc
NetworkManager[19479]: <info> VPN: loaded org.freedesktop.NetworkManager.openvpn
NetworkManager[19479]: <info> modem-manager is now available
NetworkManager[19479]: <info> monitoring kernel firmware directory '/lib/firmware'.
NetworkManager[19479]:    etcnet-alt: Current network profile: (none)
NetworkManager[19479]: <info> Loaded plugin etcnet-alt: (c) 2008 Mikhail Efremov <sem@altlinux.org>.
NetworkManager[19479]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
NetworkManager[19479]:    etcnet-alt: Couldn't access iface directory /etc/net/ifaces/eth2 .
NetworkManager[19479]:    etcnet-alt: eth2: Readed connection "System eth2"
NetworkManager[19479]: <info> WiFi enabled by radio killswitch; enabled by state file
NetworkManager[19479]: <info> WWAN enabled by radio killswitch; enabled by state file
NetworkManager[19479]: <info> WiMAX enabled by radio killswitch; enabled by state file
NetworkManager[19479]: <info> Networking is enabled by state file
NetworkManager[19479]: <info> (eth2): carrier is OFF
NetworkManager[19479]: <info> (eth2): new Ethernet device (driver: 'forcedeth' ifindex: 2)
NetworkManager[19479]: <info> (eth2): exported as /org/freedesktop/NetworkManager/Devices/0
NetworkManager[19479]: <info> (eth2): now managed
NetworkManager[19479]: <info> (eth2): device state change: 1 -> 2 (reason 2)
NetworkManager[19479]: <info> (eth2): bringing up device.
NetworkManager[19479]: <info> (eth2): preparing device.
NetworkManager[19479]: <info> (eth2): deactivating device (reason: 2).
NetworkManager[19479]: <info> (eth1): carrier is OFF
NetworkManager[19479]: <info> (eth1): new Ethernet device (driver: '8139too' ifindex: 3)
NetworkManager[19479]: <info> (eth1): exported as /org/freedesktop/NetworkManager/Devices/1
NetworkManager[19479]: <info> (eth1): now managed
NetworkManager[19479]: <info> (eth1): device state change: 1 -> 2 (reason 2)
NetworkManager[19479]: <info> (eth1): bringing up device.
NetworkManager[19479]: <info> (eth1): preparing device.
NetworkManager[19479]: <info> (eth1): deactivating device (reason: 2).
NetworkManager[19479]: <info> (eth1): carrier now ON (device state 2)
NetworkManager[19479]: <info> (eth1): device state change: 2 -> 3 (reason 40)
NetworkManager[19479]: <warn> bluez error getting default adapter: No such adapter
^CNetworkManager[19479]: <info> caught signal 2, shutting down normally.
NetworkManager[19479]: <warn> quit request received, terminating...
NetworkManager[19479]: <info> (eth2): now unmanaged
NetworkManager[19479]: <info> (eth2): device state change: 2 -> 1 (reason 36)
NetworkManager[19479]: <info> (eth2): cleaning up...
NetworkManager[19479]: <info> (eth2): taking down device.
NetworkManager[19479]: <info> (eth1): now unmanaged
NetworkManager[19479]: <info> (eth1): device state change: 3 -> 1 (reason 36)
NetworkManager[19479]: <info> (eth1): cleaning up...
NetworkManager[19479]: <info> (eth1): taking down device.
NetworkManager[19479]: <info> ((null)): removing resolv.conf from /sbin/resolvconf
NetworkManager[19479]: <info> exiting (success)
[root@linux ifaces]# service network restart
Computing interface groups: ... 3 interfaces found
Processing /etc/net/vlantab: empty.
Stopping group 1/realphys (2 interfaces)
	Stopping eth0: OK
	Stopping eth1: RTNETLINK answers: No such process
....OK
Stopping group 0/virtual (1 interfaces)
	Stopping lo: .OK
Computing interface groups: ... 3 interfaces found
Starting group 0/virtual (1 interfaces)
	Starting lo: ....OK
Starting group 1/realphys (2 interfaces)
	Starting eth0: Cannot find device "eth0"
!FAILED
	Starting eth1: .....eth1: dhcpcd 4.0.15 starting
eth1: hardware address = 00:0d:0f:10:1a:bc
eth1: executing `/lib/dhcpcd/dhcpcd-run-hooks', reason PREINIT
eth1: broadcasting for a lease
eth1: sending DHCP_DISCOVER with xid 0xfa54bd4, next in 4.48 seconds
eth1: sending DHCP_DISCOVER with xid 0xfa54bd4, next in 7.81 seconds
^Ceth1: received SIGINT, stopping
!.^C
[root@linux ifaces]# 

(В этот раз -- когда я пишу этот комментарий -- у меня уже устройство не eth0, а eth1. Потому что физически поменялась карта. Но на это ошибочное поведение это никак не отражается.)

> Также посмотрите на всякий случай не завалялся ли в
> /etc/NetworkManager/nm-system-settings.conf.

Нет.

Comment 12

Ivan Zakharyaschev 2013-04-13 23:08:30 MSK

Created attachment 5806 [details]
strace NetworkManager

Забыли ещё об одной возможности хоть как-то заглянуть, что происходит (помимо gdb) -- strace. В приложении результат

strace -fF -e trace=file NetworkManager --no-daemon 2> nm-strace

Что там может быть интересного...

[new@linux ~]$ egrep '(etc|dev| exec)' nm-strace 
access("/etc/ld.so.preload", R_OK)      = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
open("/lib/libgudev-1.0.so.0", O_RDONLY|O_CLOEXEC) = 3
open("/lib/libudev.so.0", O_RDONLY|O_CLOEXEC) = 3
access("/etc/NetworkManager/nm-system-settings.conf", F_OK) = -1 ENOENT (No such file or directory)
access("/etc/NetworkManager/NetworkManager.conf", F_OK) = 0
open("/etc/NetworkManager/NetworkManager.conf", O_RDONLY|O_LARGEFILE) = 3
open("/etc/localtime", O_RDONLY|O_CLOEXEC) = 6
NetworkManager[9338]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 10
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 10
openat(AT_FDCWD, "/etc/NetworkManager/VPN", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_CLOEXEC) = 11
open("/etc/NetworkManager/VPN/nm-l2tp-service.name", O_RDONLY|O_LARGEFILE) = 12
open("/etc/NetworkManager/VPN/nm-l2tp-service.name", O_RDONLY|O_LARGEFILE) = 12
open("/etc/NetworkManager/VPN/nm-pptp-service.name", O_RDONLY|O_LARGEFILE) = 12
open("/etc/NetworkManager/VPN/nm-pptp-service.name", O_RDONLY|O_LARGEFILE) = 12
open("/etc/NetworkManager/VPN/nm-vpnc-service.name", O_RDONLY|O_LARGEFILE) = 12
open("/etc/NetworkManager/VPN/nm-vpnc-service.name", O_RDONLY|O_LARGEFILE) = 12
open("/etc/NetworkManager/VPN/nm-openvpn-service.name", O_RDONLY|O_LARGEFILE) = 12
open("/etc/NetworkManager/VPN/nm-openvpn-service.name", O_RDONLY|O_LARGEFILE) = 12
[pid  9338] stat64("/usr/lib/NetworkManager/libnm-settings-plugin-etcnet-alt.so", {st_mode=S_IFREG|0644, st_size=105300, ...}) = 0
[pid  9338] open("/usr/lib/NetworkManager/libnm-settings-plugin-etcnet-alt.so", O_RDONLY|O_CLOEXEC) = 14
[pid  9338] open("/etc/udev/udev.conf", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 14
[pid  9338] lstat64("/etc/net/ifaces", {st_mode=S_IFDIR|0755, st_size=56, ...}) = 0
[pid  9338] open("/etc/sysconfig/network", O_RDONLY) = 15
[pid  9338] lstat64("/etc/sysconfig/network", {st_mode=S_IFREG|0644, st_size=427, ...}) = 0
NetworkManager[9338]:    etcnet-alt: Current network profile: (none)
NetworkManager[9338]: <info> Loaded plugin etcnet-alt: (c) 2008 Mikhail Efremov <sem@altlinux.org>.
[pid  9338] access("/etc/NetworkManager/NetworkManager.conf", F_OK) = 0
[pid  9338] open("/etc/NetworkManager/NetworkManager.conf", O_RDONLY|O_LARGEFILE) = 15
[pid  9338] open("/etc/NetworkManager/NetworkManager.conf", O_RDONLY|O_LARGEFILE) = 15
[pid  9338] readlink("/sys/class/net/lo", "../../devices/virtual/net/lo", 1024) = 28
[pid  9338] stat64("/sys/devices/virtual/net/lo/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/class/net/eth2", "../../devices/pci0000:00/0000:00"..., 1024) = 46
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/class/net/eth1", "../../devices/pci0000:00/0000:00"..., 1024) = 59
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:05.0/net/eth2", 0xbfbcd22c, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1", 0xbfbcd22c, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/virtual/net/lo", 0xbfbcd22c, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/virtual/net/lo/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] lstat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/address", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/address", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 15
[pid  9338] lstat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/type", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/type", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 15
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/phy80211", 0xbfbcdcc0) = -1 ENOENT (No such file or directory)
[pid  9338] lstat64("/etc/net/ifaces/eth2", {st_mode=S_IFDIR|0755, st_size=76, ...}) = 0
[pid  9338] stat64("/etc/net/ifaces/eth2", {st_mode=S_IFDIR|0755, st_size=76, ...}) = 0
[pid  9338] open("/etc/net/ifaces/eth2/wpa_supplicant.conf", O_RDONLY|O_LARGEFILE) = -1 ENOENT (No such file or directory)
[pid  9338] open("/etc/net/ifaces/eth2/options", O_RDONLY) = 15
[pid  9338] open("/etc/net/ifaces/default/options-eth", O_RDONLY) = 15
[pid  9338] open("/etc/net/ifaces/default/options", O_RDONLY) = 15
[pid  9338] openat(AT_FDCWD, "/etc/net/ifaces/eth2", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_CLOEXEC) = 15
[pid  9338] lstat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/address", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/address", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 16
[pid  9338] lstat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/type", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/type", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 16
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/phy80211", 0xbfbcdcc0) = -1 ENOENT (No such file or directory)
[pid  9338] lstat64("/etc/net/ifaces/eth1", {st_mode=S_IFLNK|0777, st_size=4, ...}) = 0
[pid  9338] stat64("/etc/net/ifaces/eth1", {st_mode=S_IFDIR|0755, st_size=118, ...}) = 0
[pid  9338] readlink("/etc/net/ifaces/eth1", "eth0", 256) = 4
[pid  9338] stat64("/etc/net/ifaces/eth1", {st_mode=S_IFDIR|0755, st_size=118, ...}) = 0
[pid  9338] open("/etc/net/ifaces/eth1/wpa_supplicant.conf", O_RDONLY|O_LARGEFILE) = -1 ENOENT (No such file or directory)
[pid  9338] open("/etc/net/ifaces/eth1/options", O_RDONLY) = 16
[pid  9338] open("/etc/net/ifaces/default/options-eth", O_RDONLY) = 16
[pid  9338] open("/etc/net/ifaces/default/options", O_RDONLY) = 16
[pid  9338] openat(AT_FDCWD, "/etc/net/ifaces/eth1", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_CLOEXEC) = 16
[pid  9338] lstat64("/sys/devices/virtual/net/lo/address", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/virtual/net/lo/address", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 17
[pid  9338] lstat64("/sys/devices/virtual/net/lo/type", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/virtual/net/lo/type", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 17
[pid  9338] openat(AT_FDCWD, "/etc/NetworkManager/system-connections", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_CLOEXEC) = 17
[pid  9338] open("/etc/NetworkManager/NetworkManager.conf", O_RDONLY|O_LARGEFILE) = 17
[pid  9338] open("/etc/udev/udev.conf", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 17
[pid  9338] open("/etc/hosts", O_RDONLY|O_LARGEFILE) = 18
[pid  9340] open("/dev/null", O_RDONLY|O_LARGEFILE) = 18
[pid  9340] open("/dev/null", O_WRONLY|O_LARGEFILE) = 18
[pid  9340] open("/dev/null", O_WRONLY|O_LARGEFILE) = 18
[pid  9340] execve("/bin/pidof", ["/bin/pidof", "-s", "X", "Xorg"], [/* 36 vars */]) = 0
[pid  9340] access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
[pid  9340] open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
[pid  9340] readlink("/proc/2200/exe", "/sbin/udevd", 4096) = 11
[pid  9340] readlink("/proc/7854/exe", "/sbin/udevd", 4096) = 11
[pid  9340] readlink("/proc/7856/exe", "/sbin/udevd", 4096) = 11
[pid  9338] readlink("/sys/class/net/lo", "../../devices/virtual/net/lo", 1024) = 28
[pid  9338] stat64("/sys/devices/virtual/net/lo/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/class/net/eth2", "../../devices/pci0000:00/0000:00"..., 1024) = 46
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/class/net/eth1", "../../devices/pci0000:00/0000:00"..., 1024) = 59
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:05.0/net/eth2", 0xbfbcd90c, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1", 0xbfbcd90c, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/virtual/net/lo", 0xbfbcd90c, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/virtual/net/lo/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] lstat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/type", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/type", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
[pid  9338] open("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/uevent", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/subsystem", "../../../../../class/net", 1024) = 24
[pid  9338] access("/run/udev", F_OK)   = 0
[pid  9338] open("/run/udev/data/n3", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/driver", 0xbfbcd7bc, 1024) = -1 ENOENT (No such file or directory)
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:05.0/net", 0xbfbcd0ec, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/net/uevent", 0xbfbcd54c) = -1 ENOENT (No such file or directory)
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:05.0", 0xbfbcd0ec, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:05.0/driver", "../../../bus/pci/drivers/forcede"..., 1024) = 34
[pid  9338] lstat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/ifindex", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/ifindex", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/phy80211", 0xbfbce000) = -1 ENOENT (No such file or directory)
[pid  9338] open("/etc/udev/udev.conf", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
[pid  9338] stat64("/sys/subsystem/net/devices/eth2", 0xbfbcd898) = -1 ENOENT (No such file or directory)
[pid  9338] stat64("/sys/bus/net/devices/eth2", 0xbfbcd898) = -1 ENOENT (No such file or directory)
[pid  9338] readlink("/sys/class/net/eth2", "../../devices/pci0000:00/0000:00"..., 1024) = 46
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/net/eth2/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:05.0/net", 0xbfbccdbc, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/net/uevent", 0xbfbcd21c) = -1 ENOENT (No such file or directory)
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:05.0", 0xbfbccdbc, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:05.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:05.0/subsystem", "../../../bus/pci", 1024) = 16
[pid  9338] readlink("/sys/devices/pci0000:00", 0xbfbccdbc, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/subsystem", 0xbfbcd47c, 1024) = -1 ENOENT (No such file or directory)
NetworkManager[9338]: <info> (eth2): new Ethernet device (driver: 'forcedeth' ifindex: 3)
[pid  9338] lstat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/type", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/type", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
[pid  9338] open("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/uevent", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/subsystem", "../../../../../../class/net", 1024) = 27
[pid  9338] open("/run/udev/data/n2", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/driver", 0xbfbcd7bc, 1024) = -1 ENOENT (No such file or directory)
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net", 0xbfbcd0ec, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/uevent", 0xbfbcd54c) = -1 ENOENT (No such file or directory)
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0", 0xbfbcd0ec, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/driver", "../../../../bus/pci/drivers/8139"..., 1024) = 35
[pid  9338] lstat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/ifindex", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/ifindex", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/phy80211", 0xbfbce000) = -1 ENOENT (No such file or directory)
[pid  9338] open("/etc/udev/udev.conf", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
[pid  9338] stat64("/sys/subsystem/net/devices/eth1", 0xbfbcd898) = -1 ENOENT (No such file or directory)
[pid  9338] stat64("/sys/bus/net/devices/eth1", 0xbfbcd898) = -1 ENOENT (No such file or directory)
[pid  9338] readlink("/sys/class/net/eth1", "../../devices/pci0000:00/0000:00"..., 1024) = 59
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/eth1/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net", 0xbfbccdbc, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/net/uevent", 0xbfbcd21c) = -1 ENOENT (No such file or directory)
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0", 0xbfbccdbc, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/0000:02:07.0/subsystem", "../../../../bus/pci", 1024) = 19
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0", 0xbfbccdbc, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/0000:00:0e.0/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/0000:00:0e.0/subsystem", "../../../bus/pci", 1024) = 16
[pid  9338] readlink("/sys/devices/pci0000:00", 0xbfbccdbc, 1024) = -1 EINVAL (Invalid argument)
[pid  9338] stat64("/sys/devices/pci0000:00/uevent", {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
[pid  9338] readlink("/sys/devices/pci0000:00/subsystem", 0xbfbcd47c, 1024) = -1 ENOENT (No such file or directory)
NetworkManager[9338]: <info> (eth1): new Ethernet device (driver: '8139too' ifindex: 2)
NetworkManager[9338]: <info> (eth1): device state change: 1 -> 2 (reason 2)
NetworkManager[9338]: <info> (eth1): preparing device.
NetworkManager[9338]: <info> (eth1): deactivating device (reason: 2).
[pid  9341] execve("/bin/sh", ["sh", "-c", "/sbin/ip route | wc -l"], [/* 36 vars */]) = 0
[pid  9341] access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
[pid  9341] open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
[pid  9341] open("/dev/tty", O_RDWR|O_NONBLOCK|O_LARGEFILE) = 5
[pid  9343] execve("/bin/wc", ["wc", "-l"], [/* 36 vars */]Process 9342 attached
[pid  9343] access("/etc/ld.so.preload", R_OK <unfinished ...>
[pid  9342] execve("/sbin/ip", ["/sbin/ip", "route"], [/* 36 vars */] <unfinished ...>
[pid  9343] open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
[pid  9342] <... execve resumed> )      = 0
[pid  9342] access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
[pid  9342] open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
[pid  9338] open("/etc/NetworkManager/NetworkManager.conf", O_RDONLY|O_LARGEFILE) = 18
[pid  9338] lstat64("/sys/devices/virtual/net/lo/type", {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
[pid  9338] open("/sys/devices/virtual/net/lo/type", O_RDONLY|O_LARGEFILE|O_CLOEXEC) = 18
NetworkManager[9338]: <info> (eth1): device state change: 2 -> 3 (reason 0)
[pid  9344] execve("/bin/sh", ["sh", "-c", "/sbin/ip route | wc -l"], [/* 36 vars */]) = 0
[pid  9344] access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
[pid  9344] open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
[pid  9344] open("/dev/tty", O_RDWR|O_NONBLOCK|O_LARGEFILE) = 5
[pid  9346] execve("/bin/wc", ["wc", "-l"], [/* 36 vars */]Process 9345 attached
[pid  9346] access("/etc/ld.so.preload", R_OK <unfinished ...>
[pid  9345] execve("/sbin/ip", ["/sbin/ip", "route"], [/* 36 vars */] <unfinished ...>
[pid  9346] open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
[pid  9345] <... execve resumed> )      = 0
[pid  9345] access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
[pid  9345] open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
[pid  9347] open("/dev/null", O_RDONLY|O_LARGEFILE) = 18
[pid  9347] open("/dev/null", O_WRONLY|O_LARGEFILE) = 18
[pid  9347] open("/dev/null", O_WRONLY|O_LARGEFILE) = 18
[pid  9347] execve("/bin/pidof", ["/bin/pidof", "-s", "X", "Xorg"], [/* 36 vars */]) = 0
[pid  9347] access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
[pid  9347] open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
[pid  9347] readlink("/proc/2200/exe", "/sbin/udevd", 4096) = 11
[pid  9347] readlink("/proc/7854/exe", "/sbin/udevd", 4096) = 11
[pid  9347] readlink("/proc/7856/exe", "/sbin/udevd", 4096) = 11
NetworkManager[9338]: <info> (eth1): device state change: 3 -> 1 (reason 36)
NetworkManager[9338]: <info> (eth1): taking down device.

Правильная конфигурация etcnet такая (которую портить запуск сервиса NetworkManager):

[new@linux ~]$ ip addr
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN 
    link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
    inet 127.0.0.1/8 scope host lo
2: eth1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UNKNOWN qlen 1000
    link/ether 00:0d:0f:10:1a:bc brd ff:ff:ff:ff:ff:ff
    inet 10.11.7.106/24 brd 10.11.7.255 scope global eth1
3: eth2: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN qlen 1000
    link/ether 00:13:d4:cf:e8:53 brd ff:ff:ff:ff:ff:ff
[new@linux ~]$

Comment 13

Ivan Zakharyaschev 2013-04-13 23:30:04 MSK

Created attachment 5807 [details]
strace -fF -e trace=all NetworkManager --no-daemon 2> nm-strace2

С trace=all, может, что-то будет понятнее...

Comment 14

Ivan Zakharyaschev 2013-04-13 23:51:34 MSK

А не может быть дело в том, что /etc/net/ifaces/default/options-eth (в котором стоит NM_CONTROLLED=yes) читается после /etc/net/ifaces/eth1/options :

[pid 11001] lstat64("/etc/net/ifaces/eth1", {st_mode=S_IFLNK|0777, st_size=4, ...}) = 0
[pid 11001] stat64("/etc/net/ifaces/eth1", {st_mode=S_IFDIR|0755, st_size=118, ...}) = 0
[pid 11001] readlink("/etc/net/ifaces/eth1", "eth0", 256) = 4
[pid 11001] inotify_add_watch(10, "/etc/net/ifaces/eth1", IN_MODIFY|IN_ATTRIB|IN_CLOSE_WRITE|IN_MOVED_FROM|IN_MOVED_TO|IN_CREATE|IN_DELETE|IN_DELETE_SELF|IN_MOVE_SELF|IN_UNMOUNT|IN_ONLYDIR) = 6
[pid 11001] stat64("/etc/net/ifaces/eth1", {st_mode=S_IFDIR|0755, st_size=118, ...}) = 0
[pid 11001] open("/etc/net/ifaces/eth1/wpa_supplicant.conf", O_RDONLY|O_LARGEFILE) = -1 ENOENT (No such file or directory)
[pid 11001] open("/etc/net/ifaces/eth1/options", O_RDONLY) = 16
[pid 11001] fstat64(16, {st_mode=S_IFREG|0644, st_size=55, ...}) = 0
[pid 11001] read(16, "BOOTPROTO=static\nTYPE=eth\nNM_CON"..., 55) = 55
[pid 11001] close(16)                   = 0
[pid 11001] open("/etc/net/ifaces/default/options-eth", O_RDONLY) = 16
[pid 11001] fstat64(16, {st_mode=S_IFREG|0644, st_size=365, ...}) = 0
[pid 11001] read(16, "# This file doesn't contain comm"..., 365) = 365
[pid 11001] close(16)                   = 0
[pid 11001] open("/etc/net/ifaces/default/options", O_RDONLY) = 16
[pid 11001] fstat64(16, {st_mode=S_IFREG|0644, st_size=396, ...}) = 0
[pid 11001] read(16, "# This file doesn't contain comm"..., 396) = 396
[pid 11001] close(16)                   = 0
[pid 11001] openat(AT_FDCWD, "/etc/net/ifaces/eth1", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|O_CLOEXEC) = 16

Насколько я понимаю семантику etcnet, опции default не должны перекрывать опции конкретный интерфейсов, но, может быть, NetworkManager не следует этому принципу, когда разбирает конфигурацию etcnet?

Comment 15

Ivan Zakharyaschev 2013-04-13 23:55:50 MSK

(В ответ на комментарий №14)

> Насколько я понимаю семантику etcnet, опции default не должны перекрывать опции
> конкретный интерфейсов, но, может быть, NetworkManager не следует этому
> принципу, когда разбирает конфигурацию etcnet?

LC_ALL=C man etcnet (ключевые слова initial и default)

       default
              This  is  really  not an interface, but interface template. 'default' interface forms initial set of options for all interfaces
              and it doesn't belong to any interface group. This interface allows placing  an  additional  options  file  into  configuration
              directory:  options-<TYPE>.  This  optional file has effect ONLY for 'default' interface, it allows to override default options
              with type-specific options.  It is not recommended to change options for 'default' interface.  Each  interface  can  add  extra
              options or override existing options, if needed.

Comment 16

Ivan Zakharyaschev 2013-04-14 00:01:04 MSK

(В ответ на комментарий №14)
> А не может быть дело в том, что /etc/net/ifaces/default/options-eth (в котором
> стоит NM_CONTROLLED=yes) читается после /etc/net/ifaces/eth1/options :

Нет, вписывание NM_CONTROLLED=no в /etc/net/ifaces/default/options-eth не меняет плохое поведение!..

Comment 17

Ivan Zakharyaschev 2013-04-15 01:59:11 MSK

(В ответ на комментарий №10)

> Это странно, плагин должен следить за файлом и реагировать на любое его
> изменение.
> Причем судя по 
> > Jan 22 20:37:11 linux NetworkManager[4375]:    etcnet-alt: eth0: Readed
> > connection "System eth0"
> настройки из etcnet читаются (и NM должен сразу подключаться в соответствии с
> ними если в options нет ONBOOT=no).

Сейчас я подумал, что я не проследил, что там с ONBOOT=no -- в /etc/net/ifaces/eth1/options вообще не прописан ONBOOT (как видно из Description здесь):

BOOTPROTO=static
TYPE=eth
NM_CONTROLLED=no
DISABLED=no


а вот что нет ли ONBOOT=no в /etc/net/ifaces/default/options и /etc/net/ifaces/default/options-eth я не посмотрел. Когда доберусь опять жо этой системы, посмотрю.

Хотя вряд ли... -- ведь сама etcnet нормально его поднимает при загрузке. Должно быть, нет там мешающего ONBOOT=no.

Comment 18

Ivan Zakharyaschev 2013-04-15 02:26:05 MSK

Вопрос к знакомым с внутренностями NM:

А при каких условиях NetworkManager опускает интерфейс "по причине 2" (как в моём случае):

NetworkManager[5590]: <info> (eth0): deactivating device
(reason: 2)

Comment 19

Ivan Zakharyaschev 2013-04-15 03:04:58 MSK

(В ответ на комментарий №18)

> А при каких условиях NetworkManager опускает интерфейс "по причине 2" (как в
> моём случае):
> 
> NetworkManager[5590]: <info> (eth0): deactivating device
> (reason: 2)

Из NetworkManager.h:


	/* Device is now managed */
	NM_DEVICE_STATE_REASON_NOW_MANAGED = 2,

Comment 20

Горев Андрей 2014-06-19 09:24:24 MSK

А у меня ощущение, что сие говно гонит именно avahi-daemon:
Jun 19 09:14:58 pc00447 avahi-daemon[9166]: Interface enp0s20u7.IPv4 no longer relevant for mDNS.
Jun 19 09:14:58 pc00447 avahi-daemon[9166]: Leaving mDNS multicast group on interface enp0s20u7.IPv4 with address 192.168.137.1.
Jun 19 09:14:58 pc00447 avahi-daemon[9166]: Withdrawing address record for fe80::b877:22ff:fe02:9e6a on enp0s20u7.
Jun 19 09:14:58 pc00447 avahi-daemon[9166]: Withdrawing address record for 192.168.137.1 on enp0s20u7.
Jun 19 09:14:58 pc00447 avahi-daemon[9166]: Withdrawing workstation service for enp0s20u7.

   Адреса слетают и после этого интерфейс идёт в down, хотя в таблице интерфейсов есть. Видно через ip address и ip link.

Comment 21

Mikhail Efremov 2022-08-30 15:54:58 MSK

В любом случае p6 уже не поддерживается.