Unable to load freshly-built nvidia.ko _at_r300994.

From: David Wolfskill <david_at_catwhisker.org>
Date: Mon, 30 May 2016 05:36:54 -0700
Today's "daily update" for head was from:

FreeBSD g1-252.catwhisker.org 11.0-ALPHA1 FreeBSD 11.0-ALPHA1 #432  r300951M/300951:1100114: Sun May 29 04:44:37 PDT 2016     root_at_g1-252.catwhisker.org:/common/S4/obj/usr/src/sys/CANARY  amd64

to:

FreeBSD g1-252.catwhisker.org 11.0-ALPHA1 FreeBSD 11.0-ALPHA1 #433  r300994M/300994:1100115: Mon May 30 04:21:30 PDT 2016     root_at_g1-252.catwhisker.org:/common/S4/obj/usr/src/sys/CANARY  amd64

(See
<http://www.catwhisker.org/~david/FreeBSD/history/laptop_uname_amd64.11.txt>
for the update history for FreeBSD-11 on this laptop.)

The attempt to load nvidia.ko yielded (from dmesg.boot):

linker_load_file: Unsupported file type
lock order reversal:
 1st 0xfffffe05c2224420 bufwait (bufwait) _at_ /usr/src/sys/kern/vfs_bio.c:3512
 2nd 0xfffff80009619600 dirhash (dirhash) _at_ /usr/src/sys/ufs/ufs/ufs_dirhash.c:281
stack backtrace:
#0 0xffffffff80a6a0d0 at witness_debugger+0x70
#1 0xffffffff80a69fc4 at witness_checkorder+0xe54
#2 0xffffffff80a13242 at _sx_xlock+0x72
#3 0xffffffff80d02857 at ufsdirhash_remove+0x37
#4 0xffffffff80d05a27 at ufs_dirremove+0x127
#5 0xffffffff80d0d04e at ufs_rename+0x135e
#6 0xffffffff80f4c5a0 at VOP_RENAME_APV+0x100
#7 0xffffffff80ad7c78 at kern_renameat+0x4a8
#8 0xffffffff82c9e5b9 at filemon_wrapper_rename+0x19
#9 0xffffffff80e04c06 at amd64_syscall+0x2f6
#10 0xffffffff80de4bdb at Xfast_syscall+0xfb
acquiring duplicate lock of same type: "os.lock_sx"
 1st os.lock_sx _at_ nvidia_os.c:603
 2nd os.lock_sx _at_ nvidia_os.c:603
stack backtrace:
#0 0xffffffff80a6a0d0 at witness_debugger+0x70
#1 0xffffffff80a69fc4 at witness_checkorder+0xe54
#2 0xffffffff80a13242 at _sx_xlock+0x72
#3 0xffffffff826161e2 at os_acquire_mutex+0x32
#4 0xffffffff82600b38 at _nv010796rm+0x18
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
ACPI Warning: \134_SB.PCI0.PEG0.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20160527/nsarguments-97)
acquiring duplicate lock of same type: "os.lock_mtx"
 1st os.lock_mtx _at_ nvidia_os.c:777
 2nd os.lock_mtx _at_ nvidia_os.c:777
stack backtrace:
#0 0xffffffff80a6a0d0 at witness_debugger+0x70
#1 0xffffffff80a69fc4 at witness_checkorder+0xe54
#2 0xffffffff809ec894 at __mtx_lock_flags+0xa4
#3 0xffffffff8261653b at os_acquire_spinlock+0x1b
#4 0xffffffff823bacc5 at _nv012401rm+0xd75


and from /var/log/messages I see:
...
May 30 11:29:31 g1-252 kernel: iwn0: iwn_read_firmware: ucode rev=0x08530501
May 30 11:29:31 g1-252 kernel: wlan0: link state changed to UP
May 30 11:29:31 g1-252 kernel: battery1: battery initialization failed, giving up
May 30 11:29:31 g1-252 root: /etc/rc: WARNING: Unable to load kernel module linux64
May 30 11:29:31 g1-252 kernel: KLD linux64.ko: depends on kernel - not available or version mismatch
May 30 11:29:31 g1-252 kernel: linker_load_file: Unsupported file type
May 30 11:29:32 g1-252 dbus[165]: [system] Activating service name='org.freedesktop.ConsoleKit' (using servicehelper)
May 30 11:29:32 g1-252 dbus[165]: [system] Activating service name='org.freedesktop.PolicyKit1' (using servicehelper)
May 30 11:29:32 g1-252 dbus[165]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
May 30 11:29:32 g1-252 dbus[165]: [system] Successfully activated service 'org.freedesktop.ConsoleKit'
May 30 11:29:32 g1-252 console-kit-daemon[1100]: WARNING: kvm_getenvv failed: 
May 30 11:29:33 g1-252 kernel: KLD rtc.ko: depends on kernel - not available or version mismatch
May 30 11:29:33 g1-252 kernel: linker_load_file: Unsupported file type
May 30 11:29:33 g1-252 kernel: lock order reversal:
May 30 11:29:33 g1-252 kernel: 1st 0xfffffe05c44508e0 bufwait (bufwait) _at_ /usr/src/sys/kern/vfs_bio.c:3512
May 30 11:29:33 g1-252 kernel: 2nd 0xfffff80009369600 dirhash (dirhash) _at_ /usr/src/sys/ufs/ufs/ufs_dirhash.c:281
May 30 11:29:33 g1-252 kernel: stack backtrace:
May 30 11:29:33 g1-252 kernel: #0 0xffffffff80a66880 at witness_debugger+0x70
May 30 11:29:33 g1-252 kernel: #1 0xffffffff80a66774 at witness_checkorder+0xe54
May 30 11:29:33 g1-252 kernel: #2 0xffffffff80a0f9f2 at _sx_xlock+0x72
May 30 11:29:33 g1-252 kernel: #3 0xffffffff80cff017 at ufsdirhash_remove+0x37
May 30 11:29:33 g1-252 kernel: #4 0xffffffff80d021e7 at ufs_dirremove+0x127
May 30 11:29:33 g1-252 kernel: #5 0xffffffff80d0980e at ufs_rename+0x135e
May 30 11:29:33 g1-252 kernel: #6 0xffffffff80f49610 at VOP_RENAME_APV+0x100
May 30 11:29:33 g1-252 kernel: #7 0xffffffff80ad4428 at kern_renameat+0x4a8
May 30 11:29:33 g1-252 kernel: #8 0xffffffff821335b9 at filemon_wrapper_rename+0x19
May 30 11:29:33 g1-252 kernel: #9 0xffffffff80e01c06 at amd64_syscall+0x2f6
May 30 11:29:33 g1-252 kernel: #10 0xffffffff80de13ab at Xfast_syscall+0xfb
May 30 11:29:34 g1-252 console-kit-daemon[1100]: WARNING: Error waiting for native console 1 activation: Inappropriate ioctl for device
May 30 11:30:07 g1-252 console-kit-daemon[1100]: WARNING: Error waiting for native console 1 activation: Inappropriate ioctl for device
May 30 11:30:07 g1-252 console-kit-daemon[1100]: WARNING: Error waiting for native console 9 activation: Inappropriate ioctl for device
May 30 11:30:22 g1-252 dhclient: /etc/dhclient-enter-hooks invoked with reason TIMEOUT
May 30 11:30:22 g1-252 dhclient: Ignoring claimed TIMEOUT dhclient invocation
May 30 11:32:41 g1-252 kernel: KLD nvidia.ko: depends on kernel - not available or version mismatch
May 30 11:32:41 g1-252 kernel: linker_load_file: Unsupported file type

(I believe the whines at 11:32:41 were from my manual attempt to kldload
nvidia.ko, vs. the initial whines from the /boot/loader.conf specification.)

A bit more context:
* I run xdm(1) on the laptop.  Given the circumstances, that didn't
  work very well; I logged in on vty1 to poke around.
* As may be seen from the update history (cited above), I've been doing
  this sort of thing for "a while", generaly successfully.
* I mostly run stable/10 (also updated daily) on the laptop, and build
  the installed ports under stable/10 -- except for ports with kernel
  modules (such as nvidia.ko): those get rebuilt whenever I rebuild the
  kernel, as merely part of the build/install process.
* Other than the mere update from r300951 --> r300994, the only difference
  that comes to mind is that this is the first time I built the system
  with 'WITH_META_MODE=yes' specified in /etc/src-env.conf.  (And yes, it
  was quite noticably faster to build: thanks, Bryan!)

As a reality check:
g1-252(10.3-S)[11] sudo find /S4/boot -name nvidia\* -ls
Password:
240833    22720 -rwxr-xr-x    1 root                             wheel                            11592328 May 30 04:22 /S4/boot/modules/nvidia.ko
321843    22720 -rwxr-xr-x    1 root                             wheel                            11592328 May 29 04:45 /S4/boot/modules.old/nvidia.ko
244000    22720 -rwxr-xr-x    1 root                             wheel                            11573104 May 18  2015 /S4/boot/modules.save/nvidia.ko
g1-252(10.3-S)[12] 

I'm happy to provide more information (given a hint as to what would
be useful) or test.

Peace,
david
-- 
David H. Wolfskill				david_at_catwhisker.org
Those who would murder in the name of God or prophet are blasphemous cowards.

See http://www.catwhisker.org/~david/publickey.gpg for my public key.

Received on Mon May 30 2016 - 10:37:02 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:41:05 UTC