Skip site navigation (1)Skip section navigation (2) 
Date:      Fri, 1 Dec 2000 19:08:27 +0100
From:      Ernst de Haan <ernst@jollem.com>
To:        "Koster, K.J." <K.J.Koster@kpn.com>
Cc:        FreeBSD Java mailing list <freebsd-java@freebsd.org>
Subject:   Re: Performance hint for JDK on FreeBSD
Message-ID:  <20001201190827.A59487@c187104187.telekabel.chello.nl>
In-Reply-To: <20001201185031.A58178@c187104187.telekabel.chello.nl>; from ernst@jollem.com on Fri, Dec 01, 2000 at 06:50:31PM %2B0100
References:  <59063B5B4D98D311BC0D0001FA7E4522026D7A7D@l04.research.kpn.com> <20001201174923.A57144@c187104187.telekabel.chello.nl> <20001201185031.A58178@c187104187.telekabel.chello.nl>
next in thread | previous in thread | raw e-mail | index | archive | help 

[-- Attachment #1 --]
Ok, the votes are counted, the results are in :) I've written a small Java
program I wrote to produce new test results that should be reproducable on
other (FreeBSD) systems too. I've included the .java file in an attachment,
and, for your convenience, the AWTTest.class file.

In the program I do 2 things. First I attempt to load the class
java.awt.Toolkit. Then I invoke the class function getDefaultToolkit(), which
apparently initializes AWT.

Here are the results (I did only one run):

                Original   Applied suggested modifications
                --------   -------------------------------
Class load:     155 ms.    154 ms.
AWT startup:    2454 ms.   437 ms.

That's quite a difference, isn't it? :) Could anyone confirm these results for
his/her system?


Ernst


Ernst de Haan wrote:
> Heya Kees-Jan and all,
> 
> 
> > > I never knew that it is a 400% performance boost, which does sound a little
> > > strange. I'm really curious why that is.
> > 
> > Well, I turned on profiling, and found that most of the time is spent in X11
> > specific Java code. I will re-run the tests after I temporarily disabled your
> > suggested modifications.
> 
> Okay, I did a little profiling (using -Xrunhprof:cpu=times). This is the top
> 10 when I dont have the modified font.properties file:
> 
>    CPU TIME (ms) BEGIN (total = 29358) Fri Dec  1 17:55:12 2000
>    rank   self  accum   count trace method
>       1 24.71% 24.71%  253952  1304 sun/io/CharToByteSingleByte.getNative
>       2 16.04% 40.76%    8188  1399 sun/io/CharToByteSingleByte.convert
>       3 12.93% 53.69%  253952   182 java/lang/String.charAt
>       4  2.25% 55.94%    8184   620 java/lang/Throwable.fillInStackTrace
>       5  1.44% 57.38%    8258   938 java/lang/String.<init>
>       6  1.33% 58.71%       1  1275 sun/awt/font/NativeFontWrapper.registerFonts
>       7  1.21% 59.93%    8184   867 java/lang/Throwable.<init>
>       8  1.08% 61.01%   10958   153 java/io/StreamTokenizer.read
>       9  1.07% 62.08%   10958   623 java/io/BufferedInputStream.read
>      10  0.91% 62.99%     318   123 java/util/Properties.loadConvert
> 
> The traces for the top 3 are:
> 
>    TRACE 1304:
>       sun/io/CharToByteSingleByte.getNative
>       sun/io/CharToByteSingleByte.convert
>       sun/awt/font/NativeFontWrapper.registerFonts
>       sun/awt/X11GraphicsEnvironment.registerNativeFonts
> 
>    TRACE 1399:
>       sun/io/CharToByteSingleByte.convert
>       sun/awt/font/NativeFontWrapper.registerFonts
>       sun/awt/X11GraphicsEnvironment.registerNativeFonts
>       sun/java2d/SunGraphicsEnvironment$2.run
> 
>    TRACE 182:
>       java/lang/String.charAt
>       sun/io/CharToByteSingleByte.getNative
>       sun/io/CharToByteSingleByte.convert
>       sun/awt/font/NativeFontWrapper.registerFonts
> 
> Here are the startup times for AWT:
> 
>           Original:         w/modifications:
>           ----------------  ------------------------------
>    run 1: 2607 ms           608 ms
>    run 2: 2579 ms           573 ms
>    run 3: 2583 ms           601 ms
>    run 4: 2713 ms           599 ms
> 
> Note: The only modifications performed are adding the URW fonts to my
> XF86Config and using the font.properties from Kees-Jan's site instead of the
> original one!
> 
> Here is the top 10 of CPU time eaters for my application with the
> modifications applied:
> 
>    CPU TIME (ms) BEGIN (total = 9169) Fri Dec  1 17:46:56 2000
>    rank   self  accum   count trace method
>       1  2.91%  2.91%     318   110 java/util/Properties.loadConvert
>       2  2.75%  5.66%     252   109 java/util/Properties.loadConvert
>       3  2.25%  7.91%       5   158 java/util/Properties.load
>       4  1.98%  9.89%    6356   987 java/lang/String.indexOf
>       5  1.73% 11.63%    5479   584 java/io/BufferedInputStream.read
>       6  1.73% 13.36%    5479   142 java/io/StreamTokenizer.read
>       7  1.49% 14.85%    8590   203 java/lang/StringBuffer.append
>       8  1.42% 16.27%    8590   675 java/lang/String.charAt
>       9  1.41% 17.68%    8133   946 java/lang/StringBuffer.append
>      10  1.36% 19.04%    8133   207 java/lang/String.charAt
> 
> Now all of a sudden my application code comes on top. And that's the way I
> like it! :)
> 
> IMHO these results are quite amazing!
> 
> Next thing I'll do is write a small program that will do nothing but starting
> AWT and displaying the startup times, as the current results are obtained by
> looking at the log output of my own Swing application.
> 
> 
> Ernst
> 
> 
> To Unsubscribe: send mail to majordomo@FreeBSD.org
> with "unsubscribe freebsd-java" in the body of the message
> 

[-- Attachment #2 --]
public class AWTTest {

   public static void main(String[] args) throws Exception {

      // Load the Toolkit class from the AWT package
      long beforeClassLoad = System.currentTimeMillis();
      Class c = Class.forName("java.awt.Toolkit");
      long afterClassLoad = System.currentTimeMillis();

      // Get the getDefaultToolkit() class function
      java.lang.reflect.Method m = c.getMethod("getDefaultToolkit", null);

      // Invoke the class function
      long beforeInvoke = System.currentTimeMillis();
      m.invoke(null, null);
      long afterInvoke = System.currentTimeMillis();

      long t1 = afterClassLoad - beforeClassLoad;
      long t2 = afterInvoke    - beforeInvoke;

      System.out.println("Class load took:  " + t1 + " ms.");
      System.out.println("AWT startup took: " + t2 + " ms.");
      System.exit(0);
   }
}

[-- Attachment #3 --]
Êþº¾-NAWTTestjava/lang/Objectmain([Ljava/lang/String;)V
Exceptions	java/lang/ExceptionCodeLineNumberTable
currentTimeMillis()J
java/lang/Systemjava.awt.ToolkitforName%(Ljava/lang/String;)Ljava/lang/Class;
java/lang/ClassgetDefaultToolkit	getMethod@(Ljava/lang/String;[Ljava/lang/Class;)Ljava/lang/reflect/Method;
!"invoke9(Ljava/lang/Object;[Ljava/lang/Object;)Ljava/lang/Object;
$ %java/lang/reflect/Method'(outLjava/io/PrintStream;	&+java/lang/StringBuffer-.<init>()V
*,1Class load took:  34append,(Ljava/lang/String;)Ljava/lang/StringBuffer;
*237(J)Ljava/lang/StringBuffer;
*6: ms.<=toString()Ljava/lang/String;
*;@Aprintln(Ljava/lang/String;)V
C?Djava/io/PrintStreamFAWT startup took: HIexit(I)V
G
,
SourceFileAWTTest.java!	
Âz¸@¸N¸7-¶:¸7¶#W¸7	e7	e7
²)»*Y·/0¶5¶89¶5¶>¶B²)»*Y·/E¶5
¶89¶5¶>¶B¸J±6

%*07Vuy-.
!*·K±
LM

Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20001201190827.A59487>