Bug 999373
Summary: | [RHS-RHOS] "ls" takes >5mins to list glance images on gluster mount while instance snapshot is in progress. | ||||||
---|---|---|---|---|---|---|---|
Product: | [Red Hat Storage] Red Hat Gluster Storage | Reporter: | Gowrishankar Rajaiyan <grajaiya> | ||||
Component: | glusterfs | Assignee: | Bug Updates Notification Mailing List <rhs-bugs> | ||||
Status: | CLOSED NOTABUG | QA Contact: | shilpa <smanjara> | ||||
Severity: | unspecified | Docs Contact: | |||||
Priority: | unspecified | ||||||
Version: | 2.1 | CC: | grajaiya, rhs-bugs, tkatarki, vbellur | ||||
Target Milestone: | --- | ||||||
Target Release: | --- | ||||||
Hardware: | Unspecified | ||||||
OS: | Unspecified | ||||||
Whiteboard: | |||||||
Fixed In Version: | Doc Type: | Bug Fix | |||||
Doc Text: | Story Points: | --- | |||||
Clone Of: | Environment: |
virt rhos glance rhs integration
|
|||||
Last Closed: | 2014-03-27 09:53:32 UTC | Type: | Bug | ||||
Regression: | --- | Mount Type: | --- | ||||
Documentation: | --- | CRM: | |||||
Verified Versions: | Category: | --- | |||||
oVirt Team: | --- | RHEL 7.3 requirements from Atomic Host: | |||||
Cloudforms Team: | --- | Target Upstream Version: | |||||
Embargoed: | |||||||
Attachments: |
|
Description
Gowrishankar Rajaiyan
2013-08-21 08:49:20 UTC
Created attachment 788760 [details]
rhs-bricks-glance1.1530.dump.1377072403
attaching statedump.
It gets a bit better after I deleted the instance and the snapshot that was getting created, but still [root@rhs-hpc-srv1 ~]# time strace -c ls -l /var/lib/glance/images/ total 26875338 -rw-r-----. 1 glance glance 699592704 Aug 20 17:19 1245b941-79c7-4e1c-84e1-4e2727c0b530 -rw-r-----. 1 glance glance 3589636096 Aug 21 2013 2f9486ba-4f2e-4aa7-b23d-229a0999161c -rw-r-----. 1 glance glance 235536384 Aug 20 17:22 30feb3a1-f714-41c7-86be-d962a5fb216b -rw-r-----. 1 glance glance 237371392 Aug 20 17:21 569e41dd-d823-4162-bb20-036fb24557e0 -rw-r-----. 1 glance glance 1826027520 Aug 20 17:14 5818c4e8-d0fb-4b32-830e-7154eacee839 -rw-r-----. 1 glance glance 1682935808 Aug 21 2013 6c2da0ab-24ef-4da6-bb70-4113f59e847e -rw-r-----. 1 glance glance 1682059264 Aug 21 2013 6f713cee-8e3c-4057-bd37-98ccee4c3e0b -rw-r-----. 1 glance glance 1001259008 Aug 21 2013 abb48a02-4141-4d3b-a323-04829df59b35 -rw-r-----. 1 glance glance 16337731584 Aug 20 17:33 bc1349be-e4b8-479e-be02-ed3710e73844 -rw-r-----. 1 glance glance 228196352 Aug 20 17:22 effbf554-37c1-4e7f-96c6-62671929e0b7 % time seconds usecs/call calls errors syscall ------ ----------- ----------- --------- --------- ---------------- 33.63 0.009749 443 22 11 lgetxattr 30.08 0.008720 229 38 mmap 15.40 0.004464 893 5 socket 10.35 0.002999 136 22 lstat 6.28 0.001819 165 11 write 3.75 0.001086 34 32 10 open 0.48 0.000140 140 1 statfs 0.03 0.000009 0 29 close 0.00 0.000000 0 18 read 0.00 0.000000 0 9 stat 0.00 0.000000 0 22 fstat 0.00 0.000000 0 1 lseek 0.00 0.000000 0 18 mprotect 0.00 0.000000 0 8 munmap 0.00 0.000000 0 3 brk 0.00 0.000000 0 2 rt_sigaction 0.00 0.000000 0 1 rt_sigprocmask 0.00 0.000000 0 2 ioctl 0.00 0.000000 0 1 1 access 0.00 0.000000 0 5 5 connect 0.00 0.000000 0 1 execve 0.00 0.000000 0 2 fcntl 0.00 0.000000 0 6 getdents 0.00 0.000000 0 1 getrlimit 0.00 0.000000 0 1 arch_prctl 0.00 0.000000 0 4 1 futex 0.00 0.000000 0 1 set_tid_address 0.00 0.000000 0 1 set_robust_list ------ ----------- ----------- --------- --------- ---------------- 100.00 0.028986 267 28 total real 4m40.799s user 0m0.003s sys 0m0.060s [root@rhs-hpc-srv1 ~]# restarting openstack-glance-api helps: [root@rhs-hpc-srv1 ~]# time strace -c ls -l /var/lib/glance/images/ total 26880782 -rw-r-----. 1 glance glance 699592704 Aug 20 17:19 1245b941-79c7-4e1c-84e1-4e2727c0b530 -rw-r-----. 1 glance glance 3589636096 Aug 21 2013 2f9486ba-4f2e-4aa7-b23d-229a0999161c -rw-r-----. 1 glance glance 235536384 Aug 20 17:22 30feb3a1-f714-41c7-86be-d962a5fb216b -rw-r-----. 1 glance glance 237371392 Aug 20 17:21 569e41dd-d823-4162-bb20-036fb24557e0 -rw-r-----. 1 glance glance 1826027520 Aug 20 17:14 5818c4e8-d0fb-4b32-830e-7154eacee839 -rw-r-----. 1 glance glance 1682935808 Aug 21 2013 6c2da0ab-24ef-4da6-bb70-4113f59e847e -rw-r-----. 1 glance glance 1682059264 Aug 21 2013 6f713cee-8e3c-4057-bd37-98ccee4c3e0b -rw-r-----. 1 glance glance 1006833664 Aug 21 2013 abb48a02-4141-4d3b-a323-04829df59b35 -rw-r-----. 1 glance glance 16337731584 Aug 20 17:33 bc1349be-e4b8-479e-be02-ed3710e73844 -rw-r-----. 1 glance glance 228196352 Aug 20 17:22 effbf554-37c1-4e7f-96c6-62671929e0b7 % time seconds usecs/call calls errors syscall ------ ----------- ----------- --------- --------- ---------------- 40.90 0.004999 833 6 getdents 32.72 0.003999 800 5 socket 8.18 0.001000 45 22 lstat 8.18 0.001000 45 22 11 lgetxattr 5.96 0.000728 146 5 5 connect 3.94 0.000481 15 32 10 open 0.07 0.000009 0 38 mmap 0.06 0.000007 4 2 fcntl 0.00 0.000000 0 18 read 0.00 0.000000 0 11 write 0.00 0.000000 0 29 close 0.00 0.000000 0 9 stat 0.00 0.000000 0 22 fstat 0.00 0.000000 0 1 lseek 0.00 0.000000 0 18 mprotect 0.00 0.000000 0 8 munmap 0.00 0.000000 0 3 brk 0.00 0.000000 0 2 rt_sigaction 0.00 0.000000 0 1 rt_sigprocmask 0.00 0.000000 0 2 ioctl 0.00 0.000000 0 1 1 access 0.00 0.000000 0 1 execve 0.00 0.000000 0 1 getrlimit 0.00 0.000000 0 1 statfs 0.00 0.000000 0 1 arch_prctl 0.00 0.000000 0 4 1 futex 0.00 0.000000 0 1 set_tid_address 0.00 0.000000 0 1 set_robust_list ------ ----------- ----------- --------- --------- ---------------- 100.00 0.012223 267 28 total real 0m0.096s user 0m0.004s sys 0m0.024s [root@rhs-hpc-srv1 ~]# ==> /var/log/glusterfs/var-lib-glance-images.log <== [2013-08-20 21:23:42.406475] W [client-rpc-fops.c:984:client3_3_fsync_cbk] 0-glance-vol-client-11: remote operation failed: Structure needs cleaning [2013-08-20 21:23:42.406531] W [afr-transaction.c:1497:afr_changelog_fsync_cbk] 0-glance-vol-replicate-5: fsync(cfaf9576-9fa5-48e4-991a-39a3088f9590) failed on subvolume glance-vol-client-11. Transaction was WRITE [2013-08-20 21:24:29.555457] W [client-rpc-fops.c:984:client3_3_fsync_cbk] 0-glance-vol-client-11: remote operation failed: Structure needs cleaning [2013-08-20 21:24:29.555500] W [afr-transaction.c:1497:afr_changelog_fsync_cbk] 0-glance-vol-replicate-5: fsync(cfaf9576-9fa5-48e4-991a-39a3088f9590) failed on subvolume glance-vol-client-11. Transaction was WRITE [2013-08-20 21:24:52.317066] W [client-rpc-fops.c:984:client3_3_fsync_cbk] 0-glance-vol-client-11: remote operation failed: Structure needs cleaning [2013-08-20 21:24:52.317128] W [afr-transaction.c:1497:afr_changelog_fsync_cbk] 0-glance-vol-replicate-5: fsync(cfaf9576-9fa5-48e4-991a-39a3088f9590) failed on subvolume glance-vol-client-11. Transaction was WRITE [2013-08-20 21:24:52.361048] W [client-rpc-fops.c:984:client3_3_fsync_cbk] 0-glance-vol-client-11: remote operation failed: Structure needs cleaning [2013-08-20 21:24:52.361102] W [afr-transaction.c:1497:afr_changelog_fsync_cbk] 0-glance-vol-replicate-5: fsync(cfaf9576-9fa5-48e4-991a-39a3088f9590) failed on subvolume glance-vol-client-11. Transaction was WRITE [2013-08-20 21:40:08.669613] W [client-rpc-fops.c:984:client3_3_fsync_cbk] 0-glance-vol-client-11: remote operation failed: Structure needs cleaning [2013-08-20 21:40:08.669660] W [afr-transaction.c:1497:afr_changelog_fsync_cbk] 0-glance-vol-replicate-5: fsync(cfaf9576-9fa5-48e4-991a-39a3088f9590) failed on subvolume glance-vol-client-11. Transaction was WRITE (In reply to Gowrishankar Rajaiyan from comment #0) ... > > How reproducible: Always > ... Couldn't reproduce it the 3rd time. Re-tested on RHOS4.0 with glusterfs-3.4.0.59rhs-1.el6_4.x86_64 While the instance is being snapshotted time time taken to list /mnt/gluster/glance/images is 0m0.051s # time strace -c ls -l /mnt/gluster/glance/images/ total 1249024 -rw-r-----. 1 glance glance 251985920 Feb 18 12:52 036b032a-88c2-4f8b-a9c1-8cf6ef032841 -rwxrwxrwx. 1 glance glance 251985920 Mar 13 14:40 4c320f2a-e369-43a8-91a7-031109e99057 -rw-r-----. 1 glance glance 251985920 Mar 27 12:04 4e7d2806-e2f0-429c-bcab-d289cdacf391 -rw-r-----. 1 glance glance 19070976 Mar 10 15:06 58ae3788-60c7-45e7-b333-64bd0f622b70 -rw-r-----. 1 glance glance 251985920 Mar 25 13:08 a1c71334-152f-4311-ab89-f59754c4f449 -rw-r-----. 1 glance glance 251985920 Mar 25 13:07 dcde5200-cd98-4ffa-a363-65dcb36018d7 % time seconds usecs/call calls errors syscall ------ ----------- ----------- --------- --------- ---------------- 60.20 0.001546 110 14 7 lgetxattr 39.41 0.001012 72 14 lstat 0.39 0.000010 0 38 mmap 0.00 0.000000 0 18 read 0.00 0.000000 0 7 write 0.00 0.000000 0 32 10 open 0.00 0.000000 0 29 close 0.00 0.000000 0 5 stat 0.00 0.000000 0 22 fstat 0.00 0.000000 0 1 lseek 0.00 0.000000 0 18 mprotect 0.00 0.000000 0 8 munmap 0.00 0.000000 0 3 brk 0.00 0.000000 0 2 rt_sigaction 0.00 0.000000 0 1 rt_sigprocmask 0.00 0.000000 0 2 ioctl 0.00 0.000000 0 1 1 access 0.00 0.000000 0 5 socket 0.00 0.000000 0 5 5 connect 0.00 0.000000 0 1 execve 0.00 0.000000 0 2 fcntl 0.00 0.000000 0 6 getdents 0.00 0.000000 0 1 getrlimit 0.00 0.000000 0 1 statfs 0.00 0.000000 0 1 arch_prctl 0.00 0.000000 0 4 1 futex 0.00 0.000000 0 1 set_tid_address 0.00 0.000000 0 1 set_robust_list ------ ----------- ----------- --------- --------- ---------------- 100.00 0.002568 243 24 total real 0m0.051s user 0m0.002s sys 0m0.010s Since the bug cannot be reproduced Closing this bug as Not a bug. |