Results for igt@perf_pmu@semaphore-busy-vcs0

Result: dmesg-warn

integration-manifest git-log-oneline igt_runner29 run29 boot29 dmesg29 runtimes29

DetailValue
Duration 5.79 seconds
Hostname
shard-skl6
Igt-Version
IGT-Version: 1.24-g5997df31d (x86_64) (Linux: 5.4.0-rc6-CI-CI_DRM_7315+ x86_64)
Out
IGT-Version: 1.24-g5997df31d (x86_64) (Linux: 5.4.0-rc6-CI-CI_DRM_7315+ x86_64)
Starting subtest: semaphore-busy-vcs0
vcs0<-rcs0, target: {50.0% [50], 99.9% [100]}, measured: {50.9%, 100.0%}
vcs0<-rcs0, target: {25.0% [25], 50.0% [50]}, measured: {25.0%, 50.0%}
vcs0<-rcs0, target: {74.9% [75], 75.0% [75]}, measured: {74.9%, 75.0%}
vcs0<-bcs0, target: {50.0% [50], 99.9% [100]}, measured: {49.9%, 100.0%}
vcs0<-bcs0, target: {25.0% [25], 50.0% [50]}, measured: {25.0%, 50.0%}
vcs0<-bcs0, target: {75.0% [75], 75.0% [75]}, measured: {74.9%, 75.0%}
vcs0<-vecs0, target: {50.0% [50], 100.0% [100]}, measured: {50.0%, 100.0%}
vcs0<-vecs0, target: {25.0% [25], 50.0% [50]}, measured: {25.0%, 50.0%}
vcs0<-vecs0, target: {75.0% [75], 75.0% [75]}, measured: {74.9%, 75.0%}
Subtest semaphore-busy-vcs0: SUCCESS (5.788s)
Err
Starting subtest: semaphore-busy-vcs0
Subtest semaphore-busy-vcs0: SUCCESS (5.788s)
Dmesg-Warnings
<4> [2432.504695]  #3: ffff88815936a1e0 (wakeref#3){+.+.}, at: __intel_wakeref_put_last+0x25/0x50 [i915]
<4> [2432.506712]    intel_gt_retire_requests_timeout+0x1b9/0x540 [i915]
<4> [2432.506983]    i915_drop_caches_set+0x160/0x240 [i915]
<4> [2432.507478]                      intel_timeline_enter+0x64/0x150 [i915]
<4> [2432.507901]                      ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.508125]                      intel_gt_resume+0x1fd/0x2c0 [i915]
<4> [2432.508445]                      i915_gem_init+0x366/0x8d0 [i915]
<4> [2432.508625]                      i915_driver_probe+0xb9d/0x15b0 [i915]
<4> [2432.508811]                      i915_pci_probe+0x43/0x1c0 [i915]
<4> [2432.509711]    intel_timeline_enter+0x64/0x150 [i915]
<4> [2432.510132]    ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.510367]    intel_gt_resume+0x1fd/0x2c0 [i915]
<4> [2432.510661]    i915_gem_init+0x366/0x8d0 [i915]
<4> [2432.510836]    i915_driver_probe+0xb9d/0x15b0 [i915]
<4> [2432.511019]    i915_pci_probe+0x43/0x1c0 [i915]
<4> [2432.511820]                     ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.512030]                     i915_sample+0x316/0x350 [i915]
<4> [2432.513571]                    ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.513791]                    intel_gt_resume+0x1fd/0x2c0 [i915]
<4> [2432.514091]                    i915_gem_init+0x366/0x8d0 [i915]
<4> [2432.514292]                    i915_driver_probe+0xb9d/0x15b0 [i915]
<4> [2432.514480]                    i915_pci_probe+0x43/0x1c0 [i915]
<4> [2432.515544]    ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.515749]    i915_sample+0x316/0x350 [i915]
<4> [2432.517835]  ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.518038]  i915_sample+0x316/0x350 [i915]
<4> [2432.518262]  ? i915_pmu_cpu_online+0x70/0x70 [i915]
Dmesg
<6> [2430.294961] Console: switching to colour dummy device 80x25
<6> [2430.295619] [IGT] perf_pmu: executing
<6> [2430.385767] [IGT] perf_pmu: starting subtest semaphore-busy-vcs0
<7> [2431.982685] [drm:edp_panel_vdd_off_sync [i915]] Turning [ENCODER:94:DDI A] VDD off
<7> [2431.983126] [drm:edp_panel_vdd_off_sync [i915]] PP_STATUS: 0x80000008 PP_CONTROL: 0x00000007
<7> [2431.983572] [drm:intel_power_well_disable [i915]] disabling DC off
<7> [2431.983993] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [2431.984449] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7> [2431.991064] [drm:intel_power_well_enable [i915]] enabling DC off
<7> [2431.992221] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<4> [2432.503369] 
<4> [2432.503375] irq event stamp: 53654
<4> [2432.503405] hardirqs last  enabled at (53653): [<ffffffff819e522c>] _raw_spin_unlock_irqrestore+0x4c/0x60
<4> [2432.503424] hardirqs last disabled at (53654): [<ffffffff819e4f9d>] _raw_spin_lock_irqsave+0xd/0x50
<4> [2432.503440] softirqs last  enabled at (53640): [<ffffffff81c00385>] __do_softirq+0x385/0x47f
<4> [2432.503465] ========================================================
<4> [2432.503534] softirqs last disabled at (53631): [<ffffffff810b7faa>] irq_exit+0xba/0xc0
<4> [2432.503551] WARNING: possible irq lock inversion dependency detected
<4> [2432.503560] 5.4.0-rc6-CI-CI_DRM_7315+ #1 Tainted: G     U           
<4> [2432.503686] --------------------------------------------------------
<4> [2432.503722] kworker/0:1H/227 just changed the state of lock:
<4> [2432.503751] ffff8881746dca70 (&kernel/2){-...}, at: __engine_park+0x175/0x420 [i915]
<4> [2432.504014] but this lock took another, HARDIRQ-unsafe lock in the past:
<4> [2432.504032]  (&(&lock->wait_lock)->rlock){+.+.}
<4> [2432.504038] 

and interrupts could create inverse lock ordering between them.

<4> [2432.504081] 
other info that might help us debug this:
<4> [2432.504101] Chain exists of:
  &kernel/2 --> &(&timelines->lock)->rlock --> &(&lock->wait_lock)->rlock

<4> [2432.504158]  Possible interrupt unsafe locking scenario:

<4> [2432.504195]        CPU0                    CPU1
<4> [2432.504221]        ----                    ----
<4> [2432.504246]   lock(&(&lock->wait_lock)->rlock);
<4> [2432.504289]                                local_irq_disable();
<4> [2432.504327]                                lock(&kernel/2);
<4> [2432.504379]                                lock(&(&timelines->lock)->rlock);
<4> [2432.504416]   <Interrupt>
<4> [2432.504431]     lock(&kernel/2);
<4> [2432.504454] 
 *** DEADLOCK ***

<4> [2432.504493] 4 locks held by kworker/0:1H/227:
<4> [2432.504518]  #0: ffff888179638f28 ((wq_completion)kblockd){+.+.}, at: process_one_work+0x1de/0x620
<4> [2432.504580]  #1: ffffc90000ff7e78 ((work_completion)(&(&hctx->run_work)->work)){+.+.}, at: process_one_work+0x1de/0x620
<4> [2432.504645]  #2: ffff888176e16e58 (hctx->srcu){....}, at: hctx_lock+0x5b/0xe0
<4> [2432.504695]  #3: ffff88815936a1e0 (wakeref#3){+.+.}, at: __intel_wakeref_put_last+0x25/0x50 [i915]
<4> [2432.505012] 
the shortest dependencies between 2nd lock and 1st lock:
<4> [2432.505111]   -> (&(&lock->wait_lock)->rlock){+.+.} ops: 577792 {
<4> [2432.505152]      HARDIRQ-ON-W at:
<4> [2432.505192]                         lock_acquire+0xa7/0x1c0
<4> [2432.505235]                         _raw_spin_lock+0x2a/0x40
<4> [2432.505271]                         __mutex_lock+0x198/0x9d0
<4> [2432.505309]                         hub_port_init+0x70/0xcd0
<4> [2432.505353]                         hub_event+0x797/0x16d0
<4> [2432.505390]                         process_one_work+0x26a/0x620
<4> [2432.505427]                         worker_thread+0x37/0x380
<4> [2432.505469]                         kthread+0x119/0x130
<4> [2432.505504]                         ret_from_fork+0x3a/0x50
<4> [2432.505535]      SOFTIRQ-ON-W at:
<4> [2432.505569]                         lock_acquire+0xa7/0x1c0
<4> [2432.505610]                         _raw_spin_lock+0x2a/0x40
<4> [2432.505652]                         __mutex_lock+0x198/0x9d0
<4> [2432.505690]                         hub_port_init+0x70/0xcd0
<4> [2432.505729]                         hub_event+0x797/0x16d0
<4> [2432.505769]                         process_one_work+0x26a/0x620
<4> [2432.505807]                         worker_thread+0x37/0x380
<4> [2432.505849]                         kthread+0x119/0x130
<4> [2432.505881]                         ret_from_fork+0x3a/0x50
<4> [2432.505914]      INITIAL USE at:
<4> [2432.505947]                        lock_acquire+0xa7/0x1c0
<4> [2432.505990]                        _raw_spin_lock+0x2a/0x40
<4> [2432.506028]                        __mutex_lock+0x198/0x9d0
<4> [2432.506065]                        hub_port_init+0x70/0xcd0
<4> [2432.506104]                        hub_event+0x797/0x16d0
<4> [2432.506140]                        process_one_work+0x26a/0x620
<4> [2432.506178]                        worker_thread+0x37/0x380
<4> [2432.506218]                        kthread+0x119/0x130
<4> [2432.506252]                        ret_from_fork+0x3a/0x50
<4> [2432.506284]    }
<4> [2432.506317]    ... key      at: [<ffffffff828389f0>] __key.18650+0x0/0x10
<4> [2432.506353]    ... acquired at:
<4> [2432.506384]    _raw_spin_lock+0x2a/0x40
<4> [2432.506420]    __mutex_unlock_slowpath+0x18e/0x2b0
<4> [2432.506712]    intel_gt_retire_requests_timeout+0x1b9/0x540 [i915]
<4> [2432.506983]    i915_drop_caches_set+0x160/0x240 [i915]
<4> [2432.507009]    simple_attr_write+0xb0/0xd0
<4> [2432.507030]    full_proxy_write+0x51/0x80
<4> [2432.507049]    vfs_write+0xb9/0x1d0
<4> [2432.507065]    ksys_write+0x9f/0xe0
<4> [2432.507084]    do_syscall_64+0x4f/0x210
<4> [2432.507102]    entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [2432.507119] 
<4> [2432.507132]  -> (&(&timelines->lock)->rlock){....} ops: 221249 {
<4> [2432.507173]     INITIAL USE at:
<4> [2432.507196]                      lock_acquire+0xa7/0x1c0
<4> [2432.507222]                      _raw_spin_lock_irqsave+0x33/0x50
<4> [2432.507478]                      intel_timeline_enter+0x64/0x150 [i915]
<4> [2432.507700]                      __engine_park+0x1ef/0x420 [i915]
<4> [2432.507901]                      ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.508125]                      intel_gt_resume+0x1fd/0x2c0 [i915]
<4> [2432.508445]                      i915_gem_init+0x366/0x8d0 [i915]
<4> [2432.508625]                      i915_driver_probe+0xb9d/0x15b0 [i915]
<4> [2432.508811]                      i915_pci_probe+0x43/0x1c0 [i915]
<4> [2432.508836]                      pci_device_probe+0x9e/0x120
<4> [2432.508862]                      really_probe+0xea/0x420
<4> [2432.508886]                      driver_probe_device+0x10b/0x120
<4> [2432.508913]                      device_driver_attach+0x4a/0x50
<4> [2432.508938]                      __driver_attach+0x97/0x130
<4> [2432.508961]                      bus_for_each_dev+0x74/0xc0
<4> [2432.508985]                      bus_add_driver+0x142/0x220
<4> [2432.509009]                      driver_register+0x56/0xf0
<4> [2432.509030]                      do_one_initcall+0x58/0x2ff
<4> [2432.509056]                      do_init_module+0x56/0x1f8
<4> [2432.509080]                      load_module+0x243e/0x29f0
<4> [2432.509103]                      __do_sys_finit_module+0xe9/0x110
<4> [2432.509126]                      do_syscall_64+0x4f/0x210
<4> [2432.509160]                      entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [2432.509196]   }
<4> [2432.509416]   ... key      at: [<ffffffffa04e1b90>] __key.70130+0x0/0xffffffffffeeb470 [i915]
<4> [2432.509442]   ... acquired at:
<4> [2432.509464]    _raw_spin_lock_irqsave+0x33/0x50
<4> [2432.509711]    intel_timeline_enter+0x64/0x150 [i915]
<4> [2432.509931]    __engine_park+0x1ef/0x420 [i915]
<4> [2432.510132]    ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.510367]    intel_gt_resume+0x1fd/0x2c0 [i915]
<4> [2432.510661]    i915_gem_init+0x366/0x8d0 [i915]
<4> [2432.510836]    i915_driver_probe+0xb9d/0x15b0 [i915]
<4> [2432.511019]    i915_pci_probe+0x43/0x1c0 [i915]
<4> [2432.511040]    pci_device_probe+0x9e/0x120
<4> [2432.511061]    really_probe+0xea/0x420
<4> [2432.511082]    driver_probe_device+0x10b/0x120
<4> [2432.511104]    device_driver_attach+0x4a/0x50
<4> [2432.511127]    __driver_attach+0x97/0x130
<4> [2432.511160]    bus_for_each_dev+0x74/0xc0
<4> [2432.511185]    bus_add_driver+0x142/0x220
<4> [2432.511206]    driver_register+0x56/0xf0
<4> [2432.511224]    do_one_initcall+0x58/0x2ff
<4> [2432.511246]    do_init_module+0x56/0x1f8
<4> [2432.511266]    load_module+0x243e/0x29f0
<4> [2432.511286]    __do_sys_finit_module+0xe9/0x110
<4> [2432.511305]    do_syscall_64+0x4f/0x210
<4> [2432.511322]    entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [2432.511339] 
<4> [2432.511348] -> (&kernel/2){-...} ops: 446 {
<4> [2432.511378]    IN-HARDIRQ-W at:
<4> [2432.511401]                     lock_acquire+0xa7/0x1c0
<4> [2432.511619]                     __engine_park+0x1ac/0x420 [i915]
<4> [2432.511820]                     ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.512030]                     i915_sample+0x316/0x350 [i915]
<4> [2432.512056]                     __hrtimer_run_queues+0x121/0x4a0
<4> [2432.512078]                     hrtimer_interrupt+0xea/0x250
<4> [2432.512102]                     smp_apic_timer_interrupt+0x96/0x280
<4> [2432.512126]                     apic_timer_interrupt+0xf/0x20
<4> [2432.512165]                     rcu_read_lock_held_common+0x25/0x70
<4> [2432.512199]                     rcu_read_lock_sched_held+0x1e/0x80
<4> [2432.512223]                     __do_softirq+0x442/0x47f
<4> [2432.512244]                     irq_exit+0xba/0xc0
<4> [2432.512263]                     do_IRQ+0x83/0x160
<4> [2432.512282]                     ret_from_intr+0x0/0x1d
<4> [2432.512309]                     __slab_alloc.isra.84.constprop.89+0x4f/0x70
<4> [2432.512337]                     kmem_cache_alloc+0x24a/0x2a0
<4> [2432.512363]                     acpi_ut_allocate_object_desc_dbg+0x33/0x5b
<4> [2432.512390]                     acpi_ut_create_internal_object_dbg+0x18/0x79
<4> [2432.512416]                     acpi_ds_create_operand+0x1df/0x244
<4> [2432.512439]                     acpi_ds_create_operands+0x70/0xd1
<4> [2432.512462]                     acpi_ds_exec_end_op+0x214/0x3fc
<4> [2432.512488]                     acpi_ps_parse_loop+0x486/0x5af
<4> [2432.512514]                     acpi_ps_parse_aml+0x8c/0x2b3
<4> [2432.512534]                     acpi_ps_execute_method+0x158/0x18c
<4> [2432.512558]                     acpi_ns_evaluate+0x1bc/0x249
<4> [2432.512582]                     acpi_evaluate_object+0x132/0x23b
<4> [2432.512608]                     acpi_dev_pm_explicit_set.part.3+0x42/0x70
<4> [2432.512633]                     acpi_device_set_power+0x1d4/0x300
<4> [2432.512660]                     acpi_pci_set_power_state+0x7e/0xe0
<4> [2432.512684]                     pci_platform_power_transition+0x3e/0x90
<4> [2432.512709]                     pci_set_power_state+0x83/0xf0
<4> [2432.512734]                     pci_restore_standard_config+0x22/0x40
<4> [2432.512760]                     pci_pm_runtime_resume+0x23/0xc0
<4> [2432.512786]                     __rpm_callback+0xb1/0x110
<4> [2432.512808]                     rpm_callback+0x4a/0x70
<4> [2432.512829]                     rpm_resume+0x50e/0x790
<4> [2432.512851]                     __pm_runtime_resume+0x42/0x80
<4> [2432.512876]                     __mmc_claim_host+0x1ff/0x290
<4> [2432.512898]                     mmc_mq_queue_rq+0x1f2/0x220
<4> [2432.512920]                     blk_mq_dispatch_rq_list+0x3b6/0x570
<4> [2432.512945]                     blk_mq_do_dispatch_sched+0x71/0x110
<4> [2432.512969]                     blk_mq_sched_dispatch_requests+0xf2/0x160
<4> [2432.512992]                     __blk_mq_run_hw_queue+0x7a/0xe0
<4> [2432.513015]                     process_one_work+0x26a/0x620
<4> [2432.513036]                     worker_thread+0x37/0x380
<4> [2432.513060]                     kthread+0x119/0x130
<4> [2432.513079]                     ret_from_fork+0x3a/0x50
<4> [2432.513096]    INITIAL USE at:
<4> [2432.513119]                    lock_acquire+0xa7/0x1c0
<4> [2432.513368]                    __engine_park+0x1ac/0x420 [i915]
<4> [2432.513571]                    ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.513791]                    intel_gt_resume+0x1fd/0x2c0 [i915]
<4> [2432.514091]                    i915_gem_init+0x366/0x8d0 [i915]
<4> [2432.514292]                    i915_driver_probe+0xb9d/0x15b0 [i915]
<4> [2432.514480]                    i915_pci_probe+0x43/0x1c0 [i915]
<4> [2432.514504]                    pci_device_probe+0x9e/0x120
<4> [2432.514529]                    really_probe+0xea/0x420
<4> [2432.514552]                    driver_probe_device+0x10b/0x120
<4> [2432.514577]                    device_driver_attach+0x4a/0x50
<4> [2432.514602]                    __driver_attach+0x97/0x130
<4> [2432.514624]                    bus_for_each_dev+0x74/0xc0
<4> [2432.514647]                    bus_add_driver+0x142/0x220
<4> [2432.514671]                    driver_register+0x56/0xf0
<4> [2432.514691]                    do_one_initcall+0x58/0x2ff
<4> [2432.514716]                    do_init_module+0x56/0x1f8
<4> [2432.514739]                    load_module+0x243e/0x29f0
<4> [2432.514761]                    __do_sys_finit_module+0xe9/0x110
<4> [2432.514782]                    do_syscall_64+0x4f/0x210
<4> [2432.514803]                    entry_SYSCALL_64_after_hwframe+0x49/0xbe
<4> [2432.514822]  }
<4> [2432.515034]  ... key      at: [<ffffffffa04e1952>] kernel.70648+0x2/0xffffffffffeeb6b0 [i915]
<4> [2432.515058]  ... acquired at:
<4> [2432.515080]    mark_lock+0x382/0x500
<4> [2432.515096]    __lock_acquire+0x7e1/0x15d0
<4> [2432.515118]    lock_acquire+0xa7/0x1c0
<4> [2432.515347]    __engine_park+0x1ac/0x420 [i915]
<4> [2432.515544]    ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.515749]    i915_sample+0x316/0x350 [i915]
<4> [2432.515771]    __hrtimer_run_queues+0x121/0x4a0
<4> [2432.515791]    hrtimer_interrupt+0xea/0x250
<4> [2432.515812]    smp_apic_timer_interrupt+0x96/0x280
<4> [2432.515832]    apic_timer_interrupt+0xf/0x20
<4> [2432.515854]    rcu_read_lock_held_common+0x25/0x70
<4> [2432.515876]    rcu_read_lock_sched_held+0x1e/0x80
<4> [2432.515896]    __do_softirq+0x442/0x47f
<4> [2432.515914]    irq_exit+0xba/0xc0
<4> [2432.515930]    do_IRQ+0x83/0x160
<4> [2432.515946]    ret_from_intr+0x0/0x1d
<4> [2432.515969]    __slab_alloc.isra.84.constprop.89+0x4f/0x70
<4> [2432.515994]    kmem_cache_alloc+0x24a/0x2a0
<4> [2432.516016]    acpi_ut_allocate_object_desc_dbg+0x33/0x5b
<4> [2432.516040]    acpi_ut_create_internal_object_dbg+0x18/0x79
<4> [2432.516063]    acpi_ds_create_operand+0x1df/0x244
<4> [2432.516083]    acpi_ds_create_operands+0x70/0xd1
<4> [2432.516102]    acpi_ds_exec_end_op+0x214/0x3fc
<4> [2432.516126]    acpi_ps_parse_loop+0x486/0x5af
<4> [2432.516160]    acpi_ps_parse_aml+0x8c/0x2b3
<4> [2432.516188]    acpi_ps_execute_method+0x158/0x18c
<4> [2432.516209]    acpi_ns_evaluate+0x1bc/0x249
<4> [2432.516230]    acpi_evaluate_object+0x132/0x23b
<4> [2432.516252]    acpi_dev_pm_explicit_set.part.3+0x42/0x70
<4> [2432.516275]    acpi_device_set_power+0x1d4/0x300
<4> [2432.516298]    acpi_pci_set_power_state+0x7e/0xe0
<4> [2432.516320]    pci_platform_power_transition+0x3e/0x90
<4> [2432.516342]    pci_set_power_state+0x83/0xf0
<4> [2432.516364]    pci_restore_standard_config+0x22/0x40
<4> [2432.516388]    pci_pm_runtime_resume+0x23/0xc0
<4> [2432.516409]    __rpm_callback+0xb1/0x110
<4> [2432.516428]    rpm_callback+0x4a/0x70
<4> [2432.516447]    rpm_resume+0x50e/0x790
<4> [2432.516466]    __pm_runtime_resume+0x42/0x80
<4> [2432.516487]    __mmc_claim_host+0x1ff/0x290
<4> [2432.516505]    mmc_mq_queue_rq+0x1f2/0x220
<4> [2432.516524]    blk_mq_dispatch_rq_list+0x3b6/0x570
<4> [2432.516545]    blk_mq_do_dispatch_sched+0x71/0x110
<4> [2432.516567]    blk_mq_sched_dispatch_requests+0xf2/0x160
<4> [2432.516587]    __blk_mq_run_hw_queue+0x7a/0xe0
<4> [2432.516607]    process_one_work+0x26a/0x620
<4> [2432.516626]    worker_thread+0x37/0x380
<4> [2432.516647]    kthread+0x119/0x130
<4> [2432.516663]    ret_from_fork+0x3a/0x50
<4> [2432.516677] 
<4> [2432.516687] 
stack backtrace:
<4> [2432.516710] CPU: 0 PID: 227 Comm: kworker/0:1H Tainted: G     U            5.4.0-rc6-CI-CI_DRM_7315+ #1
<4> [2432.516737] Hardware name: Google Caroline/Caroline, BIOS MrChromebox 08/27/2018
<4> [2432.516766] Workqueue: kblockd blk_mq_run_work_fn
<4> [2432.516784] Call Trace:
<4> [2432.516799]  <IRQ>
<4> [2432.516821]  dump_stack+0x67/0x9b
<4> [2432.516846]  check_usage_forwards+0x13c/0x150
<4> [2432.516878]  ? mark_lock+0x382/0x500
<4> [2432.516899]  mark_lock+0x382/0x500
<4> [2432.516920]  ? check_usage_backwards+0x140/0x140
<4> [2432.516942]  __lock_acquire+0x7e1/0x15d0
<4> [2432.516970]  lock_acquire+0xa7/0x1c0
<4> [2432.517203]  ? __engine_park+0x175/0x420 [i915]
<4> [2432.517426]  __engine_park+0x1ac/0x420 [i915]
<4> [2432.517637]  ? __engine_park+0x175/0x420 [i915]
<4> [2432.517835]  ____intel_wakeref_put_last+0x1c/0x70 [i915]
<4> [2432.518038]  i915_sample+0x316/0x350 [i915]
<4> [2432.518262]  ? i915_pmu_cpu_online+0x70/0x70 [i915]
<4> [2432.518287]  __hrtimer_run_queues+0x121/0x4a0
<4> [2432.518312]  hrtimer_interrupt+0xea/0x250
<4> [2432.518338]  smp_apic_timer_interrupt+0x96/0x280
<4> [2432.518359]  apic_timer_interrupt+0xf/0x20
<4> [2432.518384] RIP: 0010:rcu_read_lock_held_common+0x25/0x70
<4> [2432.518407] Code: e9 54 ff ff ff 8b 15 d6 84 20 01 85 d2 74 0a 8b 05 24 b1 20 01 85 c0 75 09 c6 07 01 b8 01 00 00 00 c3 48 83 ec 08 48 89 3c 24 <e8> c6 f9 ff ff 85 c0 48 8b 3c 24 75 0d c6 07 01 b8 01 00 00 00 48
<4> [2432.518453] RSP: 0018:ffffc90000003f48 EFLAGS: 00000296 ORIG_RAX: ffffffffffffff13
<4> [2432.518481] RAX: 0000000000000001 RBX: 0000000000000001 RCX: ffffffff82340bb0
<4> [2432.518503] RDX: 0000000000000002 RSI: ffff888177380920 RDI: ffffc90000003f5f
<4> [2432.518524] RBP: 0000000000000010 R08: ffff888177380920 R09: 0000000000000000
<4> [2432.518546] R10: 0000000000000000 R11: 0000000000000000 R12: ffffffff82205108
<4> [2432.518566] R13: 0000000000000001 R14: 0000000000000001 R15: 0000000000000000
<4> [2432.518592]  ? apic_timer_interrupt+0xa/0x20
<4> [2432.518624]  rcu_read_lock_sched_held+0x1e/0x80
<4> [2432.518646]  __do_softirq+0x442/0x47f
<4> [2432.518669]  irq_exit+0xba/0xc0
<4> [2432.518686]  do_IRQ+0x83/0x160
<4> [2432.518704]  common_interrupt+0xf/0xf
<4> [2432.518719]  </IRQ>
<4> [2432.518741] RIP: 0010:__slab_alloc.isra.84.constprop.89+0x4f/0x70
<4> [2432.518764] Code: 89 f2 44 89 ee 48 8b 0c c5 80 46 16 82 4c 89 e7 48 01 d9 e8 23 f8 ff ff f7 c5 00 02 00 00 48 89 c3 74 13 e8 d3 be f5 ff 55 9d <48> 89 d8 5b 5d 41 5c 41 5d 41 5e c3 55 9d e8 be c0 f5 ff 48 89 d8
<4> [2432.518808] RSP: 0018:ffffc90000ff77c8 EFLAGS: 00000246 ORIG_RAX: ffffffffffffffdb
<4> [2432.518834] RAX: ffff888177380040 RBX: ffff888178e0c4d0 RCX: 0000000000000000
<4> [2432.518855] RDX: 0000000000000007 RSI: ffff888177380920 RDI: ffffffff8213c1e1
<4> [2432.518876] RBP: 0000000000000246 R08: 0000000000000001 R09: 0000000000000000
<4> [2432.518897] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88817a015fc0
<4> [2432.518918] R13: 0000000000000dc0 R14: ffffffff815d75db R15: ffff88817a015fc0
<4> [2432.518947]  ? acpi_ut_allocate_object_desc_dbg+0x33/0x5b
<4> [2432.518980]  ? acpi_ut_allocate_object_desc_dbg+0x33/0x5b
<4> [2432.519005]  kmem_cache_alloc+0x24a/0x2a0
<4> [2432.519029]  acpi_ut_allocate_object_desc_dbg+0x33/0x5b
<4> [2432.519054]  acpi_ut_create_internal_object_dbg+0x18/0x79
<4> [2432.519079]  acpi_ds_create_operand+0x1df/0x244
<4> [2432.519105]  ? debug_check_no_obj_freed+0x11d/0x210
<4> [2432.519131]  acpi_ds_create_operands+0x70/0xd1
<4> [2432.519168]  ? rcu_read_lock_sched_held+0x4d/0x80
<4> [2432.519194]  ? kmem_cache_free+0x31f/0x390
<4> [2432.519216]  ? kmem_cache_free+0x2cf/0x390
<4> [2432.519238]  acpi_ds_exec_end_op+0x214/0x3fc
<4> [2432.519262]  acpi_ps_parse_loop+0x486/0x5af
<4> [2432.519288]  acpi_ps_parse_aml+0x8c/0x2b3
<4> [2432.519308]  acpi_ps_execute_method+0x158/0x18c
<4> [2432.519330]  acpi_ns_evaluate+0x1bc/0x249
<4> [2432.519352]  acpi_evaluate_object+0x132/0x23b
<4> [2432.519379]  acpi_dev_pm_explicit_set.part.3+0x42/0x70
<4> [2432.519402]  acpi_device_set_power+0x1d4/0x300
<4> [2432.519429]  acpi_pci_set_power_state+0x7e/0xe0
<4> [2432.519455]  ? pci_restore_standard_config+0x40/0x40
<4> [2432.519477]  pci_platform_power_transition+0x3e/0x90
<4> [2432.519501]  pci_set_power_state+0x83/0xf0
<4> [2432.519525]  pci_restore_standard_config+0x22/0x40
<4> [2432.519549]  pci_pm_runtime_resume+0x23/0xc0
<4> [2432.519572]  __rpm_callback+0xb1/0x110
<4> [2432.519594]  rpm_callback+0x4a/0x70
<4> [2432.519615]  ? pci_restore_standard_config+0x40/0x40
<4> [2432.519637]  rpm_resume+0x50e/0x790
<4> [2432.519662]  ? __mmc_claim_host+0xcf/0x290
<4> [2432.519683]  __pm_runtime_resume+0x42/0x80
<4> [2432.519705]  __mmc_claim_host+0x1ff/0x290
<4> [2432.519729]  ? _raw_spin_unlock_irqrestore+0x4c/0x60
<4> [2432.519754]  ? wake_up_q+0x70/0x70
<4> [2432.519775]  mmc_mq_queue_rq+0x1f2/0x220
<4> [2432.519798]  blk_mq_dispatch_rq_list+0x3b6/0x570
<4> [2432.519818]  ? __lock_acquire+0x460/0x15d0
<4> [2432.519843]  ? _raw_spin_trylock_bh+0x51/0x70
<4> [2432.519867]  blk_mq_do_dispatch_sched+0x71/0x110
<4> [2432.519893]  blk_mq_sched_dispatch_requests+0xf2/0x160
<4> [2432.519917]  __blk_mq_run_hw_queue+0x7a/0xe0
<4> [2432.519938]  process_one_work+0x26a/0x620
<4> [2432.519963]  worker_thread+0x37/0x380
<4> [2432.519984]  ? process_one_work+0x620/0x620
<4> [2432.520006]  kthread+0x119/0x130
<4> [2432.520027]  ? kthread_park+0x80/0x80
<4> [2432.520045]  ret_from_fork+0x3a/0x50
<7> [2433.582106] [drm:intel_power_well_disable [i915]] disabling DC off
<7> [2433.582540] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [2433.582915] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7> [2433.586355] [drm:intel_power_well_enable [i915]] enabling DC off
<7> [2433.587010] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<7> [2434.094698] [drm:intel_power_well_disable [i915]] disabling DC off
<7> [2434.095213] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [2434.095785] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7> [2434.106336] [drm:intel_power_well_enable [i915]] enabling DC off
<7> [2434.107146] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<7> [2434.614619] [drm:intel_power_well_disable [i915]] disabling DC off
<7> [2434.615009] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [2434.615441] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7> [2434.624250] [drm:intel_power_well_enable [i915]] enabling DC off
<7> [2434.624943] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<7> [2435.131216] [drm:intel_power_well_disable [i915]] disabling DC off
<7> [2435.131779] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [2435.132262] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7> [2435.146340] [drm:intel_power_well_enable [i915]] enabling DC off
<7> [2435.147144] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<7> [2435.654869] [drm:intel_power_well_disable [i915]] disabling DC off
<7> [2435.655384] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [2435.655914] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<7> [2435.667744] [drm:intel_power_well_enable [i915]] enabling DC off
<7> [2435.668496] [drm:gen9_set_dc_state [i915]] Setting DC state from 02 to 00
<7> [2436.178427] [drm:intel_power_well_disable [i915]] disabling DC off
<7> [2436.178981] [drm:skl_enable_dc6 [i915]] Enabling DC6
<7> [2436.179457] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 02
<6> [2436.200568] [IGT] perf_pmu: exiting, ret=0
<5> [2436.201992] Setting dangerous option reset - tainting kernel
<5> [2436.203937] Setting dangerous option reset - tainting kernel
<6> [2436.233261] Console: switching to colour frame buffer device 300x100
Created at 2019-11-12 22:34:57