Author Topic: Slow jPCT-AE view initialization  (Read 1740 times)

Offline Firzen

  • byte
  • *
  • Posts: 12
    • View Profile
Slow jPCT-AE view initialization
« on: May 16, 2015, 05:59:52 pm »
Hi,

I am creating Android application which uses jPCT-AE.
Everything works well, but when I open activity with jPCT view, it takes several seconds to show simple (Rubik's cube) scene. I see hunderds of jPCT-AE logs in LogCat during view initialization.

Is there any way how to speed up initialization or at least somehow pre-load the 3D scene?
I also suspect jPCT that init time is not much depend on objects count in the scene. When I open my activity with 2x2x2 cube, it takes approximately same amount of time as with 3x3x3 cube...
I am new to both jPCT and Android so I am probably doing something wrong.

Thanks in advance!

Some of mentioned logs:
Quote
05-16 17:44:52.870: I/jPCT-AE(20063): VBO disposed for object 'object163'
05-16 17:44:52.880: I/jPCT-AE(20063): VBO disposed for object 'object164'
05-16 17:44:52.880: I/jPCT-AE(20063): VBO disposed for object 'object161'
05-16 17:44:52.880: I/jPCT-AE(20063): VBO disposed for object 'object162'
05-16 17:44:52.880: I/jPCT-AE(20063): VBO disposed for object 'object165'
05-16 17:44:52.880: I/jPCT-AE(20063): VBO disposed for object 'object160'
05-16 17:44:52.880: D/dalvikvm(20063): WAIT_FOR_CONCURRENT_GC blocked 0ms
05-16 17:44:52.890: I/jPCT-AE(20063): VBO disposed for object 'object168'
05-16 17:44:52.890: I/jPCT-AE(20063): VBO disposed for object 'object169'
05-16 17:44:52.900: I/jPCT-AE(20063): VBO disposed for object 'object167'
05-16 17:44:52.900: I/jPCT-AE(20063): VBO disposed for object 'object170'
05-16 17:44:52.900: I/jPCT-AE(20063): VBO disposed for object 'object171'
05-16 17:44:52.900: I/jPCT-AE(20063): VBO disposed for object 'object166'
05-16 17:44:52.900: I/jPCT-AE(20063): VBO disposed for object 'object175'
...
And many more of these messages ...

Quote
05-16 17:44:54.370: I/jPCT-AE(20063): 0 shaders in replacement map!
05-16 17:44:54.370: I/jPCT-AE(20063): Default fragment shader is: /defaultFragmentShader.src
05-16 17:44:54.370: I/jPCT-AE(20063): Loading file from InputStream
05-16 17:44:54.380: I/jPCT-AE(20063): Text file from InputStream loaded...199 bytes
05-16 17:44:54.380: I/jPCT-AE(20063): Default vertex shader is: /defaultVertexShader.src
05-16 17:44:54.380: I/jPCT-AE(20063): Loading file from InputStream
05-16 17:44:54.380: I/jPCT-AE(20063): Text file from InputStream loaded...757 bytes
05-16 17:44:54.380: I/jPCT-AE(20063): Compiling shader program!
05-16 17:44:54.410: I/jPCT-AE(20063): Handles of 6: 2/3/1
05-16 17:44:54.410: I/jPCT-AE(20063): Loading default shaders (Tex0)!
05-16 17:44:54.410: I/jPCT-AE(20063): 0 shaders in replacement map!
05-16 17:44:54.410: I/jPCT-AE(20063): Default fragment shader is: /defaultFragmentShader.src
05-16 17:44:54.410: I/jPCT-AE(20063): Loading file from InputStream
05-16 17:44:54.410: I/jPCT-AE(20063): Text file from InputStream loaded...201 bytes
05-16 17:44:54.410: I/jPCT-AE(20063): Default vertex shader is: /defaultVertexShader.src
05-16 17:44:54.410: I/jPCT-AE(20063): Loading file from InputStream
05-16 17:44:54.410: I/jPCT-AE(20063): Text file from InputStream loaded...4020 bytes
05-16 17:44:54.410: I/jPCT-AE(20063): Compiling shader program!
05-16 17:44:55.250: I/jPCT-AE(20063): Handles of 9: 2/29/1
05-16 17:44:55.250: I/jPCT-AE(20063): Loading default shaders (Tex1)!
05-16 17:44:55.250: I/jPCT-AE(20063): 0 shaders in replacement map!
05-16 17:44:55.250: I/jPCT-AE(20063): Default fragment shader is: /defaultFragmentShader.src
05-16 17:44:55.250: I/jPCT-AE(20063): Loading file from InputStream
05-16 17:44:55.250: I/jPCT-AE(20063): Text file from InputStream loaded...997 bytes
05-16 17:44:55.260: I/jPCT-AE(20063): Default vertex shader is: /defaultVertexShader.src
05-16 17:44:55.260: I/jPCT-AE(20063): Loading file from InputStream
05-16 17:44:55.260: I/jPCT-AE(20063): Text file from InputStream loaded...4390 bytes
05-16 17:44:55.260: I/jPCT-AE(20063): Compiling shader program!
05-16 17:44:56.120: I/jPCT-AE(20063): Handles of 12: 2/34/1
...
...
Lots of these messages

Quote
5-16 17:44:57.169: I/jPCT-AE(20063): Object 317/object319 compiled to 1 subobjects in 3ms!
05-16 17:44:57.179: I/jPCT-AE(20063): Subobject of object 318/object320 compiled to indexed fixed point data using 6/4 vertices in 6ms!
05-16 17:44:57.179: I/jPCT-AE(20063): Object 318/object320 compiled to 1 subobjects in 7ms!
05-16 17:44:57.179: I/jPCT-AE(20063): Subobject of object 321/object323 compiled to indexed fixed point data using 6/4 vertices in 1ms!
05-16 17:44:57.189: I/jPCT-AE(20063): Object 321/object323 compiled to 1 subobjects in 4ms!
05-16 17:44:57.189: I/jPCT-AE(20063): Subobject of object 316/object318 compiled to indexed fixed point data using 6/4 vertices in 1ms!
05-16 17:44:57.189: I/jPCT-AE(20063): Object 316/object318 compiled to 1 subobjects in 4ms!
05-16 17:44:57.189: I/jPCT-AE(20063): Subobject of object 324/object326 compiled to indexed fixed point data using 6/4 vertices in 1ms!
05-16 17:44:57.199: I/jPCT-AE(20063): Object 324/object326 compiled to 1 subobjects in 6ms!
05-16 17:44:57.199: I/jPCT-AE(20063): Subobject of object 325/object327 compiled to indexed fixed point data using 6/4 vertices in 1ms!
05-16 17:44:57.199: I/jPCT-AE(20063): Object 325/object327 compiled to 1 subobjects in 2ms!
05-16 17:44:57.199: I/jPCT-AE(20063): Subobject of object 323/object325 compiled to indexed fixed point data using 6/4 vertices in 1ms!
...
...

Quote
05-16 17:44:57.879: I/jPCT-AE(20063): VBO created for object 'object445'
05-16 17:44:57.879: I/jPCT-AE(20063): Creating buffers...
05-16 17:44:57.879: I/jPCT-AE(20063): VBO created for object 'object446'
05-16 17:44:57.879: I/jPCT-AE(20063): Creating buffers...
05-16 17:44:57.879: I/jPCT-AE(20063): VBO created for object 'object447'
05-16 17:44:57.879: I/jPCT-AE(20063): Creating buffers...
05-16 17:44:57.879: I/jPCT-AE(20063): VBO created for object 'object448'
05-16 17:44:57.879: I/jPCT-AE(20063): Creating buffers...
05-16 17:44:57.879: I/jPCT-AE(20063): VBO created for object 'object334'
05-16 17:44:57.879: I/jPCT-AE(20063): Creating buffers...
05-16 17:44:57.889: I/jPCT-AE(20063): VBO created for object 'object449'
...
...

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 11775
    • View Profile
    • http://www.jpct.net
Re: Slow jPCT-AE view initialization
« Reply #1 on: May 16, 2015, 07:42:50 pm »
You are using lots of objects, which all have to be processed and uploaded to the GPU. If your cube parts are actually all the same object just with maybe a different texture, consider to create one blue print object, clone it and share the compiled mesh between those. That will speed up the init process and save lots of memory: http://www.jpct.net/wiki/index.php/Reducing_memory_usage#Reduce_memory_usage_of_objects
« Last Edit: May 16, 2015, 07:44:29 pm by EgonOlsen »

Offline Firzen

  • byte
  • *
  • Posts: 12
    • View Profile
Re: Slow jPCT-AE view initialization
« Reply #2 on: May 17, 2015, 06:31:11 pm »
Hi,
you are right that I am not cloning objects, but I think the problem is not here. When I open my activity with 3D scene, and then lock the screen, after unlocking it seems that scene is rendering again, and it takes really much time. And at that moment no objects are created.
Why it takes so long? I have tested it with empty scene (with no objects), and it lagged too.

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 11775
    • View Profile
    • http://www.jpct.net
Re: Slow jPCT-AE view initialization
« Reply #3 on: May 17, 2015, 08:24:02 pm »
Because all of your objects have to uploaded to the GPU again if the context changes. Try cloning...it really helps.

Offline Firzen

  • byte
  • *
  • Posts: 12
    • View Profile
Re: Slow jPCT-AE view initialization
« Reply #4 on: May 22, 2015, 01:32:43 pm »
Thanks for your answers. I have found why it takes my jPCT view so long for load. The problem is in framebuffer initialization. There was this code in my app:

Code: [Select]
public void onSurfaceChanged(GL10 gl, int w, int h) {
if (fb != null) {
fb.dispose();
}
fb = new FrameBuffer(w, h);
}

So I have changed it to:

Code: [Select]
public void onSurfaceChanged(GL10 gl, int w, int h) {
if(width != w || height != h) {
if (fb != null) {
fb.dispose();
}
fb = new FrameBuffer(w, h);
width = w;
height = h;
}
}

When I now start my 3D scene and then lock and unlock the screen, it is very fast. But still my 3D scene taks very long to load for the first time. I have tested it with an empty scene without any objects, and it lagged for the same time too. In empty scene, there is nothing to clone. Cloning really will not help me.
Is there any way how to speed up FrameBuffer initialization? It is also possible that I have just slow phone. For testing I use Samgung Galaxy Young which is really not very fast. :-)
« Last Edit: May 22, 2015, 01:36:34 pm by Firzen »

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 11775
    • View Profile
    • http://www.jpct.net
Re: Slow jPCT-AE view initialization
« Reply #5 on: May 22, 2015, 02:25:30 pm »
While that might be fast, it's not the way do to it. This way, you'll ignore a context change and that will crash the application. It's better to do it like mentioned here: http://www.jpct.net/forum2/index.php/topic,4304.msg29937.html#msg29937

Can you post the complete startup log?

Offline Firzen

  • byte
  • *
  • Posts: 12
    • View Profile
Re: Slow jPCT-AE view initialization
« Reply #6 on: May 22, 2015, 03:36:16 pm »
Thank you for better way to manipulating with FrameBuffer! I have changed it in code.
Here is complete initialization log:

Code: [Select]
05-22 15:30:55.571: I/jPCT-AE(20271): onCreate
05-22 15:30:55.641: I/jPCT-AE(20271): Adding Lightsource: 0
05-22 15:30:55.681: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.691: I/jPCT-AE(20271): Normal vectors calculated in 2ms!
05-22 15:30:55.691: I/jPCT-AE(20271): Normal vectors calculated in 2ms!
05-22 15:30:55.701: I/jPCT-AE(20271): Normal vectors calculated in 2ms!
05-22 15:30:55.701: I/jPCT-AE(20271): Normal vectors calculated in 2ms!
05-22 15:30:55.701: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.711: I/jPCT-AE(20271): Normal vectors calculated in 4ms!
05-22 15:30:55.711: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.721: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.721: I/jPCT-AE(20271): Normal vectors calculated in 3ms!
05-22 15:30:55.721: I/jPCT-AE(20271): Normal vectors calculated in 2ms!
05-22 15:30:55.741: I/jPCT-AE(20271): Normal vectors calculated in 4ms!
05-22 15:30:55.741: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.741: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.741: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.751: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.751: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.761: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.761: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.761: I/jPCT-AE(20271): Normal vectors calculated in 4ms!
05-22 15:30:55.761: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.771: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.771: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.771: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.781: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.781: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.791: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.791: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.801: I/jPCT-AE(20271): Normal vectors calculated in 2ms!
05-22 15:30:55.801: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.801: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.801: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.801: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.811: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.811: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.811: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.811: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.811: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.811: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.821: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.831: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.831: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.831: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.831: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.831: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.831: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.831: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.831: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.831: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.831: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.841: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.841: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.841: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.841: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.841: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.841: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.841: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.841: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.841: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.841: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.851: I/jPCT-AE(20271): Normal vectors calculated in 2ms!
05-22 15:30:55.851: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.851: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.851: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.851: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.851: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.851: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.851: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.851: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.861: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.871: I/jPCT-AE(20271): Normal vectors calculated in 2ms!
05-22 15:30:55.871: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.871: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.871: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.871: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.871: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.871: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.871: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.871: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.871: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.881: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 2ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.891: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.901: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.911: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.921: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.921: I/jPCT-AE(20271): Normal vectors calculated in 0ms!
05-22 15:30:55.921: I/jPCT-AE(20271): Normal vectors calculated in 1ms!
05-22 15:30:55.921: I/jPCT-AE(20271): Memory usage before compacting: 10564 KB used out of 13315 KB. Max. memory available to the VM is 49152 KB.
05-22 15:30:55.921: D/dalvikvm(20271): WAIT_FOR_CONCURRENT_GC blocked 0ms
05-22 15:30:55.991: D/dalvikvm(20271): GC_EXPLICIT freed 549K, 25% free 10016K/13315K, paused 3ms+4ms, total 71ms
05-22 15:30:55.991: D/dalvikvm(20271): WAIT_FOR_CONCURRENT_GC blocked 0ms
05-22 15:30:56.041: D/dalvikvm(20271): GC_EXPLICIT freed 21K, 25% free 9994K/13315K, paused 2ms+3ms, total 41ms
05-22 15:30:56.191: I/jPCT-AE(20271): Memory usage after compacting: 9995 KB used out of 13315 KB. Max. memory available to the VM is 49152 KB.
05-22 15:30:56.281: I/jPCT-AE(20271): MSAA enabled with 2 samples!
05-22 15:30:56.321: I/jPCT-AE(20271): GL context is 1099322296
05-22 15:30:56.341: I/jPCT-AE(20271): Initializing GL20 render pipeline...
05-22 15:30:56.351: I/jPCT-AE(20271): Accessing shaders via JAR!
05-22 15:30:56.351: I/jPCT-AE(20271): Loading default shaders !
05-22 15:30:56.351: I/jPCT-AE(20271): 0 shaders in replacement map!
05-22 15:30:56.351: I/jPCT-AE(20271): Default fragment shader is: /defaultFragmentShader.src
05-22 15:30:56.531: E/SpannableStringBuilder(20271): SPAN_EXCLUSIVE_EXCLUSIVE spans cannot have a zero length
05-22 15:30:56.531: E/SpannableStringBuilder(20271): SPAN_EXCLUSIVE_EXCLUSIVE spans cannot have a zero length
05-22 15:30:56.571: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:56.581: I/jPCT-AE(20271): Text file from InputStream loaded...2416 bytes
05-22 15:30:56.581: I/jPCT-AE(20271): Default vertex shader is: /defaultVertexShader.src
05-22 15:30:56.581: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:56.581: I/jPCT-AE(20271): Text file from InputStream loaded...4496 bytes
05-22 15:30:56.581: I/jPCT-AE(20271): Compiling shader program!
05-22 15:30:57.511: I/jPCT-AE(20271): Handles of 3: 2/36/1
05-22 15:30:57.511: I/jPCT-AE(20271): Loading default shaders (Tex0Amb)!
05-22 15:30:57.511: I/jPCT-AE(20271): 0 shaders in replacement map!
05-22 15:30:57.511: I/jPCT-AE(20271): Default fragment shader is: /defaultFragmentShader.src
05-22 15:30:57.511: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:57.521: I/jPCT-AE(20271): Text file from InputStream loaded...199 bytes
05-22 15:30:57.521: I/jPCT-AE(20271): Default vertex shader is: /defaultVertexShader.src
05-22 15:30:57.521: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:57.521: I/jPCT-AE(20271): Text file from InputStream loaded...757 bytes
05-22 15:30:57.521: I/jPCT-AE(20271): Compiling shader program!
05-22 15:30:57.551: I/jPCT-AE(20271): Handles of 6: 2/3/1
05-22 15:30:57.551: I/jPCT-AE(20271): Loading default shaders (Tex0)!
05-22 15:30:57.551: I/jPCT-AE(20271): 0 shaders in replacement map!
05-22 15:30:57.551: I/jPCT-AE(20271): Default fragment shader is: /defaultFragmentShader.src
05-22 15:30:57.551: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:57.551: I/jPCT-AE(20271): Text file from InputStream loaded...201 bytes
05-22 15:30:57.551: I/jPCT-AE(20271): Default vertex shader is: /defaultVertexShader.src
05-22 15:30:57.561: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:57.561: I/jPCT-AE(20271): Text file from InputStream loaded...4020 bytes
05-22 15:30:57.561: I/jPCT-AE(20271): Compiling shader program!
05-22 15:30:58.401: I/jPCT-AE(20271): Handles of 9: 2/29/1
05-22 15:30:58.401: I/jPCT-AE(20271): Loading default shaders (Tex1)!
05-22 15:30:58.401: I/jPCT-AE(20271): 0 shaders in replacement map!
05-22 15:30:58.401: I/jPCT-AE(20271): Default fragment shader is: /defaultFragmentShader.src
05-22 15:30:58.401: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:58.401: I/jPCT-AE(20271): Text file from InputStream loaded...997 bytes
05-22 15:30:58.401: I/jPCT-AE(20271): Default vertex shader is: /defaultVertexShader.src
05-22 15:30:58.411: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:58.411: I/jPCT-AE(20271): Text file from InputStream loaded...4390 bytes
05-22 15:30:58.411: I/jPCT-AE(20271): Compiling shader program!
05-22 15:30:59.281: I/jPCT-AE(20271): Handles of 12: 2/34/1
05-22 15:30:59.281: I/jPCT-AE(20271): Loading default shaders (Tex0Light0)!
05-22 15:30:59.281: I/jPCT-AE(20271): 0 shaders in replacement map!
05-22 15:30:59.281: I/jPCT-AE(20271): Default fragment shader is: /defaultFragmentShader.src
05-22 15:30:59.281: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:59.291: I/jPCT-AE(20271): Text file from InputStream loaded...201 bytes
05-22 15:30:59.291: I/jPCT-AE(20271): Default vertex shader is: /defaultVertexShader.src
05-22 15:30:59.291: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:59.291: I/jPCT-AE(20271): Text file from InputStream loaded...1293 bytes
05-22 15:30:59.291: I/jPCT-AE(20271): Compiling shader program!
05-22 15:30:59.331: I/jPCT-AE(20271): Handles of 15: 2/7/1
05-22 15:30:59.331: I/jPCT-AE(20271): Loading default shaders (Fog)!
05-22 15:30:59.331: I/jPCT-AE(20271): 0 shaders in replacement map!
05-22 15:30:59.331: I/jPCT-AE(20271): Default fragment shader is: /defaultFragmentShader.src
05-22 15:30:59.341: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:59.341: I/jPCT-AE(20271): Text file from InputStream loaded...328 bytes
05-22 15:30:59.341: I/jPCT-AE(20271): Default vertex shader is: /defaultVertexShader.src
05-22 15:30:59.341: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:30:59.341: I/jPCT-AE(20271): Text file from InputStream loaded...4267 bytes
05-22 15:30:59.341: I/jPCT-AE(20271): Compiling shader program!
05-22 15:31:00.361: I/jPCT-AE(20271): Handles of 18: 2/32/1
05-22 15:31:00.361: I/jPCT-AE(20271): Loading default shaders (FogLight0)!
05-22 15:31:00.371: I/jPCT-AE(20271): 0 shaders in replacement map!
05-22 15:31:00.371: I/jPCT-AE(20271): Default fragment shader is: /defaultFragmentShader.src
05-22 15:31:00.371: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:31:00.371: I/jPCT-AE(20271): Text file from InputStream loaded...328 bytes
05-22 15:31:00.371: I/jPCT-AE(20271): Default vertex shader is: /defaultVertexShader.src
05-22 15:31:00.371: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:31:00.371: I/jPCT-AE(20271): Text file from InputStream loaded...1608 bytes
05-22 15:31:00.371: I/jPCT-AE(20271): Compiling shader program!
05-22 15:31:00.421: I/jPCT-AE(20271): Handles of 21: 2/10/1
05-22 15:31:00.421: I/jPCT-AE(20271): Loading default shaders (Depth)!
05-22 15:31:00.421: I/jPCT-AE(20271): 0 shaders in replacement map!
05-22 15:31:00.421: I/jPCT-AE(20271): Default fragment shader is: /defaultFragmentShader.src
05-22 15:31:00.421: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:31:00.431: I/jPCT-AE(20271): Text file from InputStream loaded...745 bytes
05-22 15:31:00.431: I/jPCT-AE(20271): Default vertex shader is: /defaultVertexShader.src
05-22 15:31:00.431: I/jPCT-AE(20271): Loading file from InputStream
05-22 15:31:00.431: I/jPCT-AE(20271): Text file from InputStream loaded...248 bytes
05-22 15:31:00.431: I/jPCT-AE(20271): Compiling shader program!
05-22 15:31:00.451: I/jPCT-AE(20271): Handles of 24: 0/0/-1
05-22 15:31:00.451: I/jPCT-AE(20271): GL20 render pipeline initialized!
05-22 15:31:00.461: I/jPCT-AE(20271): OpenGL vendor:     Qualcomm
05-22 15:31:00.461: I/jPCT-AE(20271): OpenGL renderer:   Adreno (TM) 200
05-22 15:31:00.461: I/jPCT-AE(20271): OpenGL version:    OpenGL ES 2.0 V@6.0 AU@04.01.01.45.000 (CL@)
05-22 15:31:00.461: I/jPCT-AE(20271): OpenGL renderer initialized (using 2/4 texture stages)
05-22 15:31:00.461: I/jPCT-AE(20271): Subobject of object 3/object5 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.461: I/jPCT-AE(20271): Object 3/object5 compiled to 1 subobjects in 2ms!
05-22 15:31:00.461: I/jPCT-AE(20271): Object 'object5' uses one texture set!
05-22 15:31:00.471: I/jPCT-AE(20271): Subobject of object 4/object6 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.471: I/jPCT-AE(20271): Object 4/object6 compiled to 1 subobjects in 2ms!
05-22 15:31:00.471: I/jPCT-AE(20271): Object 'object6' uses one texture set!
05-22 15:31:00.471: I/jPCT-AE(20271): Subobject of object 1/object3 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.471: I/jPCT-AE(20271): Object 1/object3 compiled to 1 subobjects in 1ms!
05-22 15:31:00.471: I/jPCT-AE(20271): Object 'object3' uses one texture set!
05-22 15:31:00.471: I/jPCT-AE(20271): Subobject of object 2/object4 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.471: I/jPCT-AE(20271): Object 2/object4 compiled to 1 subobjects in 2ms!
05-22 15:31:00.471: I/jPCT-AE(20271): Object 'object4' uses one texture set!
05-22 15:31:00.471: I/jPCT-AE(20271): Subobject of object 5/object7 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.471: I/jPCT-AE(20271): Object 5/object7 compiled to 1 subobjects in 1ms!
05-22 15:31:00.471: I/jPCT-AE(20271): Object 'object7' uses one texture set!
05-22 15:31:00.481: I/jPCT-AE(20271): Subobject of object 0/object2 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.481: I/jPCT-AE(20271): Object 0/object2 compiled to 1 subobjects in 2ms!
05-22 15:31:00.481: I/jPCT-AE(20271): Object 'object2' uses one texture set!
05-22 15:31:00.481: I/jPCT-AE(20271): Subobject of object 8/object10 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.481: I/jPCT-AE(20271): Object 8/object10 compiled to 1 subobjects in 2ms!
05-22 15:31:00.481: I/jPCT-AE(20271): Object 'object10' uses one texture set!
05-22 15:31:00.481: I/jPCT-AE(20271): Subobject of object 9/object11 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.491: I/jPCT-AE(20271): Object 9/object11 compiled to 1 subobjects in 1ms!
05-22 15:31:00.491: I/jPCT-AE(20271): Object 'object11' uses one texture set!
05-22 15:31:00.491: I/jPCT-AE(20271): Subobject of object 7/object9 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.491: I/jPCT-AE(20271): Object 7/object9 compiled to 1 subobjects in 1ms!
05-22 15:31:00.491: I/jPCT-AE(20271): Object 'object9' uses one texture set!
05-22 15:31:00.491: I/jPCT-AE(20271): Subobject of object 10/object12 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.491: I/jPCT-AE(20271): Object 10/object12 compiled to 1 subobjects in 1ms!
05-22 15:31:00.491: I/jPCT-AE(20271): Object 'object12' uses one texture set!
05-22 15:31:00.491: I/jPCT-AE(20271): Subobject of object 11/object13 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.491: I/jPCT-AE(20271): Object 11/object13 compiled to 1 subobjects in 1ms!
05-22 15:31:00.491: I/jPCT-AE(20271): Object 'object13' uses one texture set!
05-22 15:31:00.491: I/jPCT-AE(20271): Subobject of object 6/object8 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.491: I/jPCT-AE(20271): Object 6/object8 compiled to 1 subobjects in 2ms!
05-22 15:31:00.501: I/jPCT-AE(20271): Object 'object8' uses one texture set!
05-22 15:31:00.501: I/jPCT-AE(20271): Subobject of object 15/object17 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.501: I/jPCT-AE(20271): Object 15/object17 compiled to 1 subobjects in 1ms!
05-22 15:31:00.501: I/jPCT-AE(20271): Object 'object17' uses one texture set!
05-22 15:31:00.501: I/jPCT-AE(20271): Subobject of object 14/object16 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.511: I/jPCT-AE(20271): Object 14/object16 compiled to 1 subobjects in 7ms!
05-22 15:31:00.511: I/jPCT-AE(20271): Object 'object16' uses one texture set!
05-22 15:31:00.521: I/jPCT-AE(20271): Subobject of object 13/object15 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.521: I/jPCT-AE(20271): Object 13/object15 compiled to 1 subobjects in 2ms!
05-22 15:31:00.521: I/jPCT-AE(20271): Object 'object15' uses one texture set!
05-22 15:31:00.521: I/jPCT-AE(20271): Subobject of object 16/object18 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.521: I/jPCT-AE(20271): Object 16/object18 compiled to 1 subobjects in 1ms!
05-22 15:31:00.521: I/jPCT-AE(20271): Object 'object18' uses one texture set!
05-22 15:31:00.521: I/jPCT-AE(20271): Subobject of object 17/object19 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.521: I/jPCT-AE(20271): Object 17/object19 compiled to 1 subobjects in 2ms!
05-22 15:31:00.521: I/jPCT-AE(20271): Object 'object19' uses one texture set!
05-22 15:31:00.521: I/jPCT-AE(20271): Subobject of object 12/object14 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.531: I/jPCT-AE(20271): Object 12/object14 compiled to 1 subobjects in 7ms!
05-22 15:31:00.531: I/jPCT-AE(20271): Object 'object14' uses one texture set!
05-22 15:31:00.531: I/jPCT-AE(20271): Subobject of object 20/object22 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.531: I/jPCT-AE(20271): Object 20/object22 compiled to 1 subobjects in 1ms!
05-22 15:31:00.531: I/jPCT-AE(20271): Object 'object22' uses one texture set!
05-22 15:31:00.531: I/jPCT-AE(20271): Subobject of object 21/object23 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.531: I/jPCT-AE(20271): Object 21/object23 compiled to 1 subobjects in 1ms!
05-22 15:31:00.531: I/jPCT-AE(20271): Object 'object23' uses one texture set!
05-22 15:31:00.541: I/jPCT-AE(20271): Subobject of object 22/object24 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.541: I/jPCT-AE(20271): Object 22/object24 compiled to 1 subobjects in 1ms!
05-22 15:31:00.541: I/jPCT-AE(20271): Object 'object24' uses one texture set!
05-22 15:31:00.541: I/jPCT-AE(20271): Subobject of object 19/object21 compiled to flat fixed point data using 6/6 vertices in 2ms!
05-22 15:31:00.541: I/jPCT-AE(20271): Object 19/object21 compiled to 1 subobjects in 2ms!
05-22 15:31:00.551: I/jPCT-AE(20271): Object 'object21' uses one texture set!
05-22 15:31:00.551: I/jPCT-AE(20271): Subobject of object 23/object25 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.551: I/jPCT-AE(20271): Object 23/object25 compiled to 1 subobjects in 1ms!
05-22 15:31:00.551: I/jPCT-AE(20271): Object 'object25' uses one texture set!
05-22 15:31:00.551: I/jPCT-AE(20271): Subobject of object 18/object20 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.551: I/jPCT-AE(20271): Object 18/object20 compiled to 1 subobjects in 1ms!
05-22 15:31:00.551: I/jPCT-AE(20271): Object 'object20' uses one texture set!
05-22 15:31:00.551: I/jPCT-AE(20271): Subobject of object 25/object27 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.551: I/jPCT-AE(20271): Object 25/object27 compiled to 1 subobjects in 0ms!
05-22 15:31:00.551: I/jPCT-AE(20271): Object 'object27' uses one texture set!
05-22 15:31:00.561: I/jPCT-AE(20271): Subobject of object 26/object28 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.561: I/jPCT-AE(20271): Object 26/object28 compiled to 1 subobjects in 4ms!
05-22 15:31:00.561: I/jPCT-AE(20271): Object 'object28' uses one texture set!
05-22 15:31:00.561: I/jPCT-AE(20271): Subobject of object 27/object29 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.561: I/jPCT-AE(20271): Object 27/object29 compiled to 1 subobjects in 1ms!
05-22 15:31:00.561: I/jPCT-AE(20271): Object 'object29' uses one texture set!
05-22 15:31:00.561: I/jPCT-AE(20271): Subobject of object 28/object30 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.561: I/jPCT-AE(20271): Object 28/object30 compiled to 1 subobjects in 1ms!
05-22 15:31:00.561: I/jPCT-AE(20271): Object 'object30' uses one texture set!
05-22 15:31:00.571: I/jPCT-AE(20271): Subobject of object 29/object31 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.571: I/jPCT-AE(20271): Object 29/object31 compiled to 1 subobjects in 2ms!
05-22 15:31:00.571: I/jPCT-AE(20271): Object 'object31' uses one texture set!
05-22 15:31:00.571: I/jPCT-AE(20271): Subobject of object 24/object26 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.571: I/jPCT-AE(20271): Object 24/object26 compiled to 1 subobjects in 1ms!
05-22 15:31:00.571: I/jPCT-AE(20271): Object 'object26' uses one texture set!
05-22 15:31:00.571: I/jPCT-AE(20271): Subobject of object 32/object34 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.571: I/jPCT-AE(20271): Object 32/object34 compiled to 1 subobjects in 1ms!
05-22 15:31:00.571: I/jPCT-AE(20271): Object 'object34' uses one texture set!
05-22 15:31:00.571: I/jPCT-AE(20271): Subobject of object 31/object33 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.571: I/jPCT-AE(20271): Object 31/object33 compiled to 1 subobjects in 2ms!
05-22 15:31:00.571: I/jPCT-AE(20271): Object 'object33' uses one texture set!
05-22 15:31:00.581: I/jPCT-AE(20271): Subobject of object 33/object35 compiled to flat fixed point data using 6/6 vertices in 3ms!
05-22 15:31:00.581: I/jPCT-AE(20271): Object 33/object35 compiled to 1 subobjects in 3ms!
05-22 15:31:00.581: I/jPCT-AE(20271): Object 'object35' uses one texture set!
05-22 15:31:00.581: I/jPCT-AE(20271): Subobject of object 34/object36 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.581: I/jPCT-AE(20271): Object 34/object36 compiled to 1 subobjects in 1ms!
05-22 15:31:00.581: I/jPCT-AE(20271): Object 'object36' uses one texture set!
05-22 15:31:00.581: I/jPCT-AE(20271): Subobject of object 35/object37 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.581: I/jPCT-AE(20271): Object 35/object37 compiled to 1 subobjects in 1ms!
05-22 15:31:00.581: I/jPCT-AE(20271): Object 'object37' uses one texture set!
05-22 15:31:00.581: I/jPCT-AE(20271): Subobject of object 30/object32 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.581: I/jPCT-AE(20271): Object 30/object32 compiled to 1 subobjects in 2ms!
05-22 15:31:00.591: I/jPCT-AE(20271): Object 'object32' uses one texture set!
05-22 15:31:00.591: I/jPCT-AE(20271): Subobject of object 38/object40 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.591: I/jPCT-AE(20271): Object 38/object40 compiled to 1 subobjects in 1ms!
05-22 15:31:00.591: I/jPCT-AE(20271): Object 'object40' uses one texture set!
05-22 15:31:00.591: I/jPCT-AE(20271): Subobject of object 39/object41 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.591: I/jPCT-AE(20271): Object 39/object41 compiled to 1 subobjects in 1ms!
05-22 15:31:00.591: I/jPCT-AE(20271): Object 'object41' uses one texture set!
05-22 15:31:00.591: I/jPCT-AE(20271): Subobject of object 40/object42 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.591: I/jPCT-AE(20271): Object 40/object42 compiled to 1 subobjects in 1ms!
05-22 15:31:00.591: I/jPCT-AE(20271): Object 'object42' uses one texture set!
05-22 15:31:00.591: I/jPCT-AE(20271): Subobject of object 37/object39 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.591: I/jPCT-AE(20271): Object 37/object39 compiled to 1 subobjects in 1ms!
05-22 15:31:00.591: I/jPCT-AE(20271): Object 'object39' uses one texture set!
05-22 15:31:00.591: I/jPCT-AE(20271): Subobject of object 41/object43 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.591: I/jPCT-AE(20271): Object 41/object43 compiled to 1 subobjects in 1ms!
05-22 15:31:00.601: I/jPCT-AE(20271): Object 'object43' uses one texture set!
05-22 15:31:00.601: I/jPCT-AE(20271): Subobject of object 36/object38 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.601: I/jPCT-AE(20271): Object 36/object38 compiled to 1 subobjects in 1ms!
05-22 15:31:00.601: I/jPCT-AE(20271): Object 'object38' uses one texture set!
05-22 15:31:00.601: I/jPCT-AE(20271): Subobject of object 43/object45 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.601: I/jPCT-AE(20271): Object 43/object45 compiled to 1 subobjects in 1ms!
05-22 15:31:00.601: I/jPCT-AE(20271): Object 'object45' uses one texture set!
05-22 15:31:00.601: I/jPCT-AE(20271): Subobject of object 44/object46 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.601: I/jPCT-AE(20271): Object 44/object46 compiled to 1 subobjects in 1ms!
05-22 15:31:00.601: I/jPCT-AE(20271): Object 'object46' uses one texture set!
05-22 15:31:00.611: I/jPCT-AE(20271): Subobject of object 45/object47 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.611: I/jPCT-AE(20271): Object 45/object47 compiled to 1 subobjects in 1ms!
05-22 15:31:00.611: I/jPCT-AE(20271): Object 'object47' uses one texture set!
05-22 15:31:00.611: I/jPCT-AE(20271): Subobject of object 46/object48 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.611: I/jPCT-AE(20271): Object 46/object48 compiled to 1 subobjects in 2ms!
05-22 15:31:00.611: I/jPCT-AE(20271): Object 'object48' uses one texture set!
05-22 15:31:00.611: I/jPCT-AE(20271): Subobject of object 47/object49 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.611: I/jPCT-AE(20271): Object 47/object49 compiled to 1 subobjects in 1ms!
05-22 15:31:00.611: I/jPCT-AE(20271): Object 'object49' uses one texture set!
05-22 15:31:00.611: I/jPCT-AE(20271): Subobject of object 42/object44 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.611: I/jPCT-AE(20271): Object 42/object44 compiled to 1 subobjects in 2ms!
05-22 15:31:00.611: I/jPCT-AE(20271): Object 'object44' uses one texture set!
05-22 15:31:00.621: I/jPCT-AE(20271): Subobject of object 49/object51 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.621: I/jPCT-AE(20271): Object 49/object51 compiled to 1 subobjects in 1ms!
05-22 15:31:00.621: I/jPCT-AE(20271): Object 'object51' uses one texture set!
05-22 15:31:00.621: I/jPCT-AE(20271): Subobject of object 50/object52 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.621: I/jPCT-AE(20271): Object 50/object52 compiled to 1 subobjects in 1ms!
05-22 15:31:00.621: I/jPCT-AE(20271): Object 'object52' uses one texture set!
05-22 15:31:00.621: I/jPCT-AE(20271): Subobject of object 51/object53 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.621: I/jPCT-AE(20271): Object 51/object53 compiled to 1 subobjects in 1ms!
05-22 15:31:00.621: I/jPCT-AE(20271): Object 'object53' uses one texture set!
05-22 15:31:00.621: I/jPCT-AE(20271): Subobject of object 52/object54 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.621: I/jPCT-AE(20271): Object 52/object54 compiled to 1 subobjects in 1ms!
05-22 15:31:00.621: I/jPCT-AE(20271): Object 'object54' uses one texture set!
05-22 15:31:00.631: I/jPCT-AE(20271): Subobject of object 53/object55 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.631: I/jPCT-AE(20271): Object 53/object55 compiled to 1 subobjects in 0ms!
05-22 15:31:00.631: I/jPCT-AE(20271): Object 'object55' uses one texture set!
05-22 15:31:00.631: I/jPCT-AE(20271): Subobject of object 48/object50 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.631: I/jPCT-AE(20271): Object 48/object50 compiled to 1 subobjects in 1ms!
05-22 15:31:00.631: I/jPCT-AE(20271): Object 'object50' uses one texture set!
05-22 15:31:00.631: I/jPCT-AE(20271): Subobject of object 56/object58 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.631: I/jPCT-AE(20271): Object 56/object58 compiled to 1 subobjects in 1ms!
05-22 15:31:00.631: I/jPCT-AE(20271): Object 'object58' uses one texture set!
05-22 15:31:00.631: I/jPCT-AE(20271): Subobject of object 57/object59 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.631: I/jPCT-AE(20271): Object 57/object59 compiled to 1 subobjects in 2ms!
05-22 15:31:00.631: I/jPCT-AE(20271): Object 'object59' uses one texture set!
05-22 15:31:00.641: I/jPCT-AE(20271): Subobject of object 55/object57 compiled to flat fixed point data using 6/6 vertices in 3ms!
05-22 15:31:00.641: I/jPCT-AE(20271): Object 55/object57 compiled to 1 subobjects in 3ms!
05-22 15:31:00.641: I/jPCT-AE(20271): Object 'object57' uses one texture set!
05-22 15:31:00.641: I/jPCT-AE(20271): Subobject of object 54/object56 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.641: I/jPCT-AE(20271): Object 54/object56 compiled to 1 subobjects in 1ms!
05-22 15:31:00.641: I/jPCT-AE(20271): Object 'object56' uses one texture set!
05-22 15:31:00.641: I/jPCT-AE(20271): Subobject of object 58/object60 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.641: I/jPCT-AE(20271): Object 58/object60 compiled to 1 subobjects in 2ms!
05-22 15:31:00.641: I/jPCT-AE(20271): Object 'object60' uses one texture set!
05-22 15:31:00.641: I/jPCT-AE(20271): Subobject of object 59/object61 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.641: I/jPCT-AE(20271): Object 59/object61 compiled to 1 subobjects in 1ms!
05-22 15:31:00.651: I/jPCT-AE(20271): Object 'object61' uses one texture set!
05-22 15:31:00.651: I/jPCT-AE(20271): Subobject of object 61/object63 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.651: I/jPCT-AE(20271): Object 61/object63 compiled to 1 subobjects in 1ms!
05-22 15:31:00.651: I/jPCT-AE(20271): Object 'object63' uses one texture set!
05-22 15:31:00.651: I/jPCT-AE(20271): Subobject of object 62/object64 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.651: I/jPCT-AE(20271): Object 62/object64 compiled to 1 subobjects in 1ms!
05-22 15:31:00.651: I/jPCT-AE(20271): Object 'object64' uses one texture set!
05-22 15:31:00.651: I/jPCT-AE(20271): Subobject of object 60/object62 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.651: I/jPCT-AE(20271): Object 60/object62 compiled to 1 subobjects in 2ms!
05-22 15:31:00.651: I/jPCT-AE(20271): Object 'object62' uses one texture set!
05-22 15:31:00.651: I/jPCT-AE(20271): Subobject of object 63/object65 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.651: I/jPCT-AE(20271): Object 63/object65 compiled to 1 subobjects in 1ms!
05-22 15:31:00.651: I/jPCT-AE(20271): Object 'object65' uses one texture set!
05-22 15:31:00.661: I/jPCT-AE(20271): Subobject of object 64/object66 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.661: I/jPCT-AE(20271): Object 64/object66 compiled to 1 subobjects in 2ms!
05-22 15:31:00.661: I/jPCT-AE(20271): Object 'object66' uses one texture set!
05-22 15:31:00.661: I/jPCT-AE(20271): Subobject of object 65/object67 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.661: I/jPCT-AE(20271): Object 65/object67 compiled to 1 subobjects in 1ms!
05-22 15:31:00.661: I/jPCT-AE(20271): Object 'object67' uses one texture set!
05-22 15:31:00.661: I/jPCT-AE(20271): Subobject of object 68/object70 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.661: I/jPCT-AE(20271): Object 68/object70 compiled to 1 subobjects in 1ms!
05-22 15:31:00.661: I/jPCT-AE(20271): Object 'object70' uses one texture set!
05-22 15:31:00.661: I/jPCT-AE(20271): Subobject of object 66/object68 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.671: I/jPCT-AE(20271): Object 66/object68 compiled to 1 subobjects in 2ms!
05-22 15:31:00.671: I/jPCT-AE(20271): Object 'object68' uses one texture set!
05-22 15:31:00.671: I/jPCT-AE(20271): Subobject of object 67/object69 compiled to flat fixed point data using 6/6 vertices in 2ms!
05-22 15:31:00.671: I/jPCT-AE(20271): Object 67/object69 compiled to 1 subobjects in 2ms!
05-22 15:31:00.671: I/jPCT-AE(20271): Object 'object69' uses one texture set!
05-22 15:31:00.671: I/jPCT-AE(20271): Subobject of object 69/object71 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.671: I/jPCT-AE(20271): Object 69/object71 compiled to 1 subobjects in 1ms!
05-22 15:31:00.671: I/jPCT-AE(20271): Object 'object71' uses one texture set!
05-22 15:31:00.671: I/jPCT-AE(20271): Subobject of object 70/object72 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.671: I/jPCT-AE(20271): Object 70/object72 compiled to 1 subobjects in 1ms!
05-22 15:31:00.681: I/jPCT-AE(20271): Object 'object72' uses one texture set!
05-22 15:31:00.681: I/jPCT-AE(20271): Subobject of object 71/object73 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.681: I/jPCT-AE(20271): Object 71/object73 compiled to 1 subobjects in 1ms!
05-22 15:31:00.681: I/jPCT-AE(20271): Object 'object73' uses one texture set!
05-22 15:31:00.681: I/jPCT-AE(20271): Subobject of object 73/object75 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.681: I/jPCT-AE(20271): Object 73/object75 compiled to 1 subobjects in 2ms!
05-22 15:31:00.681: I/jPCT-AE(20271): Object 'object75' uses one texture set!
05-22 15:31:00.681: I/jPCT-AE(20271): Subobject of object 74/object76 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.681: I/jPCT-AE(20271): Object 74/object76 compiled to 1 subobjects in 1ms!
05-22 15:31:00.681: I/jPCT-AE(20271): Object 'object76' uses one texture set!
05-22 15:31:00.691: I/jPCT-AE(20271): Subobject of object 75/object77 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.691: I/jPCT-AE(20271): Object 75/object77 compiled to 1 subobjects in 1ms!
05-22 15:31:00.691: I/jPCT-AE(20271): Object 'object77' uses one texture set!
05-22 15:31:00.691: I/jPCT-AE(20271): Subobject of object 72/object74 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.691: I/jPCT-AE(20271): Object 72/object74 compiled to 1 subobjects in 1ms!
05-22 15:31:00.691: I/jPCT-AE(20271): Object 'object74' uses one texture set!
05-22 15:31:00.691: I/jPCT-AE(20271): Subobject of object 76/object78 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.691: I/jPCT-AE(20271): Object 76/object78 compiled to 1 subobjects in 2ms!
05-22 15:31:00.691: I/jPCT-AE(20271): Object 'object78' uses one texture set!
05-22 15:31:00.691: I/jPCT-AE(20271): Subobject of object 77/object79 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.691: I/jPCT-AE(20271): Object 77/object79 compiled to 1 subobjects in 1ms!
05-22 15:31:00.691: I/jPCT-AE(20271): Object 'object79' uses one texture set!
05-22 15:31:00.691: I/jPCT-AE(20271): Subobject of object 79/object81 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.701: I/jPCT-AE(20271): Object 79/object81 compiled to 1 subobjects in 3ms!
05-22 15:31:00.701: I/jPCT-AE(20271): Object 'object81' uses one texture set!
05-22 15:31:00.701: I/jPCT-AE(20271): Subobject of object 78/object80 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.701: I/jPCT-AE(20271): Object 78/object80 compiled to 1 subobjects in 1ms!
05-22 15:31:00.701: I/jPCT-AE(20271): Object 'object80' uses one texture set!
05-22 15:31:00.701: I/jPCT-AE(20271): Subobject of object 80/object82 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.701: I/jPCT-AE(20271): Object 80/object82 compiled to 1 subobjects in 1ms!
05-22 15:31:00.701: I/jPCT-AE(20271): Object 'object82' uses one texture set!
05-22 15:31:00.701: I/jPCT-AE(20271): Subobject of object 81/object83 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.701: I/jPCT-AE(20271): Object 81/object83 compiled to 1 subobjects in 1ms!
05-22 15:31:00.701: I/jPCT-AE(20271): Object 'object83' uses one texture set!
05-22 15:31:00.711: I/jPCT-AE(20271): Subobject of object 82/object84 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.711: I/jPCT-AE(20271): Object 82/object84 compiled to 1 subobjects in 2ms!
05-22 15:31:00.711: I/jPCT-AE(20271): Object 'object84' uses one texture set!
05-22 15:31:00.711: I/jPCT-AE(20271): Subobject of object 83/object85 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.711: I/jPCT-AE(20271): Object 83/object85 compiled to 1 subobjects in 1ms!
05-22 15:31:00.711: I/jPCT-AE(20271): Object 'object85' uses one texture set!
05-22 15:31:00.711: I/jPCT-AE(20271): Subobject of object 85/object87 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.711: I/jPCT-AE(20271): Object 85/object87 compiled to 1 subobjects in 0ms!
05-22 15:31:00.711: I/jPCT-AE(20271): Object 'object87' uses one texture set!
05-22 15:31:00.711: I/jPCT-AE(20271): Subobject of object 86/object88 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.711: I/jPCT-AE(20271): Object 86/object88 compiled to 1 subobjects in 1ms!
05-22 15:31:00.711: I/jPCT-AE(20271): Object 'object88' uses one texture set!
05-22 15:31:00.711: I/jPCT-AE(20271): Subobject of object 87/object89 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.711: I/jPCT-AE(20271): Object 87/object89 compiled to 1 subobjects in 2ms!
05-22 15:31:00.711: I/jPCT-AE(20271): Object 'object89' uses one texture set!
05-22 15:31:00.721: I/jPCT-AE(20271): Subobject of object 84/object86 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.721: I/jPCT-AE(20271): Object 84/object86 compiled to 1 subobjects in 2ms!
05-22 15:31:00.721: I/jPCT-AE(20271): Object 'object86' uses one texture set!
05-22 15:31:00.721: I/jPCT-AE(20271): Subobject of object 88/object90 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.721: I/jPCT-AE(20271): Object 88/object90 compiled to 1 subobjects in 1ms!
05-22 15:31:00.721: I/jPCT-AE(20271): Object 'object90' uses one texture set!
05-22 15:31:00.721: I/jPCT-AE(20271): Subobject of object 89/object91 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.721: I/jPCT-AE(20271): Object 89/object91 compiled to 1 subobjects in 1ms!
05-22 15:31:00.721: I/jPCT-AE(20271): Object 'object91' uses one texture set!
05-22 15:31:00.721: I/jPCT-AE(20271): Subobject of object 90/object92 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.731: I/jPCT-AE(20271): Object 90/object92 compiled to 1 subobjects in 2ms!
05-22 15:31:00.731: I/jPCT-AE(20271): Object 'object92' uses one texture set!
05-22 15:31:00.731: I/jPCT-AE(20271): Subobject of object 91/object93 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.731: I/jPCT-AE(20271): Object 91/object93 compiled to 1 subobjects in 1ms!
05-22 15:31:00.731: I/jPCT-AE(20271): Object 'object93' uses one texture set!
05-22 15:31:00.731: I/jPCT-AE(20271): Subobject of object 92/object94 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.731: I/jPCT-AE(20271): Object 92/object94 compiled to 1 subobjects in 1ms!
05-22 15:31:00.731: I/jPCT-AE(20271): Object 'object94' uses one texture set!
05-22 15:31:00.731: I/jPCT-AE(20271): Subobject of object 93/object95 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.731: I/jPCT-AE(20271): Object 93/object95 compiled to 1 subobjects in 1ms!
05-22 15:31:00.731: I/jPCT-AE(20271): Object 'object95' uses one texture set!
05-22 15:31:00.731: I/jPCT-AE(20271): Subobject of object 94/object96 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.731: I/jPCT-AE(20271): Object 94/object96 compiled to 1 subobjects in 1ms!
05-22 15:31:00.731: I/jPCT-AE(20271): Object 'object96' uses one texture set!
05-22 15:31:00.731: I/jPCT-AE(20271): Subobject of object 95/object97 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.741: I/jPCT-AE(20271): Object 95/object97 compiled to 1 subobjects in 3ms!
05-22 15:31:00.741: I/jPCT-AE(20271): Object 'object97' uses one texture set!
05-22 15:31:00.741: I/jPCT-AE(20271): Subobject of object 97/object99 compiled to flat fixed point data using 6/6 vertices in 2ms!
05-22 15:31:00.741: I/jPCT-AE(20271): Object 97/object99 compiled to 1 subobjects in 2ms!
05-22 15:31:00.741: I/jPCT-AE(20271): Object 'object99' uses one texture set!
05-22 15:31:00.741: I/jPCT-AE(20271): Subobject of object 96/object98 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.741: I/jPCT-AE(20271): Object 96/object98 compiled to 1 subobjects in 1ms!
05-22 15:31:00.741: I/jPCT-AE(20271): Object 'object98' uses one texture set!
05-22 15:31:00.741: I/jPCT-AE(20271): Subobject of object 98/object100 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.741: I/jPCT-AE(20271): Object 98/object100 compiled to 1 subobjects in 1ms!
05-22 15:31:00.741: I/jPCT-AE(20271): Object 'object100' uses one texture set!
05-22 15:31:00.751: I/jPCT-AE(20271): Subobject of object 99/object101 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.751: I/jPCT-AE(20271): Object 99/object101 compiled to 1 subobjects in 1ms!
05-22 15:31:00.751: I/jPCT-AE(20271): Object 'object101' uses one texture set!
05-22 15:31:00.751: I/jPCT-AE(20271): Subobject of object 100/object102 compiled to flat fixed point data using 6/6 vertices in 2ms!
05-22 15:31:00.751: I/jPCT-AE(20271): Object 100/object102 compiled to 1 subobjects in 2ms!
05-22 15:31:00.751: I/jPCT-AE(20271): Object 'object102' uses one texture set!
05-22 15:31:00.751: I/jPCT-AE(20271): Subobject of object 101/object103 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.751: I/jPCT-AE(20271): Object 101/object103 compiled to 1 subobjects in 2ms!
05-22 15:31:00.751: I/jPCT-AE(20271): Object 'object103' uses one texture set!
05-22 15:31:00.751: I/jPCT-AE(20271): Subobject of object 105/object107 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.751: I/jPCT-AE(20271): Object 105/object107 compiled to 1 subobjects in 1ms!
05-22 15:31:00.751: I/jPCT-AE(20271): Object 'object107' uses one texture set!
05-22 15:31:00.761: I/jPCT-AE(20271): Subobject of object 106/object108 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.761: I/jPCT-AE(20271): Object 106/object108 compiled to 1 subobjects in 1ms!
05-22 15:31:00.761: I/jPCT-AE(20271): Object 'object108' uses one texture set!
05-22 15:31:00.761: I/jPCT-AE(20271): Subobject of object 103/object105 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.761: I/jPCT-AE(20271): Object 103/object105 compiled to 1 subobjects in 1ms!
05-22 15:31:00.761: I/jPCT-AE(20271): Object 'object105' uses one texture set!
05-22 15:31:00.761: I/jPCT-AE(20271): Subobject of object 102/object104 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.761: I/jPCT-AE(20271): Object 102/object104 compiled to 1 subobjects in 1ms!
05-22 15:31:00.761: I/jPCT-AE(20271): Object 'object104' uses one texture set!
05-22 15:31:00.761: I/jPCT-AE(20271): Subobject of object 104/object106 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.761: I/jPCT-AE(20271): Object 104/object106 compiled to 1 subobjects in 1ms!
05-22 15:31:00.771: I/jPCT-AE(20271): Object 'object106' uses one texture set!
05-22 15:31:00.771: I/jPCT-AE(20271): Subobject of object 107/object109 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.771: I/jPCT-AE(20271): Object 107/object109 compiled to 1 subobjects in 1ms!
05-22 15:31:00.771: I/jPCT-AE(20271): Object 'object109' uses one texture set!
05-22 15:31:00.771: I/jPCT-AE(20271): Subobject of object 110/object112 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.771: I/jPCT-AE(20271): Object 110/object112 compiled to 1 subobjects in 0ms!
05-22 15:31:00.771: I/jPCT-AE(20271): Object 'object112' uses one texture set!
05-22 15:31:00.771: I/jPCT-AE(20271): Subobject of object 111/object113 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.771: I/jPCT-AE(20271): Object 111/object113 compiled to 1 subobjects in 1ms!
05-22 15:31:00.771: I/jPCT-AE(20271): Object 'object113' uses one texture set!
05-22 15:31:00.771: I/jPCT-AE(20271): Subobject of object 109/object111 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.771: I/jPCT-AE(20271): Object 109/object111 compiled to 1 subobjects in 2ms!
05-22 15:31:00.771: I/jPCT-AE(20271): Object 'object111' uses one texture set!
05-22 15:31:00.781: I/jPCT-AE(20271): Subobject of object 112/object114 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.781: I/jPCT-AE(20271): Object 112/object114 compiled to 1 subobjects in 1ms!
05-22 15:31:00.781: I/jPCT-AE(20271): Object 'object114' uses one texture set!
05-22 15:31:00.781: I/jPCT-AE(20271): Subobject of object 108/object110 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.781: I/jPCT-AE(20271): Object 108/object110 compiled to 1 subobjects in 1ms!
05-22 15:31:00.781: I/jPCT-AE(20271): Object 'object110' uses one texture set!
05-22 15:31:00.781: I/jPCT-AE(20271): Subobject of object 113/object115 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.781: I/jPCT-AE(20271): Object 113/object115 compiled to 1 subobjects in 1ms!
05-22 15:31:00.781: I/jPCT-AE(20271): Object 'object115' uses one texture set!
05-22 15:31:00.781: I/jPCT-AE(20271): Subobject of object 117/object119 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.781: I/jPCT-AE(20271): Object 117/object119 compiled to 1 subobjects in 1ms!
05-22 15:31:00.781: I/jPCT-AE(20271): Object 'object119' uses one texture set!
05-22 15:31:00.791: I/jPCT-AE(20271): Subobject of object 116/object118 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.791: I/jPCT-AE(20271): Object 116/object118 compiled to 1 subobjects in 1ms!
05-22 15:31:00.791: I/jPCT-AE(20271): Object 'object118' uses one texture set!
05-22 15:31:00.791: I/jPCT-AE(20271): Subobject of object 114/object116 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.791: I/jPCT-AE(20271): Object 114/object116 compiled to 1 subobjects in 1ms!
05-22 15:31:00.791: I/jPCT-AE(20271): Object 'object116' uses one texture set!
05-22 15:31:00.791: I/jPCT-AE(20271): Subobject of object 118/object120 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.791: I/jPCT-AE(20271): Object 118/object120 compiled to 1 subobjects in 2ms!
05-22 15:31:00.791: I/jPCT-AE(20271): Object 'object120' uses one texture set!
05-22 15:31:00.791: I/jPCT-AE(20271): Subobject of object 115/object117 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.791: I/jPCT-AE(20271): Object 115/object117 compiled to 1 subobjects in 1ms!
05-22 15:31:00.791: I/jPCT-AE(20271): Object 'object117' uses one texture set!
05-22 15:31:00.801: I/jPCT-AE(20271): Subobject of object 119/object121 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.801: I/jPCT-AE(20271): Object 119/object121 compiled to 1 subobjects in 2ms!
05-22 15:31:00.801: I/jPCT-AE(20271): Object 'object121' uses one texture set!
05-22 15:31:00.801: I/jPCT-AE(20271): Subobject of object 122/object124 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.801: I/jPCT-AE(20271): Object 122/object124 compiled to 1 subobjects in 1ms!
05-22 15:31:00.801: I/jPCT-AE(20271): Object 'object124' uses one texture set!
05-22 15:31:00.801: I/jPCT-AE(20271): Subobject of object 123/object125 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.801: I/jPCT-AE(20271): Object 123/object125 compiled to 1 subobjects in 1ms!
05-22 15:31:00.801: I/jPCT-AE(20271): Object 'object125' uses one texture set!
05-22 15:31:00.811: I/jPCT-AE(20271): Subobject of object 124/object126 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.811: I/jPCT-AE(20271): Object 124/object126 compiled to 1 subobjects in 1ms!
05-22 15:31:00.811: I/jPCT-AE(20271): Object 'object126' uses one texture set!
05-22 15:31:00.811: I/jPCT-AE(20271): Subobject of object 120/object122 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.811: I/jPCT-AE(20271): Object 120/object122 compiled to 1 subobjects in 2ms!
05-22 15:31:00.811: I/jPCT-AE(20271): Object 'object122' uses one texture set!
05-22 15:31:00.811: I/jPCT-AE(20271): Subobject of object 121/object123 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.811: I/jPCT-AE(20271): Object 121/object123 compiled to 1 subobjects in 1ms!
05-22 15:31:00.811: I/jPCT-AE(20271): Object 'object123' uses one texture set!
05-22 15:31:00.811: I/jPCT-AE(20271): Subobject of object 125/object127 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.811: I/jPCT-AE(20271): Object 125/object127 compiled to 1 subobjects in 1ms!
05-22 15:31:00.811: I/jPCT-AE(20271): Object 'object127' uses one texture set!
05-22 15:31:00.821: I/jPCT-AE(20271): Subobject of object 127/object129 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.821: I/jPCT-AE(20271): Object 127/object129 compiled to 1 subobjects in 3ms!
05-22 15:31:00.821: I/jPCT-AE(20271): Object 'object129' uses one texture set!
05-22 15:31:00.821: I/jPCT-AE(20271): Subobject of object 128/object130 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.821: I/jPCT-AE(20271): Object 128/object130 compiled to 1 subobjects in 1ms!
05-22 15:31:00.821: I/jPCT-AE(20271): Object 'object130' uses one texture set!
05-22 15:31:00.821: I/jPCT-AE(20271): Subobject of object 129/object131 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.821: I/jPCT-AE(20271): Object 129/object131 compiled to 1 subobjects in 1ms!
05-22 15:31:00.821: I/jPCT-AE(20271): Object 'object131' uses one texture set!
05-22 15:31:00.821: I/jPCT-AE(20271): Subobject of object 130/object132 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.821: I/jPCT-AE(20271): Object 130/object132 compiled to 1 subobjects in 1ms!
05-22 15:31:00.831: I/jPCT-AE(20271): Object 'object132' uses one texture set!
05-22 15:31:00.831: I/jPCT-AE(20271): Subobject of object 126/object128 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.831: I/jPCT-AE(20271): Object 126/object128 compiled to 1 subobjects in 1ms!
05-22 15:31:00.831: I/jPCT-AE(20271): Object 'object128' uses one texture set!
05-22 15:31:00.831: I/jPCT-AE(20271): Subobject of object 131/object133 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.831: I/jPCT-AE(20271): Object 131/object133 compiled to 1 subobjects in 1ms!
05-22 15:31:00.831: I/jPCT-AE(20271): Object 'object133' uses one texture set!
05-22 15:31:00.831: I/jPCT-AE(20271): Subobject of object 134/object136 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.831: I/jPCT-AE(20271): Object 134/object136 compiled to 1 subobjects in 1ms!
05-22 15:31:00.831: I/jPCT-AE(20271): Object 'object136' uses one texture set!
05-22 15:31:00.831: I/jPCT-AE(20271): Subobject of object 133/object135 compiled to flat fixed point data using 6/6 vertices in 1ms!
05-22 15:31:00.831: I/jPCT-AE(20271): Object 133/object135 compiled to 1 subobjects in 1ms!
05-22 15:31:00.831: I/jPCT-AE(20271): Object 'object135' uses one texture set!
05-22 15:31:00.831: I/jPCT-AE(20271): Subobject of object 135/object137 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.831: I/jPCT-AE(20271): Object 135/object137 compiled to 1 subobjects in 1ms!
05-22 15:31:00.841: I/jPCT-AE(20271): Object 'object137' uses one texture set!
05-22 15:31:00.841: I/jPCT-AE(20271): Subobject of object 136/object138 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.841: I/jPCT-AE(20271): Object 136/object138 compiled to 1 subobjects in 1ms!
05-22 15:31:00.841: I/jPCT-AE(20271): Object 'object138' uses one texture set!
05-22 15:31:00.841: I/jPCT-AE(20271): Subobject of object 132/object134 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.841: I/jPCT-AE(20271): Object 132/object134 compiled to 1 subobjects in 1ms!
05-22 15:31:00.841: I/jPCT-AE(20271): Object 'object134' uses one texture set!
05-22 15:31:00.841: I/jPCT-AE(20271): Subobject of object 137/object139 compiled to flat fixed point data using 6/6 vertices in 0ms!
05-22 15:31:00.841: I/jPCT-AE(20271): Object 137/object139 compiled to 1 subobjects in 1ms!
05-22 15:31:00.841: I/jPCT-AE(20271): Object 'object139' uses one texture set!
05-22 15:31:00.841: I/jPCT-AE(20271): Subobject of object 140/object142 compiled to flat fixed point data using 6/6 vertices in 1ms!
« Last Edit: May 22, 2015, 03:59:20 pm by EgonOlsen »

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 11775
    • View Profile
    • http://www.jpct.net
Re: Slow jPCT-AE view initialization
« Reply #7 on: May 22, 2015, 04:04:31 pm »
As you can see in the log, the actual buffer init doesn't even take a second. The rest of the log is full ob normal generation and object compilation calls. Reusing the buffer at least cuts away the time needed for data upload to the gpu, which might explain the faster processing.
As said: Try to use shared meshes if possible!

Offline Firzen

  • byte
  • *
  • Posts: 12
    • View Profile
Re: Slow jPCT-AE view initialization
« Reply #8 on: May 22, 2015, 04:19:32 pm »
Please believe me that there is absolutely no problem with objects..  :) This is init log with completely empty scene. As you can see, empty scene took approximately 4 seconds to start up.
Reusing buffer helped, but still there is quite long init time for first time. Can I somehow prepare my View before user opens mentioned 3D activity?

Code: [Select]
05-22 16:16:15.909: I/jPCT-AE(22361): onCreate
05-22 16:16:15.979: I/jPCT-AE(22361): Adding Lightsource: 0
05-22 16:16:16.019: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.019: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.029: I/jPCT-AE(22361): Normal vectors calculated in 3ms!
05-22 16:16:16.029: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.039: I/jPCT-AE(22361): Normal vectors calculated in 3ms!
05-22 16:16:16.039: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.039: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.039: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.039: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.049: I/jPCT-AE(22361): Normal vectors calculated in 5ms!
05-22 16:16:16.049: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.049: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.049: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.059: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.059: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.069: I/jPCT-AE(22361): Normal vectors calculated in 7ms!
05-22 16:16:16.069: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.069: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.079: I/jPCT-AE(22361): Normal vectors calculated in 9ms!
05-22 16:16:16.079: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.089: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.089: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.089: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.099: I/jPCT-AE(22361): Normal vectors calculated in 5ms!
05-22 16:16:16.099: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.099: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.099: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.109: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.109: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.109: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.119: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.119: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.119: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.119: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.129: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.129: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.139: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.139: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.139: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.139: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.139: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.139: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.139: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.149: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.149: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.149: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.159: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.159: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.159: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.159: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.159: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.159: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.169: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.169: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.169: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.169: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.169: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.169: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.169: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.169: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.179: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.179: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.179: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.179: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.179: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.179: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.179: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.189: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.189: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.189: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.189: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.189: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.189: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.199: I/jPCT-AE(22361): Normal vectors calculated in 2ms!
05-22 16:16:16.199: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.199: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.199: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.199: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.199: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.199: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.209: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.209: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.209: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.209: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.209: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.209: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.209: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.219: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.219: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.219: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.219: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.219: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.219: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.219: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.229: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.229: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.229: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.229: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.229: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.229: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.229: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.229: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.239: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.239: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.239: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.239: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.239: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.239: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.239: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.249: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.249: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.249: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.249: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.249: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.249: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.249: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.259: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.259: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.259: I/jPCT-AE(22361): Normal vectors calculated in 2ms!
05-22 16:16:16.259: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.259: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.259: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.269: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.269: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.269: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.269: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.269: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.269: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.269: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.269: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.279: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.279: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.279: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.279: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.279: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.279: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.279: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.279: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.279: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.289: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.289: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.289: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.289: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.289: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.289: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.289: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.289: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.299: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.299: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.299: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.299: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.299: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.299: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.299: I/jPCT-AE(22361): Normal vectors calculated in 1ms!
05-22 16:16:16.309: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.309: I/jPCT-AE(22361): Normal vectors calculated in 0ms!
05-22 16:16:16.309: I/jPCT-AE(22361): Memory usage before compacting: 10557 KB used out of 13315 KB. Max. memory available to the VM is 49152 KB.
05-22 16:16:16.309: D/dalvikvm(22361): WAIT_FOR_CONCURRENT_GC blocked 1ms
05-22 16:16:16.359: D/dalvikvm(22361): GC_EXPLICIT freed 542K, 25% free 10016K/13315K, paused 3ms+4ms, total 48ms
05-22 16:16:16.359: D/dalvikvm(22361): WAIT_FOR_CONCURRENT_GC blocked 0ms
05-22 16:16:16.399: D/dalvikvm(22361): GC_EXPLICIT freed 21K, 25% free 9994K/13315K, paused 3ms+3ms, total 40ms
05-22 16:16:16.549: I/jPCT-AE(22361): Memory usage after compacting: 9994 KB used out of 13315 KB. Max. memory available to the VM is 49152 KB.
05-22 16:16:16.649: I/jPCT-AE(22361): MSAA enabled with 2 samples!
05-22 16:16:16.689: I/jPCT-AE(22361): GL context is 1099292272
05-22 16:16:16.709: I/jPCT-AE(22361): Initializing GL20 render pipeline...
05-22 16:16:16.709: I/jPCT-AE(22361): Accessing shaders via JAR!
05-22 16:16:16.709: I/jPCT-AE(22361): Loading default shaders !
05-22 16:16:16.709: I/jPCT-AE(22361): 0 shaders in replacement map!
05-22 16:16:16.709: I/jPCT-AE(22361): Default fragment shader is: /defaultFragmentShader.src
05-22 16:16:16.879: E/SpannableStringBuilder(22361): SPAN_EXCLUSIVE_EXCLUSIVE spans cannot have a zero length
05-22 16:16:16.879: E/SpannableStringBuilder(22361): SPAN_EXCLUSIVE_EXCLUSIVE spans cannot have a zero length
05-22 16:16:16.919: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:16.919: I/jPCT-AE(22361): Text file from InputStream loaded...2416 bytes
05-22 16:16:16.919: I/jPCT-AE(22361): Default vertex shader is: /defaultVertexShader.src
05-22 16:16:16.929: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:16.929: I/jPCT-AE(22361): Text file from InputStream loaded...4496 bytes
05-22 16:16:16.929: I/jPCT-AE(22361): Compiling shader program!
05-22 16:16:17.809: I/jPCT-AE(22361): Handles of 3: 2/36/1
05-22 16:16:17.809: I/jPCT-AE(22361): Loading default shaders (Tex0Amb)!
05-22 16:16:17.809: I/jPCT-AE(22361): 0 shaders in replacement map!
05-22 16:16:17.809: I/jPCT-AE(22361): Default fragment shader is: /defaultFragmentShader.src
05-22 16:16:17.809: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:17.809: I/jPCT-AE(22361): Text file from InputStream loaded...199 bytes
05-22 16:16:17.819: I/jPCT-AE(22361): Default vertex shader is: /defaultVertexShader.src
05-22 16:16:17.819: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:17.819: I/jPCT-AE(22361): Text file from InputStream loaded...757 bytes
05-22 16:16:17.819: I/jPCT-AE(22361): Compiling shader program!
05-22 16:16:17.839: I/jPCT-AE(22361): Handles of 6: 2/3/1
05-22 16:16:17.839: I/jPCT-AE(22361): Loading default shaders (Tex0)!
05-22 16:16:17.839: I/jPCT-AE(22361): 0 shaders in replacement map!
05-22 16:16:17.839: I/jPCT-AE(22361): Default fragment shader is: /defaultFragmentShader.src
05-22 16:16:17.849: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:17.849: I/jPCT-AE(22361): Text file from InputStream loaded...201 bytes
05-22 16:16:17.849: I/jPCT-AE(22361): Default vertex shader is: /defaultVertexShader.src
05-22 16:16:17.849: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:17.849: I/jPCT-AE(22361): Text file from InputStream loaded...4020 bytes
05-22 16:16:17.849: I/jPCT-AE(22361): Compiling shader program!
05-22 16:16:18.609: I/jPCT-AE(22361): Handles of 9: 2/29/1
05-22 16:16:18.609: I/jPCT-AE(22361): Loading default shaders (Tex1)!
05-22 16:16:18.609: I/jPCT-AE(22361): 0 shaders in replacement map!
05-22 16:16:18.609: I/jPCT-AE(22361): Default fragment shader is: /defaultFragmentShader.src
05-22 16:16:18.619: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:18.619: I/jPCT-AE(22361): Text file from InputStream loaded...997 bytes
05-22 16:16:18.619: I/jPCT-AE(22361): Default vertex shader is: /defaultVertexShader.src
05-22 16:16:18.619: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:18.619: I/jPCT-AE(22361): Text file from InputStream loaded...4390 bytes
05-22 16:16:18.619: I/jPCT-AE(22361): Compiling shader program!
05-22 16:16:19.409: I/jPCT-AE(22361): Handles of 12: 2/34/1
05-22 16:16:19.409: I/jPCT-AE(22361): Loading default shaders (Tex0Light0)!
05-22 16:16:19.409: I/jPCT-AE(22361): 0 shaders in replacement map!
05-22 16:16:19.409: I/jPCT-AE(22361): Default fragment shader is: /defaultFragmentShader.src
05-22 16:16:19.409: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:19.409: I/jPCT-AE(22361): Text file from InputStream loaded...201 bytes
05-22 16:16:19.409: I/jPCT-AE(22361): Default vertex shader is: /defaultVertexShader.src
05-22 16:16:19.409: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:19.409: I/jPCT-AE(22361): Text file from InputStream loaded...1293 bytes
05-22 16:16:19.419: I/jPCT-AE(22361): Compiling shader program!
05-22 16:16:19.449: I/jPCT-AE(22361): Handles of 15: 2/7/1
05-22 16:16:19.449: I/jPCT-AE(22361): Loading default shaders (Fog)!
05-22 16:16:19.449: I/jPCT-AE(22361): 0 shaders in replacement map!
05-22 16:16:19.449: I/jPCT-AE(22361): Default fragment shader is: /defaultFragmentShader.src
05-22 16:16:19.459: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:19.459: I/jPCT-AE(22361): Text file from InputStream loaded...328 bytes
05-22 16:16:19.459: I/jPCT-AE(22361): Default vertex shader is: /defaultVertexShader.src
05-22 16:16:19.459: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:19.459: I/jPCT-AE(22361): Text file from InputStream loaded...4267 bytes
05-22 16:16:19.459: I/jPCT-AE(22361): Compiling shader program!
05-22 16:16:20.229: I/jPCT-AE(22361): Handles of 18: 2/32/1
05-22 16:16:20.229: I/jPCT-AE(22361): Loading default shaders (FogLight0)!
05-22 16:16:20.229: I/jPCT-AE(22361): 0 shaders in replacement map!
05-22 16:16:20.229: I/jPCT-AE(22361): Default fragment shader is: /defaultFragmentShader.src
05-22 16:16:20.239: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:20.239: I/jPCT-AE(22361): Text file from InputStream loaded...328 bytes
05-22 16:16:20.239: I/jPCT-AE(22361): Default vertex shader is: /defaultVertexShader.src
05-22 16:16:20.239: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:20.239: I/jPCT-AE(22361): Text file from InputStream loaded...1608 bytes
05-22 16:16:20.239: I/jPCT-AE(22361): Compiling shader program!
05-22 16:16:20.279: I/jPCT-AE(22361): Handles of 21: 2/10/1
05-22 16:16:20.279: I/jPCT-AE(22361): Loading default shaders (Depth)!
05-22 16:16:20.279: I/jPCT-AE(22361): 0 shaders in replacement map!
05-22 16:16:20.289: I/jPCT-AE(22361): Default fragment shader is: /defaultFragmentShader.src
05-22 16:16:20.289: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:20.289: I/jPCT-AE(22361): Text file from InputStream loaded...745 bytes
05-22 16:16:20.289: I/jPCT-AE(22361): Default vertex shader is: /defaultVertexShader.src
05-22 16:16:20.289: I/jPCT-AE(22361): Loading file from InputStream
05-22 16:16:20.289: I/jPCT-AE(22361): Text file from InputStream loaded...248 bytes
05-22 16:16:20.289: I/jPCT-AE(22361): Compiling shader program!
05-22 16:16:20.309: I/jPCT-AE(22361): Handles of 24: 0/0/-1
05-22 16:16:20.309: I/jPCT-AE(22361): GL20 render pipeline initialized!
05-22 16:16:20.359: I/jPCT-AE(22361): OpenGL vendor:     Qualcomm
05-22 16:16:20.359: I/jPCT-AE(22361): OpenGL renderer:   Adreno (TM) 200
05-22 16:16:20.359: I/jPCT-AE(22361): OpenGL version:    OpenGL ES 2.0 V@6.0 AU@04.01.01.45.000 (CL@)
05-22 16:16:20.369: I/jPCT-AE(22361): OpenGL renderer initialized (using 2/4 texture stages)
« Last Edit: May 22, 2015, 04:21:35 pm by Firzen »

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 11775
    • View Profile
    • http://www.jpct.net
Re: Slow jPCT-AE view initialization
« Reply #9 on: May 22, 2015, 04:33:13 pm »
Your former log showed almost no impact for compiling the shaders while your second one does. How comes that? Was the first log from another device?

Anyway, it's shader compilation time that matters the most here and there's not much that you can do about it. Are you actually using any OpenGL ES 2.0 features? Because if not, you might want to switch to 1.x instead, which doesn't require this step.

Or, if you are using only your own shaders, you can skip the default shaders: http://www.jpct.net/jpct-ae/doc/com/threed/jpct/Config.html#skipDefaultShaders

In any other case, there's no way to avoid this delay. It's a required step and it has to happen in the gl thread at init time.

Offline Firzen

  • byte
  • *
  • Posts: 12
    • View Profile
Re: Slow jPCT-AE view initialization
« Reply #10 on: May 22, 2015, 06:10:48 pm »
It is from same device, and first log IMHO compiles shaders for long time too. I have tried to set OpenGL ES 1, but FrameBuffer caused an exception because of missing shaders. However with

Code: [Select]
Config.skipDefaultShaders = true;
it is good enough for me now (initialization time is nearly 2 times shorter). On better devices my application runs very fast, so for this moment I can say the problem was solved. Thank you very much!

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 11775
    • View Profile
    • http://www.jpct.net
Re: Slow jPCT-AE view initialization
« Reply #11 on: May 23, 2015, 10:08:42 am »
For 1.x, you have to use the other constructor of FrameBuffer. The one that takes the GL context. And of course, you can't use your own shaders then.
Skipping the default shaders will cause a performance loss though. The point of these shaders is that the engine picks the best fitting one for an object depending on its attributes. If most of them don't exist, it has to pick the most complex one, which is slower than the optimized ones. If that doesn't really matter for your app, then I guess everything is fine. Just keep it in mind...

Offline Firzen

  • byte
  • *
  • Posts: 12
    • View Profile
Re: Slow jPCT-AE view initialization
« Reply #12 on: May 25, 2015, 05:47:23 pm »
Today I have tried to use openGL ES 1, and I am totaly amazed with the performance jump.. Init time is now only 2 seconds on my phone!
Really thanks for your help. Is there any way how to donate jPCT?

Offline gamenewer

  • long
  • ***
  • Posts: 171
    • View Profile
Re: Slow jPCT-AE view initialization
« Reply #13 on: May 26, 2015, 07:09:34 am »
Hi , I notied this message:OpenGL renderer:   Adreno (TM) 200,  for adreno200 , It  take long time to compile the shader,  Because I have a handset and it's gpu is adren200, which take a long time for compile the shader, but other new GPU will fast to do it. :)

Offline Firzen

  • byte
  • *
  • Posts: 12
    • View Profile
Re: Slow jPCT-AE view initialization
« Reply #14 on: May 26, 2015, 04:46:41 pm »
Yes, I know it. But it would be quite weird if my own application was nearly unusable on my phone.. :D  Now it works very fast even on my phone, and so it should work well on other (better) devices.