Results for igt@gem_exec_suspend@basic-s3@smem

Result: Pass

git-log-oneline igt_runner0 run0 runtimes0 results0.json boot0 dmesg0

DetailValue
Igt-Version 
IGT-Version: 1.27.1-g605d12880 (x86_64) (Linux: 6.6.0-rc1-linus-v6.6-rc1-g0bb80ecc33a8+ x86_64)
Out 
Opened device: /dev/dri/card0
Starting subtest: basic-S3
Starting dynamic subtest: smem
[cmd] rtcwake: wakeup from "mem" using /dev/rtc0 at Mon Sep 11 00:00:56 2023
Dynamic subtest smem: SUCCESS (6.332s)
Subtest basic-S3: SUCCESS (6.338s)
Err 
Starting subtest: basic-S3
Starting dynamic subtest: smem
Dynamic subtest smem: SUCCESS (6.332s)
Subtest basic-S3: SUCCESS (6.338s)
Dmesg
<6> [545.819003] Console: switching to colour dummy device 80x25
<6> [545.821169] [IGT] gem_exec_suspend: executing
<7> [545.844408] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [545.848712] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [545.850768] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [545.853328] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [545.914235] [IGT] gem_exec_suspend: starting subtest basic-S3
<6> [545.916792] [IGT] gem_exec_suspend: starting dynamic subtest smem
<7> [545.925136] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [547.508478] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [547.523498] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [549.442159] PM: suspend entry (deep)
<6> [549.449289] Filesystems sync: 0.007 seconds
<6> [549.453462] Freezing user space processes
<6> [549.462923] Freezing user space processes completed (elapsed 0.009 seconds)
<6> [549.462968] OOM killer disabled.
<6> [549.462983] Freezing remaining freezable tasks
<6> [549.466520] Freezing remaining freezable tasks completed (elapsed 0.003 seconds)
<5> [549.496303] sd 0:0:0:0: [sda] Synchronizing SCSI cache
<5> [549.496794] sd 0:0:0:0: [sda] Stopping disk
<6> [549.501535] serial 00:06: disabled
<6> [549.503705] serial 00:05: disabled
<6> [549.505030] r8169 0000:01:00.0 enp1s0: Link is Down
<7> [549.509383] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.enable (expected 1, found 0)
<7> [549.510818] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.active (expected 1, found 0)
<7> [549.512068] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [549.514523] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [549.515817] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [549.516929] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1024, found 0)
<7> [549.517985] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 1344, found 0)
<7> [549.519083] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1024, found 0)
<7> [549.520121] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 1344, found 0)
<7> [549.521144] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1048, found 0)
<7> [549.522188] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 1184, found 0)
<7> [549.523208] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 768, found 0)
<7> [549.524228] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 806, found 0)
<7> [549.525249] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 768, found 0)
<7> [549.526268] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 806, found 0)
<7> [549.527288] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 771, found 0)
<7> [549.528311] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 777, found 0)
<7> [549.529334] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1024, found 0)
<7> [549.530400] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 1344, found 0)
<7> [549.531558] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1024, found 0)
<7> [549.532835] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 1344, found 0)
<7> [549.533899] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1048, found 0)
<7> [549.534923] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 1184, found 0)
<7> [549.535943] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 768, found 0)
<7> [549.536978] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 806, found 0)
<7> [549.537996] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 768, found 0)
<7> [549.539020] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 806, found 0)
<7> [549.540043] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 771, found 0)
<7> [549.541065] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 777, found 0)
<7> [549.542140] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [549.543156] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 2, found 0)
<7> [549.544182] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [549.545208] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 64960, found 0)
<7> [549.546230] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 64960, found 0)
<7> [549.547252] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in port_clock (expected 64960, found 0)
<7> [549.548277] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] fastset requirement not met, forcing full modeset
<7> [549.549421] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 333333 kHz, actual 333333 kHz
<7> [549.550422] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [549.551494] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:37:pipe A] enable: no [modeset]
<7> [549.552538] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:31:plane A] fb: [NOFB], visible: no
<7> [549.553967] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is disabled (was enabled)
<7> [549.558966] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [549.587799] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is disabled (was disabled)
<7> [549.588826] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:46:CRT]
<7> [549.589895] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:45:VGA-1]
<7> [549.591067] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:37:pipe A]
<7> [549.796878] PM: suspend of devices complete after 315.327 msecs
<7> [549.796916] PM: start suspend of devices complete after 329.588 msecs
<7> [549.810927] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [549.824788] PM: late suspend of devices complete after 27.845 msecs
<7> [549.856066] PM: noirq suspend of devices complete after 29.461 msecs
<6> [549.856100] ACPI: PM: Preparing to enter system sleep state S3
<6> [549.883935] ACPI: PM: Saving platform NVS memory
<6> [549.895999] Disabling non-boot CPUs ...
<6> [549.908767] smpboot: CPU 1 is now offline
<6> [549.924025] smpboot: CPU 2 is now offline
<6> [549.944541] smpboot: CPU 3 is now offline
<7> [549.955256] Checking wakeup interrupts
<7> [549.955281] Calling mce_syscore_suspend+0x0/0x10
<7> [549.955309] Calling timekeeping_suspend+0x0/0x320
<7> [549.955381] Calling save_ioapic_entries+0x0/0xa0
<7> [549.955522] Calling i8259A_suspend+0x0/0x30
<7> [549.955542] Calling fw_suspend+0x0/0x20
<7> [549.955560] Calling acpi_save_bm_rld+0x0/0x20
<7> [549.955581] Calling lapic_suspend+0x0/0x180
<6> [549.955581] ACPI: PM: Low-level resume complete
<6> [549.955581] ACPI: PM: Restoring platform NVS memory
<7> [549.955581] Calling lapic_resume+0x0/0x280
<7> [549.955581] Calling acpi_restore_bm_rld+0x0/0x70
<7> [549.955581] Calling irqrouter_resume+0x0/0x50
<7> [549.955581] Calling i8259A_resume+0x0/0x30
<7> [549.955581] Calling i8237A_resume+0x0/0xa0
<7> [549.955581] Calling ioapic_resume+0x0/0xb0
<7> [549.955581] Calling irq_pm_syscore_resume+0x0/0x10
<7> [549.955581] Calling timekeeping_resume+0x0/0x1c0
<7> [549.955581] Timekeeping suspended for 16.142 seconds
<7> [549.955581] Calling mce_syscore_resume+0x0/0x30
<7> [549.955581] Calling microcode_bsp_resume+0x0/0xc0
<6> [549.955581] Enabling non-boot CPUs ...
<6> [549.956790] smpboot: Booting Node 0 Processor 1 APIC 0x1
<6> [549.968472] CPU1 is up
<6> [549.969306] smpboot: Booting Node 0 Processor 2 APIC 0x2
<6> [549.983152] CPU2 is up
<6> [549.984044] smpboot: Booting Node 0 Processor 3 APIC 0x3
<6> [549.995447] CPU3 is up
<6> [550.006599] ACPI: PM: Waking up from system sleep state S3
<7> [550.040821] PM: noirq resume of devices complete after 12.269 msecs
<7> [550.048860] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [550.052967] PM: early resume of devices complete after 10.415 msecs
<5> [550.060909] usb usb1: root hub lost power or was reset
<7> [550.060414] i915 0000:00:02.0: [drm:intel_vga_redisable_power_on [i915]] Something enabled VGA plane, disabling it
<7> [550.063110] i915 0000:00:02.0: [drm:intel_cdclk_dump_config [i915]] Current CDCLK 333333 kHz, VCO 0 kHz, ref 0 kHz, bypass 0 kHz, voltage level 0
<7> [550.064770] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:37:pipe A] hw state readout: disabled
<7> [550.066170] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:44:pipe B] hw state readout: disabled
<7> [550.067536] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:31:plane A] hw state readout: disabled, pipe A
<5> [550.069226] usb usb4: root hub lost power or was reset
<7> [550.068869] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:34:cursor A] hw state readout: disabled, pipe A
<7> [550.070275] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:38:plane B] hw state readout: disabled, pipe A
<7> [550.071705] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:41:cursor B] hw state readout: disabled, pipe A
<7> [550.073077] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:46:CRT] hw state readout: disabled, pipe A
<7> [550.074420] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:45:VGA-1] hw state readout: disabled
<7> [550.075746] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:31:plane A] min_cdclk 0 kHz
<7> [550.076992] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:34:cursor A] min_cdclk 0 kHz
<7> [550.078238] i915 0000:00:02.0: [drm:intel_bw_crtc_update [i915]] pipe A data rate 0 num active planes 0
<7> [550.079513] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:38:plane B] min_cdclk 0 kHz
<7> [550.080751] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:41:cursor B] min_cdclk 0 kHz
<7> [550.081846] i915 0000:00:02.0: [drm:intel_bw_crtc_update [i915]] pipe B data rate 0 num active planes 0
<7> [550.083002] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:37:pipe A] enable: no [setup_hw_state]
<5> [550.084597] usb usb5: root hub lost power or was reset
<7> [550.084164] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:44:pipe B] enable: no [setup_hw_state]
<7> [550.087383] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:37:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [550.088869] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.enable (expected 0, found 1)
<7> [550.090158] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.active (expected 0, found 1)
<7> [550.091443] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [550.092787] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [550.094050] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1024)
<7> [550.095320] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 1344)
<7> [550.096527] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1024)
<7> [550.097560] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 1344)
<7> [550.098784] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1048)
<7> [550.100047] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 1184)
<7> [550.101329] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 768)
<7> [550.102605] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 806)
<7> [550.103871] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 768)
<7> [550.105158] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 806)
<7> [550.106442] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 771)
<6> [550.107792] serial 00:05: activated
<5> [550.107927] usb usb3: root hub lost power or was reset
<7> [550.107739] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 777)
<7> [550.109042] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1024)
<7> [550.110322] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 1344)
<7> [550.111601] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1024)
<7> [550.112860] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 1344)
<7> [550.114145] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1048)
<7> [550.115510] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 1184)
<7> [550.116817] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 768)
<7> [550.118099] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 806)
<7> [550.119366] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 768)
<7> [550.120503] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 806)
<7> [550.121540] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 771)
<7> [550.122743] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 777)
<7> [550.124043] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [550.125326] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.flags (2) (expected 0, found 2)
<7> [550.126608] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [550.127944] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 64960)
<7> [550.129283] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 64960)
<7> [550.130576] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in port_clock (expected 0, found 64960)
<7> [550.131832] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] fastset requirement not met, forcing full modeset
<7> [550.133098] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:44:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [550.134392] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] fastset requirement not met, forcing full modeset
<7> [550.135870] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:31:plane A] min cdclk (64960 kHz) > [CRTC:37:pipe A] min cdclk (0 kHz)
<7> [550.137166] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 333333 kHz, actual 333333 kHz
<7> [550.138437] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [550.139789] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:37:pipe A] enable: yes [modeset]
<7> [550.141070] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [550.142335] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<6> [550.143728] serial 00:06: activated
<7> [550.143596] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [550.144841] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [550.146221] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bigjoiner: no, pipes: 0x0
<7> [550.147497] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [550.148767] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [550.149750] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [550.150733] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] vrr: no, vmin: 0, vmax: 0, pipeline full: 0, guardband: 0 flipline: 0, vmin vblank: -1, vmax vblank: -2
<7> [550.151719] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [550.152705] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1024x768": 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [550.153602] i915 0000:00:02.0: [drm:intel_dump_crtc_timings [i915]] crtc timings: clock=64960, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [550.154587] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1024x768": 60 64960 1024 1048 1184 1344 768 771 777 806 0x40 0xa
<7> [550.155575] i915 0000:00:02.0: [drm:intel_dump_crtc_timings [i915]] crtc timings: clock=64960, hd=1024 hb=1024-1344 hs=1048-1184 ht=1344, vd=768 vb=768-806 vs=771-777 vt=806, flags=0xa
<7> [550.156563] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 64960, pipe src: 1024x768+0+0, pixel rate 64960
<7> [550.157546] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [550.158524] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] gmch pfit: control: 0x00000000, ratios: 0x00000000, lvds border: 0x00000000
<7> [550.159505] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [550.160484] i915 0000:00:02.0: [drm:intel_dpll_dump_hw_state [i915]] dpll_hw_state: dpll: 0x94080000, dpll_md: 0x0, fp0: 0x4000c9, fp1: 0x4000c9
<7> [550.161459] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [550.162435] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [550.163418] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:31:plane A] fb: [FB:47] 1024x768 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [550.164408] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [550.165388] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1024.000000x768.000000+0.000000+0.000000 dst: 1024x768+0+0
<7> [550.166376] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:cursor A] fb: [NOFB], visible: no
<7> [550.167361] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:44:pipe B] enable: no [modeset]
<7> [550.168346] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:38:plane B] fb: [NOFB], visible: no
<7> [550.169329] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:cursor B] fb: [NOFB], visible: no
<7> [550.170981] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:46:CRT]
<7> [550.172674] [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 24
<7> [550.173543] [drm:intel_calculate_wm [i915]] FIFO watermark level: 488
<7> [550.174501] i915 0000:00:02.0: [drm:pnv_update_wm [i915]] DSPFW1 register is f4030f0f
<7> [550.175458] [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 21
<7> [550.176407] [drm:intel_calculate_wm [i915]] FIFO watermark level: 491
<7> [550.177363] [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 146
<7> [550.178317] [drm:intel_calculate_wm [i915]] FIFO watermark level: 366
<7> [550.179273] [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 143
<7> [550.180228] [drm:intel_calculate_wm [i915]] FIFO watermark level: 369
<7> [550.181187] i915 0000:00:02.0: [drm:pnv_update_wm [i915]] DSPFW3 register is 3f3f016e
<7> [550.182156] i915 0000:00:02.0: [drm:_intel_set_memory_cxsr [i915]] memory self-refresh is enabled (was disabled)
<7> [550.183120] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [550.201063] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:45:VGA-1]
<7> [550.202137] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:37:pipe A]
<7> [550.203259] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:44:pipe B]
<7> [550.205180] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:45:VGA-1] force=0
<7> [550.206326] i915 0000:00:02.0: [drm:intel_opregion_resume [i915]] 1 outputs detected
<7> [550.218059] i915 0000:00:02.0: [drm:intel_get_hpd_pins [i915]] hotplug event received, stat 0x00000800, dig 0x00000800, pins 0x00000002, long 0x00000000
<7> [550.218940] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 1 - cnt: 10
<7> [550.219819] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7> [550.220880] i915 0000:00:02.0: [drm:check_connector_changed] [CONNECTOR:45:VGA-1] Same epoch counter 1
<7> [550.220928] i915 0000:00:02.0: [drm:i915_hotplug_work_func [i915]] running encoder hotplug functions
<7> [550.221938] i915 0000:00:02.0: [drm:i915_hotplug_work_func [i915]] Connector VGA-1 (pin 1) received hotplug event. (retry 0)
<7> [550.222952] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:45:VGA-1] force=0
<7> [550.235402] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<6> [550.241591] r8169 0000:01:00.0 enp1s0: Link is Down
<7> [550.249374] PM: resume of devices complete after 196.369 msecs
<6> [550.254478] OOM killer enabled.
<6> [550.254507] Restarting tasks ...
<6> [550.256154] pci 0000:00:1e.0: PCI bridge to [bus 05]
<6> [550.266829] pci 0000:00:1e.0: PCI bridge to [bus 05]
<6> [550.274965] pci 0000:00:1e.0: PCI bridge to [bus 05]
<6> [550.282418] pci 0000:00:1e.0: PCI bridge to [bus 05]
<4> [550.284165] done.
<5> [550.284255] random: crng reseeded on system resumption
<6> [550.286046] PM: suspend exit
<6> [550.287571] pci 0000:00:1e.0: PCI bridge to [bus 05]
<7> [550.306987] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [550.344022] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [550.378767] ata2: SATA link down (SStatus 0 SControl 300)
<6> [550.379751] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
<6> [550.393719] ata1.00: configured for UDMA/133
<7> [552.240200] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [552.252098] [IGT] gem_exec_suspend: finished subtest smem, SUCCESS
<6> [552.254099] [IGT] gem_exec_suspend: finished subtest basic-S3, SUCCESS
<6> [552.395127] [IGT] gem_exec_suspend: exiting, ret=0
<7> [552.398908] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6> [552.436859] Console: switching to colour frame buffer device 128x48
Created at 2023-09-11 01:21:38