Summary: | [BAT] [CNL only] igt@* - incomplete - timeout/system hang | ||
---|---|---|---|
Product: | DRI | Reporter: | Marta Löfstedt <marta.lofstedt> |
Component: | DRM/Intel | Assignee: | Intel GFX Bugs mailing list <intel-gfx-bugs> |
Status: | CLOSED WORKSFORME | QA Contact: | Intel GFX Bugs mailing list <intel-gfx-bugs> |
Severity: | normal | ||
Priority: | low | CC: | intel-gfx-bugs, rodrigo.vivi |
Version: | DRI git | ||
Hardware: | Other | ||
OS: | All | ||
Whiteboard: | ReadyForDev | ||
i915 platform: | CNL | i915 features: |
Description
Marta Löfstedt
2017-10-18 10:00:46 UTC
CI_DRM_3283 CNL-y incomplete igt@kms_pipe_crc_basic@read-crc-pipe-b no pstore last dmesg: <7>[ 512.764057] [drm:intel_enable_shared_dpll [i915]] enable DPLL 0 (active 1, on? 0) for crtc 42 <7>[ 512.764081] [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0 [239/289] skip: 24, pass: 215 \ FATAL: command execution failed java.io.EOFException https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3283/fi-cnl-y/igt@kms_pipe_crc_basic@read-crc-pipe-b.html Was this one time thing randomly or is this happening always now? I believe it is the same I'm starting to see with testdisplay with multiple monitors connected. do you have more than 1 monitor plugged on this cnl? if this is always happening now, do we have a good day / a good bisect point? Thanks, Rodrigo. (In reply to Rodrigo Vivi from comment #2) > Was this one time thing randomly or is this happening always now? > It is random. Here is a description on how you can retrieve this information. So, you don't have to wait for a reply in the future. From Intel GFX CI top page: https://intel-gfx-ci.01.org/ The CNL machine is in farm1 running the IGT fastfeedback testslist. So, to get a visual overview of the current issues checkout DRM-Tip - Fast: issues: https://intel-gfx-ci.01.org/tree/drm-tip/ we are looking for fi-cnl-y, when I just check the last 5 run has been green so, yes this issue appear to be random. Now if you click the fi-cnl-y label, you will see a longer history of the result for fi-cnl-y. Here I see 5 purple spots indicating the incomplete test results. If you click those you'll get to the piglit generated result pages: https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3218/fi-cnl-y/igt@gem_ctx_switch@basic-default.html https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3271/fi-cnl-y/igt@gem_exec_flush@basic-batch-kernel-default-uc.html https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3259/fi-cnl-y/igt@kms_pipe_crc_basic@read-crc-pipe-b-frame-sequence.html https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3283/fi-cnl-y/igt@kms_pipe_crc_basic@read-crc-pipe-b.html https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3258/fi-cnl-y/igt@pm_rpm@basic-pci-d3-state.html The first 2 have pstore generated panic/oops logs. In this case a panic was generated from a BUG_ON, this issue issue is filed on bug 102035 and not on this bug. The last 3 doesn't have any logs, so the machine ended up in a state that either none of our watchdog systems could trigger, i.e. system hang or the run was timed out externally by the Jenkins system. When I found these issues I file a new bug for each machine, and when I get a new occurrence in cibuglog I will open the bug and add the new information. However, in this case it looks like I have missed the https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3259/fi-cnl-y/igt@kms_pipe_crc_basic@read-crc-pipe-b-frame-sequence.html. But, if we look at the dmesg from that run: https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3259/fi-cnl-y/dmesg0.log the last message is: <2>[ 471.997559] watchdog: watchdog0: watchdog did not stop! This we believe is due to a flaw in our softdog deamon owatch, and is filed on bug 102332. You can also got a lot of information from the cibuglog page: https://intel-gfx-ci.01.org/cibuglog/ If you are interested in fi-cnl-y, find a link for it in the Affected machines column: https://intel-gfx-ci.01.org/cibuglog/index.html%3Faction_failures_history=-1&failures_machine=fi-cnl-y.html and you will see all issues that has occurred since CI_DRM_3121. Note, once a failing test has been reported on a machine and bug. The following results from this test machine combination is suppressed from both affecting pre-merge results and new reporting to cibuglog, i.e. I will not see if for example an incomplete is reproduced or not. If you look at the entry of this bug in cibuglog, it currently say 2 / 23 runs (9 %) in the Failure rate column. In this case it is true, this issue has only happened twice, however if one of the reporeted test had failed instead of incomplete:ed there would ne 3 / 23 runs. Martin is currently fixing this so that the reason for fail on a test should matter. > I believe it is the same I'm starting to see with testdisplay with multiple > monitors connected. > > do you have more than 1 monitor plugged on this cnl? From our top page there is a link to our hardware descrition page: https://intel-gfx-ci.01.org/tree/drm-tip/hardware.html fi-cnl-y Intel Cannonlake-Y RVP Cannonlake eDP, (DP, HDMI) So, this machine only has an eDP panel connected. Parentheses show non connected options. > > if this is always happening now, do we have a good day / a good bisect point? > > Thanks, > Rodrigo. https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3304/fi-cnl-y/igt@kms_flip@basic-flip-vs-dpms.html [216/289] skip: 20, pass: 196 | Build timed out (after 17 minutes). Marking the build as aborted. https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3303/fi-cnl-y/igt@kms_flip@basic-flip-vs-dpms.html [216/289] skip: 20, pass: 196 | Build timed out (after 17 minutes). Marking the build as aborted. https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3305/fi-cnl-y/igt@kms_flip@basic-flip-vs-dpms.html Last dmesg: <7>[ 345.339997] [drm:intel_hpd_irq_handler [i915]] digital hpd port A - long <7>[ 345.340062] [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 1 run.log: FATAL: command execution failed java.io.EOFException ... Completed CI_IGT_test CI_DRM_3305@fi-cnl-y : FAILURE CI_IGT_test runtime 846 seconds https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3306/fi-cnl-y/igt@kms_flip@basic-flip-vs-dpms.html Last dmesg: <7>[ 355.027905] [drm:verify_connector_state.isra.74 [i915]] [CONNECTOR:76:eDP-1] <7>[ 355.027983] [drm:intel_atomic_commit_tail [i915] run.log: Build timed out (after 17 minutes). Marking the build as aborted. CI_IGT_test runtime 1028 seconds Oops log: https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3306/fi-cnl-y/dmesg-1509558799_Oops_1.log This looks weird: <14>[ 79.392239] [IGT] gem_mmap_gtt: starting subtest basic-small-copy <6>[ 80.337920] traps: python3[1533] general protection ip:55c92fdd78b0 sp:7fe6bcdbc8b8 error:0 in python3.5[55c92fc7c000+3ee000] <14>[ 80.345125] [IGT] gem_mmap_gtt: exiting, ret=99 <12>[ 80.370407] owatch: timeout for /dev/watchdog0 set to 30 (requested 30) <12>[ 80.370552] owatch: /dev/watchdog0 closed <4>[ 96.643022] list_add corruption. prev->next should be next (ffff88025d3c20d0), but was ffffc278ffffffff. (prev=ffffc90000453b58). <4>[ 96.643049] ------------[ cut here ]------------ <4>[ 96.643056] WARNING: CPU: 1 PID: 1 at lib/list_debug.c:28 __list_add_valid+0x53/0x80 <4>[ 96.643059] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal snd_hda_intel intel_powerclamp coretemp snd_hda_codec crct10dif_pclmul crc32_pclmul snd_hwdep snd_hda_core e1000e ghash_clmulni_intel snd_pcm asix usbnet mii ptp pps_core prime_numbers i2c_hid <4>[ 96.643137] CPU: 1 PID: 1 Comm: systemd Tainted: G U W 4.14.0-rc7-CI-CI_DIF_210+ #1 <4>[ 96.643140] Hardware name: Intel Corporation CannonLake Client Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X100.A01.1708151220 08/15/2017 <4>[ 96.643144] task: ffff880267780040 task.stack: ffffc90000048000 <4>[ 96.643148] RIP: 0010:__list_add_valid+0x53/0x80 <4>[ 96.643152] RSP: 0018:ffffc9000004bc58 EFLAGS: 00010092 <4>[ 96.643158] RAX: 0000000000000075 RBX: ffff88025d3c2098 RCX: 0000000000000002 <4>[ 96.643162] RDX: 0000000080000002 RSI: ffffffff81d1937f RDI: 00000000ffffffff <4>[ 96.643165] RBP: ffffc9000004bc58 R08: 0000000000000000 R09: 0000000000000001 <4>[ 96.643168] R10: ffffc9000004bbd0 R11: 000000002e10e38c R12: ffffc9000004bcd0 <4>[ 96.643172] R13: ffffc90000453b58 R14: ffffc9000004bce8 R15: ffff88025d3c20d0 <4>[ 96.643176] FS: 00007fa3b27e9940(0000) GS:ffff880271080000(0000) knlGS:0000000000000000 <4>[ 96.643179] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 <4>[ 96.643182] CR2: 00005600297c123d CR3: 00000002652ad005 CR4: 00000000007606e0 <4>[ 96.643185] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 <4>[ 96.643189] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 <4>[ 96.643192] PKRU: 55555554 <4>[ 96.643195] Call Trace: <4>[ 96.643202] add_wait_queue+0x43/0x80 <4>[ 96.643214] n_tty_write+0xaf/0x460 <4>[ 96.643223] ? do_wait_intr_irq+0xc0/0xc0 <4>[ 96.643231] tty_write+0x1c2/0x300 <4>[ 96.643236] ? n_tty_open+0xd0/0xd0 <4>[ 96.643243] redirected_tty_write+0x92/0xb0 <4>[ 96.643251] do_iter_write+0x157/0x1a0 <4>[ 96.643257] vfs_writev+0x89/0xf0 <4>[ 96.643272] ? entry_SYSCALL_64_fastpath+0x5/0xb1 <4>[ 96.643279] do_writev+0x55/0xe0 <4>[ 96.643282] ? do_writev+0x55/0xe0 <4>[ 96.643290] SyS_writev+0x10/0x20 <4>[ 96.643295] entry_SYSCALL_64_fastpath+0x1c/0xb1 <4>[ 96.643299] RIP: 0033:0x7fa3b0ddd63d <4>[ 96.643302] RSP: 002b:00007ffd2cc6db30 EFLAGS: 00000293 ORIG_RAX: 0000000000000014 <4>[ 96.643309] RAX: ffffffffffffffda RBX: ffffffff81491f23 RCX: 00007fa3b0ddd63d <4>[ 96.643312] RDX: 0000000000000003 RSI: 00007ffd2cc6db60 RDI: 000000000000001a <4>[ 96.643316] RBP: ffffc9000004bf88 R08: 000000000000fcfe R09: 0000000000000000 <4>[ 96.643319] R10: 000056002a592300 R11: 0000000000000293 R12: 0000000000000000 <4>[ 96.643322] R13: 0000000000000003 R14: 000000000000001a R15: 0000000000000001 <4>[ 96.643330] ? __this_cpu_preempt_check+0x13/0x20 <4>[ 96.643339] Code: c3 48 89 d1 48 c7 c7 28 22 cc 81 4c 89 c2 e8 c6 f0 c5 ff 0f ff 31 c0 5d c3 4c 89 c1 48 89 c6 48 c7 c7 78 22 cc 81 e8 ae f0 c5 ff <0f> ff 31 c0 5d c3 48 89 f2 48 89 c1 48 89 fe 48 c7 c7 c8 22 cc <4>[ 96.643520] ---[ end trace 706a622906825a2a ]--- <1>[ 96.643582] BUG: unable to handle kernel paging request at ffffc278ffffffff <1>[ 96.643607] IP: __wake_up_common+0x61/0x180 <6>[ 96.643618] PGD 0 P4D 0 <4>[ 96.643627] Oops: 0000 [#1] PREEMPT SMP <4>[ 96.643634] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal snd_hda_intel intel_powerclamp coretemp snd_hda_codec crct10dif_pclmul crc32_pclmul snd_hwdep snd_hda_core e1000e ghash_clmulni_intel snd_pcm asix usbnet mii ptp pps_core prime_numbers i2c_hid <4>[ 96.643695] CPU: 1 PID: 1 Comm: systemd Tainted: G U W 4.14.0-rc7-CI-CI_DIF_210+ #1 <4>[ 96.643708] Hardware name: Intel Corporation CannonLake Client Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X100.A01.1708151220 08/15/2017 <4>[ 96.643728] task: ffff880267780040 task.stack: ffffc90000048000 <4>[ 96.643739] RIP: 0010:__wake_up_common+0x61/0x180 <4>[ 96.643747] RSP: 0018:ffffc9000004bc48 EFLAGS: 00010002 <4>[ 96.643756] RAX: ffffc278ffffffff RBX: 0000000000000000 RCX: 0000000000000000 <4>[ 96.643767] RDX: 0000000000000001 RSI: 0000000000000001 RDI: ffff88025d3c2098 <4>[ 96.643779] RBP: ffffc9000004bc90 R08: 0000000000000004 R09: ffffc90000453b40 <4>[ 96.643790] R10: ffffc9000004bc08 R11: 000000007f29fe86 R12: ffffc9000004bca8 <4>[ 96.643801] R13: ffff88025d3c20d0 R14: 00000000ffffffff R15: ffffc278ffffffe7 <4>[ 96.643813] FS: 00007fa3b27e9940(0000) GS:ffff880271080000(0000) knlGS:0000000000000000 <4>[ 96.643827] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 <4>[ 96.643837] CR2: ffffc278ffffffff CR3: 00000002652ad005 CR4: 00000000007606e0 <4>[ 96.643848] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 <4>[ 96.643859] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 <4>[ 96.643870] PKRU: 55555554 <4>[ 96.643876] Call Trace: <4>[ 96.643885] __wake_up_common_lock+0x71/0xa0 <4>[ 96.643896] __wake_up+0x13/0x20 <4>[ 96.643904] tty_write_unlock+0x34/0x40 <4>[ 96.643913] tty_write+0x1fc/0x300 <4>[ 96.643921] ? n_tty_open+0xd0/0xd0 <4>[ 96.643930] redirected_tty_write+0x92/0xb0 <4>[ 96.643940] do_iter_write+0x157/0x1a0 <4>[ 96.643948] vfs_writev+0x89/0xf0 <4>[ 96.643960] ? entry_SYSCALL_64_fastpath+0x5/0xb1 <4>[ 96.643970] do_writev+0x55/0xe0 <4>[ 96.643976] ? do_writev+0x55/0xe0 <4>[ 96.643985] SyS_writev+0x10/0x20 <4>[ 96.643993] entry_SYSCALL_64_fastpath+0x1c/0xb1 <4>[ 96.644002] RIP: 0033:0x7fa3b0ddd63d <4>[ 96.644009] RSP: 002b:00007ffd2cc6db30 EFLAGS: 00000293 ORIG_RAX: 0000000000000014 <4>[ 96.644023] RAX: ffffffffffffffda RBX: ffffffff81491f23 RCX: 00007fa3b0ddd63d <4>[ 96.644034] RDX: 0000000000000003 RSI: 00007ffd2cc6db60 RDI: 000000000000001a <4>[ 96.644046] RBP: ffffc9000004bf88 R08: 000000000000fcfe R09: 0000000000000000 <4>[ 96.644057] R10: 000056002a592300 R11: 0000000000000293 R12: 0000000000000000 <4>[ 96.644069] R13: 0000000000000003 R14: 000000000000001a R15: 0000000000000001 <4>[ 96.644083] ? __this_cpu_preempt_check+0x13/0x20 <4>[ 96.644093] Code: 8b 45 38 49 83 c5 38 4c 8d 48 e8 49 8d 41 18 49 39 c5 0f 84 d6 00 00 00 49 8b 41 18 89 55 c0 31 db 4c 8d 78 e8 eb 14 49 8d 47 18 <49> 8b 57 18 4d 89 f9 49 39 c5 4c 8d 7a e8 74 46 45 8b 31 41 f6 <1>[ 96.644191] RIP: __wake_up_common+0x61/0x180 RSP: ffffc9000004bc48 <4>[ 96.644201] CR2: ffffc278ffffffff <4>[ 96.644209] ---[ end trace 706a622906825a2b ]--- https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3307/fi-cnl-y/igt@kms_flip@basic-flip-vs-dpms.html Last dmesg: <7>[ 338.149281] [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP port A <7>[ 338.226739] [IGT] kms_flip: executing Then followed by stray. run.log Build timed out (after 17 minutes). Marking the build as aborted. CI_IGT_test runtime 1027 seconds (In reply to Marta Löfstedt from comment #8) > https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3307/fi-cnl-y/ > igt@kms_flip@basic-flip-vs-dpms.html > > Last dmesg: > <7>[ 338.149281] [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP > port A > <7>[ 338.226739] [IGT] kms_flip: executing > Then followed by stray. > > run.log > Build timed out (after 17 minutes). Marking the build as aborted. > CI_IGT_test runtime 1027 seconds NOTE incompletes on: igt@kms_flip@basic-flip-vs-dpms during CI_DRM_3303 - CI_DRM_3308 are related to adding a second panel to the CNL-y system. The panel has then been removed. Patchwork: https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_6962/fi-cnl-y/igt@kms_pipe_crc_basic@read-crc-pipe-b-frame-sequence.html dmesg: <5>[ 37.437904] owatch: Using watchdog device /dev/watchdog0 <5>[ 37.438145] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 37.439423] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 489.045473] [drm:edp_panel_vdd_on [i915]] eDP port A panel power wasn't enabled <7>[ 489.095375] [drm:intel_get_hpd_pins [i915]] hotplug event received, stat 0x01000000, dig 0x12101010, pins 0x00000010 <7>[ 489.095423] [drm:intel_hpd_irq_handler [i915]] digital hpd port A - long <7>[ 489.095464] [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 1 run.log: [241/289] skip: 24, pass: 217 | FATAL: command execution failed java.io.EOFException ... Finished: FAILURE Completed CI_IGT_test Patchwork_6962@fi-cnl-y : FAILURE CI_IGT_test runtime 481 seconds https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3320/fi-cnl-y/igt@kms_flip@basic-plain-flip.html dmesg: <5>[ 39.256843] owatch: Using watchdog device /dev/watchdog0 <5>[ 39.257367] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 39.258375] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 418.097362] [drm:intel_atomic_commit_tail [i915]] [CRTC:42:pipe A] <7>[ 418.097435] [drm:verify_single_dpll_state.isra.75 [i915]] DPLL 0 <7>[ 418.098326] [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP port A run.log: [218/289] skip: 20, pass: 198 - FATAL: command execution failed java.io.EOFException ... Completed CI_IGT_test CI_DRM_3320@fi-cnl-y : FAILURE CI_IGT_test runtime 414 seconds Patchwork: https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_6994/fi-cnl-y/igt@kms_pipe_crc_basic@suspend-read-crc-pipe-c.html dmesg: <5>[ 16.437979] owatch: Using watchdog device /dev/watchdog0 <5>[ 16.438222] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 16.439178] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 505.905489] [drm:intel_atomic_commit_tail [i915]] [CRTC:42:pipe A] <7>[ 505.905557] [drm:verify_single_dpll_state.isra.75 [i915]] DPLL 0 <7>[ 505.906983] [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP port A run.log [245/289] skip: 24, pass: 221 / pass: igt/kms_pipe_crc_basic/suspend-read-crc-pipe-b FATAL: command execution failed java.io.EOFException ... Completed CI_IGT_test Patchwork_6994@fi-cnl-y : FAILURE CI_IGT_test runtime 526 seconds https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3331/fi-cnl-y/igt@prime_self_import@basic-with_two_bos.html dmesg: <5>[ 21.200160] owatch: Using watchdog device /dev/watchdog0 <5>[ 21.200326] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 21.202312] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 485.262605] [IGT] prime_self_import: executing <7>[ 485.264673] [IGT] prime_self_import: starting subtest basic-with_two_bos <7>[ 485.325032] [IGT] prime_self_import: exiting, ret=0 run.log [252/289] skip: 25, pass: 227 | FATAL: command execution failed ... Completed CI_IGT_test CI_DRM_3331@fi-cnl-y : FAILURE CI_IGT_test runtime 521 seconds https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_7111/fi-cnl-y/igt@kms_pipe_crc_basic@read-crc-pipe-c-frame-sequence.html dmesg: <5>[ 36.429325] owatch: Using watchdog device /dev/watchdog0 <5>[ 36.429693] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 36.450742] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 465.862564] [drm:intel_dp_set_signal_levels [i915]] Using vswing level 0 <7>[ 465.862597] [drm:intel_dp_set_signal_levels [i915]] Using pre-emphasis level 1 <7>[ 465.865745] [drm:intel_dp_start_link_train [i915]] Channel EQ done. DP Training successful run.log [242/289] skip: 24, pass: 217, fail: 1 - pass: igt/kms_pipe_crc_basic/read-crc-pipe-c FATAL: command execution failed java.io.EOFException ... Completed CI_IGT_test Patchwork_7111@fi-cnl-y : FAILURE CI_IGT_test runtime 459 seconds https://intel-gfx-ci.01.org/tree/drm-tip/IGT_3975/fi-cnl-y/igt@kms_pipe_crc_basic@suspend-read-crc-pipe-a.html dmesg: <5>[ 45.719473] owatch: Using watchdog device /dev/watchdog0 <5>[ 45.719630] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 45.720992] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 471.489816] [drm:drm_mode_setcrtc] [CRTC:74:pipe C] <7>[ 471.606497] [drm:drm_mode_addfb2] [FB:97] <7>[ 471.619388] [drm:drm_mode_setcrtc] [CRTC:42:pipe A] <7>[ 471.619461] [drm:drm_mode_setcrtc] [CONNECTOR:76:eDP-1] run.log: [243/289] skip: 24, pass: 219 \ pass: igt/kms_pipe_crc_basic/read-crc-pipe-c-frame-sequence FATAL: command execution failed java.io.IOException: Unexpected EOF ... Finished: FAILURE Completed CI_IGT_test CI_DRM_3327@fi-cnl-y : FAILURE CI_IGT_test runtime 470 seconds Rising priority since it is BAT. https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_7130/fi-cnl-y/igt@kms_pipe_crc_basic@nonblocking-crc-pipe-c-frame-sequence.html dmesg: <5>[ 20.850570] owatch: Using watchdog device /dev/watchdog0 <5>[ 20.850836] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 20.852703] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 434.646092] [drm:intel_find_shared_dpll [i915]] [CRTC:42:pipe A] allocated DPLL 0 <7>[ 434.646130] [drm:intel_reference_shared_dpll [i915]] using DPLL 0 for pipe A <7>[ 434.646342] [drm:intel_edp_backlight_off [i915]] run.log: [236/289] skip: 24, pass: 212 | FATAL: command execution failed java.io.EOFException ... Finished: FAILURE Completed CI_IGT_test Patchwork_7130@fi-cnl-y : FAILURE CI_IGT_test runtime 429 seconds https://intel-gfx-ci.01.org/tree/drm-tip/IGT_3987/fi-cnl-y/igt@prime_vgem@basic-fence-wait-default.html dmesg: <5>[ 62.371916] owatch: Using watchdog device /dev/watchdog0 <5>[ 62.372091] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 62.373383] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 521.971118] [IGT] prime_vgem: executing <7>[ 522.000901] [IGT] prime_vgem: starting subtest basic-fence-wait-default <7>[ 522.021532] [drm:vgem_gem_dumb_create [vgem]] Created object of size 4096 run.log: running: igt/pm_rpm/basic-rte [252/289] skip: 25, pass: 227 | FATAL: command execution failed java.io.EOFException ... Completed CI_IGT_test CI_DRM_3353@fi-cnl-y : FAILURE CI_IGT_test runtime 558 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3354/fi-cnl-y/igt@kms_psr_sink_crc@psr_basic.html dmesg: <5>[ 43.371131] owatch: Using watchdog device /dev/watchdog0 <5>[ 43.371586] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 43.372866] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 487.756259] [drm:intel_atomic_commit_tail [i915]] [CRTC:74:pipe C] <7>[ 487.757708] [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP port A <7>[ 487.856049] [IGT] kms_psr_sink_crc: executing run.log: running: igt/kms_pipe_crc_basic/suspend-read-crc-pipe-b [245/289] skip: 24, pass: 221 / FATAL: command execution failed java.io.EOFException ... Completed CI_IGT_test CI_DRM_3354@fi-cnl-y : FAILURE CI_IGT_test runtime 505 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3359/fi-cnl-y/igt@kms_frontbuffer_tracking@basic.html dmesg: <5>[ 47.742321] owatch: Using watchdog device /dev/watchdog0 <5>[ 47.742488] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 47.743403] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 437.030886] [drm:wait_panel_status [i915]] Wait complete <7>[ 437.031043] [drm:edp_panel_vdd_on [i915]] PP_STATUS: 0x00000000 PP_CONTROL: 0x00000068 <7>[ 437.031103] [drm:edp_panel_vdd_on [i915]] eDP port A panel power wasn't enabled run.log: running: igt/kms_force_connector_basic/force-connector-state [220/289] skip: 20, pass: 200 | FATAL: command execution failed java.io.EOFException ... Completed CI_IGT_test CI_DRM_3359/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 431 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4004/fi-cnl-y/igt@kms_pipe_crc_basic@nonblocking-crc-pipe-b-frame-sequence.html dmesg: <5>[ 59.179399] owatch: Using watchdog device /dev/watchdog0 <5>[ 59.179565] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 59.180739] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 466.845708] [drm:intel_power_well_disable [i915]] disabling power well 2 <7>[ 466.845784] [drm:intel_atomic_commit_tail [i915]] [CRTC:58:pipe B] <7>[ 466.848139] [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP port A run.log: running: igt/kms_pipe_crc_basic/nonblocking-crc-pipe-b [234/289] skip: 24, pass: 210 - FATAL: command execution failed java.io.EOFException ... Finished: FAILURE Completed CI_IGT_test CI_DRM_3372/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 466 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3377/fi-cnl-y/igt@pm_rpm@basic-rte.html dmesg: <5>[ 40.399108] owatch: Using watchdog device /dev/watchdog0 <5>[ 40.399364] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 40.400955] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 492.182793] [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 1 <7>[ 492.341904] [drm:intel_dp_read_dpcd [i915]] DPCD: 12 0a 84 41 00 00 01 01 02 00 00 00 00 0b 00 <7>[ 492.344034] [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP port A run.log: running: igt/pm_rpm/basic-pci-d3-state [251/289] skip: 25, pass: 226 \ FATAL: command execution failed java.io.EOFException ... Finished: FAILURE Completed CI_IGT_test CI_DRM_3377/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 530 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3397/fi-cnl-y/igt@kms_pipe_crc_basic@nonblocking-crc-pipe-b.html dmesg: <5>[ 27.190255] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 427.529605] [drm:drm_mode_addfb2] [FB:98] <7>[ 427.552100] [drm:drm_mode_setcrtc] [CRTC:59:pipe B] <7>[ 427.552162] [drm:drm_mode_setcrtc] [CONNECTOR:77:eDP-1] run.log: running: igt/kms_pipe_crc_basic/hang-read-crc-pipe-c [231/289] skip: 24, pass: 207 \ FATAL: command execution failed java.io.EOFException ... Finished: FAILURE Completed CI_IGT_test CI_DRM_3397/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 423 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3401/fi-cnl-y/igt@vgem_basic@unload.html dmesg: <5>[ 36.292545] owatch: Using watchdog device /dev/watchdog0 <5>[ 36.292874] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 36.294129] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 496.051935] [IGT] vgem_basic: starting subtest unload <6>[ 496.185395] [drm] Initialized vgem 1.0.0 20120112 for virtual device on minor 1 <7>[ 496.198931] [drm:vgem_gem_dumb_create [vgem]] Created object of size 4096 <6>[ 496.237748] [drm] Initialized vgem 1.0.0 20120112 for virtual device on minor 1 run.log: pass: igt/prime_vgem/basic-fence-wait-default [268/288] skip: 26, pass: 242 \ FATAL: command execution failed java.io.EOFException ... Finished: FAILURE Completed CI_IGT_test CI_DRM_3401/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 537 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3403/fi-cnl-y/igt@kms_pipe_crc_basic@read-crc-pipe-c.html dmesg: <5>[ 47.928181] owatch: Using watchdog device /dev/watchdog0 <5>[ 47.928350] owatch: Watchdog /dev/watchdog0 is a software watchdog <5>[ 47.929849] owatch: timeout for /dev/watchdog0 set to 100 (requested 100) ... <7>[ 472.414335] [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0 <7>[ 472.416993] [drm:edp_panel_on [i915]] Turn eDP port A panel power on <7>[ 472.417078] [drm:wait_panel_power_cycle [i915]] Wait for panel power cycle run.log: running: igt/kms_pipe_crc_basic/read-crc-pipe-b-frame-sequence [240/288] skip: 24, pass: 216 | FATAL: command execution failed java.io.EOFException ... Finished: FAILURE Completed CI_IGT_test CI_DRM_3403/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 467 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3419/fi-cnl-y/igt@kms_pipe_crc_basic@nonblocking-crc-pipe-c.html last dmesg: <7>[ 431.165611] [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:93:DP-2] disconnected <7>[ 431.166310] [IGT] kms_pipe_crc_basic: starting subtest nonblocking-crc-pipe-C <7>[ 431.166644] [drm:drm_mode_addfb2] [FB:101] run.log hints at system hang. https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4028/fi-cnl-y/igt@kms_flip@basic-flip-vs-wf_vblank.html last dmesg: <7>[ 404.718436] [drm:intel_fbc_enable [i915]] Enabling FBC on pipe A <7>[ 404.734773] [drm:verify_connector_state.isra.75 [i915]] [CONNECTOR:77:eDP-1] <7>[ 404.734807] [drm:intel_atomic_commit_tail [i915]] [CRTC:43:pipe A] <7>[ 404.734865] [drm:verify_single_dpll_state.isra.76 [i915]] DPLL 0 run.log hints at system hang https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3449/fi-cnl-y/igt@vgem_basic@dmabuf-mmap.html last dmesg: <7>[ 545.219103] [IGT] vgem_basic: starting subtest dmabuf-mmap <7>[ 545.219188] [drm:vgem_gem_dumb_create [vgem]] Created object of size 4194304 <7>[ 545.219693] [IGT] vgem_basic: exiting, ret=0 run.log: running: igt/prime_vgem/basic-fence-mmap [265/288] skip: 25, pass: 240 / FATAL: command execution failed java.io.EOFException ... Completed CI_IGT_test CI_DRM_3449/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 588 seconds Rebooting fi-cnl-y Note: run.log doesn't list same subtest as the result file, i.e. this could be network issue and Jenkins rebooting the machine after loosing contact. https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4041/fi-cnl-y/igt@kms_sink_crc_basic.html last dmesg: <7>[ 548.971243] [drm:gen8_irq_handler [i915]] hotplug event received, stat 0x01000000, dig 0x12101010, pins 0x00000010 <7>[ 548.971290] [drm:intel_hpd_irq_handler [i915]] digital hpd port A - long <7>[ 548.971334] [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 1 run.log: pass: igt/kms_psr_sink_crc/psr_basic [247/288] skip: 24, pass: 223 - FATAL: command execution failed java.io.EOFException ... Completed CI_IGT_test CI_DRM_3470/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 594 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3480/fi-cnl-y/igt@kms_flip@basic-flip-vs-modeset.html last dmesg: <7>[ 376.184813] [drm:intel_atomic_commit_tail [i915]] [CRTC:75:pipe C] <7>[ 376.185141] [drm:drm_mode_setcrtc] [CRTC:75:pipe C] <7>[ 376.185264] [drm:edp_panel_vdd_on [i915]] Turning eDP port A VDD on <7>[ 376.185399] [drm:wait_panel_power_cycle [i915]] Wait for panel power cycle run.log: running: igt/kms_flip/basic-flip-vs-modeset [216/288] skip: 20, pass: 196 | FATAL: command execution failed java.io.EOFException ... Completed CI_IGT_test CI_DRM_3480/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 374 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3486/fi-cnl-y/igt@kms_cursor_legacy@basic-flip-before-cursor-varying-size.html dmesg: <7>[ 355.963993] [IGT] kms_cursor_legacy: exiting, ret=0 <7>[ 355.965174] [drm:edp_panel_vdd_on [i915]] Turning eDP port A VDD on <7>[ 355.965301] [drm:wait_panel_power_cycle [i915]] Wait for panel power cycle run.log: pass: igt/kms_cursor_legacy/basic-flip-before-cursor-legacy FATAL: command execution failed java.io.IOException: Unexpected EOF ... Finished: FAILURE Completed CI_IGT_test CI_DRM_3486/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 356 seconds Rebooting fi-cnl-y run.log: running: igt/kms_pipe_crc_basic/hang-read-crc-pipe-b dmesg: <7>[ 459.483021] [drm:verify_single_dpll_state.isra.78 [i915]] DPLL 0 <7>[ 459.483224] [drm:edp_panel_vdd_on [i915]] Turning eDP port A VDD on <7>[ 459.483307] [drm:wait_panel_power_cycle [i915]] Wait for panel power cycle FATAL: command execution failed java.io.EOFException ... Finished: FAILURE Completed CI_IGT_test CI_DRM_3483/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 458 seconds Rebooting fi-cnl-y (In reply to Marta Löfstedt from comment #32) > run.log: > running: igt/kms_pipe_crc_basic/hang-read-crc-pipe-b > > dmesg: > <7>[ 459.483021] [drm:verify_single_dpll_state.isra.78 [i915]] DPLL 0 > <7>[ 459.483224] [drm:edp_panel_vdd_on [i915]] Turning eDP port A VDD on > <7>[ 459.483307] [drm:wait_panel_power_cycle [i915]] Wait for panel power > cycle > > FATAL: command execution failed > java.io.EOFException > ... > Finished: FAILURE > Completed CI_IGT_test CI_DRM_3483/fi-cnl-y/0 : FAILURE > CI_IGT_test runtime 458 seconds > Rebooting fi-cnl-y OOPS forgot the link: https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4050/fi-cnl-y/igt@kms_pipe_crc_basic@hang-read-crc-pipe-b.html https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4053/fi-cnl-y/igt@kms_cursor_legacy@basic-flip-after-cursor-legacy.html last dmesg: <7>[ 357.937273] [drm:intel_power_well_disable [i915]] disabling AUX D <7>[ 357.937283] [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:93:DP-2] disconnected <7>[ 357.937554] [IGT] kms_cursor_legacy: starting subtest basic-flip-after-cursor-legacy <7>[ 357.937856] [drm:drm_mode_addfb2] [FB:134] run.log: running: igt/kms_cursor_legacy/basic-busy-flip-before-cursor-legacy [208/288] skip: 20, pass: 188 | FATAL: command execution failed java.io.EOFException ... Finished: FAILURE Completed CI_IGT_test CI_DRM_3492/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 356 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3512/fi-cnl-y/igt@kms_setmode@basic-clone-single-crtc.html run.log doesn't match results. However, it is just 1 test diff, so this is still congruent with system hang. last test in run.log: running: igt/kms_psr_sink_crc/psr_basic dmesg: <7>[ 521.630488] [IGT] kms_psr_sink_crc: starting subtest psr_basic ... <7>[ 524.034600] [IGT] kms_psr_sink_crc: exiting, ret=0 ... <7>[ 525.322011] [IGT] kms_setmode: executing <7>[ 525.342013] [IGT] kms_setmode: starting subtest basic-clone-single-crtc ... <7>[ 525.603118] [drm:drm_mode_setcrtc] [CRTC:59:pipe B] <7>[ 525.603212] [drm:edp_panel_vdd_on [i915]] Turning eDP port A VDD on <7>[ 525.603302] [drm:wait_panel_power_cycle [i915]] Wait for panel power cycle https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3514/fi-cnl-y/igt@kms_busy@basic-flip-b.html last dmesg: <7>[ 321.032799] [drm:gen8_irq_handler [i915]] hotplug event received, stat 0x01000000, dig 0x12101010, pins 0x00000010 <7>[ 321.032834] [drm:intel_hpd_irq_handler [i915]] digital hpd port A - long <7>[ 321.032864] [drm:intel_hpd_irq_handler [i915]] Received HPD interrupt on PIN 4 - cnt: 1 run.log: pass: igt/kms_busy/basic-flip-a [196/288] skip: 11, pass: 185 \ FATAL: command execution failed java.io.EOFException ... Finished: FAILURE Completed CI_IGT_test CI_DRM_3514/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 321 seconds Rebooting fi-cnl-y https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4065/fi-cnl-y/igt@kms_pipe_crc_basic@nonblocking-crc-pipe-a.html run.log: running: igt/kms_pipe_crc_basic/hang-read-crc-pipe-c [230/288] skip: 24, pass: 206 - FATAL: command execution failed java.io.EOFException ... CI_IGT_test runtime 468 seconds Rebooting fi-cnl-y last dmesg: <7>[ 467.951488] [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0 <7>[ 467.951643] [drm:edp_panel_on [i915]] Turn eDP port A panel power on <7>[ 467.951709] [drm:wait_panel_power_cycle [i915]] Wait for panel power cycle https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4111/fi-cnl-y/igt@kms_cursor_legacy@basic-flip-after-cursor-atomic.html last dmesg: <7>[ 331.436064] [drm:intel_enable_shared_dpll [i915]] enabling DPLL 0 <7>[ 331.436196] [drm:edp_panel_on [i915]] Turn eDP port A panel power on <7>[ 331.436313] [drm:wait_panel_power_cycle [i915]] Wait for panel power cycle run.log: pass: igt/kms_cursor_legacy/basic-busy-flip-before-cursor-legacy [209/288] skip: 20, pass: 189 | FATAL: command execution failed ... Completed CI_IGT_test CI_DRM_3596/fi-cnl-y/0 : FAILURE CI_IGT_test runtime 331 seconds Rebooting fi-cnl-y The fi-cnl-y machine is not longer in the CI lab. I will archive this bug from cibuglog, but since others may have the same revision CPU as the fi-cnl-y I'll keep this bug open but with lower priority. Although latest hardware is not so reliably booting, I believe we never saw this again for a while, right? Can we close this? (In reply to Rodrigo Vivi from comment #41) > Although latest hardware is not so reliably booting, I believe we never saw > this again for a while, right? > Can we close this? As I wrote in previous comment this bug is archived from cibuglog, since we don't have the same revision on CNL in CI lab any more, but I can't close the bug since someone else might have that revision. If you want to close it I have no problems with that. Lets close for now. |
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.