Author Topic: Error code 1000 and black screen issue  (Read 10914 times)

Offline zammbi

  • float
  • ****
  • Posts: 361
    • View Profile
Error code 1000 and black screen issue
« on: March 13, 2012, 03:52:32 am »
When the application sleeps when jpct is loaded, I'm getting messages in logcat "Error code 1000" from jpct.

When I woke up the app (pressed the power button and unlocked) I got a out of memory error, which I found odd as it had ~20 megs free before the event.

But now it seems to be a blank screen. Is this a bug or something in my code? None of the other phones seem to have this problem.

Its a LG Optimus 2x.

Quote
03-13 15:45:55.431: I/jPCT-AE(12643): OpenGL vendor:     NVIDIA Corporation
03-13 15:45:55.431: I/jPCT-AE(12643): OpenGL renderer:   NVIDIA AP
03-13 15:45:55.431: I/jPCT-AE(12643): OpenGL version:    OpenGL ES-CM 1.1
03-13 15:45:55.431: I/jPCT-AE(12643): OpenGL renderer initialized (using 2 texture stages)


Edit: Here is the memory crash, that seems repeatable:


Quote
03-13 16:05:28.981: I/jPCT-AE(14132): GL20 render pipeline initialized!
03-13 16:05:28.981: I/jPCT-AE(14132): OpenGL vendor:     NVIDIA Corporation
03-13 16:05:28.981: I/jPCT-AE(14132): OpenGL renderer:   NVIDIA Tegra
03-13 16:05:28.981: I/jPCT-AE(14132): OpenGL version:    OpenGL ES 2.0
03-13 16:05:28.981: I/jPCT-AE(14132): OpenGL renderer initialized (using 2 texture stages)
03-13 16:05:28.981: I/jPCT-AE(14132): 1fps
03-13 16:05:29.191: D/dalvikvm(14132): GC_FOR_MALLOC freed 10794K, 62% free 11431K/29959K, external 13400K/15441K, paused 25ms
03-13 16:05:29.191: I/dalvikvm-heap(14132): Grow heap (frag case) to 30.569MB for 4194320-byte allocation
03-13 16:05:29.231: D/dalvikvm(14132): GC_FOR_MALLOC freed 65K, 49% free 15462K/29959K, external 13400K/15441K, paused 23ms
03-13 16:05:29.271: D/dalvikvm(14132): GC_CONCURRENT freed 0K, 49% free 15462K/29959K, external 13400K/15441K, paused 2ms+3ms
03-13 16:05:29.291: D/dalvikvm(14132): GC_FOR_MALLOC freed <1K, 49% free 15461K/29959K, external 13400K/15441K, paused 23ms
03-13 16:05:29.301: I/dalvikvm-heap(14132): Grow heap (frag case) to 34.504MB for 4194320-byte allocation
03-13 16:05:29.341: D/dalvikvm(14132): GC_FOR_MALLOC freed 0K, 35% free 19557K/29959K, external 13400K/15441K, paused 23ms
03-13 16:05:29.521: D/dalvikvm(14132): GC_CONCURRENT freed <1K, 35% free 19557K/29959K, external 13400K/15441K, paused 2ms+3ms
03-13 16:05:29.551: D/dalvikvm(14132): GC_EXTERNAL_ALLOC freed 8435K, 63% free 11122K/29959K, external 13400K/15441K, paused 30ms
03-13 16:05:29.711: I/jPCT-AE(14132): OpenGL context has changed(2)...recovering for renderer 3/2!
03-13 16:05:29.711: I/jPCT-AE(14132): Creating buffers...
03-13 16:05:29.981: D/dalvikvm(14132): GC_FOR_MALLOC freed 9562K, 66% free 10218K/29959K, external 14759K/15441K, paused 23ms
03-13 16:05:29.981: I/dalvikvm-heap(14132): Forcing collection of SoftReferences for 6536076-byte allocation
03-13 16:05:30.001: D/dalvikvm(14132): GC_FOR_MALLOC freed 8K, 66% free 10210K/29959K, external 14759K/15441K, paused 22ms
03-13 16:05:30.001: E/dalvikvm-heap(14132): Out of memory on a 6536076-byte allocation.
03-13 16:05:30.001: I/dalvikvm(14132): "GLThread 24" prio=5 tid=21 RUNNABLE
03-13 16:05:30.001: I/dalvikvm(14132):   | group="main" sCount=0 dsCount=0 obj=0x405758a0 self=0x5b2688
03-13 16:05:30.001: I/dalvikvm(14132):   | sysTid=14516 nice=0 sched=0/0 cgrp=default handle=6389416
03-13 16:05:30.001: I/dalvikvm(14132):   | schedstat=( 19215072000 17495376000 22424 )
03-13 16:05:30.001: I/dalvikvm(14132):   at java.io.ByteArrayOutputStream.expand(ByteArrayOutputStream.java:~91)
03-13 16:05:30.001: I/dalvikvm(14132):   at java.io.ByteArrayOutputStream.write(ByteArrayOutputStream.java:216)
03-13 16:05:30.001: I/dalvikvm(14132):   at com.threed.jpct.ZipHelper.unzip(ZipHelper.java:30)
03-13 16:05:30.001: I/dalvikvm(14132):   at com.threed.jpct.GLRenderer.convertTexture(GLRenderer.java:769)
03-13 16:05:30.001: I/dalvikvm(14132):   at com.threed.jpct.GLRenderer.setTextures(GLRenderer.java:2258)
03-13 16:05:30.001: I/dalvikvm(14132):   at com.threed.jpct.GLRenderer.drawVertexArray(GLRenderer.java:2183)
03-13 16:05:30.001: I/dalvikvm(14132):   at com.threed.jpct.World.draw(World.java:1309)
03-13 16:05:30.001: I/dalvikvm(14132):   at com.threed.jpct.World.draw(World.java:1076)
03-13 16:05:30.001: I/dalvikvm(14132):   at com.synapse.android.cricscore.wagonwheel.World3DBase.renderGame(World3DBase.java:362)
03-13 16:05:30.001: I/dalvikvm(14132):   at com.synapse.android.cricscore.wagonwheel.World3DBase.onDrawFrame(World3DBase.java:423)
03-13 16:05:30.001: I/dalvikvm(14132):   at com.synapse.android.cricscore.wagonwheel.WWActivity$1.onDrawFrame(WWActivity.java:187)
03-13 16:05:30.001: I/dalvikvm(14132):   at android.opengl.GLSurfaceView$GLThread.guardedRun(GLSurfaceView.java:1363)
03-13 16:05:30.001: I/dalvikvm(14132):   at android.opengl.GLSurfaceView$GLThread.run(GLSurfaceView.java:1118)
03-13 16:05:30.011: W/dalvikvm(14132): threadid=21: thread exiting with uncaught exception (group=0x4001d560)
03-13 16:05:30.011: E/AndroidRuntime(14132): FATAL EXCEPTION: GLThread 24
03-13 16:05:30.011: E/AndroidRuntime(14132): java.lang.OutOfMemoryError
03-13 16:05:30.011: E/AndroidRuntime(14132):    at java.io.ByteArrayOutputStream.expand(ByteArrayOutputStream.java:91)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at java.io.ByteArrayOutputStream.write(ByteArrayOutputStream.java:216)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at com.threed.jpct.ZipHelper.unzip(ZipHelper.java:30)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at com.threed.jpct.GLRenderer.convertTexture(GLRenderer.java:769)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at com.threed.jpct.GLRenderer.setTextures(GLRenderer.java:2258)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at com.threed.jpct.GLRenderer.drawVertexArray(GLRenderer.java:2183)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at com.threed.jpct.World.draw(World.java:1309)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at com.threed.jpct.World.draw(World.java:1076)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at com.synapse.android.cricscore.wagonwheel.World3DBase.renderGame(World3DBase.java:362)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at com.synapse.android.cricscore.wagonwheel.World3DBase.onDrawFrame(World3DBase.java:423)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at com.synapse.android.cricscore.wagonwheel.WWActivity$1.onDrawFrame(WWActivity.java:187)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at android.opengl.GLSurfaceView$GLThread.guardedRun(GLSurfaceView.java:1363)
03-13 16:05:30.011: E/AndroidRuntime(14132):    at android.opengl.GLSurfaceView$GLThread.run(GLSurfaceView.java:1118)
« Last Edit: March 13, 2012, 04:28:19 am by zammbi »

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 12295
    • View Profile
    • http://www.jpct.net
Re: Error code 1000 and black screen issue
« Reply #1 on: March 13, 2012, 11:21:01 am »
I've no idea about that "Error code 1000" output. I'm not printing this message. It might be some opengl related error that i just pirint out, but i somehow doubt it. Are you able to reproduce this problem and post a log?

The problem with the OOM doesn't seem to be caused by VM memory but by native memory. Looking at the output external 14759K/15441K, the memory outside of the VM seems to be filled. You might want to check if you are keeping references to Bitmaps or something similar. If that's not the case...how large is that texture? It trys to enlarge the ByteArrayOutputStream by 6mb, which means that the texture itself has to be much larger. Have you tried to reduce texture size?

In addition, it might help if you

  • set Logger to LL_DEBUG
  • post the log from start to crash

Offline zammbi

  • float
  • ****
  • Posts: 361
    • View Profile
Re: Error code 1000 and black screen issue
« Reply #2 on: March 13, 2012, 10:41:18 pm »
Quote
I've no idea about that "Error code 1000" output. I'm not printing this message. It might be some opengl related error that i just pirint out, but i somehow doubt it. Are you able to reproduce this problem and post a log?
It seems to be linked to some broken webview stuff on this phone. So lets just ignore that in that case.

Quote
The problem with the OOM doesn't seem to be caused by VM memory but by native memory.

By my research that when "(Debug.getNativeHeapAllocatedSize() +4) < Runtime.getRuntime().maxMemory()" it should never run out of memory. That native heap is included in "maxMemory".

Quote
if you are keeping references to Bitmaps or something similar.
Nope.

Quote
If that's not the case...how large is that texture?
2 images at 1024x1024(for top end phones).
While the images are large, I expect devices with such large free memory to handle it.

If tested this on a tablet(Samsung tab 10.1, which also has a NVIDIA chip) and it also seems to get this crash. Before I load the 2 images I get reported 54 megs free. The Samsung tab is running Android 3.1 which I believe now puts images in the java heap and not the native heap. Or so I've read...


Samsung tab 10.1 log:

Quote
03-14 10:30:14.040: D/dalvikvm(27261): GC_CONCURRENT freed 1039K, 10% free 10514K/11655K, paused 2ms+5ms
03-14 10:30:14.070: I/jPCT-AE(27261): MATCH ID: 7871
03-14 10:30:14.070: D/WW(27261): Checking device stats... Max mem:64 Cpus:2
03-14 10:30:14.070: D/WW(27261): Turning on AA.
03-14 10:30:14.070: D/WW(27261): Using OpenGL 2.0
03-14 10:30:14.070: D/WWActivity(27261): ON START
03-14 10:30:14.120: D/libEGL(27261): loaded /system/lib/egl/libGLES_android.so
03-14 10:30:14.120: D/libEGL(27261): loaded /system/lib/egl/libEGL_tegra.so
03-14 10:30:14.120: D/WWActivity(27261): SERVICE CONNECTED
03-14 10:30:14.130: D/WWActivity(27261): RUNNING: true
03-14 10:30:14.150: D/WWActivity(27261): URL: http://crichq-staging-629692596.us-west-1.elb.amazonaws.com/mobile/matches/live_matches/7871/wagon_wheel
03-14 10:30:14.150: D/libEGL(27261): loaded /system/lib/egl/libGLESv1_CM_tegra.so
03-14 10:30:14.160: D/libEGL(27261): loaded /system/lib/egl/libGLESv2_tegra.so
03-14 10:30:14.170: I/jPCT-AE(27261): CSAA enabled!
03-14 10:30:14.230: I/jPCT-AE(27261): GLRenderer created with id 0 on Thread[GLThread 23,5,main]
03-14 10:30:14.230: I/jPCT-AE(27261): Initializing GL20 render pipeline...
03-14 10:30:14.230: I/jPCT-AE(27261): Accessing shaders via JAR!
03-14 10:30:14.230: I/jPCT-AE(27261): Loading default shaders !
03-14 10:30:14.230: I/jPCT-AE(27261): 0 shaders in replacement map!
03-14 10:30:14.230: I/jPCT-AE(27261): Default fragment shader is: /defaultFragmentShader.src
03-14 10:30:14.350: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:14.350: I/jPCT-AE(27261): Text file from InputStream loaded...2008 bytes
03-14 10:30:14.350: I/jPCT-AE(27261): Default vertex shader is: /defaultVertexShader.src
03-14 10:30:14.350: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:14.350: I/jPCT-AE(27261): Text file from InputStream loaded...3897 bytes
03-14 10:30:14.350: I/jPCT-AE(27261): Compiling shader program!
03-14 10:30:14.590: I/jPCT-AE(27261): Handles of 3: 2/28
03-14 10:30:14.600: I/jPCT-AE(27261): Loading default shaders (Tex0)!
03-14 10:30:14.600: I/jPCT-AE(27261): 0 shaders in replacement map!
03-14 10:30:14.600: I/jPCT-AE(27261): Default fragment shader is: /defaultFragmentShader.src
03-14 10:30:14.600: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:14.600: I/jPCT-AE(27261): Text file from InputStream loaded...201 bytes
03-14 10:30:14.600: I/jPCT-AE(27261): Default vertex shader is: /defaultVertexShader.src
03-14 10:30:14.600: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:14.620: I/jPCT-AE(27261): Text file from InputStream loaded...3421 bytes
03-14 10:30:14.620: I/jPCT-AE(27261): Compiling shader program!
03-14 10:30:14.670: D/dalvikvm(27261): GC_CONCURRENT freed 978K, 9% free 10960K/12039K, paused 4ms+3ms
03-14 10:30:14.730: I/jPCT-AE(27261): Handles of 6: 2/21
03-14 10:30:14.730: I/jPCT-AE(27261): Loading default shaders (Tex1)!
03-14 10:30:14.730: I/jPCT-AE(27261): 0 shaders in replacement map!
03-14 10:30:14.730: I/jPCT-AE(27261): Default fragment shader is: /defaultFragmentShader.src
03-14 10:30:14.730: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:14.730: I/jPCT-AE(27261): Text file from InputStream loaded...871 bytes
03-14 10:30:14.730: I/jPCT-AE(27261): Default vertex shader is: /defaultVertexShader.src
03-14 10:30:14.730: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:14.730: I/jPCT-AE(27261): Text file from InputStream loaded...3791 bytes
03-14 10:30:14.730: I/jPCT-AE(27261): Compiling shader program!
03-14 10:30:14.790: D/CricHQService(27261): Content-Type: text/html; charset=utf-8
03-14 10:30:14.790: D/CricHQService(27261): 5831
03-14 10:30:14.790: D/CricHQService(27261): utf-8
03-14 10:30:14.800: D/CricHQService(27261): {"balls":[{"wagonWheel":null,"pitchMap":{"back_square":8,"bad_bowl":false,"pitch_square":2},"runs":0},{"wagonWheel":{"hit_angle_rads":4.483394,"cut_slider_proportion":1,"height_slider_proportion":0.25,"ellipse_proportion":1.063881,"arc_sector":4},"pitchMap":{"back_square":1,"bad_bowl":false,"pitch_square":1},"runs":6},{"wagonWheel":{"hit_angle_rads":5.515028,"cut_slider_proportion":0.7283465,"height_slider_proportion":0.7598425,"ellipse_proportion":1,"arc_sector":5},"pitchMap":{"back_square":1,"bad_bowl":false,"pitch_square":9},"runs":4},{"wagonWheel":{"hit_angle_rads":5.803667,"cut_slider_proportion":1,"height_slider_proportion":0.05,"ellipse_proportion":0.8780316,"arc_sector":5},"pitchMap":{"back_square":2,"bad_bowl":false,"pitch_square":5},"runs":2},{"wagonWheel":{"hit_angle_rads":4.882167,"cut_slider_proportion":0.8543307,"height_slider_proportion":0.2322835,"ellipse_proportion":0.556477,"arc_sector":4},"pitchMap":{"back_square":5,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":{"hit_angle_rads":1.610775,"cut_slider_proportion":1,"height_slider_proportion":0.05,"ellipse_proportion":0.3946149,"arc_sector":1},"pitchMap":{"back_square":8,"bad_bowl":false,"pitch_square":2},"runs":0},{"wagonWheel":{"hit_angle_rads":0.3982132,"cut_slider_proportion":1,"height_slider_proportion":1,"ellipse_proportion":1,"arc_sector":0},"pitchMap":{"back_square":9,"bad_bowl":false,"pitch_square":3},"runs":6},{"wagonWheel":null,"pitchMap":{"back_square":4,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":{"hit_angle_rads":5.028821,"cut_slider_proportion":0.7204725,"height_slider_proportion":0.3267716,"ellipse_proportion":1,"arc_sector":5},"pitchMap":{"back_square":4,"bad_bowl":false,"pitch_square":5},"runs":4},{"wagonWheel":{"hit_angle_rads":3.740932,"cut_slider_proportion":1,"height_slider_proportion":0.05,"ellipse_proportion":0.791822,"arc_sector":3},"pitchMap":{"back_square":8,"bad_bowl":false,"pitch_square":2},"runs":2},{"wagonWheel":null,"pitchMap":{"back_square":2,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":{"hit_angle_rads":4.354822,"cut_slider_proportion":0.6732283,"height_slider_proportion":0.6574803,"ellipse_proportion":1,"arc_sector":4},"pitchMap":{"back_square":7,"bad_bowl":false,"pitch_square":null},"runs":4},{"wagonWheel":{"hit_angle_rads":3.955231,"cut_slider_proportion":0.3031496,"height_slider_proportion":0.1850394,"ellipse_proportion":0.8688989,"arc_sector":3},"pitchMap":{"back_square":7,"bad_bowl":false,"pitch_square":1},"runs":3},{"wagonWheel":{"hit_angle_rads":0.4012282,"cut_slider_proportion":0.5629922,"height_slider_proportion":0.2480315,"ellipse_proportion":0.8726951,"arc_sector":0},"pitchMap":{"back_square":5,"bad_bowl":false,"pitch_square":5},"runs":2},{"wagonWheel":null,"pitchMap":{"back_square":2,"bad_bowl":false,"pitch_square":8},"runs":0},{"wagonWheel":{"hit_angle_rads":1.248425,"cut_slider_proportion":1,"height_slider_proportion":1,"ellipse_proportion":2,"arc_sector":0},"pitchMap":{"back_square":2,"bad_bowl":false,"pitch_square":8},"runs":6},{"wagonWheel":null,"pitchMap":{"back_square":5,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":{"hit_angle_rads":3.195242,"cut_slider_proportion":1,"height_slider_proportion":1,"ellipse_proportion":2,"arc_sector":3},"pitchMap":{"back_square":8,"bad_bowl":false,"pitch_square":2},"runs":6},{"wagonWheel":{"hit_angle_rads":1.509639,"cut_slider_proportion":1,"height_slider_proportion":0.7785433,"ellipse_proportion":1.329352,"arc_sector":1},"pitchMap":{"back_square":5,"bad_bowl":false,"pitch_square":5},"runs":6},{"wagonWheel":{"hit_angle_rads":4.432281,"cut_slider_proportion":1,"height_slider_proportion":1,"ellipse_proportion":0.5881524,"arc_sector":4},"pitchMap":{"back_square":5,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":null,"pitchMap":{"back_square":4,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":{"hit_angle_rads":3.164316,"cut_slider_proportion":0.7519685,"height_slider_proportion":0.6732283,"ellipse_proportion":1,"arc_sector":3},"pitchMap":{"back_square":8,"bad_bowl"
03-14 10:30:14.800: I/jPCT-AE(27261): Error Code: 0
03-14 10:30:14.810: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":4,"height_slider_proportion":0.25,"cut_slider_proportion":1,"ellipse_proportion":1.063881,"hit_angle_rads":4.483394}
03-14 10:30:14.810: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.7598425,"cut_slider_proportion":0.7283465,"ellipse_proportion":1,"hit_angle_rads":5.515028}
03-14 10:30:14.810: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.8780316,"hit_angle_rads":5.803667}
03-14 10:30:14.820: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":4,"height_slider_proportion":0.2322835,"cut_slider_proportion":0.8543307,"ellipse_proportion":0.556477,"hit_angle_rads":4.882167}
03-14 10:30:14.820: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":1,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.3946149,"hit_angle_rads":1.610775}
03-14 10:30:14.820: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":0,"height_slider_proportion":1,"cut_slider_proportion":1,"ellipse_proportion":1,"hit_angle_rads":0.3982132}
03-14 10:30:14.820: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.3267716,"cut_slider_proportion":0.7204725,"ellipse_proportion":1,"hit_angle_rads":5.028821}
03-14 10:30:14.820: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":3,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.791822,"hit_angle_rads":3.740932}
03-14 10:30:14.820: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":4,"height_slider_proportion":0.6574803,"cut_slider_proportion":0.6732283,"ellipse_proportion":1,"hit_angle_rads":4.354822}
03-14 10:30:14.820: I/dalvikvm(27261): Jit: resizing JitTable from 4096 to 8192
03-14 10:30:14.830: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":3,"height_slider_proportion":0.1850394,"cut_slider_proportion":0.3031496,"ellipse_proportion":0.8688989,"hit_angle_rads":3.955231}
03-14 10:30:14.830: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":0,"height_slider_proportion":0.2480315,"cut_slider_proportion":0.5629922,"ellipse_proportion":0.8726951,"hit_angle_rads":0.4012282}
03-14 10:30:14.830: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":0,"height_slider_proportion":1,"cut_slider_proportion":1,"ellipse_proportion":2,"hit_angle_rads":1.248425}
03-14 10:30:14.840: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":3,"height_slider_proportion":1,"cut_slider_proportion":1,"ellipse_proportion":2,"hit_angle_rads":3.195242}
03-14 10:30:14.840: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":1,"height_slider_proportion":0.7785433,"cut_slider_proportion":1,"ellipse_proportion":1.329352,"hit_angle_rads":1.509639}
03-14 10:30:14.840: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":4,"height_slider_proportion":1,"cut_slider_proportion":1,"ellipse_proportion":0.5881524,"hit_angle_rads":4.432281}
03-14 10:30:14.840: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":3,"height_slider_proportion":0.6732283,"cut_slider_proportion":0.7519685,"ellipse_proportion":1,"hit_angle_rads":3.164316}
03-14 10:30:14.840: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":1,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.599682,"hit_angle_rads":1.544486}
03-14 10:30:14.840: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":1,"hit_angle_rads":5.226536}
03-14 10:30:14.840: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.2874016,"cut_slider_proportion":0.6102362,"ellipse_proportion":0.8631719,"hit_angle_rads":5.337499}
03-14 10:30:14.840: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":3,"height_slider_proportion":0.8070866,"cut_slider_proportion":0.8464567,"ellipse_proportion":1,"hit_angle_rads":3.21234}
03-14 10:30:14.840: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.5535527,"hit_angle_rads":5.566645}
03-14 10:30:14.840: I/jPCT-AE(27261): Wagon Wheel: {"arc_sector":0,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.8503579,"hit_angle_rads":0.4701827}
03-14 10:30:14.860: I/jPCT-AE(27261): Handles of 9: 2/26
03-14 10:30:14.860: I/jPCT-AE(27261): Loading default shaders (Tex0Light0)!
03-14 10:30:14.860: I/jPCT-AE(27261): 0 shaders in replacement map!
03-14 10:30:14.860: I/jPCT-AE(27261): Default fragment shader is: /defaultFragmentShader.src
03-14 10:30:14.860: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:14.860: I/jPCT-AE(27261): Text file from InputStream loaded...201 bytes
03-14 10:30:14.860: I/jPCT-AE(27261): Default vertex shader is: /defaultVertexShader.src
03-14 10:30:14.870: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:14.870: I/jPCT-AE(27261): Text file from InputStream loaded...1200 bytes
03-14 10:30:14.870: I/jPCT-AE(27261): Compiling shader program!
03-14 10:30:14.890: I/jPCT-AE(27261): Handles of 12: 2/6
03-14 10:30:14.890: I/jPCT-AE(27261): Loading default shaders (Fog)!
03-14 10:30:14.890: I/jPCT-AE(27261): 0 shaders in replacement map!
03-14 10:30:14.890: I/jPCT-AE(27261): Default fragment shader is: /defaultFragmentShader.src
03-14 10:30:14.890: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:14.890: I/jPCT-AE(27261): Text file from InputStream loaded...328 bytes
03-14 10:30:14.890: I/jPCT-AE(27261): Default vertex shader is: /defaultVertexShader.src
03-14 10:30:14.890: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:14.890: I/jPCT-AE(27261): Text file from InputStream loaded...3668 bytes
03-14 10:30:14.900: I/jPCT-AE(27261): Compiling shader program!
03-14 10:30:15.000: I/jPCT-AE(27261): Handles of 15: 2/24
03-14 10:30:15.000: I/jPCT-AE(27261): Loading default shaders (FogLight0)!
03-14 10:30:15.000: I/jPCT-AE(27261): 0 shaders in replacement map!
03-14 10:30:15.000: I/jPCT-AE(27261): Default fragment shader is: /defaultFragmentShader.src
03-14 10:30:15.000: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:15.000: I/jPCT-AE(27261): Text file from InputStream loaded...328 bytes
03-14 10:30:15.000: I/jPCT-AE(27261): Default vertex shader is: /defaultVertexShader.src
03-14 10:30:15.000: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:15.000: I/jPCT-AE(27261): Text file from InputStream loaded...1516 bytes
03-14 10:30:15.000: I/jPCT-AE(27261): Compiling shader program!
03-14 10:30:15.040: I/jPCT-AE(27261): Handles of 18: 2/9
03-14 10:30:15.040: I/jPCT-AE(27261): Loading default shaders (Tex0Amb)!
03-14 10:30:15.040: I/jPCT-AE(27261): 0 shaders in replacement map!
03-14 10:30:15.040: I/jPCT-AE(27261): Default fragment shader is: /defaultFragmentShader.src
03-14 10:30:15.040: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:15.040: I/jPCT-AE(27261): Text file from InputStream loaded...199 bytes
03-14 10:30:15.040: I/jPCT-AE(27261): Default vertex shader is: /defaultVertexShader.src
03-14 10:30:15.040: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:15.040: I/jPCT-AE(27261): Text file from InputStream loaded...757 bytes
03-14 10:30:15.040: I/jPCT-AE(27261): Compiling shader program!
03-14 10:30:15.050: I/jPCT-AE(27261): Handles of 21: 1/3
03-14 10:30:15.050: I/jPCT-AE(27261): Loading default shaders (Depth)!
03-14 10:30:15.050: I/jPCT-AE(27261): 0 shaders in replacement map!
03-14 10:30:15.050: I/jPCT-AE(27261): Default fragment shader is: /defaultFragmentShader.src
03-14 10:30:15.050: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:15.050: I/jPCT-AE(27261): Text file from InputStream loaded...745 bytes
03-14 10:30:15.050: I/jPCT-AE(27261): Default vertex shader is: /defaultVertexShader.src
03-14 10:30:15.050: I/jPCT-AE(27261): Loading file from InputStream
03-14 10:30:15.060: I/jPCT-AE(27261): Text file from InputStream loaded...248 bytes
03-14 10:30:15.060: I/jPCT-AE(27261): Compiling shader program!
03-14 10:30:15.070: I/jPCT-AE(27261): Handles of 24: 0/0
03-14 10:30:15.070: I/jPCT-AE(27261): GL20 render pipeline initialized!
03-14 10:30:15.070: I/jPCT-AE(27261): OpenGL vendor:     NVIDIA Corporation
03-14 10:30:15.070: I/jPCT-AE(27261): OpenGL renderer:   NVIDIA Tegra
03-14 10:30:15.070: I/jPCT-AE(27261): OpenGL version:    OpenGL ES 2.0
03-14 10:30:15.070: I/jPCT-AE(27261): OpenGL renderer initialized (using 2 texture stages)
03-14 10:30:15.070: D/WW(27261): World is null. Being created...
03-14 10:30:15.070: I/jPCT-AE(27261): Adding Lightsource: 0
03-14 10:30:15.080: D/PhoneRank(27261): Max mem64 mem left:54
03-14 10:30:15.080: D/PhoneRank(27261): Very high ram free.
03-14 10:30:15.110: D/dalvikvm(27261): GC_FOR_ALLOC freed 735K, 11% free 10818K/12039K, paused 33ms
03-14 10:30:15.110: I/dalvikvm-heap(27261): Grow heap (frag case) to 12.660MB for 2097168-byte allocation
03-14 10:30:15.140: D/dalvikvm(27261): GC_FOR_ALLOC freed 3K, 10% free 12863K/14151K, paused 30ms
03-14 10:30:15.190: I/jPCT-AE(27261): Loading Texture...
03-14 10:30:15.190: D/dalvikvm(27261): GC_CONCURRENT freed 0K, 10% free 12863K/14151K, paused 2ms+3ms
03-14 10:30:15.220: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 10% free 12863K/14151K, paused 30ms
03-14 10:30:15.230: I/dalvikvm-heap(27261): Grow heap (frag case) to 16.657MB for 4194320-byte allocation
03-14 10:30:15.260: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 8% free 16959K/18311K, paused 30ms
03-14 10:30:15.260: I/jPCT-AE(27261): Texture loaded...4194304 bytes
03-14 10:30:15.290: D/dalvikvm(27261): GC_CONCURRENT freed <1K, 8% free 16961K/18311K, paused 2ms+3ms
03-14 10:30:15.320: D/dalvikvm(27261): GC_FOR_ALLOC freed 2048K, 19% free 14913K/18311K, paused 30ms
03-14 10:30:15.330: I/dalvikvm-heap(27261): Grow heap (frag case) to 18.659MB for 4194320-byte allocation
03-14 10:30:15.370: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 16% free 19009K/22471K, paused 29ms
03-14 10:30:15.480: D/dalvikvm(27261): GC_CONCURRENT freed 0K, 11% free 20033K/22471K, paused 2ms+3ms
03-14 10:30:16.530: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 11% free 20033K/22471K, paused 38ms
03-14 10:30:16.540: I/dalvikvm-heap(27261): Grow heap (frag case) to 21.660MB for 2097188-byte allocation
03-14 10:30:16.600: D/dalvikvm(27261): GC_CONCURRENT freed 0K, 11% free 22081K/24583K, paused 4ms+3ms
03-14 10:30:16.700: I/jPCT-AE(27261): Compressed 4194304 bytes to 1110217 bytes!
03-14 10:30:16.730: D/dalvikvm(27261): GC_FOR_ALLOC freed 5120K, 32% free 16962K/24583K, paused 32ms
03-14 10:30:16.730: I/jPCT-AE(27261): Texture compressed to 26% (1110217/4194304) of uncompressed size!
03-14 10:30:16.760: D/dalvikvm(27261): GC_FOR_ALLOC freed 6145K, 52% free 11902K/24583K, paused 30ms
03-14 10:30:16.770: I/dalvikvm-heap(27261): Grow heap (frag case) to 15.718MB for 4194320-byte allocation
03-14 10:30:16.820: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 35% free 15998K/24583K, paused 30ms
03-14 10:30:16.860: D/dalvikvm(27261): GC_CONCURRENT freed <1K, 35% free 15998K/24583K, paused 2ms+3ms
03-14 10:30:17.000: I/jPCT-AE(27261): Loading Texture...
03-14 10:30:17.030: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 35% free 15998K/24583K, paused 29ms
03-14 10:30:17.030: I/dalvikvm-heap(27261): Grow heap (frag case) to 19.718MB for 4194320-byte allocation
03-14 10:30:17.070: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 19% free 20094K/24583K, paused 29ms
03-14 10:30:17.080: I/jPCT-AE(27261): Texture loaded...4194304 bytes
03-14 10:30:17.120: D/dalvikvm(27261): GC_CONCURRENT freed <1K, 19% free 20094K/24583K, paused 2ms+3ms
03-14 10:30:17.170: D/dalvikvm(27261): GC_FOR_ALLOC freed 4096K, 35% free 15998K/24583K, paused 29ms
03-14 10:30:17.170: I/dalvikvm-heap(27261): Grow heap (frag case) to 19.718MB for 4194320-byte allocation
03-14 10:30:17.270: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 19% free 20094K/24583K, paused 30ms
03-14 10:30:17.320: D/dalvikvm(27261): GC_CONCURRENT freed <1K, 15% free 21119K/24583K, paused 2ms+3ms
03-14 10:30:17.780: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 15% free 21119K/24583K, paused 29ms
03-14 10:30:17.780: I/dalvikvm-heap(27261): Grow heap (frag case) to 22.719MB for 2097188-byte allocation
03-14 10:30:17.830: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 6% free 23167K/24583K, paused 29ms
03-14 10:30:17.880: D/dalvikvm(27261): GC_CONCURRENT freed 1024K, 10% free 22143K/24583K, paused 2ms+3ms
03-14 10:30:18.130: I/jPCT-AE(27261): Compressed 4194304 bytes to 1633755 bytes!
03-14 10:30:18.160: D/dalvikvm(27261): GC_FOR_ALLOC freed 4096K, 27% free 18047K/24583K, paused 29ms
03-14 10:30:18.160: I/jPCT-AE(27261): Texture compressed to 38% (1633755/4194304) of uncompressed size!
03-14 10:30:18.160: I/jPCT-AE(27261): Loading Texture...
03-14 10:30:18.160: I/jPCT-AE(27261): Texture loaded...65536 bytes
03-14 10:30:18.170: I/jPCT-AE(27261): Compressed 65536 bytes to 516 bytes!
03-14 10:30:18.170: I/jPCT-AE(27261): Texture compressed to 0% (516/65536) of uncompressed size!
03-14 10:30:18.170: D/szipinf(27261): Initializing inflate state
03-14 10:30:18.170: D/szipinf(27261): Initializing zlib to inflate
03-14 10:30:18.260: D/dalvikvm(27261): GC_CONCURRENT freed 6360K, 45% free 13727K/24583K, paused 2ms+4ms
03-14 10:30:18.320: I/jPCT-AE(27261): Normal vectors calculated in 60ms!
03-14 10:30:18.320: D/szipinf(27261): Initializing inflate state
03-14 10:30:18.320: D/szipinf(27261): Initializing zlib to inflate
03-14 10:30:18.330: I/jPCT-AE(27261): Memory usage before compacting: 14243 KB used out of 24583 KB. Max. memory available to the VM is 65536 KB.
03-14 10:30:18.380: D/dalvikvm(27261): GC_EXPLICIT freed 452K, 44% free 13791K/24583K, paused 3ms+3ms
03-14 10:30:18.420: D/dalvikvm(27261): GC_EXPLICIT freed 2K, 44% free 13789K/24583K, paused 2ms+3ms
03-14 10:30:18.570: I/jPCT-AE(27261): Memory usage after compacting: 13789 KB used out of 24583 KB. Max. memory available to the VM is 65536 KB.
03-14 10:30:18.590: I/jPCT-AE(27261): Checking for triangle strip...
03-14 10:30:18.590: I/jPCT-AE(27261): Not a triangle strip at position 1!
03-14 10:30:18.630: I/jPCT-AE(27261): Subobject of object 2/object4 compiled to indexed fixed point data using 2598 vertices in 42ms!
03-14 10:30:18.630: I/jPCT-AE(27261): Processing and uploading vertices of subobject of object 2/object4 took 42ms
03-14 10:30:18.630: I/jPCT-AE(27261): Checking for triangle strip...
03-14 10:30:18.630: I/jPCT-AE(27261): Not a triangle strip at position 1!
03-14 10:30:18.630: I/jPCT-AE(27261): Subobject of object 2/object4 compiled to indexed fixed point data using 384 vertices in 2ms!
03-14 10:30:18.630: I/jPCT-AE(27261): Processing and uploading vertices of subobject of object 2/object4 took 2ms
03-14 10:30:18.630: I/jPCT-AE(27261): Object 2/object4 compiled to 2 subobjects in 62ms!
03-14 10:30:18.630: I/jPCT-AE(27261): Object 'object4' uses multiple texture sets!
03-14 10:30:18.640: I/jPCT-AE(27261): Checking for triangle strip...
03-14 10:30:18.640: I/jPCT-AE(27261): Not a triangle strip at position 1!
03-14 10:30:18.650: I/jPCT-AE(27261): Subobject of object 3/object5 compiled to indexed fixed point data using 2580 vertices in 14ms!
03-14 10:30:18.650: I/jPCT-AE(27261): Processing and uploading vertices of subobject of object 3/object5 took 14ms
03-14 10:30:18.650: I/jPCT-AE(27261): Object 3/object5 compiled to 1 subobjects in 20ms!
03-14 10:30:18.650: I/jPCT-AE(27261): Object 'object5' uses one texture set!
03-14 10:30:18.650: I/jPCT-AE(27261): 0fps
03-14 10:30:18.650: I/jPCT-AE(27261): New texture's id is: 1
03-14 10:30:18.650: I/jPCT-AE(27261): New texture uploaded: com.threed.jpct.Texture@40ab6d08 in thread Thread[GLThread 23,5,main]
03-14 10:30:18.650: I/jPCT-AE(27261): Creating buffers...
03-14 10:30:18.650: I/jPCT-AE(27261): VBO created for object 'object5'
03-14 10:30:18.700: D/dalvikvm(27261): GC_FOR_ALLOC freed 1416K, 44% free 13930K/24583K, paused 38ms
03-14 10:30:18.700: I/dalvikvm-heap(27261): Grow heap (frag case) to 15.829MB for 2220450-byte allocation
03-14 10:30:18.740: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 35% free 16098K/24583K, paused 30ms
03-14 10:30:18.790: D/dalvikvm(27261): GC_CONCURRENT freed <1K, 35% free 16099K/24583K, paused 2ms+4ms
03-14 10:30:18.890: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 35% free 16099K/24583K, paused 29ms
03-14 10:30:18.890: I/dalvikvm-heap(27261): Grow heap (frag case) to 20.066MB for 4442412-byte allocation
03-14 10:30:18.930: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 17% free 20437K/24583K, paused 29ms
03-14 10:30:18.990: D/dalvikvm(27261): GC_CONCURRENT freed 2168K, 26% free 18269K/24583K, paused 2ms+3ms
03-14 10:30:19.030: I/jPCT-AE(27261): Uncompressed 1110217 bytes to 4194304 bytes in 375ms!
03-14 10:30:19.060: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 26% free 18269K/24583K, paused 30ms
03-14 10:30:19.070: I/dalvikvm-heap(27261): Grow heap (frag case) to 21.949MB for 4194320-byte allocation
03-14 10:30:19.110: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 23% free 22365K/28743K, paused 30ms
03-14 10:30:19.150: D/dalvikvm(27261): GC_CONCURRENT freed <1K, 23% free 22365K/28743K, paused 2ms+4ms
03-14 10:30:19.190: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 23% free 22365K/28743K, paused 29ms
03-14 10:30:19.200: I/dalvikvm-heap(27261): Grow heap (frag case) to 25.949MB for 4194320-byte allocation
03-14 10:30:19.250: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 20% free 26461K/32903K, paused 38ms
03-14 10:30:19.370: D/dalvikvm(27261): GC_CONCURRENT freed 0K, 20% free 26461K/32903K, paused 2ms+3ms
03-14 10:30:19.400: D/dalvikvm(27261): GC_FOR_ALLOC freed 8435K, 46% free 18026K/32903K, paused 30ms
03-14 10:30:19.420: I/dalvikvm-heap(27261): Grow heap (frag case) to 33.711MB for 16777232-byte allocation
03-14 10:30:19.450: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 31% free 34410K/49351K, paused 30ms
03-14 10:30:19.480: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 31% free 34410K/49351K, paused 30ms
03-14 10:30:19.490: I/dalvikvm-heap(27261): Grow heap (frag case) to 37.711MB for 4194320-byte allocation
03-14 10:30:19.530: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 22% free 38506K/49351K, paused 31ms
03-14 10:30:19.650: D/dalvikvm(27261): GC_CONCURRENT freed 0K, 22% free 38506K/49351K, paused 2ms+4ms
03-14 10:30:19.650: I/jPCT-AE(27261): New texture's id is: 2
03-14 10:30:19.770: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 20% free 39530K/49351K, paused 31ms
03-14 10:30:19.770: I/dalvikvm-heap(27261): Grow heap (frag case) to 42.711MB for 4194320-byte allocation
03-14 10:30:19.810: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 12% free 43626K/49351K, paused 31ms
03-14 10:30:19.850: I/jPCT-AE(27261): Mipmaps generated by the CPU in 202ms
03-14 10:30:19.860: I/jPCT-AE(27261): New texture uploaded: com.threed.jpct.Texture@40af6ec8 in thread Thread[GLThread 23,5,main]
03-14 10:30:19.860: I/jPCT-AE(27261): Creating buffers...
03-14 10:30:19.860: I/jPCT-AE(27261): VBO created for object 'object4'
03-14 10:30:19.870: D/dalvikvm(27261): GC_CONCURRENT freed 1710K, 12% free 43627K/49351K, paused 2ms+4ms
03-14 10:30:19.900: D/dalvikvm(27261): GC_FOR_ALLOC freed 29696K, 72% free 13930K/49351K, paused 31ms
03-14 10:30:19.900: I/dalvikvm-heap(27261): Grow heap (frag case) to 16.828MB for 3267526-byte allocation
03-14 10:30:19.940: D/dalvikvm(27261): GC_FOR_ALLOC freed 0K, 66% free 17121K/49351K, paused 29ms
03-14 10:30:19.980: D/dalvikvm(27261): GC_CONCURRENT freed <1K, 66% free 17122K/49351K, paused 2ms+4ms
03-14 10:30:20.150: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 66% free 17122K/49351K, paused 30ms
03-14 10:30:20.150: I/dalvikvm-heap(27261): Grow heap (frag case) to 23.062MB for 6536076-byte allocation
03-14 10:30:20.200: D/dalvikvm(27261): GC_CONCURRENT freed 0K, 53% free 23505K/49351K, paused 3ms+4ms
03-14 10:30:20.200: I/jPCT-AE(27261): Uncompressed 1633755 bytes to 4194304 bytes in 344ms!
03-14 10:30:20.230: D/dalvikvm(27261): GC_FOR_ALLOC freed 3191K, 59% free 20314K/49351K, paused 30ms
03-14 10:30:20.230: I/dalvikvm-heap(27261): Grow heap (frag case) to 23.946MB for 4194320-byte allocation
03-14 10:30:20.280: D/dalvikvm(27261): GC_CONCURRENT freed 0K, 51% free 24410K/49351K, paused 3ms+3ms
03-14 10:30:20.310: D/dalvikvm(27261): GC_FOR_ALLOC freed <1K, 51% free 24410K/49351K, paused 29ms
03-14 10:30:20.310: I/dalvikvm-heap(27261): Grow heap (frag case) to 27.946MB for 4194320-byte allocation
03-14 10:30:20.430: D/dalvikvm(27261): GC_CONCURRENT freed 0K, 43% free 28506K/49351K, paused 2ms+4ms
03-14 10:30:20.460: D/dalvikvm(27261): GC_FOR_ALLOC freed 10479K, 64% free 18026K/49351K, paused 30ms
03-14 10:30:20.460: I/dalvikvm-heap(27261): Forcing collection of SoftReferences for 16777232-byte allocation
03-14 10:30:20.500: D/dalvikvm(27261): GC_BEFORE_OOM freed 0K, 64% free 18026K/49351K, paused 36ms
03-14 10:30:20.500: E/dalvikvm-heap(27261): Out of memory on a 16777232-byte allocation.
03-14 10:30:20.500: I/dalvikvm(27261): "GLThread 23" prio=5 tid=17 RUNNABLE
03-14 10:30:20.500: I/dalvikvm(27261):   | group="main" sCount=0 dsCount=0 obj=0x40d0c0d0 self=0x1f5188
03-14 10:30:20.500: I/dalvikvm(27261):   | sysTid=27321 nice=0 sched=0/0 cgrp=default handle=996312
03-14 10:30:20.500: I/dalvikvm(27261):   | schedstat=( 4951131000 336693000 3072 ) utm=480 stm=15 core=1
03-14 10:30:20.500: I/dalvikvm(27261):   at dalvik.system.VMRuntime.newNonMovableArray(Native Method)
03-14 10:30:20.500: I/dalvikvm(27261):   at java.nio.MemoryBlock.allocate(MemoryBlock.java:92)
03-14 10:30:20.500: I/dalvikvm(27261):   at java.nio.ReadWriteDirectByteBuffer.<init>(ReadWriteDirectByteBuffer.java:46)
03-14 10:30:20.500: I/dalvikvm(27261):   at java.nio.ByteBuffer.allocateDirect(ByteBuffer.java:67)
03-14 10:30:20.500: I/dalvikvm(27261):   at com.threed.jpct.GLRenderer.convertTexture(GLRenderer.java:795)
03-14 10:30:20.500: I/dalvikvm(27261):   at com.threed.jpct.GLRenderer.setTextures(GLRenderer.java:2258)
03-14 10:30:20.500: I/dalvikvm(27261):   at com.threed.jpct.GLRenderer.drawVertexArray(GLRenderer.java:2183)
03-14 10:30:20.500: I/dalvikvm(27261):   at com.threed.jpct.World.draw(World.java:1309)
03-14 10:30:20.500: I/dalvikvm(27261):   at com.threed.jpct.World.draw(World.java:1076)
03-14 10:30:20.500: I/dalvikvm(27261):   at com.synapse.android.cricscore.wagonwheel.World3DBase.renderGame(World3DBase.java:393)
03-14 10:30:20.500: I/dalvikvm(27261):   at com.synapse.android.cricscore.wagonwheel.World3DBase.onDrawFrame(World3DBase.java:465)
03-14 10:30:20.500: I/dalvikvm(27261):   at android.opengl.GLSurfaceView$GLThread.guardedRun(GLSurfaceView.java:1431)
03-14 10:30:20.500: I/dalvikvm(27261):   at android.opengl.GLSurfaceView$GLThread.run(GLSurfaceView.java:1180)
03-14 10:30:20.510: W/dalvikvm(27261): threadid=17: thread exiting with uncaught exception (group=0x401de760)
03-14 10:30:20.510: E/AndroidRuntime(27261): FATAL EXCEPTION: GLThread 23
03-14 10:30:20.510: E/AndroidRuntime(27261): java.lang.OutOfMemoryError
03-14 10:30:20.510: E/AndroidRuntime(27261):    at dalvik.system.VMRuntime.newNonMovableArray(Native Method)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at java.nio.MemoryBlock.allocate(MemoryBlock.java:92)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at java.nio.ReadWriteDirectByteBuffer.<init>(ReadWriteDirectByteBuffer.java:46)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at java.nio.ByteBuffer.allocateDirect(ByteBuffer.java:67)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at com.threed.jpct.GLRenderer.convertTexture(GLRenderer.java:795)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at com.threed.jpct.GLRenderer.setTextures(GLRenderer.java:2258)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at com.threed.jpct.GLRenderer.drawVertexArray(GLRenderer.java:2183)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at com.threed.jpct.World.draw(World.java:1309)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at com.threed.jpct.World.draw(World.java:1076)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at com.synapse.android.cricscore.wagonwheel.World3DBase.renderGame(World3DBase.java:393)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at com.synapse.android.cricscore.wagonwheel.World3DBase.onDrawFrame(World3DBase.java:465)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at android.opengl.GLSurfaceView$GLThread.guardedRun(GLSurfaceView.java:1431)
03-14 10:30:20.510: E/AndroidRuntime(27261):    at android.opengl.GLSurfaceView$GLThread.run(GLSurfaceView.java:1180)
03-14 10:30:20.570: D/WebViewActivity(27261): ### onStop is being called
03-14 10:30:22.530: I/dalvikvm(27261): threadid=4: reacting to signal 3
03-14 10:30:22.540: I/dalvikvm(27261): Wrote stack traces to '/data/anr/traces.txt'
« Last Edit: March 13, 2012, 10:54:36 pm by zammbi »

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 12295
    • View Profile
    • http://www.jpct.net
Re: Error code 1000 and black screen issue
« Reply #3 on: March 13, 2012, 11:44:25 pm »
...but it looks like as if something isn't 1024*1024...or otherwise, this line doesn't make any sense:

Out of memory on a 16777232-byte allocation.

That's exactly the amount of memory a 2048*2048 texture would need and that's where it fails. Are you sure that you are not using such textures?

Offline zammbi

  • float
  • ****
  • Posts: 361
    • View Profile
Re: Error code 1000 and black screen issue
« Reply #4 on: March 13, 2012, 11:46:10 pm »
Quote
That's exactly the amount of memory a 2048*2048 texture would need and that's where it fails. Are you sure that you are not using such textures?
I'm only using 1024x1024. No higher resolution than that (and tripled checked).


For now I've lowered one of my images to 512x512 but it looks crappy on the tablet. I hope you can solve the issue. If you need anything else to test just give me a yell.
« Last Edit: March 13, 2012, 11:58:19 pm by zammbi »

Offline zammbi

  • float
  • ****
  • Posts: 361
    • View Profile
Re: Error code 1000 and black screen issue
« Reply #5 on: March 14, 2012, 04:05:48 am »
Running this code...

Code: [Select]
Texture tempTex = TextureManager.getInstance().getTexture("cricket_pitch.png");
Log.d("3d", "cricket_pitch.png Texture height: " + tempTex.getHeight() + " width:" + tempTex.getWidth());

tempTex = TextureManager.getInstance().getTexture("stadium_png.png");
Log.d("3d", "stadium_png.png Texture height: " + tempTex.getHeight()+ " width:" + tempTex.getWidth());

Log.d("3d", "Texture memory usage: " + TextureManager.getInstance().getMemoryUsage());

Quote
03-14 16:00:08.600: D/3d(5474): cricket_pitch.png Texture height: 1024 width:1024
03-14 16:00:08.600: D/3d(5474): stadium_png.png Texture height: 1024 width:1024
03-14 16:00:08.600: D/3d(5474): Texture memory usage: 2745512

So the textures are loaded as 1024x1024 into jpct.

Offline zammbi

  • float
  • ****
  • Posts: 361
    • View Profile
Re: Error code 1000 and black screen issue
« Reply #6 on: March 14, 2012, 09:31:16 pm »
Removing this code:
Code: [Select]
world.draw(frameBuffer);
And it wont crash. So what ever is creating the 2048x2048 image must be in that method?

Edit:
Looks like setting it to OpenGL 1.0, it no longer crashes also. So what's in OpenGL 2.0 that's causing the large images?

Edit2: The weird thing, so far it only has this problem on the 2 nvidia chips(Tegra 2) from what I can see. With the exact same setup (OpenGL 2.0, large textures), the Galaxy Nexus is fine and so is the crappy Galaxy Y(it has a high java heap for some reason).

So I hope EgonOlsen can find the issue. Because on high end devices(tablets) I need OpenGL 2.0 for AA and I need large textures for pitch maps (Will be close to the pitch) and that I need to draw the framebuffer :P
« Last Edit: March 15, 2012, 12:56:50 am by zammbi »

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 12295
    • View Profile
    • http://www.jpct.net
Re: Error code 1000 and black screen issue
« Reply #7 on: March 15, 2012, 12:22:35 am »
That code doesn't create the texture or at least not explicitly. The textures are uploaded the first time the object that uses them will be rendered. If you remove that method-call, nothing will be rendered, i.e. no texture will be uploaded. I've no idea what causes this. I'll add additional debug output tomorrow to track this down...
« Last Edit: March 15, 2012, 07:51:03 am by EgonOlsen »

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 12295
    • View Profile
    • http://www.jpct.net
Re: Error code 1000 and black screen issue
« Reply #8 on: March 15, 2012, 07:25:28 am »
Where do you load your textures from? res/raw, res/drawable, assets...?

Offline zammbi

  • float
  • ****
  • Posts: 361
    • View Profile
Re: Error code 1000 and black screen issue
« Reply #9 on: March 15, 2012, 07:44:00 am »
Assets.

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 12295
    • View Profile
    • http://www.jpct.net
Re: Error code 1000 and black screen issue
« Reply #10 on: March 15, 2012, 08:01:34 pm »
Please re-download the beta version: http://jpct.de/download/beta/jpct_ae.jar, do a Logger.setLogLevel(Logger.LL_DEBUG); run the app and post the complete log after the crash. Maybe the additional output helps to understand what's going on...

Offline zammbi

  • float
  • ****
  • Posts: 361
    • View Profile
Re: Error code 1000 and black screen issue
« Reply #11 on: March 15, 2012, 09:56:16 pm »
Thanks egon. I won't be able to try it until I get back from my long weekend.

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 12295
    • View Profile
    • http://www.jpct.net
Re: Error code 1000 and black screen issue
« Reply #12 on: March 15, 2012, 11:14:16 pm »
I won't be able to try it until I get back from my long weekend.
Have fun... ;)

Offline zammbi

  • float
  • ****
  • Posts: 361
    • View Profile
Re: Error code 1000 and black screen issue
« Reply #13 on: March 19, 2012, 09:46:54 pm »
Quote
Have fun...
Thanks. It was a good birthday :)

Here is the full log. I hope this helps. Do you need a log for the one that works?

Quote
03-20 09:39:58.100: I/jPCT-AE(13841): onCreate
03-20 09:39:58.190: D/dalvikvm(13841): GC_CONCURRENT freed 883K, 9% free 10635K/11655K, paused 2ms+4ms
03-20 09:39:58.190: I/jPCT-AE(13841): MATCH ID: 7871
03-20 09:39:58.190: D/WW(13841): Checking device stats... Max mem:64 Cpus:2
03-20 09:39:58.200: D/PhoneRank(13841): Max mem64 mem left:56
03-20 09:39:58.200: D/PhoneRank(13841): Very high ram free.
03-20 09:39:58.200: D/WW(13841): Turning on AA with transparency.
03-20 09:39:58.200: D/WW(13841): Using OpenGL 2.0
03-20 09:39:58.200: D/WWActivity(13841): ON START
03-20 09:39:58.240: D/libEGL(13841): loaded /system/lib/egl/libGLES_android.so
03-20 09:39:58.240: D/libEGL(13841): loaded /system/lib/egl/libEGL_tegra.so
03-20 09:39:58.250: D/WWActivity(13841): SERVICE CONNECTED
03-20 09:39:58.260: D/WWActivity(13841): RUNNING: true
03-20 09:39:58.260: D/WWActivity(13841): URL: http://crichq-staging-629692596.us-west-1.elb.amazonaws.com/mobile/matches/live_matches/7871/wagon_wheel
03-20 09:39:58.280: D/libEGL(13841): loaded /system/lib/egl/libGLESv1_CM_tegra.so
03-20 09:39:58.300: D/libEGL(13841): loaded /system/lib/egl/libGLESv2_tegra.so
03-20 09:39:58.300: I/jPCT-AE(13841): CSAA enabled!
03-20 09:39:58.310: I/jPCT-AE(13841): Unable to find a matching config...using default!
03-20 09:39:58.410: I/jPCT-AE(13841): GLRenderer created with id 0 on Thread[GLThread 24,5,main]
03-20 09:39:58.410: I/jPCT-AE(13841): Initializing GL20 render pipeline...
03-20 09:39:58.420: I/jPCT-AE(13841): Accessing shaders via JAR!
03-20 09:39:58.430: I/jPCT-AE(13841): Loading default shaders !
03-20 09:39:58.430: I/jPCT-AE(13841): 0 shaders in replacement map!
03-20 09:39:58.430: I/jPCT-AE(13841): Default fragment shader is: /defaultFragmentShader.src
03-20 09:39:58.580: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:58.580: I/jPCT-AE(13841): Text file from InputStream loaded...2008 bytes
03-20 09:39:58.580: I/jPCT-AE(13841): Default vertex shader is: /defaultVertexShader.src
03-20 09:39:58.580: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:58.590: I/jPCT-AE(13841): Text file from InputStream loaded...3897 bytes
03-20 09:39:58.590: I/jPCT-AE(13841): Compiling shader program!
03-20 09:39:58.700: D/WebViewActivity(13841): ### onStop is being called
03-20 09:39:58.820: I/jPCT-AE(13841): Handles of 3: 2/28
03-20 09:39:58.820: I/jPCT-AE(13841): Loading default shaders (Tex0)!
03-20 09:39:58.820: I/jPCT-AE(13841): 0 shaders in replacement map!
03-20 09:39:58.820: I/jPCT-AE(13841): Default fragment shader is: /defaultFragmentShader.src
03-20 09:39:58.820: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:58.820: I/jPCT-AE(13841): Text file from InputStream loaded...201 bytes
03-20 09:39:58.820: I/jPCT-AE(13841): Default vertex shader is: /defaultVertexShader.src
03-20 09:39:58.820: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:58.820: I/jPCT-AE(13841): Text file from InputStream loaded...3421 bytes
03-20 09:39:58.820: I/jPCT-AE(13841): Compiling shader program!
03-20 09:39:58.920: I/jPCT-AE(13841): Handles of 6: 2/21
03-20 09:39:58.920: I/jPCT-AE(13841): Loading default shaders (Tex1)!
03-20 09:39:58.920: I/jPCT-AE(13841): 0 shaders in replacement map!
03-20 09:39:58.920: I/jPCT-AE(13841): Default fragment shader is: /defaultFragmentShader.src
03-20 09:39:58.920: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:58.920: I/jPCT-AE(13841): Text file from InputStream loaded...871 bytes
03-20 09:39:58.920: I/jPCT-AE(13841): Default vertex shader is: /defaultVertexShader.src
03-20 09:39:58.920: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:58.920: I/jPCT-AE(13841): Text file from InputStream loaded...3791 bytes
03-20 09:39:58.920: I/jPCT-AE(13841): Compiling shader program!
03-20 09:39:58.990: D/dalvikvm(13841): GC_CONCURRENT freed 988K, 10% free 11038K/12167K, paused 2ms+2ms
03-20 09:39:59.010: D/CricHQService(13841): Content-Type: text/html; charset=utf-8
03-20 09:39:59.010: D/CricHQService(13841): 5831
03-20 09:39:59.010: D/CricHQService(13841): utf-8
03-20 09:39:59.010: D/CricHQService(13841): {"balls":[{"wagonWheel":null,"pitchMap":{"back_square":8,"bad_bowl":false,"pitch_square":2},"runs":0},{"wagonWheel":{"hit_angle_rads":4.483394,"cut_slider_proportion":1,"height_slider_proportion":0.25,"ellipse_proportion":1.063881,"arc_sector":4},"pitchMap":{"back_square":1,"bad_bowl":false,"pitch_square":1},"runs":6},{"wagonWheel":{"hit_angle_rads":5.515028,"cut_slider_proportion":0.7283465,"height_slider_proportion":0.7598425,"ellipse_proportion":1,"arc_sector":5},"pitchMap":{"back_square":1,"bad_bowl":false,"pitch_square":9},"runs":4},{"wagonWheel":{"hit_angle_rads":5.803667,"cut_slider_proportion":1,"height_slider_proportion":0.05,"ellipse_proportion":0.8780316,"arc_sector":5},"pitchMap":{"back_square":2,"bad_bowl":false,"pitch_square":5},"runs":2},{"wagonWheel":{"hit_angle_rads":4.882167,"cut_slider_proportion":0.8543307,"height_slider_proportion":0.2322835,"ellipse_proportion":0.556477,"arc_sector":4},"pitchMap":{"back_square":5,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":{"hit_angle_rads":1.610775,"cut_slider_proportion":1,"height_slider_proportion":0.05,"ellipse_proportion":0.3946149,"arc_sector":1},"pitchMap":{"back_square":8,"bad_bowl":false,"pitch_square":2},"runs":0},{"wagonWheel":{"hit_angle_rads":0.3982132,"cut_slider_proportion":1,"height_slider_proportion":1,"ellipse_proportion":1,"arc_sector":0},"pitchMap":{"back_square":9,"bad_bowl":false,"pitch_square":3},"runs":6},{"wagonWheel":null,"pitchMap":{"back_square":4,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":{"hit_angle_rads":5.028821,"cut_slider_proportion":0.7204725,"height_slider_proportion":0.3267716,"ellipse_proportion":1,"arc_sector":5},"pitchMap":{"back_square":4,"bad_bowl":false,"pitch_square":5},"runs":4},{"wagonWheel":{"hit_angle_rads":3.740932,"cut_slider_proportion":1,"height_slider_proportion":0.05,"ellipse_proportion":0.791822,"arc_sector":3},"pitchMap":{"back_square":8,"bad_bowl":false,"pitch_square":2},"runs":2},{"wagonWheel":null,"pitchMap":{"back_square":2,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":{"hit_angle_rads":4.354822,"cut_slider_proportion":0.6732283,"height_slider_proportion":0.6574803,"ellipse_proportion":1,"arc_sector":4},"pitchMap":{"back_square":7,"bad_bowl":false,"pitch_square":null},"runs":4},{"wagonWheel":{"hit_angle_rads":3.955231,"cut_slider_proportion":0.3031496,"height_slider_proportion":0.1850394,"ellipse_proportion":0.8688989,"arc_sector":3},"pitchMap":{"back_square":7,"bad_bowl":false,"pitch_square":1},"runs":3},{"wagonWheel":{"hit_angle_rads":0.4012282,"cut_slider_proportion":0.5629922,"height_slider_proportion":0.2480315,"ellipse_proportion":0.8726951,"arc_sector":0},"pitchMap":{"back_square":5,"bad_bowl":false,"pitch_square":5},"runs":2},{"wagonWheel":null,"pitchMap":{"back_square":2,"bad_bowl":false,"pitch_square":8},"runs":0},{"wagonWheel":{"hit_angle_rads":1.248425,"cut_slider_proportion":1,"height_slider_proportion":1,"ellipse_proportion":2,"arc_sector":0},"pitchMap":{"back_square":2,"bad_bowl":false,"pitch_square":8},"runs":6},{"wagonWheel":null,"pitchMap":{"back_square":5,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":{"hit_angle_rads":3.195242,"cut_slider_proportion":1,"height_slider_proportion":1,"ellipse_proportion":2,"arc_sector":3},"pitchMap":{"back_square":8,"bad_bowl":false,"pitch_square":2},"runs":6},{"wagonWheel":{"hit_angle_rads":1.509639,"cut_slider_proportion":1,"height_slider_proportion":0.7785433,"ellipse_proportion":1.329352,"arc_sector":1},"pitchMap":{"back_square":5,"bad_bowl":false,"pitch_square":5},"runs":6},{"wagonWheel":{"hit_angle_rads":4.432281,"cut_slider_proportion":1,"height_slider_proportion":1,"ellipse_proportion":0.5881524,"arc_sector":4},"pitchMap":{"back_square":5,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":null,"pitchMap":{"back_square":4,"bad_bowl":false,"pitch_square":5},"runs":0},{"wagonWheel":{"hit_angle_rads":3.164316,"cut_slider_proportion":0.7519685,"height_slider_proportion":0.6732283,"ellipse_proportion":1,"arc_sector":3},"pitchMap":{"back_square":8,"bad_bowl"
03-20 09:39:59.010: I/jPCT-AE(13841): Error Code: 0
03-20 09:39:59.030: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":4,"height_slider_proportion":0.25,"cut_slider_proportion":1,"ellipse_proportion":1.063881,"hit_angle_rads":4.483394}
03-20 09:39:59.030: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.7598425,"cut_slider_proportion":0.7283465,"ellipse_proportion":1,"hit_angle_rads":5.515028}
03-20 09:39:59.030: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.8780316,"hit_angle_rads":5.803667}
03-20 09:39:59.030: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":4,"height_slider_proportion":0.2322835,"cut_slider_proportion":0.8543307,"ellipse_proportion":0.556477,"hit_angle_rads":4.882167}
03-20 09:39:59.030: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":1,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.3946149,"hit_angle_rads":1.610775}
03-20 09:39:59.030: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":0,"height_slider_proportion":1,"cut_slider_proportion":1,"ellipse_proportion":1,"hit_angle_rads":0.3982132}
03-20 09:39:59.030: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.3267716,"cut_slider_proportion":0.7204725,"ellipse_proportion":1,"hit_angle_rads":5.028821}
03-20 09:39:59.030: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":3,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.791822,"hit_angle_rads":3.740932}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":4,"height_slider_proportion":0.6574803,"cut_slider_proportion":0.6732283,"ellipse_proportion":1,"hit_angle_rads":4.354822}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":3,"height_slider_proportion":0.1850394,"cut_slider_proportion":0.3031496,"ellipse_proportion":0.8688989,"hit_angle_rads":3.955231}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":0,"height_slider_proportion":0.2480315,"cut_slider_proportion":0.5629922,"ellipse_proportion":0.8726951,"hit_angle_rads":0.4012282}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":0,"height_slider_proportion":1,"cut_slider_proportion":1,"ellipse_proportion":2,"hit_angle_rads":1.248425}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":3,"height_slider_proportion":1,"cut_slider_proportion":1,"ellipse_proportion":2,"hit_angle_rads":3.195242}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":1,"height_slider_proportion":0.7785433,"cut_slider_proportion":1,"ellipse_proportion":1.329352,"hit_angle_rads":1.509639}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":4,"height_slider_proportion":1,"cut_slider_proportion":1,"ellipse_proportion":0.5881524,"hit_angle_rads":4.432281}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":3,"height_slider_proportion":0.6732283,"cut_slider_proportion":0.7519685,"ellipse_proportion":1,"hit_angle_rads":3.164316}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":1,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.599682,"hit_angle_rads":1.544486}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":1,"hit_angle_rads":5.226536}
03-20 09:39:59.040: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.2874016,"cut_slider_proportion":0.6102362,"ellipse_proportion":0.8631719,"hit_angle_rads":5.337499}
03-20 09:39:59.050: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":3,"height_slider_proportion":0.8070866,"cut_slider_proportion":0.8464567,"ellipse_proportion":1,"hit_angle_rads":3.21234}
03-20 09:39:59.050: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":5,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.5535527,"hit_angle_rads":5.566645}
03-20 09:39:59.050: I/jPCT-AE(13841): Wagon Wheel: {"arc_sector":0,"height_slider_proportion":0.05,"cut_slider_proportion":1,"ellipse_proportion":0.8503579,"hit_angle_rads":0.4701827}
03-20 09:39:59.060: I/jPCT-AE(13841): Handles of 9: 2/26
03-20 09:39:59.060: I/jPCT-AE(13841): Loading default shaders (Tex0Light0)!
03-20 09:39:59.060: I/jPCT-AE(13841): 0 shaders in replacement map!
03-20 09:39:59.060: I/jPCT-AE(13841): Default fragment shader is: /defaultFragmentShader.src
03-20 09:39:59.060: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:59.060: I/jPCT-AE(13841): Text file from InputStream loaded...201 bytes
03-20 09:39:59.060: I/jPCT-AE(13841): Default vertex shader is: /defaultVertexShader.src
03-20 09:39:59.060: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:59.060: I/jPCT-AE(13841): Text file from InputStream loaded...1200 bytes
03-20 09:39:59.060: I/jPCT-AE(13841): Compiling shader program!
03-20 09:39:59.090: I/jPCT-AE(13841): Handles of 12: 2/6
03-20 09:39:59.090: I/jPCT-AE(13841): Loading default shaders (Fog)!
03-20 09:39:59.090: I/jPCT-AE(13841): 0 shaders in replacement map!
03-20 09:39:59.090: I/jPCT-AE(13841): Default fragment shader is: /defaultFragmentShader.src
03-20 09:39:59.090: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:59.090: I/jPCT-AE(13841): Text file from InputStream loaded...328 bytes
03-20 09:39:59.090: I/jPCT-AE(13841): Default vertex shader is: /defaultVertexShader.src
03-20 09:39:59.090: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:59.090: I/jPCT-AE(13841): Text file from InputStream loaded...3668 bytes
03-20 09:39:59.090: I/jPCT-AE(13841): Compiling shader program!
03-20 09:39:59.200: I/jPCT-AE(13841): Handles of 15: 2/24
03-20 09:39:59.200: I/jPCT-AE(13841): Loading default shaders (FogLight0)!
03-20 09:39:59.200: I/jPCT-AE(13841): 0 shaders in replacement map!
03-20 09:39:59.200: I/jPCT-AE(13841): Default fragment shader is: /defaultFragmentShader.src
03-20 09:39:59.200: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:59.200: I/jPCT-AE(13841): Text file from InputStream loaded...328 bytes
03-20 09:39:59.200: I/jPCT-AE(13841): Default vertex shader is: /defaultVertexShader.src
03-20 09:39:59.200: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:59.200: I/jPCT-AE(13841): Text file from InputStream loaded...1516 bytes
03-20 09:39:59.200: I/jPCT-AE(13841): Compiling shader program!
03-20 09:39:59.240: I/jPCT-AE(13841): Handles of 18: 2/9
03-20 09:39:59.240: I/jPCT-AE(13841): Loading default shaders (Tex0Amb)!
03-20 09:39:59.240: I/jPCT-AE(13841): 0 shaders in replacement map!
03-20 09:39:59.240: I/jPCT-AE(13841): Default fragment shader is: /defaultFragmentShader.src
03-20 09:39:59.240: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:59.240: I/jPCT-AE(13841): Text file from InputStream loaded...199 bytes
03-20 09:39:59.240: I/jPCT-AE(13841): Default vertex shader is: /defaultVertexShader.src
03-20 09:39:59.240: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:59.240: I/jPCT-AE(13841): Text file from InputStream loaded...757 bytes
03-20 09:39:59.240: I/jPCT-AE(13841): Compiling shader program!
03-20 09:39:59.250: I/jPCT-AE(13841): Handles of 21: 1/3
03-20 09:39:59.250: I/jPCT-AE(13841): Loading default shaders (Depth)!
03-20 09:39:59.250: I/jPCT-AE(13841): 0 shaders in replacement map!
03-20 09:39:59.250: I/jPCT-AE(13841): Default fragment shader is: /defaultFragmentShader.src
03-20 09:39:59.250: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:59.250: I/jPCT-AE(13841): Text file from InputStream loaded...745 bytes
03-20 09:39:59.250: I/jPCT-AE(13841): Default vertex shader is: /defaultVertexShader.src
03-20 09:39:59.250: I/jPCT-AE(13841): Loading file from InputStream
03-20 09:39:59.250: I/jPCT-AE(13841): Text file from InputStream loaded...248 bytes
03-20 09:39:59.250: I/jPCT-AE(13841): Compiling shader program!
03-20 09:39:59.270: I/jPCT-AE(13841): Handles of 24: 0/0
03-20 09:39:59.270: I/jPCT-AE(13841): GL20 render pipeline initialized!
03-20 09:39:59.270: I/jPCT-AE(13841): OpenGL vendor:     NVIDIA Corporation
03-20 09:39:59.270: I/jPCT-AE(13841): OpenGL renderer:   NVIDIA Tegra
03-20 09:39:59.270: I/jPCT-AE(13841): OpenGL version:    OpenGL ES 2.0
03-20 09:39:59.270: I/jPCT-AE(13841): OpenGL renderer initialized (using 2 texture stages)
03-20 09:39:59.270: D/3d(13841): World is null. Being created...
03-20 09:39:59.270: D/PhoneRank(13841): Using hardware renderer (Not PixelFlinger): NVIDIA Tegra
03-20 09:39:59.270: I/jPCT-AE(13841): Adding Lightsource: 0
03-20 09:39:59.270: D/PhoneRank(13841): Max mem64 mem left:55
03-20 09:39:59.270: D/PhoneRank(13841): Very high ram free.
03-20 09:39:59.310: D/dalvikvm(13841): GC_FOR_ALLOC freed 653K, 11% free 10866K/12167K, paused 32ms
03-20 09:39:59.310: I/dalvikvm-heap(13841): Grow heap (frag case) to 12.707MB for 2097168-byte allocation
03-20 09:39:59.340: D/dalvikvm(13841): GC_FOR_ALLOC freed 2K, 10% free 12911K/14279K, paused 31ms
03-20 09:39:59.380: I/jPCT-AE(13841): Loading Texture...
03-20 09:39:59.390: D/dalvikvm(13841): GC_CONCURRENT freed 0K, 10% free 12911K/14279K, paused 2ms+3ms
03-20 09:39:59.430: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 10% free 12911K/14279K, paused 30ms
03-20 09:39:59.440: I/dalvikvm-heap(13841): Grow heap (frag case) to 16.705MB for 4194320-byte allocation
03-20 09:39:59.470: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 8% free 17007K/18439K, paused 30ms
03-20 09:39:59.480: I/jPCT-AE(13841): Texture loaded...4194304 bytes/1024*1024 pixels!
03-20 09:39:59.510: D/(13841): sampleSize : 1
03-20 09:39:59.510: D/(13841): GIF : image width - 1, image height - 1
03-20 09:39:59.510: D/(13841): sampleSize : 1
03-20 09:39:59.510: D/(13841): GIF : image width - 1, image height - 1
03-20 09:39:59.520: D/dalvikvm(13841): GC_CONCURRENT freed <1K, 8% free 17009K/18439K, paused 2ms+3ms
03-20 09:39:59.550: D/dalvikvm(13841): GC_FOR_ALLOC freed 2048K, 19% free 14961K/18439K, paused 31ms
03-20 09:39:59.550: I/dalvikvm-heap(13841): Grow heap (frag case) to 18.706MB for 4194320-byte allocation
03-20 09:39:59.590: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 16% free 19057K/22599K, paused 30ms
03-20 09:39:59.710: D/dalvikvm(13841): GC_CONCURRENT freed 0K, 12% free 20081K/22599K, paused 2ms+3ms
03-20 09:40:00.800: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 12% free 20082K/22599K, paused 43ms
03-20 09:40:00.800: I/dalvikvm-heap(13841): Grow heap (frag case) to 21.707MB for 2097188-byte allocation
03-20 09:40:00.850: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 11% free 22130K/24711K, paused 31ms
03-20 09:40:00.880: D/dalvikvm(13841): GC_CONCURRENT freed 1024K, 15% free 21106K/24711K, paused 2ms+3ms
03-20 09:40:00.970: I/jPCT-AE(13841): Compressed 4194304 bytes to 1110217 bytes!
03-20 09:40:00.980: I/jPCT-AE(13841): Texture compressed to 26% (1110217/4194304) of uncompressed size!
03-20 09:40:01.010: D/dalvikvm(13841): GC_FOR_ALLOC freed 10241K, 52% free 11950K/24711K, paused 30ms
03-20 09:40:01.010: I/dalvikvm-heap(13841): Grow heap (frag case) to 15.766MB for 4194320-byte allocation
03-20 09:40:01.040: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 36% free 16046K/24711K, paused 31ms
03-20 09:40:01.090: D/dalvikvm(13841): GC_CONCURRENT freed 0K, 36% free 16046K/24711K, paused 2ms+3ms
03-20 09:40:01.190: I/jPCT-AE(13841): Loading Texture...
03-20 09:40:01.220: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 36% free 16046K/24711K, paused 30ms
03-20 09:40:01.230: I/dalvikvm-heap(13841): Grow heap (frag case) to 19.766MB for 4194320-byte allocation
03-20 09:40:01.250: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 19% free 20142K/24711K, paused 29ms
03-20 09:40:01.270: I/jPCT-AE(13841): Texture loaded...4194304 bytes/1024*1024 pixels!
03-20 09:40:01.300: D/dalvikvm(13841): GC_CONCURRENT freed <1K, 19% free 20142K/24711K, paused 3ms+4ms
03-20 09:40:01.350: D/dalvikvm(13841): GC_FOR_ALLOC freed 4096K, 36% free 16046K/24711K, paused 30ms
03-20 09:40:01.360: I/dalvikvm-heap(13841): Grow heap (frag case) to 19.766MB for 4194320-byte allocation
03-20 09:40:01.460: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 19% free 20142K/24711K, paused 30ms
03-20 09:40:01.510: D/dalvikvm(13841): GC_CONCURRENT freed 0K, 15% free 21167K/24711K, paused 2ms+3ms
03-20 09:40:01.960: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 15% free 21167K/24711K, paused 30ms
03-20 09:40:01.960: I/dalvikvm-heap(13841): Grow heap (frag case) to 22.766MB for 2097188-byte allocation
03-20 09:40:02.010: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 7% free 23215K/24711K, paused 29ms
03-20 09:40:02.040: D/dalvikvm(13841): GC_CONCURRENT freed 1024K, 11% free 22191K/24711K, paused 2ms+3ms
03-20 09:40:02.300: I/jPCT-AE(13841): Compressed 4194304 bytes to 1633755 bytes!
03-20 09:40:02.330: D/dalvikvm(13841): GC_FOR_ALLOC freed 4096K, 27% free 18095K/24711K, paused 30ms
03-20 09:40:02.330: I/jPCT-AE(13841): Texture compressed to 38% (1633755/4194304) of uncompressed size!
03-20 09:40:02.340: I/jPCT-AE(13841): Loading Texture...
03-20 09:40:02.340: I/jPCT-AE(13841): Texture loaded...65536 bytes/128*128 pixels!
03-20 09:40:02.350: I/jPCT-AE(13841): Compressed 65536 bytes to 516 bytes!
03-20 09:40:02.350: I/jPCT-AE(13841): Texture compressed to 0% (516/65536) of uncompressed size!
03-20 09:40:02.350: I/jPCT-AE(13841): Loading Texture...
03-20 09:40:02.370: I/jPCT-AE(13841): Texture loaded...65536 bytes/128*128 pixels!
03-20 09:40:02.380: I/jPCT-AE(13841): Compressed 65536 bytes to 551 bytes!
03-20 09:40:02.380: I/jPCT-AE(13841): Texture compressed to 0% (551/65536) of uncompressed size!
03-20 09:40:02.410: D/szipinf(13841): Initializing inflate state
03-20 09:40:02.410: D/szipinf(13841): Initializing zlib to inflate
03-20 09:40:02.430: D/dalvikvm(13841): GC_CONCURRENT freed 6438K, 45% free 13701K/24711K, paused 3ms+4ms
03-20 09:40:02.430: I/dalvikvm(13841): Jit: resizing JitTable from 4096 to 8192
03-20 09:40:02.510: I/jPCT-AE(13841): Normal vectors calculated in 33ms!
03-20 09:40:02.510: D/szipinf(13841): Initializing inflate state
03-20 09:40:02.510: D/szipinf(13841): Initializing zlib to inflate
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 1ms!
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.520: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.530: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.530: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.530: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.530: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.530: I/jPCT-AE(13841): Normal vectors calculated in 1ms!
03-20 09:40:02.530: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.530: I/jPCT-AE(13841): Normal vectors calculated in 0ms!
03-20 09:40:02.540: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.540: I/jPCT-AE(13841): Not a triangle strip at position 1!
03-20 09:40:02.580: I/jPCT-AE(13841): Subobject of object 2/object4 compiled to indexed fixed point data using 2598 vertices in 38ms!
03-20 09:40:02.580: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 2/object4 took 38ms
03-20 09:40:02.580: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.580: I/jPCT-AE(13841): Not a triangle strip at position 1!
03-20 09:40:02.580: I/jPCT-AE(13841): Subobject of object 2/object4 compiled to indexed fixed point data using 384 vertices in 3ms!
03-20 09:40:02.580: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 2/object4 took 3ms
03-20 09:40:02.580: I/jPCT-AE(13841): Object 2/object4 compiled to 2 subobjects in 55ms!
03-20 09:40:02.580: I/jPCT-AE(13841): Object 'object4' uses multiple texture sets!
03-20 09:40:02.590: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.590: I/jPCT-AE(13841): Not a triangle strip at position 1!
03-20 09:40:02.640: D/dalvikvm(13841): GC_CONCURRENT freed 1474K, 43% free 14157K/24711K, paused 3ms+3ms
03-20 09:40:02.660: I/jPCT-AE(13841): Subobject of object 3/object5 compiled to indexed fixed point data using 2580 vertices in 69ms!
03-20 09:40:02.660: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 3/object5 took 69ms
03-20 09:40:02.660: I/jPCT-AE(13841): Object 3/object5 compiled to 1 subobjects in 76ms!
03-20 09:40:02.660: I/jPCT-AE(13841): Object 'object5' uses one texture set!
03-20 09:40:02.660: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.660: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.660: I/jPCT-AE(13841): Subobject of object 4/pitch0 compiled to indexed fixed point data using 4 vertices in a strip in 0ms!
03-20 09:40:02.660: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 4/pitch0 took 0ms
03-20 09:40:02.660: I/jPCT-AE(13841): Object 4/pitch0 compiled to 1 subobjects in 1ms!
03-20 09:40:02.660: I/jPCT-AE(13841): Object 'pitch0' uses one texture set!
03-20 09:40:02.660: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.660: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.660: I/jPCT-AE(13841): Subobject of object 5/pitch1 compiled to indexed fixed point data using 4 vertices in a strip in 0ms!
03-20 09:40:02.660: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 5/pitch1 took 0ms
03-20 09:40:02.660: I/jPCT-AE(13841): Object 5/pitch1 compiled to 1 subobjects in 2ms!
03-20 09:40:02.660: I/jPCT-AE(13841): Object 'pitch1' uses one texture set!
03-20 09:40:02.660: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.660: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.660: I/jPCT-AE(13841): Subobject of object 6/pitch2 compiled to indexed fixed point data using 4 vertices in a strip in 1ms!
03-20 09:40:02.670: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 6/pitch2 took 1ms
03-20 09:40:02.670: I/jPCT-AE(13841): Object 6/pitch2 compiled to 1 subobjects in 1ms!
03-20 09:40:02.670: I/jPCT-AE(13841): Object 'pitch2' uses one texture set!
03-20 09:40:02.670: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.670: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.670: I/jPCT-AE(13841): Subobject of object 7/pitch3 compiled to indexed fixed point data using 4 vertices in a strip in 1ms!
03-20 09:40:02.670: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 7/pitch3 took 2ms
03-20 09:40:02.670: I/jPCT-AE(13841): Object 7/pitch3 compiled to 1 subobjects in 2ms!
03-20 09:40:02.670: I/jPCT-AE(13841): Object 'pitch3' uses one texture set!
03-20 09:40:02.670: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.670: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.670: I/jPCT-AE(13841): Subobject of object 8/pitch4 compiled to indexed fixed point data using 4 vertices in a strip in 1ms!
03-20 09:40:02.670: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 8/pitch4 took 1ms
03-20 09:40:02.670: I/jPCT-AE(13841): Object 8/pitch4 compiled to 1 subobjects in 2ms!
03-20 09:40:02.670: I/jPCT-AE(13841): Object 'pitch4' uses one texture set!
03-20 09:40:02.670: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.670: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.670: I/jPCT-AE(13841): Subobject of object 9/pitch5 compiled to indexed fixed point data using 4 vertices in a strip in 1ms!
03-20 09:40:02.670: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 9/pitch5 took 1ms
03-20 09:40:02.670: I/jPCT-AE(13841): Object 9/pitch5 compiled to 1 subobjects in 2ms!
03-20 09:40:02.670: I/jPCT-AE(13841): Object 'pitch5' uses one texture set!
03-20 09:40:02.670: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.670: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.670: I/jPCT-AE(13841): Subobject of object 10/pitch6 compiled to indexed fixed point data using 4 vertices in a strip in 0ms!
03-20 09:40:02.670: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 10/pitch6 took 0ms
03-20 09:40:02.670: I/jPCT-AE(13841): Object 10/pitch6 compiled to 1 subobjects in 1ms!
03-20 09:40:02.670: I/jPCT-AE(13841): Object 'pitch6' uses one texture set!
03-20 09:40:02.670: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.670: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.680: I/jPCT-AE(13841): Subobject of object 11/pitch7 compiled to indexed fixed point data using 4 vertices in a strip in 1ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 11/pitch7 took 1ms
03-20 09:40:02.680: I/jPCT-AE(13841): Object 11/pitch7 compiled to 1 subobjects in 1ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Object 'pitch7' uses one texture set!
03-20 09:40:02.680: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.680: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.680: I/jPCT-AE(13841): Subobject of object 12/pitch8 compiled to indexed fixed point data using 4 vertices in a strip in 0ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 12/pitch8 took 1ms
03-20 09:40:02.680: I/jPCT-AE(13841): Object 12/pitch8 compiled to 1 subobjects in 2ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Object 'pitch8' uses one texture set!
03-20 09:40:02.680: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.680: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.680: I/jPCT-AE(13841): Subobject of object 13/back0 compiled to indexed fixed point data using 4 vertices in a strip in 0ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 13/back0 took 0ms
03-20 09:40:02.680: I/jPCT-AE(13841): Object 13/back0 compiled to 1 subobjects in 2ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Object 'back0' uses one texture set!
03-20 09:40:02.680: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.680: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.680: I/jPCT-AE(13841): Subobject of object 14/back1 compiled to indexed fixed point data using 4 vertices in a strip in 1ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 14/back1 took 1ms
03-20 09:40:02.680: I/jPCT-AE(13841): Object 14/back1 compiled to 1 subobjects in 1ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Object 'back1' uses one texture set!
03-20 09:40:02.680: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.680: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.680: I/jPCT-AE(13841): Subobject of object 15/back2 compiled to indexed fixed point data using 4 vertices in a strip in 0ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 15/back2 took 1ms
03-20 09:40:02.680: I/jPCT-AE(13841): Object 15/back2 compiled to 1 subobjects in 1ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Object 'back2' uses one texture set!
03-20 09:40:02.680: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.680: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.680: I/jPCT-AE(13841): Subobject of object 16/back3 compiled to indexed fixed point data using 4 vertices in a strip in 1ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 16/back3 took 1ms
03-20 09:40:02.680: I/jPCT-AE(13841): Object 16/back3 compiled to 1 subobjects in 1ms!
03-20 09:40:02.680: I/jPCT-AE(13841): Object 'back3' uses one texture set!
03-20 09:40:02.680: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.690: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.690: I/jPCT-AE(13841): Subobject of object 17/back4 compiled to indexed fixed point data using 4 vertices in a strip in 1ms!
03-20 09:40:02.690: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 17/back4 took 1ms
03-20 09:40:02.690: I/jPCT-AE(13841): Object 17/back4 compiled to 1 subobjects in 1ms!
03-20 09:40:02.690: I/jPCT-AE(13841): Object 'back4' uses one texture set!
03-20 09:40:02.690: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.690: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.690: I/jPCT-AE(13841): Subobject of object 18/back5 compiled to indexed fixed point data using 4 vertices in a strip in 1ms!
03-20 09:40:02.690: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 18/back5 took 1ms
03-20 09:40:02.690: I/jPCT-AE(13841): Object 18/back5 compiled to 1 subobjects in 1ms!
03-20 09:40:02.690: I/jPCT-AE(13841): Object 'back5' uses one texture set!
03-20 09:40:02.690: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.690: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.690: I/jPCT-AE(13841): Subobject of object 19/back6 compiled to indexed fixed point data using 4 vertices in a strip in 0ms!
03-20 09:40:02.690: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 19/back6 took 1ms
03-20 09:40:02.690: I/jPCT-AE(13841): Object 19/back6 compiled to 1 subobjects in 2ms!
03-20 09:40:02.690: I/jPCT-AE(13841): Object 'back6' uses one texture set!
03-20 09:40:02.690: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.690: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.690: I/jPCT-AE(13841): Subobject of object 20/back7 compiled to indexed fixed point data using 4 vertices in a strip in 0ms!
03-20 09:40:02.690: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 20/back7 took 0ms
03-20 09:40:02.690: I/jPCT-AE(13841): Object 20/back7 compiled to 1 subobjects in 2ms!
03-20 09:40:02.690: I/jPCT-AE(13841): Object 'back7' uses one texture set!
03-20 09:40:02.690: I/jPCT-AE(13841): Checking for triangle strip...
03-20 09:40:02.690: I/jPCT-AE(13841): Triangle strip found!
03-20 09:40:02.690: I/jPCT-AE(13841): Subobject of object 21/back8 compiled to indexed fixed point data using 4 vertices in a strip in 1ms!
03-20 09:40:02.690: I/jPCT-AE(13841): Processing and uploading vertices of subobject of object 21/back8 took 1ms
03-20 09:40:02.690: I/jPCT-AE(13841): Object 21/back8 compiled to 1 subobjects in 1ms!
03-20 09:40:02.690: I/jPCT-AE(13841): Object 'back8' uses one texture set!
03-20 09:40:02.690: I/jPCT-AE(13841): Memory usage before compacting: 14751 KB used out of 24711 KB. Max. memory available to the VM is 65536 KB.
03-20 09:40:02.740: D/dalvikvm(13841): GC_EXPLICIT freed 640K, 43% free 14112K/24711K, paused 3ms+5ms
03-20 09:40:02.780: D/dalvikvm(13841): GC_EXPLICIT freed 2K, 43% free 14109K/24711K, paused 2ms+3ms
03-20 09:40:02.930: I/jPCT-AE(13841): Memory usage after compacting: 14109 KB used out of 24711 KB. Max. memory available to the VM is 65536 KB.
03-20 09:40:02.930: I/jPCT-AE(13841): 0fps
03-20 09:40:02.950: I/jPCT-AE(13841): Allocating native memory for 16*16 texture(true/false/false/false/): 1024 bytes!
03-20 09:40:02.950: I/jPCT-AE(13841): New texture's id is: 1
03-20 09:40:02.950: I/jPCT-AE(13841): New texture uploaded: com.threed.jpct.Texture@40b24148 in thread Thread[GLThread 24,5,main]
03-20 09:40:02.950: I/jPCT-AE(13841): Creating buffers...
03-20 09:40:02.950: I/jPCT-AE(13841): VBO created for object 'object5'
03-20 09:40:02.990: D/dalvikvm(13841): GC_FOR_ALLOC freed 40K, 43% free 14108K/24711K, paused 35ms
03-20 09:40:02.990: I/dalvikvm-heap(13841): Grow heap (frag case) to 16.003MB for 2220450-byte allocation
03-20 09:40:03.020: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 35% free 16277K/24711K, paused 31ms
03-20 09:40:03.080: D/dalvikvm(13841): GC_CONCURRENT freed <1K, 35% free 16278K/24711K, paused 2ms+3ms
03-20 09:40:03.180: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 35% free 16278K/24711K, paused 31ms
03-20 09:40:03.180: I/dalvikvm-heap(13841): Grow heap (frag case) to 20.241MB for 4442412-byte allocation
03-20 09:40:03.230: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 17% free 20616K/24711K, paused 31ms
03-20 09:40:03.270: D/dalvikvm(13841): GC_CONCURRENT freed 2168K, 26% free 18448K/24711K, paused 2ms+3ms
03-20 09:40:03.320: I/jPCT-AE(13841): Uncompressed 1110217 bytes to 4194304 bytes in 368ms!
03-20 09:40:03.360: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 26% free 18448K/24711K, paused 31ms
03-20 09:40:03.360: I/dalvikvm-heap(13841): Grow heap (frag case) to 22.124MB for 4194320-byte allocation
03-20 09:40:03.400: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 22% free 22544K/28871K, paused 32ms
03-20 09:40:03.450: D/dalvikvm(13841): GC_CONCURRENT freed <1K, 22% free 22544K/28871K, paused 2ms+3ms
03-20 09:40:03.480: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 22% free 22544K/28871K, paused 32ms
03-20 09:40:03.490: I/dalvikvm-heap(13841): Grow heap (frag case) to 26.124MB for 4194320-byte allocation
03-20 09:40:03.530: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 20% free 26640K/33031K, paused 32ms
03-20 09:40:03.690: I/jPCT-AE(13841): Allocating native memory for 1024*1024 texture(true/false/true/false/): 4194304 bytes!
03-20 09:40:03.720: D/dalvikvm(13841): GC_CONCURRENT freed <1K, 20% free 26640K/33031K, paused 3ms+3ms
03-20 09:40:03.760: D/dalvikvm(13841): GC_FOR_ALLOC freed 8434K, 45% free 18205K/33031K, paused 36ms
03-20 09:40:03.790: I/dalvikvm-heap(13841): Grow heap (frag case) to 33.886MB for 16777232-byte allocation
03-20 09:40:03.820: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 31% free 34589K/49479K, paused 33ms
03-20 09:40:03.860: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 31% free 34589K/49479K, paused 33ms
03-20 09:40:03.860: I/dalvikvm-heap(13841): Grow heap (frag case) to 37.886MB for 4194320-byte allocation
03-20 09:40:03.960: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 22% free 38685K/49479K, paused 33ms
03-20 09:40:03.980: I/jPCT-AE(13841): New texture's id is: 2
03-20 09:40:04.030: D/dalvikvm(13841): GC_CONCURRENT freed <1K, 20% free 39709K/49479K, paused 2ms+3ms
03-20 09:40:04.120: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 20% free 39709K/49479K, paused 32ms
03-20 09:40:04.120: I/dalvikvm-heap(13841): Grow heap (frag case) to 42.886MB for 4194320-byte allocation
03-20 09:40:04.160: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 12% free 43805K/49479K, paused 31ms
03-20 09:40:04.220: I/jPCT-AE(13841): Mipmaps generated by the CPU in 234ms
03-20 09:40:04.220: I/jPCT-AE(13841): New texture uploaded: com.threed.jpct.Texture@40a9c508 in thread Thread[GLThread 24,5,main]
03-20 09:40:04.220: I/jPCT-AE(13841): Creating buffers...
03-20 09:40:04.220: I/jPCT-AE(13841): VBO created for object 'object4'
03-20 09:40:04.220: D/dalvikvm(13841): GC_CONCURRENT freed 1708K, 12% free 43808K/49479K, paused 3ms+4ms
03-20 09:40:04.250: D/dalvikvm(13841): GC_FOR_ALLOC freed 29698K, 72% free 14109K/49479K, paused 33ms
03-20 09:40:04.260: I/dalvikvm-heap(13841): Grow heap (frag case) to 17.002MB for 3267526-byte allocation
03-20 09:40:04.300: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 66% free 17300K/49479K, paused 32ms
03-20 09:40:04.340: D/dalvikvm(13841): GC_CONCURRENT freed <1K, 66% free 17301K/49479K, paused 2ms+4ms
03-20 09:40:04.530: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 66% free 17301K/49479K, paused 31ms
03-20 09:40:04.530: I/dalvikvm-heap(13841): Grow heap (frag case) to 23.237MB for 6536076-byte allocation
03-20 09:40:04.570: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 53% free 23684K/49479K, paused 31ms
03-20 09:40:04.610: I/jPCT-AE(13841): Uncompressed 1633755 bytes to 4194304 bytes in 394ms!
03-20 09:40:04.620: D/dalvikvm(13841): GC_CONCURRENT freed 3191K, 59% free 20493K/49479K, paused 2ms+4ms
03-20 09:40:04.650: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 59% free 20493K/49479K, paused 32ms
03-20 09:40:04.660: I/dalvikvm-heap(13841): Grow heap (frag case) to 24.121MB for 4194320-byte allocation
03-20 09:40:04.700: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 51% free 24589K/49479K, paused 31ms
03-20 09:40:04.750: D/dalvikvm(13841): GC_CONCURRENT freed <1K, 51% free 24589K/49479K, paused 2ms+4ms
03-20 09:40:04.780: D/dalvikvm(13841): GC_FOR_ALLOC freed <1K, 51% free 24589K/49479K, paused 32ms
03-20 09:40:04.780: I/dalvikvm-heap(13841): Grow heap (frag case) to 28.121MB for 4194320-byte allocation
03-20 09:40:04.830: D/dalvikvm(13841): GC_FOR_ALLOC freed 0K, 43% free 28685K/49479K, paused 31ms
03-20 09:40:04.990: I/jPCT-AE(13841): Allocating native memory for 1024*1024 texture(true/false/true/false/): 4194304 bytes!
03-20 09:40:05.030: D/dalvikvm(13841): GC_CONCURRENT freed <1K, 43% free 28685K/49479K, paused 3ms+4ms
03-20 09:40:05.060: D/dalvikvm(13841): GC_FOR_ALLOC freed 10479K, 64% free 18205K/49479K, paused 33ms
03-20 09:40:05.060: I/dalvikvm-heap(13841): Forcing collection of SoftReferences for 16777232-byte allocation
03-20 09:40:05.100: D/dalvikvm(13841): GC_BEFORE_OOM freed 0K, 64% free 18205K/49479K, paused 39ms
03-20 09:40:05.100: E/dalvikvm-heap(13841): Out of memory on a 16777232-byte allocation.
03-20 09:40:05.100: I/dalvikvm(13841): "GLThread 24" prio=5 tid=18 RUNNABLE
03-20 09:40:05.100: I/dalvikvm(13841):   | group="main" sCount=0 dsCount=0 obj=0x40cf6340 self=0x2f34c8
03-20 09:40:05.100: I/dalvikvm(13841):   | sysTid=13880 nice=0 sched=0/0 cgrp=default handle=4814408
03-20 09:40:05.100: I/dalvikvm(13841):   | schedstat=( 5070911000 523269000 3492 ) utm=494 stm=13 core=0
03-20 09:40:05.100: I/dalvikvm(13841):   at dalvik.system.VMRuntime.newNonMovableArray(Native Method)
03-20 09:40:05.100: I/dalvikvm(13841):   at java.nio.MemoryBlock.allocate(MemoryBlock.java:92)
03-20 09:40:05.100: I/dalvikvm(13841):   at java.nio.ReadWriteDirectByteBuffer.<init>(ReadWriteDirectByteBuffer.java:46)
03-20 09:40:05.100: I/dalvikvm(13841):   at java.nio.ByteBuffer.allocateDirect(ByteBuffer.java:67)
03-20 09:40:05.100: I/dalvikvm(13841):   at com.threed.jpct.GLRenderer.convertTexture(GLRenderer.java:800)
03-20 09:40:05.100: I/dalvikvm(13841):   at com.threed.jpct.GLRenderer.setTextures(GLRenderer.java:2262)
03-20 09:40:05.100: I/dalvikvm(13841):   at com.threed.jpct.GLRenderer.drawVertexArray(GLRenderer.java:2187)
03-20 09:40:05.100: I/dalvikvm(13841):   at com.threed.jpct.World.draw(World.java:1309)
03-20 09:40:05.100: I/dalvikvm(13841):   at com.threed.jpct.World.draw(World.java:1076)
03-20 09:40:05.100: I/dalvikvm(13841):   at com.synapse.android.cricscore.wagonwheel.World3DBase.renderWorld(World3DBase.java:409)
03-20 09:40:05.100: I/dalvikvm(13841):   at com.synapse.android.cricscore.wagonwheel.World3DBase.onDrawFrame(World3DBase.java:472)
03-20 09:40:05.100: I/dalvikvm(13841):   at android.opengl.GLSurfaceView$GLThread.guardedRun(GLSurfaceView.java:1431)
03-20 09:40:05.100: I/dalvikvm(13841):   at android.opengl.GLSurfaceView$GLThread.run(GLSurfaceView.java:1180)
03-20 09:40:05.110: W/dalvikvm(13841): threadid=18: thread exiting with uncaught exception (group=0x401fd760)
03-20 09:40:05.110: E/AndroidRuntime(13841): FATAL EXCEPTION: GLThread 24
03-20 09:40:05.110: E/AndroidRuntime(13841): java.lang.OutOfMemoryError
03-20 09:40:05.110: E/AndroidRuntime(13841):    at dalvik.system.VMRuntime.newNonMovableArray(Native Method)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at java.nio.MemoryBlock.allocate(MemoryBlock.java:92)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at java.nio.ReadWriteDirectByteBuffer.<init>(ReadWriteDirectByteBuffer.java:46)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at java.nio.ByteBuffer.allocateDirect(ByteBuffer.java:67)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at com.threed.jpct.GLRenderer.convertTexture(GLRenderer.java:800)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at com.threed.jpct.GLRenderer.setTextures(GLRenderer.java:2262)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at com.threed.jpct.GLRenderer.drawVertexArray(GLRenderer.java:2187)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at com.threed.jpct.World.draw(World.java:1309)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at com.threed.jpct.World.draw(World.java:1076)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at com.synapse.android.cricscore.wagonwheel.World3DBase.renderWorld(World3DBase.java:409)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at com.synapse.android.cricscore.wagonwheel.World3DBase.onDrawFrame(World3DBase.java:472)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at android.opengl.GLSurfaceView$GLThread.guardedRun(GLSurfaceView.java:1431)
03-20 09:40:05.110: E/AndroidRuntime(13841):    at android.opengl.GLSurfaceView$GLThread.run(GLSurfaceView.java:1180)
03-20 09:40:07.160: I/dalvikvm(13841): threadid=4: reacting to signal 3
03-20 09:40:07.160: I/dalvikvm(13841): Wrote stack traces to '/data/anr/traces.txt'

Offline EgonOlsen

  • Administrator
  • quad
  • *****
  • Posts: 12295
    • View Profile
    • http://www.jpct.net
Re: Error code 1000 and black screen issue
« Reply #14 on: March 19, 2012, 09:56:31 pm »
Yes, a log that shows the working case might help...