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-g34b68fb58 (x86_64) (Linux: 6.5.0-linus-v6.5-g2dde18cd1d8f+ 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 Sun Aug 27 22:21:13 2023
Dynamic subtest smem: SUCCESS (1.777s)
Subtest basic-S3: SUCCESS (1.778s)
Err 
Starting subtest: basic-S3
Starting dynamic subtest: smem
Dynamic subtest smem: SUCCESS (1.777s)
Subtest basic-S3: SUCCESS (1.778s)
Dmesg
<6> [293.972808] Console: switching to colour dummy device 80x25
<6> [293.973480] [IGT] gem_exec_suspend: executing
<7> [293.983223] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [293.984145] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [293.984659] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [293.985146] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [293.997117] [IGT] gem_exec_suspend: starting subtest basic-S3
<6> [293.997717] [IGT] gem_exec_suspend: starting dynamic subtest smem
<7> [293.999266] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [294.310428] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [294.315611] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [294.710247] PM: suspend entry (deep)
<6> [294.710601] Filesystems sync: 0.000 seconds
<6> [294.711829] Freezing user space processes
<6> [294.715257] Freezing user space processes completed (elapsed 0.003 seconds)
<6> [294.715273] OOM killer disabled.
<6> [294.715278] Freezing remaining freezable tasks
<6> [294.717056] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
<5> [294.762381] sd 0:0:0:0: [sda] Synchronizing SCSI cache
<5> [294.762462] sd 0:0:0:0: [sda] Stopping disk
<6> [294.764299] serial 00:02: disabled
<6> [294.764794] e1000e: EEE TX LPI TIMER: 00000000
<7> [294.765761] 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> [294.766195] 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> [294.766460] 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> [294.766724] 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> [294.766987] 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> [294.767263] 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 1920, found 0)
<7> [294.767527] 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 2080, found 0)
<7> [294.767790] 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 1920, found 0)
<7> [294.768135] 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 2080, found 0)
<7> [294.768425] 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 1968, found 0)
<7> [294.768688] 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 2000, found 0)
<7> [294.768949] 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 1200, found 0)
<7> [294.769224] 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 1235, found 0)
<7> [294.769487] 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 1200, found 0)
<7> [294.769749] 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 1235, found 0)
<7> [294.770010] 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 1203, found 0)
<7> [294.770289] 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 1209, found 0)
<7> [294.770552] 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 1920, found 0)
<7> [294.770813] 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 2080, found 0)
<7> [294.771074] 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 1920, found 0)
<7> [294.771404] 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 2080, found 0)
<7> [294.771667] 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 1968, found 0)
<7> [294.771929] 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 2000, found 0)
<7> [294.772208] 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 1200, found 0)
<7> [294.772470] 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 1235, found 0)
<7> [294.772732] 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 1200, found 0)
<7> [294.772993] 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 1235, found 0)
<7> [294.773270] 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 1203, found 0)
<7> [294.773532] 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 1209, found 0)
<7> [294.773794] 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> [294.774055] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [294.774334] 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> [294.774597] 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 153600, found 0)
<7> [294.774859] 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 153600, found 0)
<7> [294.775120] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in port_clock (expected 153600, found 0)
<7> [294.775417] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] fastset requirement not met, forcing full modeset
<7> [294.775723] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 333333 kHz, actual 333333 kHz
<7> [294.775988] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [294.776270] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:37:pipe A] enable: no [modeset]
<7> [294.776536] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:31:plane A] fb: [NOFB], visible: no
<7> [294.777004] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [294.791232] i915 0000:00:02.0: [drm:i9xx_get_fifo_size [i915]] FIFO size - (0x00001d9c) A: 28
<7> [294.791566] i915 0000:00:02.0: [drm:i9xx_get_fifo_size [i915]] FIFO size - (0x00001d9c) B: 31
<7> [294.791819] i915 0000:00:02.0: [drm:i9xx_update_wm [i915]] FIFO watermarks - A: 26, B: 29
<7> [294.792072] i915 0000:00:02.0: [drm:i9xx_update_wm [i915]] Setting FIFO watermarks - A: 26, B: 29, C: 2, SR 1
<7> [294.792345] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:46:CRT]
<7> [294.792614] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:45:VGA-1]
<7> [294.792940] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:37:pipe A]
<7> [294.909412] PM: suspend of devices complete after 185.923 msecs
<7> [294.909422] PM: start suspend of devices complete after 192.132 msecs
<7> [294.912663] i915 0000:00:02.0: [drm:intel_power_well_disable [i915]] disabling always-on
<7> [294.925227] PM: late suspend of devices complete after 15.797 msecs
<7> [294.941276] PM: noirq suspend of devices complete after 15.463 msecs
<6> [294.941288] ACPI: PM: Preparing to enter system sleep state S3
<6> [294.944820] ACPI: PM: Saving platform NVS memory
<6> [294.946875] Disabling non-boot CPUs ...
<6> [294.950455] smpboot: CPU 1 is now offline
<7> [294.951882] Checking wakeup interrupts
<7> [294.951892] Calling mce_syscore_suspend+0x0/0x10
<7> [294.951903] Calling timekeeping_suspend+0x0/0x320
<7> [294.951939] Calling save_ioapic_entries+0x0/0xa0
<7> [294.952009] Calling i8259A_suspend+0x0/0x30
<7> [294.952017] Calling fw_suspend+0x0/0x20
<7> [294.952023] Calling acpi_save_bm_rld+0x0/0x20
<7> [294.952031] Calling lapic_suspend+0x0/0x240
<6> [294.952734] ACPI: PM: Low-level resume complete
<6> [294.952767] ACPI: PM: Restoring platform NVS memory
<7> [294.952894] Calling lapic_resume+0x0/0x340
<7> [294.952918] Calling acpi_restore_bm_rld+0x0/0x70
<7> [294.952925] Calling irqrouter_resume+0x0/0x50
<7> [294.952932] Calling i8259A_resume+0x0/0x30
<7> [294.953066] Calling i8237A_resume+0x0/0xa0
<7> [294.953124] Calling ioapic_resume+0x0/0xb0
<7> [294.953157] Calling irq_pm_syscore_resume+0x0/0x10
<7> [294.953589] Calling timekeeping_resume+0x0/0x1c0
<7> [294.953623] Timekeeping suspended for 16.244 seconds
<7> [294.953655] Calling mce_syscore_resume+0x0/0x30
<6> [294.953709] Enabling non-boot CPUs ...
<6> [294.953960] smpboot: Booting Node 0 Processor 1 APIC 0x1
<6> [294.960673] CPU1 is up
<6> [294.963454] ACPI: PM: Waking up from system sleep state S3
<7> [294.988836] PM: noirq resume of devices complete after 3.082 msecs
<7> [294.990692] i915 0000:00:02.0: [drm:intel_power_well_enable [i915]] enabling always-on
<7> [294.992709] PM: early resume of devices complete after 3.267 msecs
<5> [294.995017] usb usb3: root hub lost power or was reset
<7> [294.996022] i915 0000:00:02.0: [drm:intel_vga_redisable_power_on [i915]] Something enabled VGA plane, disabling it
<7> [294.997000] i915 0000:00:02.0: [drm:intel_hpll_vco [i915]] HPLL VCO 4000000 kHz
<7> [294.997241] i915 0000:00:02.0: [drm:intel_cdclk_dump_config [i915]] Current CDCLK 333333 kHz, VCO 4000000 kHz, ref 0 kHz, bypass 0 kHz, voltage level 0
<7> [294.997504] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:37:pipe A] hw state readout: disabled
<7> [294.997752] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:44:pipe B] hw state readout: disabled
<5> [294.998154] usb usb4: root hub lost power or was reset
<5> [294.998315] usb usb6: root hub lost power or was reset
<5> [294.998454] usb usb7: root hub lost power or was reset
<7> [294.999821] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:31:plane A] hw state readout: disabled, pipe A
<7> [295.000097] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:34:cursor A] hw state readout: disabled, pipe A
<7> [295.000353] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:38:plane B] hw state readout: disabled, pipe A
<7> [295.000681] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:41:cursor B] hw state readout: disabled, pipe A
<7> [295.000965] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:46:CRT] hw state readout: disabled, pipe A
<5> [295.001278] usb usb5: root hub lost power or was reset
<7> [295.002193] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:45:VGA-1] hw state readout: disabled
<7> [295.002471] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:31:plane A] min_cdclk 0 kHz
<7> [295.002698] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:34:cursor A] min_cdclk 0 kHz
<7> [295.003338] i915 0000:00:02.0: [drm:intel_bw_crtc_update [i915]] pipe A data rate 0 num active planes 0
<7> [295.003599] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:38:plane B] min_cdclk 0 kHz
<7> [295.003873] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:41:cursor B] min_cdclk 0 kHz
<7> [295.004122] i915 0000:00:02.0: [drm:intel_bw_crtc_update [i915]] pipe B data rate 0 num active planes 0
<7> [295.004441] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:37:pipe A] enable: no [setup_hw_state]
<7> [295.004696] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:44:pipe B] enable: no [setup_hw_state]
<7> [295.005387] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:37:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [295.005652] 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> [295.006134] 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> [295.006371] 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> [295.006604] 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> [295.006836] 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 1920)
<7> [295.007284] 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 2080)
<7> [295.007518] 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 1920)
<7> [295.007750] 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 2080)
<7> [295.008086] 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 1968)
<7> [295.008338] 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 2000)
<7> [295.008570] 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 1200)
<7> [295.008803] 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 1235)
<7> [295.009048] 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 1200)
<7> [295.009280] 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 1235)
<7> [295.009512] 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 1203)
<7> [295.009744] 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 1209)
<7> [295.009987] 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 1920)
<7> [295.010220] 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 2080)
<7> [295.010452] 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 1920)
<7> [295.010684] 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 2080)
<7> [295.010954] 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 1968)
<7> [295.011204] 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 2000)
<7> [295.011437] 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 1200)
<7> [295.011668] 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 1235)
<7> [295.011912] 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 1200)
<7> [295.012144] 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 1235)
<7> [295.012376] 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 1203)
<7> [295.012608] 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 1209)
<7> [295.012841] 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> [295.013083] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [295.013316] 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> [295.013549] 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 153600)
<7> [295.013781] 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 153600)
<7> [295.014050] i915 0000:00:02.0: [drm:pipe_config_mismatch [i915]] [CRTC:37:pipe A] fastset requirement not met in port_clock (expected 0, found 153600)
<7> [295.014300] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] fastset requirement not met, forcing full modeset
<7> [295.014532] 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> [295.014766] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] fastset requirement not met, forcing full modeset
<7> [295.015042] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:31:plane A] min cdclk (153600 kHz) > [CRTC:37:pipe A] min cdclk (0 kHz)
<7> [295.015278] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 333333 kHz, actual 333333 kHz
<7> [295.015510] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [295.015744] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:37:pipe A] enable: yes [modeset]
<7> [295.015988] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [295.016220] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [295.016451] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [295.016681] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [295.016950] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] bigjoiner: no, pipes: 0x0
<7> [295.017199] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [295.017431] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [295.017662] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [295.017905] 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> [295.018138] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] requested mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x48 0x9
<7> [295.018371] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] adjusted mode: "1920x1200": 60 154000 1920 1968 2000 2080 1200 1203 1209 1235 0x48 0x9
<7> [295.018603] i915 0000:00:02.0: [drm:intel_dump_crtc_timings [i915]] crtc timings: clock=153600, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1200 vb=1200-1235 vs=1203-1209 vt=1235, flags=0x9
<7> [295.018836] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pipe mode: "1920x1200": 60 153600 1920 1968 2000 2080 1200 1203 1209 1235 0x40 0x9
<7> [295.019079] i915 0000:00:02.0: [drm:intel_dump_crtc_timings [i915]] crtc timings: clock=153600, hd=1920 hb=1920-2080 hs=1968-2000 ht=2080, vd=1200 vb=1200-1235 vs=1203-1209 vt=1235, flags=0x9
<7> [295.019312] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 153600, pipe src: 1920x1200+0+0, pixel rate 153600
<6> [295.019624] serial 00:02: activated
<7> [295.019544] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [295.019775] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] gmch pfit: control: 0x00000000, ratios: 0x00000000, lvds border: 0x00000000
<7> [295.020045] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [295.020294] i915 0000:00:02.0: [drm:intel_dpll_dump_hw_state [i915]] dpll_hw_state: dpll: 0x94010000, dpll_md: 0x0, fp0: 0x30d03, fp1: 0x30d03
<7> [295.020525] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [295.020756] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [295.020999] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:31:plane A] fb: [FB:48] 1920x1200 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [295.021232] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [295.021463] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1200.000000+0.000000+0.000000 dst: 1920x1200+0+0
<7> [295.021696] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:34:cursor A] fb: [NOFB], visible: no
<7> [295.021938] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:44:pipe B] enable: no [modeset]
<7> [295.022170] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:38:plane B] fb: [NOFB], visible: no
<7> [295.022401] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:41:cursor B] fb: [NOFB], visible: no
<7> [295.025569] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:46:CRT]
<7> [295.026425] i915 0000:00:02.0: [drm:i9xx_get_fifo_size [i915]] FIFO size - (0x00001d9c) A: 28
<7> [295.026651] [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 50
<7> [295.026885] [drm:intel_calculate_wm [i915]] FIFO watermark level: -22
<7> [295.027108] i915 0000:00:02.0: [drm:i9xx_get_fifo_size [i915]] FIFO size - (0x00001d9c) B: 31
<7> [295.027332] i915 0000:00:02.0: [drm:i9xx_update_wm [i915]] FIFO watermarks - A: 8, B: 29
<7> [295.027557] i915 0000:00:02.0: [drm:i9xx_update_wm [i915]] self-refresh entries: 120
<7> [295.027780] i915 0000:00:02.0: [drm:i9xx_update_wm [i915]] Setting FIFO watermarks - A: 8, B: 29, C: 2, SR 1
<7> [295.028039] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [295.045079] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:45:VGA-1]
<7> [295.045320] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:37:pipe A]
<7> [295.045576] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:44:pipe B]
<7> [295.046036] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:45:VGA-1] force=0
<7> [295.062044] i915 0000:00:02.0: [drm:intel_get_hpd_pins [i915]] hotplug event received, stat 0x00000800, dig 0x00000800, pins 0x00000002, long 0x00000000
<7> [295.062356] i915 0000:00:02.0: [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 1 - cnt: 10
<7> [295.063881] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7> [295.064180] i915 0000:00:02.0: [drm:check_connector_changed [drm_kms_helper]] [CONNECTOR:45:VGA-1] Same epoch counter 1
<7> [295.064519] i915 0000:00:02.0: [drm:i915_hotplug_work_func [i915]] running encoder hotplug functions
<7> [295.064763] i915 0000:00:02.0: [drm:i915_hotplug_work_func [i915]] Connector VGA-1 (pin 1) received hotplug event. (retry 0)
<7> [295.065013] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:45:VGA-1] force=0
<7> [295.080868] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<6> [295.311533] ata6: SATA link down (SStatus 4 SControl 300)
<6> [295.311577] ata2: SATA link down (SStatus 0 SControl 300)
<7> [295.368741] PM: resume of devices complete after 376.021 msecs
<6> [295.370301] ACPI: \_SB_.PCI0.IDE1.PRI1.MAS1: docking
<6> [295.370406] OOM killer enabled.
<6> [295.370411] Restarting tasks ...
<3> [295.376259] ACPI: \_SB_.PCI0.IDE1.PRI1.MAS1: Unable to dock!
<4> [295.386080] done.
<5> [295.386101] random: crng reseeded on system resumption
<6> [295.386217] PM: suspend exit
<7> [295.393170] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [295.397564] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [295.465899] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
<6> [295.481722] ata1.00: configured for UDMA/133
<7> [295.771480] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [295.776827] [IGT] gem_exec_suspend: finished subtest smem, SUCCESS
<6> [295.777414] [IGT] gem_exec_suspend: finished subtest basic-S3, SUCCESS
<6> [295.886478] [IGT] gem_exec_suspend: exiting, ret=0
<7> [295.887785] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<6> [295.915022] Console: switching to colour frame buffer device 240x75
Created at 2023-08-27 23:51:52