Philip Guenther writes:
> On Thu, 7 Jun 2018, Solene Rapenne wrote: >> >Synopsis: booting after hibernating loads and reboot > ... >> Details : OpenBSD 6.3-current (GENERIC.MP) #84: Tue Jun 5 19:22:09 >> MDT 2018 >> >> dera...@amd64.openbsd.org:/usr/src/sys/arch/amd64/compile/GENERIC.MP > ... >> When using ZZZ to hibernate, I can see that it writes to disk as >> usual, then the computer shut down. Everything is alright. At >> boot, it uses /bsd.booted, the console display the usual loading >> screen, and when it comes to the line looking like >> "unhibernate @block" (it displays too fast), then the screen >> goes black and after a few second, the computer reboot. > > Can you find and report the "OpenBSD 6.etc" log line in /var/log/messages* > from the previous kernel where hibernate + resume worked correctly? > That'll narrow down when the regression occurred. > > Depending on how long a range of time+builds that is, there are various > strategies for identifying the source of the failure... > > > Philip Guenther The previous kernel was OpenBSD 6.3-current (GENERIC.MP) #43: Mon May 21 16:30:33 MDT 2018 but I found this in /var/log/messages, for each reboot after unhibernate Jun 7 17:21:51 t400 syslogd[90658]: start Jun 7 17:21:51 t400 /bsd: OpenBSD 6.3-current (GENERIC.MP) #84: Tue Jun 5 19:22:09 MDT 2018 Jun 7 17:21:51 t400 /bsd: dera...@amd64.openbsd.org:/usr/src/sys/arch/amd64/compile/GENERIC.MP Jun 7 17:21:51 t400 /bsd: real mem = 4168814592 (3975MB) Jun 7 17:21:51 t400 /bsd: avail mem = 3995996160 (3810MB) Jun 7 17:21:51 t400 /bsd: mpath0 at root Jun 7 17:21:51 t400 /bsd: scsibus0 at mpath0: 256 targets Jun 7 17:21:51 t400 /bsd: mainbus0 at root Jun 7 17:21:51 t400 /bsd: bios0 at mainbus0: SMBIOS rev. 2.4 @ 0xe0010 (74 entries) Jun 7 17:21:51 t400 /bsd: bios0: vendor LENOVO version "7UET66WW (2.16 )" date 04/22/2009 Jun 7 17:21:51 t400 /bsd: bios0: LENOVO 2768V8S Jun 7 17:21:51 t400 /bsd: acpi0 at bios0: rev 2 Jun 7 17:21:51 t400 /bsd: acpi0: sleep states S0 S3 S4 S5 Jun 7 17:21:51 t400 /bsd: acpi0: tables DSDT FACP SSDT ECDT APIC MCFG HPET BOOT ASF! SSDT TCPA SSDT SSDT SSDT Jun 7 17:21:51 t400 /bsd: acpi0: wakeup devices LID_(S3) SLPB(S3) IGBE(S4) EXP0(S4) EXP1(S4) EXP2(S4) EXP3(S4) EXP4(S4) PCI1(S4) USB0(S3) USB3(S3) USB5(S3) EHC0(S3) EHC1(S3) HDEF(S4) Jun 7 17:21:51 t400 /bsd: acpitimer0 at acpi0: 3579545 Hz, 24 bits Jun 7 17:21:51 t400 /bsd: acpiec0 at acpi0 Jun 7 17:21:51 t400 /bsd: acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat Jun 7 17:21:51 t400 /bsd: cpu0 at mainbus0: apid 0 (boot processor) Jun 7 17:21:51 t400 /bsd: cpu0: Intel(R) Core(TM)2 Duo CPU T9400 @ 2.53GHz, 2527.50 MHz Jun 7 17:21:51 t400 /bsd: cpu0: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUSH,DS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE,SSE3,DTES64,MWAIT,DS-CPL,VMX,SMX,EST,TM2,SSSE3,CX16,xTPR,PDCM,SSE4.1,XSAVE,NXE,LONG,LAHF,PERF,SENSOR,MELTDOWN Jun 7 17:21:51 t400 /bsd: cpu0: 6MB 64b/line 16-way L2 cache Jun 7 17:21:51 t400 /bsd: cpu0: smt 0, core 0, package 0 Jun 7 17:21:51 t400 /bsd: mtrr: Pentium Pro MTRR support, 7 var ranges, 88 fixed ranges Jun 7 17:21:51 t400 /bsd: using xsave Jun 7 17:21:51 t400 /bsd: cpu0: apic clock running at 265MHz Jun 7 17:21:51 t400 /bsd: cpu0: mwait min=64, max=64, C-substates=0.2.2.2.2.1.3, IBE Jun 7 17:21:51 t400 /bsd: cpu1 at mainbus0: apid 1 (application processor) Jun 7 17:21:51 t400 sendsyslog: dropped 1 message, error 57, pid 23145 Jun 7 17:21:51 t400 unbound: [38185:0] notice: init module 0: validator Jun 7 17:21:51 t400 unbound: [38185:0] notice: init module 1: iterator Jun 7 17:21:52 t400 savecore: no core dump Jun 7 17:21:54 t400 apmd: battery status: high. external power status: connected. estimated battery life 100% Jun 7 17:21:59 t400 /bsd: lock order reversal: Jun 7 17:21:59 t400 /bsd: 1st 0xffffff011adb9460 vmmaplk (&map->lock) @ /usr/src/sys/uvm/uvm_fault.c:1441 Jun 7 17:21:59 t400 /bsd: 2nd 0xffff800000104138 drmdevlk (&dev->struct_mutex) @ /usr/src/sys/dev/pci/drm/i915/i915_gem.c:1801 Jun 7 17:21:59 t400 /bsd: lock order "&dev->struct_mutex"(rwlock) -> "&map->lock"(rwlock) first seen at: Jun 7 17:21:59 t400 /bsd: #0 witness_checkorder+0x4b4 Jun 7 17:21:59 t400 /bsd: #1 _rw_enter+0x68 Jun 7 17:21:59 t400 /bsd: #2 vm_map_lock_ln+0xbc Jun 7 17:21:59 t400 /bsd: #3 uvm_map+0x1a1 Jun 7 17:21:59 t400 /bsd: #4 km_alloc+0x16a Jun 7 17:21:59 t400 /bsd: #5 bus_space_map+0x159 Jun 7 17:21:59 t400 /bsd: #6 i965_alloc_ifp+0xc1 Jun 7 17:21:59 t400 /bsd: #7 intel_gtt_chipset_setup+0x1b1 Jun 7 17:21:59 t400 /bsd: #8 intel_enable_gtt+0x26 Jun 7 17:21:59 t400 /bsd: #9 i915_gem_init_hw+0x43 Jun 7 17:21:59 t400 /bsd: #10 i915_gem_init+0x24e Jun 7 17:21:59 t400 /bsd: #11 i915_driver_load+0xfc1 Jun 7 17:21:59 t400 /bsd: #12 inteldrm_attach+0x37f Jun 7 17:21:59 t400 /bsd: #13 config_attach+0x20e Jun 7 17:21:59 t400 /bsd: #14 pci_probe_device+0x501 Jun 7 17:21:59 t400 /bsd: #15 pci_enumerate_bus+0xb7 Jun 7 17:21:59 t400 /bsd: #16 config_attach+0x20e Jun 7 17:21:59 t400 /bsd: #17 mainbus_attach+0x24a Jun 7 17:21:59 t400 /bsd: #18 config_attach+0x20e Jun 7 17:21:59 t400 /bsd: lock order "&map->lock"(rwlock) -> "&dev->struct_mutex"(rwlock) first seen at: Jun 7 17:21:59 t400 /bsd: #0 witness_checkorder+0x4b4 Jun 7 17:21:59 t400 /bsd: #1 _rw_enter_write+0x53 Jun 7 17:21:59 t400 /bsd: #2 i915_gem_object_wait_rendering__nonblocking+0x1fa Jun 7 17:21:59 t400 /bsd: #3 i915_gem_fault+0x144 Jun 7 17:21:59 t400 /bsd: #4 drm_fault+0x18a Jun 7 17:21:59 t400 /bsd: #5 uvm_fault+0x743 Jun 7 17:21:59 t400 /bsd: #6 trap+0x516 Jun 7 17:21:59 t400 /bsd: #7 recall_trap+0x8 Jun 7 17:21:59 t400 /bsd: lock order reversal: Jun 7 17:21:59 t400 /bsd: 1st 0xffff800000101270 &dev_priv->irq_lock (&dev_priv->irq_lock) @ /usr/src/sys/dev/pci/drm/i915/intel_ringbuffer.c:1654 Jun 7 17:21:59 t400 /bsd: 2nd 0xffffffff81dc8538 &sched_lock (&sched_lock) @ /usr/src/sys/kern/sched_bsd.c:551 Jun 7 17:21:59 t400 /bsd: lock order "&sched_lock"(sched_lock) -> "&dev_priv->irq_lock"(mutex) first seen at: Jun 7 17:21:59 t400 /bsd: #0 witness_checkorder+0x4b4 Jun 7 17:21:59 t400 /bsd: #1 _mtx_enter+0x31 Jun 7 17:21:59 t400 /bsd: #2 i9xx_ring_put_irq+0x35 Jun 7 17:21:59 t400 /bsd: #3 __i915_wait_request+0x367 Jun 7 17:21:59 t400 /bsd: #4 i915_gem_object_wait_rendering__nonblocking+0x1d6 Jun 7 17:21:59 t400 /bsd: #5 i915_gem_fault+0x144 Jun 7 17:21:59 t400 /bsd: #6 drm_fault+0x18a Jun 7 17:21:59 t400 /bsd: #7 uvm_fault+0x743 Jun 7 17:21:59 t400 /bsd: #8 trap+0x516 Jun 7 17:21:59 t400 /bsd: #9 recall_trap+0x8 Jun 7 17:21:59 t400 /bsd: lock order "&dev_priv->irq_lock"(mutex) -> "&sched_lock"(sched_lock) first seen at: Jun 7 17:21:59 t400 /bsd: #0 witness_checkorder+0x4b4 Jun 7 17:21:59 t400 /bsd: #1 ___mp_lock+0x70 Jun 7 17:21:59 t400 /bsd: #2 schedclock+0x30 Jun 7 17:21:59 t400 /bsd: #3 hardclock+0xe3 Jun 7 17:21:59 t400 /bsd: #4 lapic_clockintr+0x3d Jun 7 17:21:59 t400 /bsd: #5 Xresume_lapic_ltimer+0x22 Jun 7 17:21:59 t400 /bsd: #6 __llvm_retpoline_r11+0x10 Jun 7 17:21:59 t400 /bsd: #7 __i915_wait_request+0x1a8 Jun 7 17:21:59 t400 /bsd: #8 i915_gem_object_wait_rendering__nonblocking+0x1d6 Jun 7 17:21:59 t400 /bsd: #9 i915_gem_fault+0x144 Jun 7 17:21:59 t400 /bsd: #10 drm_fault+0x18a Jun 7 17:21:59 t400 /bsd: #11 uvm_fault+0x743 Jun 7 17:21:59 t400 /bsd: #12 trap+0x516 Jun 7 17:21:59 t400 /bsd: #13 recall_trap+0x8
