[LU-14279] sanity-quota test_3b: write success, but expect EDQUOT

• Status: Resolved
• Project: Lustre
• Affects Version/s: Lustre 2.14.0
• Fix Version/s: Lustre 2.15.0
• Type: Bug
• Priority: Minor
• Reporter: Wang Shilong (Inactive)
• Assignee: Wang Shilong (Inactive)
• Resolution: Fixed
• Severity: 3

Description

== sanity-quota test 3b: Quota pools: Block soft limit (start timer, expires, stop timer) ============ 13:06:59 (1608710819)
limit 4 glbl_limit 8
grace 20 glbl_grace 40
User quota in qpool1(soft limit:4 MB grace:20 seconds)
Creating new pool
Pool lustre.qpool1 created
Adding targets to pool
OST lustre-OST0000_UUID added to pool lustre.qpool1
OST lustre-OST0001_UUID added to pool lustre.qpool1
Trying to set grace for pool qpool1
Trying to set quota for pool qpool1
Waiting for local destroys to complete
Creating test directory
fail_val=0
fail_loc=0
Write up to soft limit
running as uid/gid/euid/egid 60000/60000/60000/60000, groups:
[dd] [if=/dev/zero] [bs=1M] [of=/mnt/lustre/d3b.sanity-quota/f3b.sanity-quota-0] [count=4]
4+0 records in
4+0 records out
4194304 bytes (4.2 MB, 4.0 MiB) copied, 0.205771 s, 20.4 MB/s
Write to exceed soft limit
running as uid/gid/euid/egid 60000/60000/60000/60000, groups:
[dd] [if=/dev/zero] [of=/mnt/lustre/d3b.sanity-quota/f3b.sanity-quota-0] [bs=1K] [count=10] [seek=4096]
10+0 records in
10+0 records out
10240 bytes (10 kB, 10 KiB) copied, 0.00531433 s, 1.9 MB/s
mmap write when over soft limit
running as uid/gid/euid/egid 60000/60000/60000/60000, groups:
[multiop] [/mnt/lustre/d3b.sanity-quota/f3b.sanity-quota-0.mmap] [OT40960SMW]
Disk quotas for usr quota_usr (uid 60000):
Filesystem kbytes quota limit grace files quota limit grace
/mnt/lustre 4148 8192 0 - 2 0 0 -
lustre-MDT0000_UUID
0 - 0 - 2 - 0 -
lustre-OST0000_UUID
4108 - 4144 - - - - -
lustre-OST0001_UUID
40* - 40 - - - - -
Total allocated inode limit: 0, total allocated block limit: 4184
Disk quotas for grp quota_usr (gid 60000):
Filesystem kbytes quota limit grace files quota limit grace
/mnt/lustre 4148 0 0 - 2 0 0 -
lustre-MDT0000_UUID
0 - 0 - 2 - 0 -
lustre-OST0000_UUID
4108 - 0 - - - - -
lustre-OST0001_UUID
40 - 0 - - - - -
Total allocated inode limit: 0, total allocated block limit: 0
Disk quotas for prj 1000 (pid 1000):
Filesystem kbytes quota limit grace files quota limit grace
/mnt/lustre 0 0 0 - 0 0 0 -
lustre-MDT0000_UUID
0 - 0 - 0 - 0 -
lustre-OST0000_UUID
0 - 0 - - - - -
lustre-OST0001_UUID
0 - 0 - - - - -
Total allocated inode limit: 0, total allocated block limit: 0
Block grace time: 40s; Inode grace time: 1w
Block grace time: 1w; Inode grace time: 1w
Block grace time: 1w; Inode grace time: 1w
Write before timer goes off
running as uid/gid/euid/egid 60000/60000/60000/60000, groups:
[dd] [if=/dev/zero] [of=/mnt/lustre/d3b.sanity-quota/f3b.sanity-quota-0] [bs=1K] [count=10] [seek=5120]
10+0 records in
10+0 records out
10240 bytes (10 kB, 10 KiB) copied, 0.011448 s, 894 kB/s
Quota info for qpool1:
Disk quotas for usr quota_usr (uid 60000):
Filesystem kbytes quota limit grace files quota limit grace
/mnt/lustre 4160* 4096 0 20s 2 0 0 -
Sleep through grace ...
...sleep 25 seconds
Disk quotas for usr quota_usr (uid 60000):
Filesystem kbytes quota limit grace files quota limit grace
/mnt/lustre 4160 8192 0 - 2 0 0 -
lustre-MDT0000_UUID
0 - 0 - 2 - 0 -
lustre-OST0000_UUID
4120 - 4144 - - - - -
lustre-OST0001_UUID
40* - 40 - - - - -
Total allocated inode limit: 0, total allocated block limit: 4184
Disk quotas for grp quota_usr (gid 60000):
Filesystem kbytes quota limit grace files quota limit grace
/mnt/lustre 4160 0 0 - 2 0 0 -
lustre-MDT0000_UUID
0 - 0 - 2 - 0 -
lustre-OST0000_UUID
4120 - 0 - - - - -
lustre-OST0001_UUID
40 - 0 - - - - -
Total allocated inode limit: 0, total allocated block limit: 0
Disk quotas for prj 1000 (pid 1000):
Filesystem kbytes quota limit grace files quota limit grace
/mnt/lustre 0 0 0 - 0 0 0 -
lustre-MDT0000_UUID
0 - 0 - 0 - 0 -
lustre-OST0000_UUID
0 - 0 - - - - -
lustre-OST0001_UUID
0 - 0 - - - - -
Total allocated inode limit: 0, total allocated block limit: 0
Block grace time: 40s; Inode grace time: 1w
Block grace time: 1w; Inode grace time: 1w
Block grace time: 1w; Inode grace time: 1w
Write after timer goes off
running as uid/gid/euid/egid 60000/60000/60000/60000, groups:
[dd] [if=/dev/zero] [of=/mnt/lustre/d3b.sanity-quota/f3b.sanity-quota-0] [bs=1K] [count=10] [seek=6144]
10+0 records in
10+0 records out
10240 bytes (10 kB, 10 KiB) copied, 0.00177872 s, 5.8 MB/s
Write after cancel lru locks
running as uid/gid/euid/egid 60000/60000/60000/60000, groups:
[dd] [if=/dev/zero] [of=/mnt/lustre/d3b.sanity-quota/f3b.sanity-quota-0] [bs=1K] [count=10] [seek=7168]
10+0 records in
10+0 records out
10240 bytes (10 kB, 10 KiB) copied, 0.00480989 s, 2.1 MB/s
Disk quotas for usr quota_usr (uid 60000):
Filesystem kbytes quota limit grace files quota limit grace
/mnt/lustre 4172 8192 0 - 2 0 0 -
lustre-MDT0000_UUID
0 - 0 - 2 - 0 -
lustre-OST0000_UUID
4132 - 4144 - - - - -
lustre-OST0001_UUID
40* - 40 - - - - -
Total allocated inode limit: 0, total allocated block limit: 4184
Files for user (quota_usr):
File: /mnt/lustre/d3b.sanity-quota/f3b.sanity-quota-0.mmap
Size: 40960 Blocks: 80 IO Block: 4194304 regular file
Device: 2c54f966h/743766374d Inode: 144115238826934298 Links: 1
Access: (0644/rw-rr-) Uid: (60000/quota_usr) Gid: (60000/quota_usr)
Access: 2020-12-23 13:07:15.000000000 +0500
Modify: 2020-12-23 13:07:15.000000000 +0500
Change: 2020-12-23 13:07:15.000000000 +0500
Birth: 2020-12-23 13:07:15.000000000 +0500
File: /mnt/lustre/d3b.sanity-quota/f3b.sanity-quota-0
Size: 7350272 Blocks: 8264 IO Block: 4194304 regular file
Device: 2c54f966h/743766374d Inode: 144115238826934296 Links: 1
Access: (0644/rw-rr-) Uid: (60000/quota_usr) Gid: (60000/quota_usr)
Access: 2020-12-23 13:07:14.000000000 +0500
Modify: 2020-12-23 13:07:41.000000000 +0500
Change: 2020-12-23 13:07:41.000000000 +0500
Birth: 2020-12-23 13:07:14.000000000 +0500
Disk quotas for grp quota_usr (gid 60000):
Filesystem kbytes quota limit grace files quota limit grace
/mnt/lustre 4172 0 0 - 2 0 0 -
lustre-MDT0000_UUID
0 - 0 - 2 - 0 -
lustre-OST0000_UUID
4132 - 0 - - - - -
lustre-OST0001_UUID
40 - 0 - - - - -
Total allocated inode limit: 0, total allocated block limit: 0
Files for group (quota_usr):
File: /mnt/lustre/d3b.sanity-quota/f3b.sanity-quota-0.mmap
Size: 40960 Blocks: 80 IO Block: 4194304 regular file
Device: 2c54f966h/743766374d Inode: 144115238826934298 Links: 1
Access: (0644/rw-rr-) Uid: (60000/quota_usr) Gid: (60000/quota_usr)
Access: 2020-12-23 13:07:15.000000000 +0500
Modify: 2020-12-23 13:07:15.000000000 +0500
Change: 2020-12-23 13:07:15.000000000 +0500
Birth: 2020-12-23 13:07:15.000000000 +0500
File: /mnt/lustre/d3b.sanity-quota/f3b.sanity-quota-0
Size: 7350272 Blocks: 8264 IO Block: 4194304 regular file
Device: 2c54f966h/743766374d Inode: 144115238826934296 Links: 1
Access: (0644/rw-rr-) Uid: (60000/quota_usr) Gid: (60000/quota_usr)
Access: 2020-12-23 13:07:14.000000000 +0500
Modify: 2020-12-23 13:07:41.000000000 +0500
Change: 2020-12-23 13:07:41.000000000 +0500
Birth: 2020-12-23 13:07:14.000000000 +0500
Disk quotas for prj 1000 (pid 1000):
Filesystem kbytes quota limit grace files quota limit grace
/mnt/lustre 0 0 0 - 0 0 0 -
lustre-MDT0000_UUID
0 - 0 - 0 - 0 -
lustre-OST0000_UUID
0 - 0 - - - - -
lustre-OST0001_UUID
0 - 0 - - - - -
Total allocated inode limit: 0, total allocated block limit: 0
Files for project (1000):
sanity-quota test_3b: @@@@@@ FAIL: write success, but expect EDQUOT
Trace dump:
= ./../tests/test-framework.sh:6273:error()
= sanity-quota.sh:159:quota_error()
= sanity-quota.sh:1297:test_block_soft()
= sanity-quota.sh:1435:test_3b()
= ./../tests/test-framework.sh:6576:run_one()
= ./../tests/test-framework.sh:6623:run_one_logged()
= ./../tests/test-framework.sh:6450:run_test()
= sanity-quota.sh:1494:main()
Dumping lctl log to /tmp/ltest-logs/sanity-quota.test_3b.*.1608710861.log
Dumping logs only on local client.
Resetting fail_loc on all nodes...done.
Delete files...
Wait for unlink objects finished...
Waiting for local destroys to complete
Destroy the created pools: qpool1
lustre.qpool1
OST lustre-OST0000_UUID removed from pool lustre.qpool1
OST lustre-OST0001_UUID removed from pool lustre.qpool1
Pool lustre.qpool1 destroyed
FAIL 3b (85s)

Issue Links

• is related to: LU-14387 sanity-quota tests fail with “lfs: fa...
• is related to: LU-15744 sanity-quota test_3a: ldlm_lockd.c:71...
• is related to: LU-12766 sanity-quota test 3 fails with 'write...
• is related to: LU-17046 sanity-quota test_1g: user write succ...

Comments

[Gerrit Updater]

Wang Shilong ([e-mail]) uploaded a new patch: https://review.whamcloud.com/41094
Subject: LU-14279 test: fix block soft testing failure
Project: fs/lustre-release
Branch: master
Current Patch Set: 1
Commit: 200765f305afb3e3da1f1910a8d7bee6cabf194e

[Andreas Dilger]

Shilong, the sanity-quota test_3a, test_3b are failing a lot in autotest (see LU-14387) with "lfs: failed for '/mnt/lustre': Not a directory". Does your patch https://review.whamcloud.com/41094 "LU-14279 test: fix block soft testing failure" fix those problems, or are they unrelated?

[Gerrit Updater]

Oleg Drokin ([e-mail]) merged in patch https://review.whamcloud.com/41094/
Subject: LU-14279 test: fix block soft testing failure
Project: fs/lustre-release
Branch: master
Current Patch Set:
Commit: a71382df0204fe2cd465eba3873574118f46622b

[Peter Jones]

Landed for 2.15