[LU-17733] ltest_suite sanity test_45: @@@@@@ FAIL: truncate didn't lower dirty count

• Type: Bug
• Resolution: Fixed
• Priority: Minor
• Fix Version/s: Lustre 2.16.0
• Labels: msft
• Severity: 3
• Assignee: Ellis Wilson
• Reporter: Ellis Wilson

Description

2022-09-02 20:50:52,587 INF [ltest_suite sanity 0-70] == sanity test 45: osc io page accounting ============================================================ 20:50:52 (1662151852)
2022-09-02 20:50:53,035 INF [ltest_suite sanity 0-70] vm.dirty_writeback_centisecs = 0
2022-09-02 20:50:53,037 INF [ltest_suite sanity 0-70] vm.dirty_writeback_centisecs = 0
2022-09-02 20:50:53,040 INF [ltest_suite sanity 0-70] vm.dirty_ratio = 50
2022-09-02 20:50:53,043 INF [ltest_suite sanity 0-70] vm.dirty_background_ratio = 25
2022-09-02 20:50:53,144 INF [ltest_suite sanity 0-70] executing "echo blah > /mnt/lustre/f45"
2022-09-02 20:50:53,151 INF [ltest_suite sanity 0-70] before 0, after 4096
2022-09-02 20:50:53,156 INF [ltest_suite sanity 0-70] executing "> /mnt/lustre/f45"
2022-09-02 20:50:53,162 INF [ltest_suite sanity 0-70] before 0, after 0
2022-09-02 20:50:53,162 INF [ltest_suite sanity 0-70]  sanity test_45: @@@@@@ FAIL: truncate didn't lower dirty count
2022-09-02 20:50:53,664 INF [ltest_suite sanity 0-70]   Trace dump:
2022-09-02 20:50:53,664 INF [ltest_suite sanity 0-70]   = /usr/lib/lustre/tests/test-framework.sh:6273:error()
2022-09-02 20:50:53,665 INF [ltest_suite sanity 0-70]   = /usr/lib/lustre/tests/sanity.sh:5040:test_45()
2022-09-02 20:50:53,665 INF [ltest_suite sanity 0-70]   = /usr/lib/lustre/tests/test-framework.sh:6581:run_one()
2022-09-02 20:50:53,665 INF [ltest_suite sanity 0-70]   = /usr/lib/lustre/tests/test-framework.sh:6628:run_one_logged()
2022-09-02 20:50:53,666 INF [ltest_suite sanity 0-70]   = /usr/lib/lustre/tests/test-framework.sh:6455:run_test()
2022-09-02 20:50:53,666 INF [ltest_suite sanity 0-70]   = /usr/lib/lustre/tests/sanity.sh:5052:main()
2022-09-02 20:50:53,737 INF [ltest_suite sanity 0-70] Dumping lctl log to /tmp/sanity.test_45.*.1662151853.log
2022-09-02 20:50:57,183 INF [ltest_suite sanity 0-70] done.
2022-09-02 20:50:57,185 INF [ltest_suite sanity 0-70] vm.dirty_writeback_centisecs = 500
2022-09-02 20:50:57,187 INF [ltest_suite sanity 0-70] vm.dirty_background_ratio = 10
2022-09-02 20:50:57,189 INF [ltest_suite sanity 0-70] vm.dirty_ratio = 20
2022-09-02 20:50:57,193 INF [ltest_suite sanity 0-70] FAIL 45 (5s)
2022-09-02 20:50:57,635 INF [ltest_suite sanity 0-70] stop testing on rc 111
2022-09-02 20:50:57,636 INF [ltest_suite sanity 0-70] sanity returned 111

This due to a race condition, and a comment is provided in the patch that clarifies why >= is needed rather than > relating to the completion of async io.

Patch to be sent shortly.

Issue Links

• is duplicated by: LU-15226 sanity test_45: FAIL: write wasn't cached