https://bugzilla.novell.com/show_bug.cgi?id=712958 https://bugzilla.novell.com/show_bug.cgi?id=712958#c32 Michal Hocko <mhocko@novell.com> changed: What |Removed |Added ---------------------------------------------------------------------------- Status|NEW |NEEDINFO InfoProvider| |drichard@largo.com --- Comment #32 from Michal Hocko <mhocko@novell.com> 2011-08-24 15:26:04 CEST --- (In reply to comment #30)
Created an attachment (id=447246) --> (http://bugzilla.novell.com/attachment.cgi?id=447246) [details] Output of dmesg after -c -s and then sysrg-trigger to t
This still doesn't contain anything useful (any traces): $ head bug-712958_dmesg.out 000000 [631329.878618] .se->statistics.exec_max : 1.528704 [631329.878618] .se->statistics.slice_max : 1.122945 [631329.878618] .se->statistics.wait_max : 2.041506 [631329.878618] .se->statistics.wait_sum : 3.526940 [631329.878618] .se->statistics.wait_count : 30 [631329.878618] .se->load.weight : 512 Anyway, as Jiri already pointed out. File open taking almost half a sec looks really suspicious. Let's have a look at the worst (successful) file open times: $ grep "\<open\>" bug-712958_vietchostsmore.log | grep -v "= -1" | sed 's/.*<\(.*\)>/\1/' | sort | tail -n5 0.339978 0.379258 0.403296 0.411674 0.445237 Which are those files? $ grep "\<open\>" bug-712958_vietchostsmore.log | grep -v "= -1" | sed 's/.*<\(.*\)>/\1/' | sort | tail -n5 > worst_open $ grep -f worst_open bug-712958_vietchostsmore.log 24197 12:13:07.959485 open("/etc/.hosts.swp", O_RDWR|O_CREAT|O_EXCL, 0600) = 4 <0.411674> 24197 12:13:08.382062 open("/etc/.hosts.swpx", O_RDWR|O_CREAT|O_EXCL, 0600) = 5 <0.339978> 24197 12:13:10.370000 open("/etc/.hosts.swp", O_RDWR|O_CREAT|O_EXCL, 0600) = 4 <0.445237> 24197 12:13:14.776717 open("/etc/4913", O_WRONLY|O_CREAT|O_EXCL, 0100644) = 3 <0.379258> 24197 12:13:17.044206 open("/etc/hosts", O_WRONLY|O_CREAT|O_TRUNC, 0644) = 3 <0.403296> Let's have a look at all file opens from /etc: $ grep "open.*etc" bug-712958_vietchostsmore.log 24197 12:13:07.042719 open("/etc/ld.so.cache", O_RDONLY) = 3 <0.002339> 24197 12:13:07.465060 open("/etc/vimrc", O_RDONLY) = 3 <0.000014> 24197 12:13:07.789405 open("/etc/nsswitch.conf", O_RDONLY) = 3 <0.000467> 24197 12:13:07.812897 open("/etc/ld.so.cache", O_RDONLY) = 3 <0.000534> 24197 12:13:07.841753 open("/etc/ld.so.cache", O_RDONLY) = 3 <0.001435> 24197 12:13:07.907670 open("/etc/passwd", O_RDONLY|O_CLOEXEC) = 3 <0.000288> 24197 12:13:07.949504 open("/etc/hosts", O_RDONLY) = 3 <0.000467> 24197 12:13:07.955735 open("/etc/.hosts.swp", O_RDONLY) = -1 ENOENT (No such file or directory) <0.000703> 24197 12:13:07.959485 open("/etc/.hosts.swp", O_RDWR|O_CREAT|O_EXCL, 0600) = 4 <0.411674> 24197 12:13:08.378219 open("/etc/.hosts.swpx", O_RDONLY) = -1 ENOENT (No such file or directory) <0.001717> 24197 12:13:08.382062 open("/etc/.hosts.swpx", O_RDWR|O_CREAT|O_EXCL, 0600) = 5 <0.339978> 24197 12:13:10.370000 open("/etc/.hosts.swp", O_RDWR|O_CREAT|O_EXCL, 0600) = 4 <0.445237> 24197 12:13:11.601865 open("/etc/hosts", O_RDONLY) = 3 <0.000019> 24197 12:13:14.776717 open("/etc/4913", O_WRONLY|O_CREAT|O_EXCL, 0100644) = 3 <0.379258> 24197 12:13:17.044206 open("/etc/hosts", O_WRONLY|O_CREAT|O_TRUNC, 0644) = 3 <0.403296>
From this, it looks that any write access is terribly slow (O_RDWR or O_WRONLY). I guess that /etc/ is at your root partition which seems to be ext4? Have you formated the filesystem from scratch or migrated it from ext3? Is the filesystem almost full?
You said that if you copied the file to /tmp then it opens just fine. Is /tmp on the same root partition or is it a link to tmpfs which you have mounted? -- Configure bugmail: https://bugzilla.novell.com/userprefs.cgi?tab=email ------- You are receiving this mail because: ------- You are on the CC list for the bug.