Bug 105392 - [CI] igt@kms_vblank@pipe-b-ts-continuation-suspend - incomplete - INFO: task java:1022 blocked for more than 60 seconds.
Summary: [CI] igt@kms_vblank@pipe-b-ts-continuation-suspend - incomplete - INFO: task ...
Status: CLOSED DUPLICATE of bug 104874
Alias: None
Product: DRI
Classification: Unclassified
Component: DRM/Intel (show other bugs)
Version: DRI git
Hardware: Other All
: medium normal
Assignee: Intel GFX Bugs mailing list
QA Contact: Intel GFX Bugs mailing list
URL:
Whiteboard: ReadyForDev
Keywords:
Depends on:
Blocks:
 
Reported: 2018-03-08 06:55 UTC by Marta Löfstedt
Modified: 2018-03-08 07:03 UTC (History)
1 user (show)

See Also:
i915 platform: HSW
i915 features:


Attachments

Description Marta Löfstedt 2018-03-08 06:55:05 UTC
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3891/shard-hsw4/igt@kms_vblank@pipe-b-ts-continuation-suspend.html

Unfortunately link is dead on the server, however I managed to figure out that this is the run.log:
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3891/shard-hsw4/run33.log
dmesg:
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3891/shard-hsw4/dmesg33.log
pstore:
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3891/shard-hsw4/pstore33-1520464572_Panic_1.log

<3>[ 1660.248294] INFO: task java:1022 blocked for more than 60 seconds.
<3>[ 1660.249203]       Tainted: G     U  W        4.16.0-rc4-CI-CI_DRM_3891+ #1
<3>[ 1660.249331] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
<6>[ 1660.249470] java            D    0  1022    973 0x00000000
<4>[ 1660.249484] Call Trace:
<4>[ 1660.249507]  ? __schedule+0x3c7/0xb00
<4>[ 1660.249523]  ? bit_wait+0x50/0x50
<4>[ 1660.249533]  schedule+0x37/0x90
<4>[ 1660.249547]  io_schedule+0xd/0x30
<4>[ 1660.249556]  bit_wait_io+0x8/0x50
<4>[ 1660.249565]  __wait_on_bit+0x4e/0x80
<4>[ 1660.249578]  out_of_line_wait_on_bit+0x78/0x80
<4>[ 1660.249592]  ? bit_waitqueue+0x30/0x30
<4>[ 1660.249607]  do_get_write_access+0x1fe/0x670
<4>[ 1660.249628]  ? ext4_dirty_inode+0x3e/0x60
<4>[ 1660.249638]  jbd2_journal_get_write_access+0x27/0x50
<4>[ 1660.249651]  __ext4_journal_get_write_access+0x5e/0xb0
<4>[ 1660.249665]  ext4_reserve_inode_write+0x88/0xb0
<4>[ 1660.249678]  ext4_mark_inode_dirty+0x63/0x270
<4>[ 1660.249688]  ? rcu_read_lock_sched_held+0x6f/0x80
<4>[ 1660.249705]  ext4_dirty_inode+0x3e/0x60
<4>[ 1660.249717]  __mark_inode_dirty+0x67/0x690
<4>[ 1660.249735]  generic_update_time+0x62/0xc0
<4>[ 1660.249745]  file_update_time+0x9f/0xf0
<4>[ 1660.249755]  ? __sb_start_write+0xf3/0x1f0
<4>[ 1660.249770]  ext4_page_mkwrite+0x52/0x4e0
<4>[ 1660.249792]  do_page_mkwrite+0x26/0xa0
<4>[ 1660.249803]  do_wp_page+0x16b/0x4a0
<4>[ 1660.249816]  __handle_mm_fault+0x610/0xdc0
<4>[ 1660.249843]  handle_mm_fault+0x1c5/0x390
<4>[ 1660.249857]  __do_page_fault+0x2d1/0x560
<4>[ 1660.249873]  ? page_fault+0x2f/0x50
<4>[ 1660.249882]  page_fault+0x45/0x50
<4>[ 1660.249890] RIP: ca0b75c8:0x7f49ab8efd50
<4>[ 1660.249896] RSP: c4229f50:00007f49ab8efdc0 EFLAGS: 7f49c41fd000
<4>[ 1660.249922] 
<4>[ 1660.249922] Showing all locks held in the system:
<4>[ 1660.249936] 2 locks held by khungtaskd/61:
<4>[ 1660.249950]  #0:  (rcu_read_lock){....}, at: [<00000000321b3e50>] watchdog+0x9b/0x5e0
<4>[ 1660.249983]  #1:  (tasklist_lock){.+.+}, at: [<000000006f0dd0f1>] debug_show_all_locks+0x37/0x1a0
<4>[ 1660.250016] 1 lock held by in:imklog/579:
<4>[ 1660.250021]  #0:  (&f->f_pos_lock){+.+.}, at: [<0000000071ec0924>] __fdget_pos+0x3a/0x50
<4>[ 1660.250052] 1 lock held by NetworkManager/709:
<4>[ 1660.250057]  #0:  (&mm->mmap_sem){++++}, at: [<000000009a7789f2>] __do_page_fault+0x106/0x560
<4>[ 1660.250086] 3 locks held by java/1022:
<4>[ 1660.250091]  #0:  (&mm->mmap_sem){++++}, at: [<000000009a7789f2>] __do_page_fault+0x106/0x560
<4>[ 1660.250116]  #1:  (sb_pagefaults){.+.+}, at: [<0000000097ac047f>] ext4_page_mkwrite+0x46/0x4e0
<4>[ 1660.250144]  #2:  (jbd2_handle){++++}, at: [<000000009b2c45b9>] start_this_handle+0xff/0x440
<4>[ 1660.250249] 3 locks held by java/8703:
<4>[ 1660.250254]  #0:  (&mm->mmap_sem){++++}, at: [<000000009a7789f2>] __do_page_fault+0x106/0x560
<4>[ 1660.250287]  #1:  (sb_pagefaults){.+.+}, at: [<0000000097ac047f>] ext4_page_mkwrite+0x46/0x4e0
<4>[ 1660.250331]  #2:  (jbd2_handle){++++}, at: [<000000009b2c45b9>] start_this_handle+0xff/0x440
<4>[ 1660.250370] 1 lock held by java/9248:
<4>[ 1660.250377]  #0:  (&mm->mmap_sem){++++}, at: [<00000000a162c514>] vm_mmap_pgoff+0x51/0xd0
<4>[ 1660.250434] 6 locks held by kworker/u16:27/8368:
<4>[ 1660.250441]  #0:  ((wq_completion)"writeback"){+.+.}, at: [<00000000f11130c8>] process_one_work+0x191/0x620
<4>[ 1660.250484]  #1:  ((work_completion)(&(&wb->dwork)->work)){+.+.}, at: [<00000000f11130c8>] process_one_work+0x191/0x620
<4>[ 1660.250512]  #2:  (&type->s_umount_key#33){++++}, at: [<000000003bb7b121>] trylock_super+0x11/0x50
<4>[ 1660.250545]  #3:  (&sbi->s_journal_flag_rwsem){.+.+}, at: [<00000000b4a0c862>] do_writepages+0x1b/0x70
<4>[ 1660.250575]  #4:  (jbd2_handle){++++}, at: [<000000009b2c45b9>] start_this_handle+0xff/0x440
<4>[ 1660.250602]  #5:  (&ei->i_data_sem){++++}, at: [<00000000e5229d9d>] ext4_map_blocks+0x11f/0x580
<4>[ 1660.250644] 3 locks held by dmesg/8925:
<4>[ 1660.250648]  #0:  (sb_writers#3){.+.+}, at: [<000000002df8b34f>] vfs_write+0x158/0x1b0
<4>[ 1660.250679]  #1:  (&sb->s_type->i_mutex_key#14){+.+.}, at: [<00000000e45128f6>] ext4_file_write_iter+0x64/0x3a0
<4>[ 1660.250710]  #2:  (jbd2_handle){++++}, at: [<000000009b2c45b9>] start_this_handle+0xff/0x440
<4>[ 1660.250745] 1 lock held by python3/9153:
<4>[ 1660.250758]  #0:  (&sbi->s_journal_flag_rwsem){.+.+}, at: [<00000000b4a0c862>] do_writepages+0x1b/0x70
<4>[ 1660.250796] 3 locks held by cron/9247:
<4>[ 1660.250801]  #0:  (sb_writers#3){.+.+}, at: [<000000002294e138>] mnt_want_write+0x1b/0x50
<4>[ 1660.250836]  #1:  (&type->i_mutex_dir_key){++++}, at: [<0000000050926f6d>] path_openat+0x440/0x9d0
<4>[ 1660.250867]  #2:  (jbd2_handle){++++}, at: [<000000009b2c45b9>] start_this_handle+0xff/0x440
<4>[ 1660.250897] 
<4>[ 1660.250902] =============================================
<4>[ 1660.250902] 
<4>[ 1660.250908] NMI backtrace for cpu 2
<4>[ 1660.250917] CPU: 2 PID: 61 Comm: khungtaskd Tainted: G     U  W        4.16.0-rc4-CI-CI_DRM_3891+ #1
<4>[ 1660.250923] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[ 1660.250927] Call Trace:
<4>[ 1660.250940]  dump_stack+0x5f/0x86
<4>[ 1660.250950]  nmi_cpu_backtrace+0xb4/0xc0
<4>[ 1660.250964]  ? lapic_can_unplug_cpu+0x90/0x90
<4>[ 1660.250972]  nmi_trigger_cpumask_backtrace+0xb9/0xf0
<4>[ 1660.250985]  watchdog+0x43e/0x5e0
<4>[ 1660.251001]  kthread+0xfb/0x130
<4>[ 1660.251009]  ? reset_hung_task_detector+0x10/0x10
<4>[ 1660.251016]  ? _kthread_create_on_node+0x30/0x30
<4>[ 1660.251032]  ret_from_fork+0x3a/0x50
<6>[ 1660.251060] Sending NMI from CPU 2 to CPUs 0-1,3-7:
<4>[ 1660.251085] NMI backtrace for cpu 3 skipped: idling at intel_idle+0x6f/0x120
<4>[ 1660.251091] NMI backtrace for cpu 5
<4>[ 1660.251098] CPU: 5 PID: 0 Comm: swapper/5 Tainted: G     U  W        4.16.0-rc4-CI-CI_DRM_3891+ #1
<4>[ 1660.251100] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[ 1660.251111] RIP: 0010:__tick_nohz_idle_enter+0x70/0x590
<4>[ 1660.251115] RSP: 0018:ffffc900000b7e90 EFLAGS: 00000046
<4>[ 1660.251121] RAX: 0000000000000005 RBX: 0000000000000005 RCX: 000000000000001f
<4>[ 1660.251125] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000016a3ea88e6f
<4>[ 1660.251129] RBP: 00000182784144fc R08: 0000000000000000 R09: 0000000000000002
<4>[ 1660.251132] R10: 0000000000000000 R11: ffffffff811175cd R12: 0000000000000000
<4>[ 1660.251136] R13: ffff88040cd44f40 R14: 0000000000000000 R15: ffff88041fb5c7e0
<4>[ 1660.251141] FS:  0000000000000000(0000) GS:ffff88041fb40000(0000) knlGS:0000000000000000
<4>[ 1660.251145] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[ 1660.251149] CR2: 00007fff61311af0 CR3: 0000000005210003 CR4: 00000000001606e0
<4>[ 1660.251151] Call Trace:
<4>[ 1660.251160]  ? set_cpu_sd_state_idle+0x8b/0x150
<4>[ 1660.251169]  tick_nohz_idle_enter+0x61/0x80
<4>[ 1660.251175]  do_idle+0x29/0x1d0
<4>[ 1660.251183]  cpu_startup_entry+0x14/0x20
<4>[ 1660.251190]  start_secondary+0x129/0x160
<4>[ 1660.251198]  secondary_startup_64+0xa5/0xb0
<4>[ 1660.251205] Code: 00 00 45 8b 4f 48 45 85 c9 0f 84 64 03 00 00 65 48 8b 14 25 80 4e 01 00 48 8b 12 83 e2 08 0f 85 08 01 00 00 65 8b 35 20 97 f0 7e <85> f6 0f 85 6a 03 00 00 49 83 47 70 01 41 8b 47 64 48 c7 c7 8f 
<4>[ 1660.251289] NMI backtrace for cpu 7 skipped: idling at intel_idle+0x6f/0x120
<4>[ 1660.251293] NMI backtrace for cpu 1
<4>[ 1660.251300] CPU: 1 PID: 237 Comm: systemd-journal Tainted: G     U  W        4.16.0-rc4-CI-CI_DRM_3891+ #1
<4>[ 1660.251303] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[ 1660.251308] RIP: 0033:0x7fd4c5f51585
<4>[ 1660.251311] RSP: 002b:00007ffdfd210748 EFLAGS: 00000202
<4>[ 1660.251317] RAX: 0000000000000050 RBX: 00007ffdfd2137f0 RCX: fffffffffff2155c
<4>[ 1660.251320] RDX: 0000000000000001 RSI: 000055d3b64d27d0 RDI: 00007ffdfd210780
<4>[ 1660.251323] RBP: 00007ffdfd2107d0 R08: 0000000000000000 R09: 0000000000000001
<4>[ 1660.251326] R10: 00007fd4c626bb58 R11: 00007fd4c6030124 R12: 00007ffdfd210750
<4>[ 1660.251328] R13: 0000000000000001 R14: 000055d3b64d27a0 R15: 0000000000000009
<4>[ 1660.251332] FS:  00007fd4c6ccc8c0(0000) GS:ffff88041fa40000(0000) knlGS:0000000000000000
<4>[ 1660.251335] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[ 1660.251338] CR2: 00007fd4b5639000 CR3: 0000000409e7a001 CR4: 00000000001606e0
<4>[ 1660.251349] NMI backtrace for cpu 6 skipped: idling at intel_idle+0x6f/0x120
<4>[ 1660.251363] NMI backtrace for cpu 4 skipped: idling at intel_idle+0x6f/0x120
<4>[ 1660.251375] NMI backtrace for cpu 0 skipped: idling at intel_idle+0x6f/0x120
<0>[ 1660.252083] Kernel panic - not syncing: hung_task: blocked tasks
<4>[ 1660.252200] CPU: 2 PID: 61 Comm: khungtaskd Tainted: G     U  W        4.16.0-rc4-CI-CI_DRM_3891+ #1
<4>[ 1660.252363] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[ 1660.252489] Call Trace:
<4>[ 1660.252550]  dump_stack+0x5f/0x86
<4>[ 1660.252624]  panic+0xcf/0x20d
<4>[ 1660.252703]  watchdog+0x44a/0x5e0
<4>[ 1660.252780]  kthread+0xfb/0x130
<4>[ 1660.252847]  ? reset_hung_task_detector+0x10/0x10
<4>[ 1660.252937]  ? _kthread_create_on_node+0x30/0x30
<4>[ 1660.253032]  ret_from_fork+0x3a/0x50
<0>[ 1660.253182] Dumping ftrace buffer:
<0>[ 1660.253430]    (ftrace buffer empty)
<0>[ 1660.253506] Kernel Offset: disabled
Comment 1 Marta Löfstedt 2018-03-08 06:59:16 UTC
This look very similar to bug 105087
Comment 2 Marta Löfstedt 2018-03-08 07:01:58 UTC

*** This bug has been marked as a duplicate of bug 104874 ***


Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.