I got hold of iosnoop and invoked it with -v right after I started the song and 
invoked untar of mozilla. There was a time I noted when ogg123 hung for nearly 
5 seconds by simply using date commands. I took that timestamp and grepped in 
iosnoop log. Here is a snippet of iosnoop output around that time:

2005 Jul 14 13:07:23     0 101324 W    13675  49664        tar <none>
2005 Jul 14 13:07:23  1000 101247 R  6307832   4096     ogg123 
/mnt/fat32/AankhonMeinBaseHoTum.mp3.ogg
2005 Jul 14 13:07:23     0     3 W 11306544   3072    fsflush 
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/intl/uconv/native/nsNativeUConvService.h
dtrace: 285 dynamic variable drops with non-empty dirty list
2005 Jul 14 13:07:23     0     3 W 11303628   2048    fsflush 
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/intl/locale/src/mac/nsCollationMac.h
...
... 343 similar lines later
2005 Jul 14 13:07:24     0     3   10990224   1024    fsflush 
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/extensions/xmlextras/build/CVS/Root
2005 Jul 14 13:07:24     0     0   10761952   1024            
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/extensions/typeaheadfind/src/CVS/Repository
...
... 720 similar lines later
2005 Jul 14 13:07:26     0     0   11100154   1024            
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/embedding/browser/cocoa/CVS/Tag
2005 Jul 14 13:07:26     0     3 W 11088698   1024    fsflush 
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/config/Moz/CVS/Tag
...
... 125 similar lines later
2005 Jul 14 13:07:26     0     3 W 11311712   6144    fsflush 
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/intl/uconv/ucvlatin/iso-ir-111.ut
2005 Jul 14 13:07:26     0     3   11200962   5120            
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/embedding/components/ui/helperAppDlg/nsHelperAppDlg.xul
2005 Jul 14 13:07:27     0     0   11099744   1024            
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/embedding/browser/activex/tests/vbrowse/CVS/Tag
... 100 similar lines later
...
2005 Jul 14 13:07:27     0     0   10742430   1024            
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/extensions/universalchardet/Makefile.in
2005 Jul 14 13:07:27     0     3 W 11087900   2048    fsflush 
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/db/mork/src/morkBead.h
... 30 similar lines later
2005 Jul 14 13:07:27     0     3 W 11201376   1024    fsflush 
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/embedding/components/profilesharingsetup/CVS/Tag
2005 Jul 14 13:07:27     0     0   10766432   1024            
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/extensions/wallet/resources/locale/en-US/walletNavigatorOverlay.dtd
... 300 lines later
2005 Jul 14 13:07:28     0     0   11093180   1024            
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/db/mdb/Makefile.in
2005 Jul 14 13:07:28     0 101324 W    13772  11776        tar <none>
2005 Jul 14 13:07:28     0 101326 W 12935600  57344     dtrace /var/tmp/ios.log
2005 Jul 14 13:07:28     0 101326 W 12935712  57344     dtrace /var/tmp/ios.log
2005 Jul 14 13:07:28     0     3 W 11101796   5120    fsflush 
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/embedding/browser/powerplant/ReadMe.txt
... 9 lines later
2005 Jul 14 13:07:28     0     3   11097904   7168    fsflush 
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/embedding/browser/activex/src/pluginhostctrl/pluginsdk_include/jritypes.h
... 300 lines later
2005 Jul 14 13:07:28     0     0   11910194   6144            
/var/tmp/portage/mozilla-firefox-1.0.4/work/mozilla/content/xml/tests/books/charing.gif
2005 Jul 14 13:07:28     0     0   11963344   8192            <none>
2005 Jul 14 13:07:28     0 101326 W 12935824  57344     dtrace /var/tmp/ios.log
2005 Jul 14 13:07:28  1000 101247 R  6307840   4096     ogg123 
/mnt/fat32/AankhonMeinBaseHoTum.mp3.ogg

-----------------

2005 Jul 14 13:07:35     0 101323 R 41741493   4096      bzip2 
/mnt/linux-fat32/distfiles/firefox-1.0.4-source.tar.bz2

the thing to note with mozilla tar.bz2 is that bzip2 uses 4k size blocks and 
the tar itself consists of large number of very small sized files. That could 
explain why there are so many outstanding requests. This looks like a 
limitation from using fat32 format (which probably defaults to 4k block size) 
for source.

Also note that my song is in one fat32 partition and mozilla tar is on another 
fat32. The destination of untar is on "/" which is logging. Is the pcfs driver 
the culprit here? I have doubt about that because bzip2 never figured in the 
above mentioned 5 seconds in iosnoop. Moreover, the symptoms of skips/freezes 
also show up when firefox loads and tries to open around 12 tabs; this 
operation is all "/" bound.

Is it just that UFS is not suited for large number of small files or fat32/pcfs 
is the culprit? I haven't looked into tuning fsflush as yet and I am not sure 
if that is even required.

I will appreciate some help.
This message posted from opensolaris.org
_______________________________________________
opensolaris-discuss mailing list
opensolaris-discuss@opensolaris.org

Reply via email to