search

ppy / osu

rhythm is just a *click* away!
https://osu.ppy.sh
MIT License
15.33k stars 2.28k forks source link

Beatmap card thumbnail test intermittently failing #16208

Open peppy opened 2 years ago

peppy commented 2 years ago

It looks like the track was playing, but it never reached the end "stopped" state. Worth investigating as it may point to an actual failure somewhere... although could be at a bass level or anywhere in between.

https://github.com/peppy/osu/runs/4603414243?check_suite_focus=true


2021-12-22T05:51:55.8222170Z  [runtime] 2021-12-22 05:51:37 [verbose]: 💨 BeatmapCardThumbnail(TestSceneBeatmapCardThumbnail) running
2021-12-22T05:51:55.8224000Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #1 create thumbnail
2021-12-22T05:51:55.8225410Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #2 enable dim
2021-12-22T05:51:55.8226720Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #3 Until: button visible
2021-12-22T05:51:55.8228300Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #4 click button
2021-12-22T05:51:55.8230040Z  [network] 2021-12-22 05:51:37 [verbose]: Request to https://assets.ppy.sh/beatmaps/163112/covers/list.jpg successfully completed!
2021-12-22T05:51:55.8231710Z  [runtime] 2021-12-22 05:51:37 [debug]: ClickEvent(Left) handled by PlayButton.
2021-12-22T05:51:55.8233230Z  [runtime] 2021-12-22 05:51:37 [debug]: MouseClick handled by PlayButton.
2021-12-22T05:51:55.8234540Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #5 Until: wait for start
2021-12-22T05:51:55.8240380Z  [network] 2021-12-22 05:51:37 [verbose]: Request to https://b.ppy.sh/preview/241526.mp3 successfully completed!
2021-12-22T05:51:55.8242320Z  [runtime] 2021-12-22 05:51:37 [verbose]: A PreviewTrack was created without a containing IPreviewTrackOwner. An owner should be added for correct behaviour.
2021-12-22T05:51:55.8243760Z  [runtime] 2021-12-22 05:51:37 [verbose]: ✔️ 48 repetitions
2021-12-22T05:51:55.8245050Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #6 Until: icon is correct
2021-12-22T05:51:55.8246130Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #7 click again
2021-12-22T05:51:55.8247170Z  [runtime] 2021-12-22 05:51:37 [debug]: ClickEvent(Left) handled by PlayButton.
2021-12-22T05:51:55.8248300Z  [runtime] 2021-12-22 05:51:37 [debug]: MouseClick handled by PlayButton.
2021-12-22T05:51:55.8249320Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #8 Until: wait for stop
2021-12-22T05:51:55.8250400Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #9 Until: icon is correct
2021-12-22T05:51:55.8251480Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #10 click again
2021-12-22T05:51:55.8252550Z  [runtime] 2021-12-22 05:51:37 [debug]: ClickEvent(Left) handled by PlayButton.
2021-12-22T05:51:55.8253700Z  [runtime] 2021-12-22 05:51:37 [debug]: MouseClick handled by PlayButton.
2021-12-22T05:51:55.8254800Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #11 Until: wait for start
2021-12-22T05:51:55.8256260Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #12 Until: icon is correct
2021-12-22T05:51:55.8257390Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #13 disable dim
2021-12-22T05:51:55.8258630Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #14 Repeat: wait some 0/3
2021-12-22T05:51:55.8259950Z  [runtime] 2021-12-22 05:51:37 [verbose]: ✔️ 3 repetitions
2021-12-22T05:51:55.8261280Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #15 Assert: button still visible
2021-12-22T05:51:55.8262710Z  [runtime] 2021-12-22 05:51:37 [verbose]: 🔸 Step #16 Until: progress > 0.25
2021-12-22T05:51:55.8264090Z  [runtime] 2021-12-22 05:51:40 [verbose]: ✔️ 2827 repetitions
2021-12-22T05:51:55.8266160Z  [runtime] 2021-12-22 05:51:40 [verbose]: 🔸 Step #17 Until: progress > 0.5
2021-12-22T05:51:55.8267410Z  [runtime] 2021-12-22 05:51:42 [verbose]: ✔️ 2665 repetitions
2021-12-22T05:51:55.8268720Z  [runtime] 2021-12-22 05:51:42 [verbose]: 🔸 Step #18 Until: progress > 0.75
2021-12-22T05:51:55.8270010Z  [runtime] 2021-12-22 05:51:45 [verbose]: ✔️ 2649 repetitions
2021-12-22T05:51:55.8271410Z  [runtime] 2021-12-22 05:51:45 [verbose]: 🔸 Step #19 Until: wait for track to end
2021-12-22T05:51:55.8272870Z  [runtime] 2021-12-22 05:51:55 [verbose]: 💥 Failed (on attempt 10,188)
2021-12-22T05:51:55.8274180Z  [runtime] 2021-12-22 05:51:55 [verbose]: ⏳ Currently loading components (0)
2021-12-22T05:51:55.8275410Z  [runtime] 2021-12-22 05:51:55 [verbose]: 🧵 Task schedulers
2021-12-22T05:51:55.8276850Z  [runtime] 2021-12-22 05:51:55 [verbose]: LoadComponentsAsync (standard) concurrency:4 running:0 pending:0
2021-12-22T05:51:55.8278630Z  [runtime] 2021-12-22 05:51:55 [verbose]: LoadComponentsAsync (long load) concurrency:4 running:0 pending:0
2021-12-22T05:51:55.8280160Z  [runtime] 2021-12-22 05:51:55 [verbose]: Host execution state changed to Stopping
peppy commented 2 years ago

Note that in the same test run another test fails due to a similar reason. Potentially bass in a bad state?