System hanging in acpi during shutdown

[Peter Jeremy]

This morning, I upgraded my main system to an up-to-date -STABLE
including acpi and it now hangs during shutdown after reporting:
Waiting (max 60 seconds) for system process `vnlru' to stop...stopped

If I set hw.acpi.disable_on_poweroff=0 then the shutdown completes but
poweroff fails with (hand copied):
ACPI-1287: ***Error: Method execution failed [\_PTS] (Node 0xc21ed450), AE_AML_REGION_LIMIT
AcpiEnterSleepStatePrep failed - AE_AML_REGION_LIMIT

Does anyone have any suggestions other than either removing acpi?

Forcing a crashdump during the hang gives me:
#9  0xc02995ec in Debugger (msg=0xc02fa009 "manual escape to debugger")
    at machine/cpufunc.h:67
#10 0xc0293f9a in scgetc (sc=0xc03669c0, flags=2)
    at /usr/src/sys/dev/syscons/syscons.c:3164
#11 0xc0290d35 in sckbdevent (thiskbd=0xc035f2e0, event=0, arg=0xc03669c0)
    at /usr/src/sys/dev/syscons/syscons.c:617
#12 0xc028791a in atkbd_intr (kbd=0xc035f2e0, arg=0x0)
    at /usr/src/sys/dev/kbd/atkbd.c:464
#13 0xc02af378 in atkbd_isa_intr (arg=0xc035f2e0)
    at /usr/src/sys/isa/atkbd_isa.c:140
#14 0xc029b560 in doreti ()
#15 0xc0140e44 in AcpiHwSetMode (Mode=2)
    at /usr/src/sys/contrib/dev/acpica/hwacpi.c:233
#16 0xc013baf9 in AcpiDisable ()
    at /usr/src/sys/contrib/dev/acpica/evxfevnt.c:212
#17 0xc01507d6 in acpi_Disable (sc=0xc21f4f80)
    at /usr/src/sys/dev/acpica/acpi.c:1468
#18 0xc014fe86 in acpi_shutdown_pre_sync (arg=0xc21f4f80, howto=0)
    at /usr/src/sys/dev/acpica/acpi.c:917
#19 0xc018bf48 in boot (howto=0) at /usr/src/sys/kern/kern_shutdown.c:223
#20 0xc018bd98 in reboot (p=0xdf2fe2a0, uap=0xe1c42f80)
    at /usr/src/sys/kern/kern_shutdown.c:149

Frame #15 suggests that the code is in AcpiOsWritePort() when I
entered the debugger and this agrees with the results from playing
around in DDB during another shutdown attempt (which reported that
the code was blocked in bus_space_write_1() - which is an 'outb'.

This is a Gigabyte GA-7VRXP motherboard and dmesg reports:

FreeBSD 4.9-PRERELEASE #7: Wed Sep  3 21:21:26 EST 2003
root at server.c20257.belrs2.nsw.optusnet.com.au:/home/obj/usr/src/sys/server
Timecounter "i8254"  frequency 1193182 Hz
Timecounter "TSC"  frequency 1533395254 Hz
CPU: AMD Athlon(tm) XP 1800+ (1533.40-MHz 686-class CPU)
Origin = "AuthenticAMD"  Id = 0x662  Stepping = 2
Features=0x383fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE>
AMD Features=0xc0480000<MP,AMIE,DSP,3DNow!>
real memory  = 536805376 (524224K bytes)
avail memory = 518373376 (506224K bytes)
Preloaded elf kernel "kernel" at 0xc03dc000.
Pentium Pro MTRR support enabled
Using $PIR table, 12 entries at 0xc00f7b10
acpi0: <AMIINT AMIINI09> on motherboard
acpi0: power button is handled as a fixed feature programming model.
Timecounter "ACPI-fast"  frequency 3579545 Hz
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x808-0x80b on acpi0
acpi_cpu0: <CPU> on acpi0
acpi_button0: <Power Button> on acpi0
npx0: <math processor> on motherboard
npx0: INT 16 interface
pcib0: <Host to PCI bridge> on motherboard
pci0: <PCI bus> on pcib0
agp0: <VIA Generic host to PCI bridge> mem 0xe0000000-0xe7ffffff at device 0.0 on pci0
pcib1: <PCI to PCI bridge (vendor=1106 device=b099)> at device 1.0 on pci0
pci1: <PCI bus> on pcib1
drm0: <Matrox G550 (AGP)> mem 0xdf000000-0xdf7fffff,0xdfefc000-0xdfefffff,0xdc000000-0xddffffff irq 10 at device 0.0 on pci1
info: [drm] AGP at 0xe0000000 128MB
info: [drm] Initialized mga 3.1.0 20021029 on minor 0
pcm0: <Creative CT5880-E> port 0xec00-0xec3f irq 10 at device 8.0 on pci0
pcm0: <SigmaTel STAC9721/23 AC97 Codec>
puc0: <Oxford Semiconductor OX16PCI954 Dual UART> port 0xe400-0xe41f,0xe800-0xe81f mem 0xdfffe000-0xdfffefff,0xdffff000-0xdfffffff irq 10 at device 11.0 on pci0
sio2: type 16550A
sio3: type 16550A
sio4: type 16550A
sio5: type 16550A
pci0: <unknown card> (vendor=0x1415, dev=0x9500) at 11.1 irq 10
viapropm0: SMBus I/O base at 0x400
viapropm0: <VIA VT8233 Power Management Unit> port 0x400-0x40f at device 17.0 on pci0
viapropm0: could not allocate bus space
device_probe_and_attach: viapropm0 attach returned 6
atapci0: <VIA 8233 ATA133 controller> port 0xfc00-0xfc0f at device 17.1 on pci0
ata0: at 0x1f0 irq 14 on atapci0
ata1: at 0x170 irq 15 on atapci0
uhci0: <VIA 83C572 USB controller> port 0xd400-0xd41f irq 10 at device 17.2 on pci0
usb0: <VIA 83C572 USB controller> on uhci0
usb0: USB revision 1.0
uhub0: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
uhci1: <VIA 83C572 USB controller> port 0xd000-0xd01f irq 10 at device 17.3 on pci0
usb1: <VIA 83C572 USB controller> on uhci1
usb1: USB revision 1.0
uhub1: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
rl0: <RealTek 8139 10/100BaseTX> port 0xcc00-0xccff mem 0xdfffcf00-0xdfffcfff irq 10 at device 19.0 on pci0
rl0: Ethernet address: 00:20:ed:31:f3:b5
miibus0: <MII bus> on rl0
rlphy0: <RealTek internal media interface> on miibus0
rlphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
uhci2: <VIA 83C572 USB controller> port 0xc800-0xc81f irq 10 at device 20.0 on pci0
usb2: <VIA 83C572 USB controller> on uhci2
usb2: USB revision 1.0
uhub2: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 2 ports with 2 removable, self powered
uhci3: <VIA 83C572 USB controller> port 0xc400-0xc41f irq 10 at device 20.1 on pci0
usb3: <VIA 83C572 USB controller> on uhci3
usb3: USB revision 1.0
uhub3: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub3: 2 ports with 2 removable, self powered
pci0: <USB controller> at 20.2 irq 10
isa0: <ISA bus> on motherboard
orm0: <Option ROM> at iomem 0xc0000-0xc8fff on isa0
pmtimer0 on isa0
fdc0: cannot reserve I/O port range
atkbdc0: <Keyboard controller (i8042)> at port 0x60,0x64 on isa0
atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0
kbd0 at atkbd0
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: model IntelliMouse Explorer, device ID 4
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0
sio0: type 16550A
sio1 at port 0x2f8-0x2ff irq 3 on isa0
sio1: type 16550A
ppc0: <Parallel port> at port 0x378-0x37f irq 7 on isa0
ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/16 bytes threshold
ppbus0: IEEE1284 device found 
Probing for PnP devices on ppbus0:
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
acpi_cpu: throttling enabled, 16 steps (100% to 6.2%), currently 100.0%
ad0: 95396MB <WDC WD1000BB-00CAA0> [193821/16/63] at ata0-master UDMA100
ad1: 95396MB <WDC WD1000BB-00CAA0> [193821/16/63] at ata0-slave UDMA100
acd0: CD-RW <SAMSUNG CDRW/DVD SM-316B> at ata1-master UDMA33

The acpi sysctl's are (all default):
debug.acpi_debug_layer: 16383
debug.acpi_debug_level: 15
debug.acpi_ca_version: 537068072
debug.acpi_semaphore_debug: 0
hw.acpi.supported_sleep_state: S1 S4 S5 
hw.acpi.power_button_state: S5
hw.acpi.sleep_button_state: S1
hw.acpi.lid_switch_state: S1
hw.acpi.standby_state: S1
hw.acpi.suspend_state: S3
hw.acpi.sleep_delay: 0
hw.acpi.s4bios: 1
hw.acpi.verbose: 0
hw.acpi.disable_on_poweroff: 1
hw.acpi.cpu.max_speed: 16
hw.acpi.cpu.current_speed: 16
hw.acpi.cpu.performance_speed: 16
hw.acpi.cpu.economy_speed: 8
machdep.acpi_timer_freq: 3579545
machdep.acpi_root: 1025120

Peter