Machine description: fi-blb-e6850
Result:
integration-manifest git-log-oneline i915_display_info12 igt_runner12 run12 runtimes12 results12.json boot12 dmesg12
| Detail | Value |
|---|---|
| Duration | unknown |
| Igt-Version |
IGT-Version: 1.29-NO-GIT (x86_64) (Linux: 6.13.0-rc1-drmtip_1722-gf542c1521690+ x86_64) |
| Out |
Using IGT_SRANDOM=1733283678 for randomisation Opened device: /dev/dri/card0 Starting subtest: basic-S4-devices Starting dynamic subtest: smem Dynamic subtest smem: SUCCESS (7.566s) Subtest basic-S4-devices: SUCCESS (7.567s) This test caused an abort condition: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1882 [max: 8192] direct dependencies: 19388 [max: 524288] indirect dependencies: 151672 all direct dependencies: 336356 dependency chains: 26186 [max: 524288] dependency chain hlocks used: 105586 [max: 2621440] dependency chain hlocks lost: 0 in-hardirq chains: 154 in-softirq chains: 718 in-process chains: 25314 stack-trace entries: 205399 [max: 524288] number of stack traces: 9756 number of stack hash chains: 7416 combined max dependencies: 2821230175 hardirq-safe locks: 65 hardirq-unsafe locks: 1128 softirq-safe locks: 201 softirq-unsafe locks: 1040 irq-safe locks: 216 irq-unsafe locks: 1128 hardirq-read-safe locks: 4 hardirq-read-unsafe locks: 345 softirq-read-safe locks: 10 softirq-read-unsafe locks: 341 irq-read-safe locks: 10 irq-read-unsafe locks: 345 uncategorized locks: 317 unused locks: 1 max locking depth: 17 max bfs queue depth: 299 max lock class index: 1881 debug_locks: 0 zapped classes: 403 zapped lock chains: 2915 large chain blocks: 1 |
| Err |
Starting subtest: basic-S4-devices Starting dynamic subtest: smem Dynamic subtest smem: SUCCESS (7.566s) Subtest basic-S4-devices: SUCCESS (7.567s) |
| Dmesg |
<6> [71.222654] Console: switching to colour dummy device 80x25
<6> [71.222787] [IGT] gem_exec_suspend: executing
<7> [71.229004] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [71.230175] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [71.230687] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<7> [71.231331] i915 0000:00:02.0: [drm:i915_gem_open [i915]]
<6> [71.248049] [IGT] gem_exec_suspend: starting subtest basic-S4-devices
<6> [71.248588] [IGT] gem_exec_suspend: starting dynamic subtest smem
<7> [71.250851] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [71.547278] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [71.558320] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [71.854412] PM: hibernation: hibernation entry
<6> [71.865099] Filesystems sync: 0.002 seconds
<6> [71.865409] Freezing user space processes
<6> [71.867464] Freezing user space processes completed (elapsed 0.002 seconds)
<6> [71.867546] OOM killer disabled.
<7> [71.867781] PM: hibernation: Marking nosave pages: [mem 0x00000000-0x00000fff]
<7> [71.867792] PM: hibernation: Marking nosave pages: [mem 0x0009e000-0x000fffff]
<7> [71.867814] PM: hibernation: Marking nosave pages: [mem 0x7d4ff000-0x7d554fff]
<7> [71.867833] PM: hibernation: Basic memory bitmaps created
<6> [71.868131] PM: hibernation: Preallocating image memory
<4> [72.148675]
<4> [72.148689] ======================================================
<4> [72.148696] WARNING: possible circular locking dependency detected
<4> [72.148703] 6.13.0-rc1-drmtip_1722-gf542c1521690+ #1 Tainted: G U
<4> [72.148710] ------------------------------------------------------
<4> [72.148716] gem_exec_suspen/1542 is trying to acquire lock:
<4> [72.148722] ffff88800b661400 (&q->q_usage_counter(io)#25){++++}-{0:0}, at: __submit_bio+0x120/0x200
<4> [72.148743]
but task is already holding lock:
<4> [72.148748] ffffffff834cb400 (fs_reclaim){+.+.}-{0:0}, at: shrink_all_memory+0x69/0x110
<4> [72.148762]
which lock already depends on the new lock.
<4> [72.148769]
the existing dependency chain (in reverse order) is:
<4> [72.148775]
-> #1 (fs_reclaim){+.+.}-{0:0}:
<4> [72.148785] fs_reclaim_acquire+0xc5/0x100
<4> [72.148792] kmem_cache_alloc_noprof+0x50/0x470
<4> [72.148799] __alloc_object+0x34/0x2b0
<4> [72.148807] __create_object+0x29/0xc0
<4> [72.148814] kmemleak_alloc_percpu+0x47/0x90
<4> [72.148823] pcpu_alloc_noprof+0x3a1/0x8d0
<4> [72.148830] sbitmap_init_node+0x124/0x240
<4> [72.148837] scsi_realloc_sdev_budget_map+0xd6/0x1d0
<4> [72.148845] scsi_add_lun+0x45b/0x760
<4> [72.148851] scsi_probe_and_add_lun+0x15d/0x480
<4> [72.148857] __scsi_add_device+0x125/0x140
<4> [72.148864] ata_scsi_scan_host+0x9b/0x1e0
<4> [72.148872] async_port_probe+0x4b/0x70
<4> [72.148879] async_run_entry_fn+0x35/0x150
<4> [72.148887] process_one_work+0x21f/0x740
<4> [72.148895] worker_thread+0x1db/0x3c0
<4> [72.148902] kthread+0xf6/0x130
<4> [72.148908] ret_from_fork+0x47/0x70
<4> [72.148915] ret_from_fork_asm+0x1a/0x30
<4> [72.148924]
-> #0 (&q->q_usage_counter(io)#25){++++}-{0:0}:
<4> [72.148936] __lock_acquire+0x1663/0x2820
<4> [72.148944] lock_acquire+0xc9/0x300
<4> [72.148951] blk_mq_submit_bio+0x969/0xb90
<4> [72.148958] __submit_bio+0x120/0x200
<4> [72.148965] submit_bio_noacct_nocheck+0x315/0x420
<4> [72.148972] submit_bio_noacct+0x212/0x680
<4> [72.148978] submit_bio+0x38/0x110
<4> [72.148984] __swap_writepage+0xf5/0x380
<4> [72.148992] swap_writepage+0x3c1/0x600
<4> [72.148999] shmem_writepage+0x438/0x510
<4> [72.149006] pageout+0x142/0x310
<4> [72.149014] shrink_folio_list+0x674/0x1000
<4> [72.149020] evict_folios+0x354/0xba0
<4> [72.149025] try_to_shrink_lruvec+0x20f/0x400
<4> [72.149031] shrink_one+0x10f/0x200
<4> [72.149037] shrink_node+0xca4/0x1400
<4> [72.149043] do_try_to_free_pages+0xb9/0x5b0
<4> [72.149049] shrink_all_memory+0xab/0x110
<4> [72.149056] hibernate_preallocate_memory+0x228/0x490
<4> [72.149064] hibernation_snapshot+0x1e4/0x790
<4> [72.149070] hibernate+0x147/0x480
<4> [72.149076] state_store+0xd3/0xe0
<4> [72.149082] kobj_attr_store+0x12/0x40
<4> [72.149090] sysfs_kf_write+0x4c/0x80
<4> [72.149097] kernfs_fop_write_iter+0x167/0x210
<4> [72.149105] vfs_write+0x2bc/0x570
<4> [72.149112] ksys_write+0x7c/0x100
<4> [72.149119] __x64_sys_write+0x19/0x30
<4> [72.149125] x64_sys_call+0x2c4/0x2650
<4> [72.149132] do_syscall_64+0x91/0x180
<4> [72.149139] entry_SYSCALL_64_after_hwframe+0x76/0x7e
<4> [72.149148]
other info that might help us debug this:
<4> [72.149155] Possible unsafe locking scenario:
<4> [72.149161] CPU0 CPU1
<4> [72.149166] ---- ----
<4> [72.149171] lock(fs_reclaim);
<4> [72.149177] lock(&q->q_usage_counter(io)#25);
<4> [72.149186] lock(fs_reclaim);
<4> [72.149193] rlock(&q->q_usage_counter(io)#25);
<4> [72.149200]
*** DEADLOCK ***
<4> [72.149207] 7 locks held by gem_exec_suspen/1542:
<4> [72.149212] #0: ffff88800d8a4420 (sb_writers#5){.+.+}-{0:0}, at: ksys_write+0x7c/0x100
<4> [72.149228] #1: ffff888017c14488 (&of->mutex#2){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x11e/0x210
<4> [72.149244] #2: ffff888009325de0 (kn->active#212){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x127/0x210
<4> [72.149260] #3: ffffffff83299cc8 (system_transition_mutex){+.+.}-{4:4}, at: lock_system_sleep+0x2b/0x40
<4> [72.149274] #4: ffffffff8361b2a8 (device_hotplug_lock){+.+.}-{4:4}, at: lock_device_hotplug+0x17/0x30
<4> [72.149289] #5: ffffffff835c76a8 (acpi_scan_lock){+.+.}-{4:4}, at: acpi_scan_lock_acquire+0x17/0x30
<4> [72.149304] #6: ffffffff834cb400 (fs_reclaim){+.+.}-{0:0}, at: shrink_all_memory+0x69/0x110
<4> [72.149318]
stack backtrace:
<4> [72.149324] CPU: 0 UID: 0 PID: 1542 Comm: gem_exec_suspen Tainted: G U 6.13.0-rc1-drmtip_1722-gf542c1521690+ #1
<4> [72.149335] Tainted: [U]=USER
<4> [72.149340] Hardware name: Dell Inc. OptiPlex 755 /0PU052, BIOS A08 02/19/2008
<4> [72.149348] Call Trace:
<4> [72.149352] <TASK>
<4> [72.149357] dump_stack_lvl+0x91/0xf0
<4> [72.149364] dump_stack+0x10/0x20
<4> [72.149371] print_circular_bug+0x285/0x360
<4> [72.149379] check_noncircular+0x150/0x170
<4> [72.149388] __lock_acquire+0x1663/0x2820
<4> [72.149398] lock_acquire+0xc9/0x300
<4> [72.149405] ? __submit_bio+0x120/0x200
<4> [72.149412] ? blk_mq_submit_bio+0x942/0xb90
<4> [72.149419] ? lock_release+0xd4/0x2b0
<4> [72.149427] blk_mq_submit_bio+0x969/0xb90
<4> [72.149434] ? __submit_bio+0x120/0x200
<4> [72.149443] __submit_bio+0x120/0x200
<4> [72.149450] ? trace_hardirqs_on+0x1e/0xe0
<4> [72.149459] submit_bio_noacct_nocheck+0x315/0x420
<4> [72.149467] ? submit_bio_noacct_nocheck+0x315/0x420
<4> [72.149475] ? __might_sleep+0x58/0xa0
<4> [72.149483] submit_bio_noacct+0x212/0x680
<4> [72.149490] ? count_memcg_events+0x68/0x90
<4> [72.149498] submit_bio+0x38/0x110
<4> [72.149505] __swap_writepage+0xf5/0x380
<4> [72.149513] swap_writepage+0x3c1/0x600
<4> [72.149520] shmem_writepage+0x438/0x510
<4> [72.149529] pageout+0x142/0x310
<4> [72.149543] shrink_folio_list+0x674/0x1000
<4> [72.149558] evict_folios+0x354/0xba0
<4> [72.149565] ? evict_folios+0x31c/0xba0
<4> [72.149574] try_to_shrink_lruvec+0x20f/0x400
<4> [72.149583] shrink_one+0x10f/0x200
<4> [72.149589] ? shrink_node+0xc88/0x1400
<4> [72.149596] shrink_node+0xca4/0x1400
<4> [72.149603] ? shrink_node+0xad6/0x1400
<4> [72.149611] do_try_to_free_pages+0xb9/0x5b0
<4> [72.149618] ? do_try_to_free_pages+0xb9/0x5b0
<4> [72.149625] ? fs_reclaim_acquire+0x4c/0x100
<4> [72.149632] shrink_all_memory+0xab/0x110
<4> [72.149641] hibernate_preallocate_memory+0x228/0x490
<4> [72.149650] hibernation_snapshot+0x1e4/0x790
<4> [72.149657] hibernate+0x147/0x480
<4> [72.149663] state_store+0xd3/0xe0
<4> [72.149671] kobj_attr_store+0x12/0x40
<4> [72.149677] sysfs_kf_write+0x4c/0x80
<4> [72.149684] kernfs_fop_write_iter+0x167/0x210
<4> [72.149692] vfs_write+0x2bc/0x570
<4> [72.149701] ksys_write+0x7c/0x100
<4> [72.149709] __x64_sys_write+0x19/0x30
<4> [72.149716] x64_sys_call+0x2c4/0x2650
<4> [72.149723] do_syscall_64+0x91/0x180
<4> [72.149729] ? syscall_exit_to_user_mode+0x95/0x260
<4> [72.149737] ? do_syscall_64+0x9d/0x180
<4> [72.149744] ? syscall_exit_to_user_mode+0x95/0x260
<4> [72.149751] ? do_syscall_64+0x9d/0x180
<4> [72.149758] ? irqentry_exit_to_user_mode+0x8b/0x260
<4> [72.149765] ? irqentry_exit+0x77/0xb0
<4> [72.149771] ? exc_page_fault+0xb1/0x2e0
<4> [72.149779] entry_SYSCALL_64_after_hwframe+0x76/0x7e
<4> [72.149787] RIP: 0033:0x75f143f1c574
<4> [72.149795] Code: c7 00 16 00 00 00 b8 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 f3 0f 1e fa 80 3d d5 ea 0e 00 00 74 13 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 54 c3 0f 1f 00 55 48 89 e5 48 83 ec 20 48 89
<4> [72.149809] RSP: 002b:00007fff34c18718 EFLAGS: 00000202 ORIG_RAX: 0000000000000001
<4> [72.149818] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 000075f143f1c574
<4> [72.149825] RDX: 0000000000000004 RSI: 000075f14427edca RDI: 0000000000000009
<4> [72.149832] RBP: 0000000000000004 R08: 0000000000000003 R09: 0000000000000000
<4> [72.149838] R10: 0000000000000000 R11: 0000000000000202 R12: 000075f14427edca
<4> [72.149845] R13: 0000000000000009 R14: 0000000000000009 R15: 000075f1442d7960
<4> [72.149855] </TASK>
<6> [72.961727] PM: hibernation: Allocated 167960 pages for snapshot
<6> [72.961751] PM: hibernation: Allocated 671840 kbytes in 1.09 seconds (616.36 MB/s)
<6> [72.961761] Freezing remaining freezable tasks
<6> [72.962855] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
<6> [72.966680] serial 00:02: disabled
<7> [72.969564] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.enable (expected yes, found no)
<7> [72.970160] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.active (expected yes, found no)
<7> [72.970496] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in cpu_transcoder (expected 0, found -1)
<7> [72.970855] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in output_types (expected 0x00000002, found 0x00000000)
<7> [72.971187] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in framestart_delay (expected 1, found 0)
<7> [72.971516] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 1920, found 0)
<7> [72.971878] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 2080, found 0)
<7> [72.972218] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 1920, found 0)
<7> [72.972548] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 2080, found 0)
<7> [72.972892] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 1968, found 0)
<7> [72.973222] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 2000, found 0)
<7> [72.973552] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 1200, found 0)
<7> [72.973895] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 1200, found 0)
<7> [72.974230] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 1203, found 0)
<7> [72.974561] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 1209, found 0)
<7> [72.974907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 1235, found 0)
<7> [72.975236] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 1235, found 0)
<7> [72.975565] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 1920, found 0)
<7> [72.975909] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 2080, found 0)
<7> [72.976238] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 1920, found 0)
<7> [72.976568] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 2080, found 0)
<7> [72.976911] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 1968, found 0)
<7> [72.977240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 2000, found 0)
<7> [72.977570] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 1200, found 0)
<7> [72.977918] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 1200, found 0)
<7> [72.978249] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 1203, found 0)
<7> [72.978578] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 1209, found 0)
<7> [72.978921] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 1235, found 0)
<7> [72.979255] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 1235, found 0)
<7> [72.979584] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in pixel_multiplier (expected 1, found 0)
<7> [72.979931] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 1, found 0)
<7> [72.980265] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 8, found 0)
<7> [72.980600] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 153600, found 0)
<7> [72.980957] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 153600, found 0)
<7> [72.981289] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in port_clock (expected 153600, found 0)
<7> [72.981619] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:39:pipe A] fastset requirement not met, forcing full modeset
<7> [72.982040] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 333333 kHz, actual 333333 kHz
<7> [72.982375] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [72.982706] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:39:pipe A] enable: no [modeset]
<7> [72.983040] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane A] fb: [NOFB], visible: no
<7> [72.983437] i915 0000:00:02.0: [drm:intel_disable_transcoder [i915]] disabling pipe A
<7> [73.008709] i915 0000:00:02.0: [drm:i9xx_get_fifo_size [i915]] FIFO size - (0x00001d9c) A: 28
<7> [73.009112] i915 0000:00:02.0: [drm:i9xx_get_fifo_size [i915]] FIFO size - (0x00001d9c) B: 31
<7> [73.009436] i915 0000:00:02.0: [drm:i9xx_update_wm [i915]] FIFO watermarks - A: 26, B: 29
<7> [73.009767] i915 0000:00:02.0: [drm:i9xx_update_wm [i915]] Setting FIFO watermarks - A: 26, B: 29, C: 2, SR 1
<7> [73.010095] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:49:CRT]
<7> [73.010416] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:48:VGA-1]
<7> [73.010773] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:39:pipe A]
<6> [73.012617] PM: hibernation: debug: Waiting for 5 seconds.
<5> [78.058578] usb usb1: root hub lost power or was reset
<5> [78.058629] usb usb4: root hub lost power or was reset
<5> [78.058672] usb usb2: root hub lost power or was reset
<7> [78.060125] i915 0000:00:02.0: [drm:intel_hpll_vco [i915]] HPLL VCO 4000000 kHz
<7> [78.060566] 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> [78.060908] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:39:pipe A] hw state readout: disabled
<7> [78.061246] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CRTC:47:pipe B] hw state readout: disabled
<7> [78.061567] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:32:plane A] hw state readout: disabled, pipe A
<7> [78.061888] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:35:cursor A] hw state readout: disabled, pipe A
<7> [78.062214] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:40:plane B] hw state readout: disabled, pipe A
<7> [78.062534] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:43:cursor B] hw state readout: disabled, pipe A
<7> [78.062857] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [ENCODER:49:CRT] hw state readout: disabled, pipe A
<7> [78.063184] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [CONNECTOR:48:VGA-1] hw state readout: disabled
<7> [78.063503] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:32:plane A] min_cdclk 0 kHz
<7> [78.063821] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:35:cursor A] min_cdclk 0 kHz
<7> [78.064144] i915 0000:00:02.0: [drm:intel_bw_crtc_update [i915]] pipe A data rate 0 num active planes 0
<7> [78.064468] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:40:plane B] min_cdclk 0 kHz
<7> [78.064786] i915 0000:00:02.0: [drm:intel_modeset_setup_hw_state [i915]] [PLANE:43:cursor B] min_cdclk 0 kHz
<7> [78.065107] i915 0000:00:02.0: [drm:intel_bw_crtc_update [i915]] pipe B data rate 0 num active planes 0
<7> [78.065437] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:39:pipe A] enable: no [setup_hw_state]
<7> [78.065763] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe B] enable: no [setup_hw_state]
<7> [78.066233] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:39:pipe A] hw max bpp: 24, pipe bpp: 24, dithering: 0
<7> [78.066579] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.enable (expected no, found yes)
<7> [78.066907] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.active (expected no, found yes)
<7> [78.067240] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in output_types (expected 0x00000000, found 0x00000002)
<7> [78.067569] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in framestart_delay (expected 0, found 1)
<7> [78.067896] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hdisplay (expected 0, found 1920)
<7> [78.068228] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_htotal (expected 0, found 2080)
<7> [78.068556] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_start (expected 0, found 1920)
<7> [78.068884] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hblank_end (expected 0, found 2080)
<7> [78.069216] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_start (expected 0, found 1968)
<7> [78.069545] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_hsync_end (expected 0, found 2000)
<7> [78.069873] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vdisplay (expected 0, found 1200)
<7> [78.070205] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_start (expected 0, found 1200)
<7> [78.070533] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_start (expected 0, found 1203)
<7> [78.070861] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vsync_end (expected 0, found 1209)
<7> [78.071193] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vtotal (expected 0, found 1235)
<7> [78.071521] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_vblank_end (expected 0, found 1235)
<7> [78.071849] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hdisplay (expected 0, found 1920)
<7> [78.072181] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_htotal (expected 0, found 2080)
<7> [78.072509] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_start (expected 0, found 1920)
<5> [78.072780] usb usb5: root hub lost power or was reset
<5> [78.072816] usb usb6: root hub lost power or was reset
<5> [78.072849] usb usb7: root hub lost power or was reset
<5> [78.072892] usb usb3: root hub lost power or was reset
<7> [78.072850] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hblank_end (expected 0, found 2080)
<7> [78.073190] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_start (expected 0, found 1968)
<7> [78.073519] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_hsync_end (expected 0, found 2000)
<7> [78.073847] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vdisplay (expected 0, found 1200)
<7> [78.074185] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_start (expected 0, found 1200)
<7> [78.074514] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_start (expected 0, found 1203)
<7> [78.074842] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vsync_end (expected 0, found 1209)
<7> [78.075175] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vtotal (expected 0, found 1235)
<7> [78.075503] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_vblank_end (expected 0, found 1235)
<7> [78.075831] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in pixel_multiplier (expected 0, found 1)
<7> [78.076163] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.flags (1) (expected 0, found 1)
<7> [78.076491] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.flags (8) (expected 0, found 8)
<7> [78.076820] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in dpll_hw_state
<7> [78.077173] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] expected:
<7> [78.077506] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x0, dpll_md: 0x0, fp0: 0x0, fp1: 0x0
<7> [78.077833] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] found:
<7> [78.078167] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] dpll_hw_state: dpll: 0x94010000, dpll_md: 0x0, fp0: 0x30d03, fp1: 0x30d03
<7> [78.078495] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.pipe_mode.crtc_clock (expected 0, found 153600)
<7> [78.078825] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in hw.adjusted_mode.crtc_clock (expected 0, found 153600)
<7> [78.079170] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:39:pipe A] fastset requirement not met in port_clock (expected 0, found 153600)
<7> [78.079504] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:39:pipe A] fastset requirement not met, forcing full modeset
<7> [78.079832] i915 0000:00:02.0: [drm:intel_pipe_config_compare [i915]] [CRTC:47:pipe B] fastset requirement not met in cpu_transcoder (expected 1, found -1)
<7> [78.080174] i915 0000:00:02.0: [drm:intel_atomic_check [i915]] [CRTC:47:pipe B] fastset requirement not met, forcing full modeset
<7> [78.080563] i915 0000:00:02.0: [drm:intel_plane_calc_min_cdclk [i915]] [PLANE:32:plane A] min cdclk (153600 kHz) > [CRTC:39:pipe A] min cdclk (0 kHz)
<7> [78.080959] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New cdclk calculated to be logical 333333 kHz, actual 333333 kHz
<7> [78.081301] i915 0000:00:02.0: [drm:intel_modeset_calc_cdclk [i915]] New voltage level calculated to be logical 0, actual 0
<7> [78.081633] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:39:pipe A] enable: yes [modeset]
<7> [78.081962] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] active: yes, output_types: ANALOG (0x2), output format: RGB, sink format: RGB
<7> [78.082305] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] cpu_transcoder: A, pipe bpp: 24, dithering: 0
<7> [78.082632] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] MST master transcoder: <invalid>
<7> [78.082956] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port sync: master transcoder: <invalid>, slave transcoder bitmask = 0x0
<7> [78.083290] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] joiner: no, pipes: 0x0
<7> [78.083615] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] splitter: disabled, link count 0, overlap 0
<7> [78.083940] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] framestart delay: 1, MSA timing delay: 0
<7> [78.084274] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] audio: 0, infoframes: 0, infoframes enabled: 0x0
<7> [78.084601] 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> [78.084930] 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> [78.085267] 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> [78.085597] i915 0000:00:02.0: [drm:intel_crtc_state_dump [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> [78.085928] 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> [78.086268] i915 0000:00:02.0: [drm:intel_crtc_state_dump [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> [78.086600] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] port clock: 153600, pipe src: 1920x1200+0+0, pixel rate 153600
<7> [78.086927] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] linetime: 0, ips linetime: 0
<7> [78.087258] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] gmch pfit: control: 0x00000000, ratios: 0x00000000, lvds border: 0x00000000
<7> [78.087586] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] ips: 0, double wide: 0, drrs: 0
<7> [78.087911] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] dpll_hw_state: dpll: 0x94010000, dpll_md: 0x0, fp0: 0x30d03, fp1: 0x30d03
<7> [78.088246] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] csc_mode: 0x0 gamma_mode: 0x0 gamma_enable: 0 csc_enable: 0
<7> [78.088573] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] pre csc lut: 0 entries, post csc lut: 0 entries
<7> [78.088899] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:32:plane A] fb: [FB:51] 1920x1200 format = XR24 little-endian (0x34325258) modifier = 0x0, visible: yes
<7> [78.089240] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] rotation: 0x1, scaler: -1, scaling_filter: 0
<7> [78.089566] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] src: 1920.000000x1200.000000+0.000000+0.000000 dst: 1920x1200+0+0
<6> [78.089960] serial 00:02: activated
<7> [78.089895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:35:cursor A] fb: [NOFB], visible: no
<7> [78.090237] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [CRTC:47:pipe B] enable: no [modeset]
<7> [78.090566] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:40:plane B] fb: [NOFB], visible: no
<7> [78.090895] i915 0000:00:02.0: [drm:intel_crtc_state_dump [i915]] [PLANE:43:cursor B] fb: [NOFB], visible: no
<7> [78.091593] i915 0000:00:02.0: [drm:intel_modeset_verify_disabled [i915]] [ENCODER:49:CRT]
<7> [78.092563] i915 0000:00:02.0: [drm:i9xx_get_fifo_size [i915]] FIFO size - (0x00001d9c) A: 28
<7> [78.092898] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO entries required for mode: 50
<7> [78.093228] i915 0000:00:02.0: [drm:intel_calculate_wm [i915]] FIFO watermark level: -22
<7> [78.093557] i915 0000:00:02.0: [drm:i9xx_get_fifo_size [i915]] FIFO size - (0x00001d9c) B: 31
<7> [78.093880] i915 0000:00:02.0: [drm:i9xx_update_wm [i915]] FIFO watermarks - A: 8, B: 29
<7> [78.094245] i915 0000:00:02.0: [drm:i9xx_update_wm [i915]] self-refresh entries: 120
<7> [78.094575] i915 0000:00:02.0: [drm:i9xx_update_wm [i915]] Setting FIFO watermarks - A: 8, B: 29, C: 2, SR 1
<7> [78.094936] i915 0000:00:02.0: [drm:intel_enable_transcoder [i915]] enabling pipe A
<7> [78.112089] i915 0000:00:02.0: [drm:verify_connector_state [i915]] [CONNECTOR:48:VGA-1]
<7> [78.112528] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:39:pipe A]
<7> [78.112887] i915 0000:00:02.0: [drm:intel_modeset_verify_crtc [i915]] [CRTC:47:pipe B]
<7> [78.113356] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] [CONNECTOR:48:VGA-1] force=0
<7> [78.130405] i915 0000:00:02.0: [drm:intel_crt_detect [i915]] CRT detected via hotplug
<7> [78.384078] PM: hibernation: Basic memory bitmaps freed
<6> [78.384204] OOM killer enabled.
<6> [78.384213] Restarting tasks ... done.
<6> [78.386673] PM: hibernation: hibernation exit
<6> [78.388404] ata2: SATA link down (SStatus 0 SControl 300)
<6> [78.388447] ata6: SATA link down (SStatus 4 SControl 300)
<6> [78.542604] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
<5> [78.554389] sd 0:0:0:0: [sda] Starting disk
<6> [78.563962] ata1.00: configured for UDMA/133
<7> [78.569148] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [78.572315] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<7> [78.812126] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x0000005c [0x0000005c]
<6> [78.815124] [IGT] gem_exec_suspend: finished subtest smem, SUCCESS
<6> [78.815290] [IGT] gem_exec_suspend: finished subtest basic-S4-devices, SUCCESS
<7> [78.922276] i915 0000:00:02.0: [drm:error_state_write [i915]] Resetting error state
<6> [78.923057] [IGT] gem_exec_suspend: exiting, ret=0
<7> [78.923674] i915 0000:00:02.0: [drm:i915_drop_caches_set [i915]] Dropping caches: 0x000001dc [0x000001dc]
<7> [78.931710] i915 0000:00:02.0: [drm:drm_client_dev_restore] intel-fbdev: ret=0
<6> [78.948508] Console: switching to colour frame buffer device 240x75
|
