VineSeed ML アーカイブ



[VineSeed:028534] Re: Vine6.5のudevでハングする

  • From: 田中 秀知 <hidetomo@xxxxxxxxxxxxxxxxxx>
  • Subject: [VineSeed:028534] Re: Vine6.5のudevでハングする
  • Date: Thu, 07 Sep 2017 21:07:24 +0900
井上さん

田中です。
添付ファイル udev-debug.pngを見ました。
最後から6行上がった行に、
「Starting Postfix:」と 表示されていました。

私のパソコンは、今 理由(わけ)あって、
小さめのノートパソコンで VineLinux6.3で動かしています。
そのパソコンで runlevel 3で起動して、
/var/log/boot.logを見てみました。
(添付ファイル: boot.log)
 (見やすいように 少し修正を入れました。)

このboot.logでは、「Starting Postfix:」は
終り近くで 出てきます。
「Starting udev:」は すでに過ぎていることになっています。

udev_log="debug"とすることで、
udevの処理の細かい流れが変化して、
udevの途中で止まらなくなったのかなと想像しました。
(本当に そうなのかどうかは わかりませんが)


ネットワークのデバイスが どうなっているのか
知りたかったので、
こちらのパソコンで、runlevel 3、udev_log="debug"で
起動した後、/var/log/messagesの内容を
「eth0」等の言葉で 検索をかけてみました。
(正規表現で 検索をかけてみました。)

$  egrep eth[0-9]  /var/log/messages

出力結果を添付ファイルに付けます。
(添付ファイル: log_messages_egrep_eth_n.txt)


そちらのパソコン(VPS?)でも、
同じように egrepコマンドで検索をかけてもらって、
私の所の結果と比較してもらったらと思いました。


Setting up hotplug.
Loading i915 module unless booted with failsafe or nokmsboot option
Loading /lib/kbd/keymaps/i386/qwerty/jp106.map
Creating block device nodes.
Creating character device nodes.
Loading ehci-hcd module
Loading ohci-hcd module
Loading uhci-hcd module
Loading xhci-hcd module
Loading pata_acpi module
Loading ata_generic module
Loading ide-core module
Loading ide-pci-generic module
Loading usb-storage module
Waiting for driver initialization.
Unable to access resume device (swap:/dev/sda6)
Activating tux on ice
Trying to resume from target specified as resume on cmdline.
Trying to resume from swap:/dev/sda6.
Creating root device.
Mounting root filesystem.
Setting up other filesystems.
Switching to new root and running init.
			Welcome to Vine Linux
		Press 'I' to enter interactive startup.
Starting udev: [  OK  ]
Loading SCSI module (ata_generic):  [  OK  ]
Loading SCSI module (usb-storage):  [  OK  ]
Configuring kernel parameters:  net.ipv4.ip_forward = 0
net.ipv4.conf.default.rp_filter = 1
net.ipv4.conf.default.accept_source_route = 0
kernel.sysrq = 0
net.ipv4.tcp_syncookies = 1
kernel.core_uses_pid = 1
[  OK  ]
Setting clock  (localtime): Thu Sep  7 19:10:29 JST 2017 Thu Sep  7 19:10:29 JST 2017
[  OK  ]
Loading default keymap (jp106): [  OK  ]
Activating swap partitions:  [  OK  ]
Setting hostname localhost.localdomain:  [  OK  ]
Setting up Logical Volume Management:   No volume groups found
[  OK  ]
Checking root filesystem
/: clean, 270566/1123872 files, 3414096/4494175 blocks
[  OK  ]
Remounting root filesystem in specified mode(defaults):  [  OK  ]
Checking filesystems
/boot: clean, 47/31360 files, 11812/125424 blocks
[  OK  ]
Enabling local filesystem quotas:  [  OK  ]
Enabling swap space:  [  OK  ]
Entering non-interactive startup
Starting dkms_autoinstaller:  
   Running DKMS auto installation service for kernel 3.4.110-4vl6
.[  OK  ]
Enabling ondemand cpu frequency scaling: [  OK  ]
Starting iptables:  iptables: Applying firewall rules: [  OK  ]
iptables: Loading additional modules: ip_conntrack_netbios_ns [  OK  ]
[  OK  ]
Starting system logger: [  OK  ]
Starting irqbalance: [PASSED]
Starting iscsi:  [  OK  ]
Starting rpcbind: [  OK  ]
Initializing random number generator:  [  OK  ]
Starting system message bus: [  OK  ]
Setting network parameters... [  OK  ]
Starting NetworkManager daemon: [  OK  ]
Starting Avahi daemon... [  OK  ]
Starting NFS statd: [  OK  ]
Starting RPC idmapd: mount: rpc_pipefs already mounted or /var/lib/nfs/rpc_pipefs busy
mount: according to mtab, sunrpc is already mounted on /var/lib/nfs/rpc_pipefs
[  OK  ]
Starting cups: [  OK  ]
Starting acpi daemon: [  OK  ]
Starting HAL daemon: [  OK  ]
Starting udev-post:  Retrigger failed udev events[  OK  ]
[  OK  ]
Loading autofs4: [  OK  ]
Starting automount: [  OK  ]
Starting bluetoothd: [  OK  ]
Starting sshd:  Starting sshd: [  OK  ]
[  OK  ]
Starting NFS services:  [  OK  ]
Starting NFS quotas: [  OK  ]
Starting NFS daemon: [  OK  ]
Starting NFS mountd: [  OK  ]
Starting Postfix: [  OK  ]
Starting crond: [  OK  ]
Starting anacron: [  OK  ]
Starting atd: [  OK  ]
Sep  7 19:29:26 localhost kernel: 8139too 0000:01:01.0: eth0: RealTek RTL8139 at 0x3000, 00:0b:97:2b:3f:b3, IRQ 3
Sep  7 19:29:26 localhost NetworkManager[1175]:    ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-eth0 ... 
Sep  7 19:29:26 localhost NetworkManager[1175]:    ifcfg-rh:     read connection 'System eth0'
Sep  7 19:29:26 localhost NetworkManager[1175]: <info> (eth0): carrier is OFF
Sep  7 19:29:26 localhost NetworkManager[1175]: <info> (eth0): new Ethernet device (driver: '8139too' ifindex: 2)
Sep  7 19:29:26 localhost NetworkManager[1175]: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/0
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): now managed
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): device state change: 1 -> 2 (reason 2)
Sep  7 19:29:27 localhost kernel: 8139too 0000:01:01.0: eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): bringing up device.
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): preparing device.
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): deactivating device (reason: 2).
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): carrier now ON (device state 2)
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): device state change: 2 -> 3 (reason 40)
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) starting connection 'System eth0'
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): device state change: 3 -> 4 (reason 0)
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): device state change: 4 -> 5 (reason 0)
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): device state change: 5 -> 7 (reason 0)
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
Sep  7 19:29:27 localhost dhclient: Listening on LPF/eth0/00:0b:97:2b:3f:b3
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): DHCPv4 state changed nbi -> preinit
Sep  7 19:29:27 localhost dhclient: Sending on   LPF/eth0/00:0b:97:2b:3f:b3
Sep  7 19:29:27 localhost dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> (eth0): DHCPv4 state changed preinit -> reboot
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) started...
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 4 of 5 (IP4 Configure Get) complete.
Sep  7 19:29:27 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
Sep  7 19:29:27 localhost avahi-daemon[1190]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.237.81.
Sep  7 19:29:27 localhost avahi-daemon[1190]: New relevant interface eth0.IPv4 for mDNS.
Sep  7 19:29:27 localhost avahi-daemon[1190]: Registering new address record for 192.168.237.81 on eth0.IPv4.
Sep  7 19:29:28 localhost avahi-daemon[1190]: Registering new address record for fe80::20b:97ff:fe2b:3fb3 on eth0.*.
Sep  7 19:29:29 localhost NetworkManager[1175]: <info> (eth0): device state change: 7 -> 8 (reason 0)
Sep  7 19:29:29 localhost NetworkManager[1175]: <info> Policy set 'System eth0' (eth0) as default for IPv4 routing and DNS.
Sep  7 19:29:29 localhost NetworkManager[1175]: <info> Activation (eth0) successful, device activated.
Sep  7 19:29:29 localhost NetworkManager[1175]: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
Sep  7 19:29:35 localhost udevadm[1435]: device 0x8080a08 has devpath '/devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0'
Sep  7 19:29:44 localhost udevadm[1435]: device 0x8077748 has devpath '/devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0'
Sep  7 19:29:54 localhost udevd-work[1489]: device 0x80ccaf8 has devpath '/devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0'
Sep  7 19:30:00 localhost udevd-work[1489]: '/lib/udev/rename_device' (stdout) 'eth0'
Sep  7 19:30:01 localhost udevd-work[1489]: NAME 'eth0' /etc/udev/rules.d/70-persistent-net.rules:8
Sep  7 19:30:02 localhost udevd-work[1489]: IMPORT 'pci-db /devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0' /lib/udev/rules.d/75-net-description.rules:11
Sep  7 19:30:02 localhost udevd-work[1489]: 'pci-db /devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0' started
Sep  7 19:30:02 localhost udevd-work[1489]: '/lib/udev/pci-db' (stderr) 'libudev: udev_list_entry_add: 'DEVPATH=/devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0' added'
Sep  7 19:30:02 localhost udevd-work[1489]: '/lib/udev/pci-db' (stderr) 'libudev: udev_device_new_from_syspath: device 0x80520d0 has devpath '/devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0''
Sep  7 19:30:03 localhost udevd-work[1489]: 'pci-db /devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0' returned with exitcode 0
Sep  7 19:30:04 localhost udevd-work[1489]: created db file for '/devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0' in '/dev/.udev/db/net:eth0'
Sep  7 19:30:06 localhost udevd-work[1035]: device 0x806cb58 has devpath '/devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0'
Sep  7 19:30:06 localhost udevd-work[1036]: device 0x8083a78 has devpath '/devices/pci0000:00/0000:00:1e.0/0000:01:01.0/net/eth0'
Sep  7 19:34:56 localhost avahi-daemon[1190]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.237.81.
_______________________________________________
VineSeed mailing list
VineSeed@xxxxxxxxxxxxx
http://lists.vinelinux.org/mailman/listinfo/vineseed