CI_DRM_3258 fi-cnl-y igt@pm_rpm@basic-pci-d3-state incomplete no pstore. last dmesg: <7>[ 537.038705] [drm:intel_dp_hpd_pulse [i915]] ignoring long hpd on eDP port A run.log: FATAL: command execution failed java.io.EOFException https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3258/fi-cnl-y/igt@pm_rpm@basic-pci-d3-state.html
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/CI_DRM_3530/fi-cnl-y/igt@kms_chamelium@dp-hpd-fast.html
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.