[OpenIndiana-discuss] Possible bug?: Boot inconsistencies and a failing network connection (e1000g).
Robin Axelsson
gu99roax at student.chalmers.se
Wed Feb 15 10:46:26 UTC 2012
I've been tracing errors that occasionally occur with VirtualBox when it
tries to start a VM with a bridged network connection. It seems that
maybe it is nwam that creates these problems. Yesterday my VM ran fine
with the network connection but today it didn't. When I analyzed the
/var/adm/messages I saw that there were error messages linked to the
ethernet controller so perhaps nwam is corrupting the configuration
somehow or fails to update the boot_archive properly.
The e1000g2 connection is unplumbed and it is used for the network bridge.
Here are the (not necessarily) error messages I saw yesterday:
--------------------------------------------------------------------------
Feb 14 06:13:52 OpenIndana pcplusmp: [ID 805372 kern.info] pcplusmp:
pciex8086,105e (e1000g) instance 1 irq 0x18 vector 0x60 ioapic 0xff
intin 0xff is bound to cpu 0
Feb 14 06:13:53 OpenIndana mac: [ID 469746 kern.info] NOTICE: e1000g1
registered
Feb 14 06:13:53 OpenIndana pcplusmp: [ID 805372 kern.info] pcplusmp:
pciex8086,105e (e1000g) instance 2 irq 0x19 vector 0x61 ioapic 0xff
intin 0xff is bound to cpu 1
Feb 14 06:13:53 OpenIndana mac: [ID 469746 kern.info] NOTICE: e1000g2
registered
...
Feb 14 06:13:55 OpenIndana mac: [ID 435574 kern.info] NOTICE: e1000g1
link up, 1000 Mbps, full duplex
Feb 14 06:13:57 OpenIndana mac: [ID 486395 kern.info] NOTICE: e1000g1
link down
Feb 14 06:13:57 OpenIndana nwamd[95]: [ID 247971 daemon.error] 1:
nwamd_down_interface: ipadm_delete_addr failed on e1000g1: Could not
communicate with dhcpagent
Feb 14 06:13:59 OpenIndana mac: [ID 435574 kern.info] NOTICE: e1000g1
link up, 1000 Mbps, full duplex
...
Feb 14 06:14:03 OpenIndana nwamd[95]: [ID 621993 daemon.error] 8:
start_dhcp: ipadm_create_addr on e1000g1 returned: Could not communicate
with dhcpagent, retrying in 10 sec
...
Feb 14 06:14:03 OpenIndana mac: [ID 486395 kern.info] NOTICE: e1000g1
link down
Feb 14 06:14:03 OpenIndana nwamd[95]: [ID 247971 daemon.error] 1:
nwamd_down_interface: ipadm_delete_addr failed on e1000g1: Could not
communicate with dhcpagent
Feb 14 06:14:03 OpenIndana nwamd[95]: [ID 750975 daemon.error] 9:
start_dhcp: ipadm_create_addr on e1000g1 returned: Could not communicate
with dhcpagent, retrying in 10 sec
...
Feb 14 06:14:05 OpenIndana mac: [ID 435574 kern.info] NOTICE: e1000g1
link up, 1000 Mbps, full duplex
Feb 14 06:14:07 OpenIndana mac: [ID 486395 kern.info] NOTICE: e1000g1
link down
Feb 14 06:14:07 OpenIndana nwamd[95]: [ID 247971 daemon.error] 1:
nwamd_down_interface: ipadm_delete_addr failed on e1000g1: Could not
communicate with dhcpagent
Feb 14 06:14:07 OpenIndana nwamd[95]: [ID 167788 daemon.error] 10:
start_dhcp: ipadm_create_addr on e1000g1 returned: Could not communicate
with dhcpagent, retrying in 10 sec
Feb 14 06:14:09 OpenIndana mac: [ID 435574 kern.info] NOTICE: e1000g1
link up, 1000 Mbps, full duplex
...
Feb 14 06:14:10 OpenIndana mac: [ID 486395 kern.info] NOTICE: e1000g1
link down
Feb 14 06:14:10 OpenIndana nwamd[95]: [ID 247971 daemon.error] 1:
nwamd_down_interface: ipadm_delete_addr failed on e1000g1: Could not
communicate with dhcpagent
Feb 14 06:14:10 OpenIndana nwamd[95]: [ID 296770 daemon.error] 11:
start_dhcp: ipadm_create_addr on e1000g1 returned: Could not communicate
with dhcpagent, retrying in 10 sec
...
Feb 14 06:14:16 OpenIndana in.routed[727]: [ID 749644 daemon.notice]
e1000g1 has a bad address 0.0.0.0
...
Feb 14 06:14:17 OpenIndana mac: [ID 435574 kern.info] NOTICE: e1000g1
link up, 1000 Mbps, full duplex
...
Feb 14 06:14:21 OpenIndana in.routed[727]: [ID 606928 daemon.warning]
sendto(e1000g1, 224.0.0.2): Network is unreachable
...
Feb 14 06:14:23 OpenIndana mac: [ID 736570 kern.info] NOTICE: e1000g2
unregistered
...
Feb 14 06:14:24 OpenIndana in.routed[727]: [ID 606928 daemon.warning]
sendto(e1000g1, 224.0.0.2): Network is unreachable
...
Feb 14 06:20:33 OpenIndana pcplusmp: [ID 805372 kern.info] pcplusmp:
pciex8086,105e (e1000g) instance 2 irq 0x19 vector 0x61 ioapic 0xff
intin 0xff is bound to cpu 1
Feb 14 06:20:33 OpenIndana mac: [ID 469746 kern.info] NOTICE: e1000g2
registered
Feb 14 06:20:33 OpenIndana vboxdrv: [ID 409703 kern.notice]
vboxflt:vboxNetFltSolarisAttachIp4: Warning: seems 'e1000g2' is unplumbed.
Feb 14 06:20:35 OpenIndana mac: [ID 435574 kern.info] NOTICE: e1000g2
link up, 1000 Mbps, full duplex
--------------------------------------------------------------------------
Moreover, upon boot this morning the usual "header" entries that
precedes the log messages were missing this morning, i.e.:
--------------------------------------------------------------------------
Feb 14 06:13:42 OpenIndana genunix: [ID 365267 kern.notice]
^MOpenIndiana Build oi_151a 64-bit (illumos f342d051b376)
Feb 14 06:13:42 OpenIndana genunix: [ID 107366 kern.notice] SunOS
Release 5.11 - Copyright 1983-2010 Oracle and/or its affiliates.
Feb 14 06:13:42 OpenIndana genunix: [ID 864463 kern.notice] All rights
reserved. Use is subject to license terms.
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: lgpg
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: tsc
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: msr
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: mtrr
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: pge
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: de
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: cmov
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: mmx
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: mca
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: pae
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: cv8
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: pat
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: sse
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: sse2
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: asysc
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: nx
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: sse3
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: cx16
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: cmp
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: tscp
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: mwait
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: sse4a
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: cpuid
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: 1gpg
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: clfsh
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: 64
Feb 14 06:13:42 OpenIndana unix: [ID 223955 kern.info] x86_feature: svm
Feb 14 06:13:42 OpenIndana unix: [ID 168242 kern.info] mem = 4191480K
(0xffd3e000)
Feb 14 06:13:42 OpenIndana acpica: [ID 668429 kern.notice] ACPI: RSDP
f6c60 00014 (v0 GBT )
Feb 14 06:13:42 OpenIndana acpica: [ID 770516 kern.notice] ACPI: RSDT
cfdd1000 0004C (v1 GBT GBTUACPI 42302E31 GBTU 01010101)
Feb 14 06:13:42 OpenIndana acpica: [ID 670027 kern.notice] ACPI: FACP
cfdd1080 00074 (v1 GBT GBTUACPI 42302E31 GBTU 01010101)
Feb 14 06:13:42 OpenIndana acpica: [ID 213164 kern.notice] ACPI: DSDT
cfdd1100 07633 (v1 GBT GBTUACPI 00001000 MSFT 03000000)
Feb 14 06:13:42 OpenIndana acpica: [ID 434518 kern.notice] ACPI: FACS
cfda0000 00040
Feb 14 06:13:42 OpenIndana acpica: [ID 485174 kern.notice] ACPI: SSDT
cfdd8800 0088C (v1 PTLTD POWERNOW 00000001 LTP 00000001)
Feb 14 06:13:42 OpenIndana acpica: [ID 515734 kern.notice] ACPI: MSDM
cfdd90c0 00055 (v3 GBT GBTUACPI 42302E31 GBTU 01010101)
Feb 14 06:13:42 OpenIndana acpica: [ID 712835 kern.notice] ACPI: HPET
cfdd9140 00038 (v1 GBT GBTUACPI 42302E31 GBTU 00000098)
Feb 14 06:13:42 OpenIndana acpica: [ID 543908 kern.notice] ACPI: MCFG
cfdd9180 0003C (v1 GBT GBTUACPI 42302E31 GBTU 01010101)
Feb 14 06:13:42 OpenIndana acpica: [ID 592460 kern.notice] ACPI: MATS
cfdd9200 00034 (v1 GBT 00000000 00000000)
Feb 14 06:13:42 OpenIndana acpica: [ID 790975 kern.notice] ACPI: TAMG
cfdd9270 00202 (v1 GBT GBT B0 5455312E BG?? 53450101)
Feb 14 06:13:42 OpenIndana acpica: [ID 769778 kern.notice] ACPI: APIC
cfdd8740 000BC (v1 GBT GBTUACPI 42302E31 GBTU 01010101)
Feb 14 06:13:42 OpenIndana acpica: [ID 621651 kern.notice] ACPI: MATS
cfdd9480 066BA (v1 MATS RCM 80000001 INTL 20061109)
Feb 14 06:13:42 OpenIndana acpica: [ID 701093 kern.notice] ACPI: IVRS
cfddfbb0 00100 (v1 AMD RD890S 00202031 AMD 00000000)
Feb 14 06:13:42 OpenIndana unix: [ID 190185 kern.info] SMBIOS v2.4
loaded (2944 bytes)
Feb 14 06:13:42 OpenIndana unix: [ID 972737 kern.info] Skipping psm: xpv_psm
--------------------------------------------------------------------------
instead it starts at:
--------------------------------------------------------------------------
Feb 15 05:57:21 OpenIndana rootnex: [ID 466748 kern.info] root nexus = i86pc
Feb 15 05:57:21 OpenIndana iommulib: [ID 321598 kern.info] NOTICE:
iommulib_nexus_register: rootnex-1: Succesfully registered NEXUS i86pc
nexops=fffffffffbd135e0
Feb 15 05:57:21 OpenIndana rootnex: [ID 349649 kern.info] pseudo0 at root
Feb 15 05:57:21 OpenIndana genunix: [ID 936769 kern.info] pseudo0 is /pseudo
Feb 15 05:57:21 OpenIndana rootnex: [ID 349649 kern.info] scsi_vhci0 at root
--------------------------------------------------------------------------
and goes on from there. I wonder why that is. Today, the e1000g1
connection works fine but the e1000g2 connection is unavailable to the
vboxflt driver. Th boot_archive update process doesn't appear to produce
any special messages:
--------------------------------------------------------------------------
Feb 14 20:01:35 OpenIndana syslog[1684]: [ID 702911 daemon.warning]
WARNING: Failed to send buffer
Feb 14 20:01:45 OpenIndana syslogd: going down on signal 15
Feb 14 20:01:48 OpenIndana rpcbind: [ID 564983 daemon.error] rpcbind
terminating on signal.
Feb 14 20:02:00 OpenIndana genunix: [ID 672855 kern.notice] syncing file
systems...
Feb 14 20:02:02 OpenIndana genunix: [ID 904073 kern.notice] done
--------------------------------------------------------------------------
When I reinstall the vboxflt driver the e100g2 connection works once
again, any clues?
-------------- next part --------------
An embedded and charset-unspecified text was scrubbed...
Name: messages.vboxflt.txt
URL: <http://openindiana.org/pipermail/openindiana-discuss/attachments/20120215/776519ae/attachment-0001.txt>
More information about the OpenIndiana-discuss
mailing list