In a nutshell, what's not working actually ?

A project to port LÖVE to Android handhelds
User avatar
kalle2990
Party member
Posts: 245
Joined: Sat Sep 12, 2009 1:17 pm
Location: Sweden

Re: In a nutshell, what's not working actually ?

Post by kalle2990 »

Moe wrote:If you test it, provide more than a short comment. As I mentioned before, I only accept bugreports that are either easy to reproduce or give a stack trace. You can always use PM or E-Mail if the trace containts data that you do not want to become public.
Okay, the testing has been done to a point where I can't reach much longer.

Section 1 - Logcat
To start with the testing, two different apks were tested together with a dummy lovefile. The first apk contained the github version, as is, and the second contained an uncommented version.

According to your comments and statement, both the Activities were shut down. There was a little delay between these two and I will come back to this point after presenting the logcats.

Logcat #1 - Github version

Code: Select all

05-08 17:33:11.996: I/liblove(12315): onMouseDown
05-08 17:33:12.012: I/liblove(12315): deinit
05-08 17:33:12.051: I/liblove(12315): onMouseUp
05-08 17:33:12.801: A/libc(12315): Fatal signal 11 (SIGSEGV) at 0x00000005 (code=1)
05-08 17:33:13.301: I/DEBUG(10848): *** *** *** *** *** *** *** *** *** *** *** *** *** *** *** ***
05-08 17:33:13.301: I/DEBUG(10848): Build fingerprint: 'google/yakju/maguro:4.0.4/IMM76D/299849:user/release-keys'
05-08 17:33:13.301: I/DEBUG(10848): pid: 12315, tid: 12330  >>> net.schattenkind.nativelove <<<
05-08 17:33:13.301: I/DEBUG(10848): signal 11 (SIGSEGV), code 1 (SEGV_MAPERR), fault addr 00000005
05-08 17:33:13.301: I/DEBUG(10848):  r0 0018f348  r1 fffffff0  r2 00000001  r3 00000000
05-08 17:33:13.301: I/DEBUG(10848):  r4 0018f348  r5 ffffffe9  r6 00000038  r7 00000000
05-08 17:33:13.301: I/DEBUG(10848):  r8 00000050  r9 00000400  10 00000004  fp 00000000
05-08 17:33:13.301: I/DEBUG(10848):  ip 0026db50  sp 5c302960  lr 5c0cc9f9  pc 5c0cc60a  cpsr 800f0030
05-08 17:33:13.301: I/DEBUG(10848):  d0  6174732065736f6c  d1  696620647261646e
05-08 17:33:13.301: I/DEBUG(10848):  d2  65766f6c65766969  d3  494e4a65766f4c6e
05-08 17:33:13.301: I/DEBUG(10848):  d4  0000b25400000001  d5  0000000000000000
05-08 17:33:13.301: I/DEBUG(10848):  d6  0000000000000000  d7  0000000000000000
05-08 17:33:13.301: I/DEBUG(10848):  d8  3f8000003f800000  d9  4342000043c10000
05-08 17:33:13.301: I/DEBUG(10848):  d10 0000000000000000  d11 0000000000000000
05-08 17:33:13.301: I/DEBUG(10848):  d12 0000000000000000  d13 0000000000000000
05-08 17:33:13.301: I/DEBUG(10848):  d14 0000000000000000  d15 0000000000000000
05-08 17:33:13.301: I/DEBUG(10848):  d16 001464b800000001  d17 7e37e43c8800759c
05-08 17:33:13.301: I/DEBUG(10848):  d18 3fe0000000000000  d19 3fe0a3b5840f4000
05-08 17:33:13.301: I/DEBUG(10848):  d20 0000000000000000  d21 3f909fbf0fbe2b83
05-08 17:33:13.301: I/DEBUG(10848):  d22 3e21e4c221e25016  d23 bda8fae9be8838d4
05-08 17:33:13.301: I/DEBUG(10848):  d24 fbfbfbfbfbfbfbfb  d25 afafb0b0b0b0b1b2
05-08 17:33:13.301: I/DEBUG(10848):  d26 3131323232323233  d27 ffffffffffffffff
05-08 17:33:13.301: I/DEBUG(10848):  d28 0100010001000100  d29 3ff0000000000000
05-08 17:33:13.301: I/DEBUG(10848):  d30 0000000000000000  d31 3ff0000000000000
05-08 17:33:13.301: I/DEBUG(10848):  scr 20000012
05-08 17:33:14.152: I/DEBUG(10848):          #00  pc 0000660a  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:14.152: I/DEBUG(10848):          #01  pc 000069f4  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:14.152: I/DEBUG(10848):          #02  pc 00006c80  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:14.152: I/DEBUG(10848):          #03  pc 00005754  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:14.152: I/DEBUG(10848):          #04  pc 00006ca4  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:14.152: I/DEBUG(10848):          #05  pc 00010694  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:14.152: I/DEBUG(10848):          #06  pc 000066e2  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:14.160: I/DEBUG(10848):          #07  pc 00006726  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:14.160: I/DEBUG(10848):          #08  pc 00010596  /data/data/net.schattenkind.nativelove/lib/liblua.so (lua_pcall)
05-08 17:33:14.160: I/DEBUG(10848):          #09  pc 0004c70c  /data/data/net.schattenkind.nativelove/lib/liblove.so (_Z9main_stepv)
05-08 17:33:14.160: I/DEBUG(10848):          #10  pc 00047e24  /data/data/net.schattenkind.nativelove/lib/liblove.so (Java_net_schattenkind_nativelove_LoveJNI_step)
05-08 17:33:14.160: I/DEBUG(10848):          #11  pc 0001ec30  /system/lib/libdvm.so (dvmPlatformInvoke)
05-08 17:33:14.160: I/DEBUG(10848):          #12  pc 00058ee4  /system/lib/libdvm.so (_Z16dvmCallJNIMethodPKjP6JValuePK6MethodP6Thread)
05-08 17:33:14.160: I/DEBUG(10848): code around pc:
05-08 17:33:14.160: I/DEBUG(10848): 5c0cc5e8 000152be 0000016c aaaaaaab 2638b5f8  .R..l.........8&
05-08 17:33:14.160: I/DEBUG(10848): 5c0cc5f8 25025d83 422b1c04 6942d12f 3d181c15  .].%..+B/.Bi...=
05-08 17:33:14.160: I/DEBUG(10848): 5c0cc608 68536165 682a6910 68ea60e2 280061a2  eaSh.i*h.`.h.a.(
05-08 17:33:14.160: I/DEBUG(10848): 5c0cc618 68a2d013 d2134291 e0041c02 311068a5  ...h.B.......h.1
05-08 17:33:14.160: I/DEBUG(10848): 5c0cc628 428d3310 680dd90d 601d684e 688d605e  .3.B...hNh.`^`.h
05-08 17:33:14.160: I/DEBUG(10848): code around lr:
05-08 17:33:14.160: I/DEBUG(10848): 5c0cc9d8 1c206963 681b685b 4798691b 20021c01  ci .[h.h.i.G... 
05-08 17:33:14.160: I/DEBUG(10848): 5c0cc9e8 db062900 010968a3 1a591c20 fdfef7ff  .)...h.. .Y.....
05-08 17:33:14.160: I/DEBUG(10848): 5c0cc9f8 b0052001 4690bc3c 46a24699 bdf046ab  . ..<..F.F.F.F..
05-08 17:33:14.168: I/DEBUG(10848): 5c0cca08 21011c20 fd04f7ff e7ae68a3 224b691d   ..!.....h...iK"
05-08 17:33:14.168: I/DEBUG(10848): 5c0cca18 5ca968a3 010a69e0 42901ac0 e095dc00  .h.\.i.....B....
05-08 17:33:14.168: I/DEBUG(10848): stack:
05-08 17:33:14.168: I/DEBUG(10848):     5c302920  416b5360  /dev/ashmem/dalvik-heap (deleted)
05-08 17:33:14.168: I/DEBUG(10848):     5c302924  5c30293c  
05-08 17:33:14.168: I/DEBUG(10848):     5c302928  416b5360  /dev/ashmem/dalvik-heap (deleted)
05-08 17:33:14.168: I/DEBUG(10848):     5c30292c  5c1eaf5c  /data/data/net.schattenkind.nativelove/lib/liblove.so
05-08 17:33:14.168: I/DEBUG(10848):     5c302930  416b5360  /dev/ashmem/dalvik-heap (deleted)
05-08 17:33:14.168: I/DEBUG(10848):     5c302934  5c129f71  /data/data/net.schattenkind.nativelove/lib/liblove.so
05-08 17:33:14.168: I/DEBUG(10848):     5c302938  56bff360  /dev/ashmem/dalvik-LinearAlloc (deleted)
05-08 17:33:14.168: I/DEBUG(10848):     5c30293c  5c1a68a0  /data/data/net.schattenkind.nativelove/lib/liblove.so
05-08 17:33:14.168: I/DEBUG(10848):     5c302940  5c1eb798  
05-08 17:33:14.168: I/DEBUG(10848):     5c302944  5c302954  
05-08 17:33:14.168: I/DEBUG(10848):     5c302948  0018f348  [heap]
05-08 17:33:14.168: I/DEBUG(10848):     5c30294c  5c12f59f  /data/data/net.schattenkind.nativelove/lib/liblove.so
05-08 17:33:14.168: I/DEBUG(10848):     5c302950  00000004  
05-08 17:33:14.168: I/DEBUG(10848):     5c302954  5c1c7aec  /data/data/net.schattenkind.nativelove/lib/liblove.so
05-08 17:33:14.168: I/DEBUG(10848):     5c302958  df0027ad  
05-08 17:33:14.168: I/DEBUG(10848):     5c30295c  00000000  
05-08 17:33:14.168: I/DEBUG(10848): #00 5c302960  00000000  
05-08 17:33:14.168: I/DEBUG(10848):     5c302964  0018f348  [heap]
05-08 17:33:14.168: I/DEBUG(10848):     5c302968  00000000  
05-08 17:33:14.168: I/DEBUG(10848):     5c30296c  00000000  
05-08 17:33:14.168: I/DEBUG(10848):     5c302970  00000000  
05-08 17:33:14.168: I/DEBUG(10848):     5c302974  5c0cc9f9  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:14.168: I/DEBUG(10848): #01 5c302978  00000000  
05-08 17:33:14.168: I/DEBUG(10848):     5c30297c  00000030  
05-08 17:33:14.168: I/DEBUG(10848):     5c302980  00000003  
05-08 17:33:14.168: I/DEBUG(10848):     5c302984  5c0cc9f9  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:14.176: I/DEBUG(10848):     5c302988  5c302a10  
05-08 17:33:14.176: I/DEBUG(10848):     5c30298c  00282848  [heap]
05-08 17:33:14.176: I/DEBUG(10848):     5c302990  00000400  
05-08 17:33:14.176: I/DEBUG(10848):     5c302994  00000000  
05-08 17:33:14.176: I/DEBUG(10848):     5c302998  00000000  
05-08 17:33:14.176: I/DEBUG(10848):     5c30299c  0018f348  [heap]
05-08 17:33:14.176: I/DEBUG(10848):     5c3029a0  00000004  
05-08 17:33:14.176: I/DEBUG(10848):     5c3029a4  00282878  [heap]
05-08 17:33:14.176: I/DEBUG(10848):     5c3029a8  002828a8  [heap]
05-08 17:33:14.176: I/DEBUG(10848):     5c3029ac  5c0ccc85  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:33:15.738: W/InputDispatcher(13046): channel '43745228 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveLauncher (server)' ~ Consumer closed input channel or an error occurred.  events=0x8
05-08 17:33:15.738: E/InputDispatcher(13046): channel '43745228 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveLauncher (server)' ~ Channel is unrecoverably broken and will be disposed!
05-08 17:33:15.770: D/Zygote(13020): Process 12315 terminated by signal (11)
05-08 17:33:15.941: D/dalvikvm(13046): GC_FOR_ALLOC freed 1568K, 7% free 45968K/48903K, paused 211ms
05-08 17:33:15.949: W/InputDispatcher(13046): Attempted to unregister already unregistered input channel '43745228 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveLauncher (server)'
05-08 17:33:15.949: I/WindowManager(13046): WINDOW DIED Window{43745228 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveLauncher paused=false}
05-08 17:33:15.949: I/ActivityManager(13046): Process net.schattenkind.nativelove (pid 12315) has died.
05-08 17:33:15.949: W/ActivityManager(13046): Force removing ActivityRecord{436b8c28 net.schattenkind.nativelove/.LoveNative}: app died, no saved state
05-08 17:33:15.957: W/InputDispatcher(13046): channel '43748160 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveNative (server)' ~ Consumer closed input channel or an error occurred.  events=0x8
05-08 17:33:15.957: E/InputDispatcher(13046): channel '43748160 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveNative (server)' ~ Channel is unrecoverably broken and will be disposed!
05-08 17:33:15.957: W/InputDispatcher(13046): Attempted to unregister already unregistered input channel '43748160 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveNative (server)'
05-08 17:33:15.957: I/WindowManager(13046): WIN DEATH: Window{4376d888 SurfaceView paused=false}
05-08 17:33:15.957: I/WindowManager(13046): WIN DEATH: Window{43748160 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveNative paused=false}
05-08 17:33:15.957: W/WindowManager(13046): Failed looking up window
05-08 17:33:15.957: W/WindowManager(13046): java.lang.IllegalArgumentException: Requested window android.os.BinderProxy@42d36dc8 does not exist
05-08 17:33:15.957: W/WindowManager(13046): 	at com.android.server.wm.WindowManagerService.windowForClientLocked(WindowManagerService.java:7176)
05-08 17:33:15.957: W/WindowManager(13046): 	at com.android.server.wm.WindowManagerService.windowForClientLocked(WindowManagerService.java:7167)
05-08 17:33:15.957: W/WindowManager(13046): 	at com.android.server.wm.WindowState$DeathRecipient.binderDied(WindowState.java:1545)
05-08 17:33:15.957: W/WindowManager(13046): 	at android.os.BinderProxy.sendDeathNotice(Binder.java:417)
05-08 17:33:15.957: W/WindowManager(13046): 	at dalvik.system.NativeStart.run(Native Method)
05-08 17:33:15.957: I/WindowManager(13046): WIN DEATH: null
05-08 17:33:15.965: I/WindowManager(13046): WINDOW DIED Window{43748160 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveNative paused=false}
05-08 17:33:15.973: D/dalvikvm(12333): Late-enabling CheckJNI
05-08 17:33:15.973: I/ActivityManager(13046): Start proc net.schattenkind.nativelove for activity net.schattenkind.nativelove/.LoveLauncher: pid=12333 uid=10072 gids={3003, 1015}
05-08 17:33:15.988: I/dalvikvm(12333): Turning on JNI app bug workarounds for target SDK version 7...
05-08 17:33:16.129: W/InputManagerService(13046): Got RemoteException sending setActive(false) notification to pid 12315 uid 10072
05-08 17:33:16.176: I/ActivityManager(13046): Displayed net.schattenkind.nativelove/.LoveLauncher: +214ms
In short, the following main events occur
[+00.000] onMouseDown, deinit followed shortly afterwards.
[+00.805] C code crashed, signal 11 received.
[+03.774] Process killed with signal 11.
[+03.953] Process died, no saved state found for re-creation of Activity [LoveNative].
[+03.961] Win-Death between SurfaceView (found in LoveNative?) and LoveNative, SurfaceView is null so LoveNative is killed.
[+03.977] Process started again, Activity [LoveLauncher] started.

Logcat #2 - Modified version

Code: Select all

05-08 17:28:16.504: I/liblove(12315): onMouseDown
05-08 17:28:16.520: I/liblove(12062): deinit
05-08 17:28:16.543: I/liblove(12062): onMouseUp
05-08 17:28:16.785: A/libc(12062): Fatal signal 11 (SIGSEGV) at 0x00000005 (code=1)
05-08 17:28:17.090: I/DEBUG(10848): *** *** *** *** *** *** *** *** *** *** *** *** *** *** *** ***
05-08 17:28:17.090: I/DEBUG(10848): Build fingerprint: 'google/yakju/maguro:4.0.4/IMM76D/299849:user/release-keys'
05-08 17:28:17.090: I/DEBUG(10848): pid: 12062, tid: 12077  >>> net.schattenkind.nativelove <<<
05-08 17:28:17.090: I/DEBUG(10848): signal 11 (SIGSEGV), code 1 (SEGV_MAPERR), fault addr 00000005
05-08 17:28:17.090: I/DEBUG(10848):  r0 0021dca0  r1 fffffff0  r2 00000001  r3 00000000
05-08 17:28:17.090: I/DEBUG(10848):  r4 0021dca0  r5 ffffffe9  r6 00000038  r7 00000000
05-08 17:28:17.090: I/DEBUG(10848):  r8 00000050  r9 00000400  10 00000004  fp 00000000
05-08 17:28:17.090: I/DEBUG(10848):  ip 0026df58  sp 5c406960  lr 5c1d09f9  pc 5c1d060a  cpsr 800f0030
05-08 17:28:17.090: I/DEBUG(10848):  d0  0000000000000030  d1  00000000000000d0
05-08 17:28:17.090: I/DEBUG(10848):  d2  000000080000031a  d3  5b1ad0280000005b
05-08 17:28:17.090: I/DEBUG(10848):  d4  0000cb5400000001  d5  0000000000000000
05-08 17:28:17.090: I/DEBUG(10848):  d6  0000000000000000  d7  0000000000000000
05-08 17:28:17.090: I/DEBUG(10848):  d8  3f8000003f800000  d9  0000000000000000
05-08 17:28:17.090: I/DEBUG(10848):  d10 0000000000000000  d11 0000000000000000
05-08 17:28:17.090: I/DEBUG(10848):  d12 0000000000000000  d13 0000000000000000
05-08 17:28:17.090: I/DEBUG(10848):  d14 0000000000000000  d15 0000000000000000
05-08 17:28:17.090: I/DEBUG(10848):  d16 5b1ad02800000001  d17 7e37e43c8800759c
05-08 17:28:17.090: I/DEBUG(10848):  d18 3fe0000000000000  d19 3fe0a3b5840f4000
05-08 17:28:17.090: I/DEBUG(10848):  d20 0000000000000000  d21 3f909fbf0fbe2b83
05-08 17:28:17.090: I/DEBUG(10848):  d22 3e21e4c221e25016  d23 bda8fae9be8838d4
05-08 17:28:17.090: I/DEBUG(10848):  d24 fbfbfbfbfbfbfbfb  d25 afafb0b0b0b0b1b2
05-08 17:28:17.090: I/DEBUG(10848):  d26 3131323232323233  d27 ffffffffffffffff
05-08 17:28:17.090: I/DEBUG(10848):  d28 0100010001000100  d29 3ff0000000000000
05-08 17:28:17.090: I/DEBUG(10848):  d30 0000000000000000  d31 3ff0000000000000
05-08 17:28:17.090: I/DEBUG(10848):  scr 20000012
05-08 17:28:17.215: I/DEBUG(10848):          #00  pc 0000660a  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:17.215: I/DEBUG(10848):          #01  pc 000069f4  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:17.215: I/DEBUG(10848):          #02  pc 00006c80  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:17.215: I/DEBUG(10848):          #03  pc 00005754  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:17.215: I/DEBUG(10848):          #04  pc 00006ca4  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:17.215: I/DEBUG(10848):          #05  pc 00010694  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:17.215: I/DEBUG(10848):          #06  pc 000066e2  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:17.215: I/DEBUG(10848):          #07  pc 00006726  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:17.215: I/DEBUG(10848):          #08  pc 00010596  /data/data/net.schattenkind.nativelove/lib/liblua.so (lua_pcall)
05-08 17:28:17.215: I/DEBUG(10848):          #09  pc 0004c70c  /data/data/net.schattenkind.nativelove/lib/liblove.so (_Z9main_stepv)
05-08 17:28:17.215: I/DEBUG(10848):          #10  pc 00047e24  /data/data/net.schattenkind.nativelove/lib/liblove.so (Java_net_schattenkind_nativelove_LoveJNI_step)
05-08 17:28:17.215: I/DEBUG(10848):          #11  pc 0001ec30  /system/lib/libdvm.so (dvmPlatformInvoke)
05-08 17:28:17.223: I/DEBUG(10848):          #12  pc 00058ee4  /system/lib/libdvm.so (_Z16dvmCallJNIMethodPKjP6JValuePK6MethodP6Thread)
05-08 17:28:17.223: I/DEBUG(10848): code around pc:
05-08 17:28:17.223: I/DEBUG(10848): 5c1d05e8 000152be 0000016c aaaaaaab 2638b5f8  .R..l.........8&
05-08 17:28:17.223: I/DEBUG(10848): 5c1d05f8 25025d83 422b1c04 6942d12f 3d181c15  .].%..+B/.Bi...=
05-08 17:28:17.223: I/DEBUG(10848): 5c1d0608 68536165 682a6910 68ea60e2 280061a2  eaSh.i*h.`.h.a.(
05-08 17:28:17.223: I/DEBUG(10848): 5c1d0618 68a2d013 d2134291 e0041c02 311068a5  ...h.B.......h.1
05-08 17:28:17.223: I/DEBUG(10848): 5c1d0628 428d3310 680dd90d 601d684e 688d605e  .3.B...hNh.`^`.h
05-08 17:28:17.223: I/DEBUG(10848): code around lr:
05-08 17:28:17.223: I/DEBUG(10848): 5c1d09d8 1c206963 681b685b 4798691b 20021c01  ci .[h.h.i.G... 
05-08 17:28:17.223: I/DEBUG(10848): 5c1d09e8 db062900 010968a3 1a591c20 fdfef7ff  .)...h.. .Y.....
05-08 17:28:17.223: I/DEBUG(10848): 5c1d09f8 b0052001 4690bc3c 46a24699 bdf046ab  . ..<..F.F.F.F..
05-08 17:28:17.223: I/DEBUG(10848): 5c1d0a08 21011c20 fd04f7ff e7ae68a3 224b691d   ..!.....h...iK"
05-08 17:28:17.223: I/DEBUG(10848): 5c1d0a18 5ca968a3 010a69e0 42901ac0 e095dc00  .h.\.i.....B....
05-08 17:28:17.223: I/DEBUG(10848): stack:
05-08 17:28:17.223: I/DEBUG(10848):     5c406920  416b5610  /dev/ashmem/dalvik-heap (deleted)
05-08 17:28:17.223: I/DEBUG(10848):     5c406924  5c40693c  
05-08 17:28:17.223: I/DEBUG(10848):     5c406928  416b5610  /dev/ashmem/dalvik-heap (deleted)
05-08 17:28:17.223: I/DEBUG(10848):     5c40692c  5c2eef5c  /data/data/net.schattenkind.nativelove/lib/liblove.so
05-08 17:28:17.223: I/DEBUG(10848):     5c406930  416b5610  /dev/ashmem/dalvik-heap (deleted)
05-08 17:28:17.223: I/DEBUG(10848):     5c406934  5c22df71  /data/data/net.schattenkind.nativelove/lib/liblove.so
05-08 17:28:17.223: I/DEBUG(10848):     5c406938  56bff398  /dev/ashmem/dalvik-LinearAlloc (deleted)
05-08 17:28:17.223: I/DEBUG(10848):     5c40693c  5c2aa8a0  /data/data/net.schattenkind.nativelove/lib/liblove.so
05-08 17:28:17.223: I/DEBUG(10848):     5c406940  5c2ef798  
05-08 17:28:17.223: I/DEBUG(10848):     5c406944  5c406954  
05-08 17:28:17.223: I/DEBUG(10848):     5c406948  0021dca0  [heap]
05-08 17:28:17.223: I/DEBUG(10848):     5c40694c  5c23359f  /data/data/net.schattenkind.nativelove/lib/liblove.so
05-08 17:28:17.223: I/DEBUG(10848):     5c406950  00000004  
05-08 17:28:17.223: I/DEBUG(10848):     5c406954  5c2cbaec  /data/data/net.schattenkind.nativelove/lib/liblove.so
05-08 17:28:17.223: I/DEBUG(10848):     5c406958  df0027ad  
05-08 17:28:17.223: I/DEBUG(10848):     5c40695c  00000000  
05-08 17:28:17.223: I/DEBUG(10848): #00 5c406960  00000000  
05-08 17:28:17.223: I/DEBUG(10848):     5c406964  0021dca0  [heap]
05-08 17:28:17.223: I/DEBUG(10848):     5c406968  00000000  
05-08 17:28:17.223: I/DEBUG(10848):     5c40696c  00000000  
05-08 17:28:17.223: I/DEBUG(10848):     5c406970  00000000  
05-08 17:28:17.223: I/DEBUG(10848):     5c406974  5c1d09f9  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:17.223: I/DEBUG(10848): #01 5c406978  00000000  
05-08 17:28:17.223: I/DEBUG(10848):     5c40697c  00000030  
05-08 17:28:17.223: I/DEBUG(10848):     5c406980  00000003  
05-08 17:28:17.223: I/DEBUG(10848):     5c406984  5c1d09f9  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:17.223: I/DEBUG(10848):     5c406988  5c406a10  
05-08 17:28:17.223: I/DEBUG(10848):     5c40698c  0027db50  [heap]
05-08 17:28:17.223: I/DEBUG(10848):     5c406990  00000400  
05-08 17:28:17.223: I/DEBUG(10848):     5c406994  00000000  
05-08 17:28:17.223: I/DEBUG(10848):     5c406998  00000000  
05-08 17:28:17.223: I/DEBUG(10848):     5c40699c  0021dca0  [heap]
05-08 17:28:17.223: I/DEBUG(10848):     5c4069a0  00000004  
05-08 17:28:17.223: I/DEBUG(10848):     5c4069a4  0027db80  [heap]
05-08 17:28:17.223: I/DEBUG(10848):     5c4069a8  0027dbb0  [heap]
05-08 17:28:17.223: I/DEBUG(10848):     5c4069ac  5c1d0c85  /data/data/net.schattenkind.nativelove/lib/liblua.so
05-08 17:28:18.918: W/InputDispatcher(13046): channel '437f75b8 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveLauncher (server)' ~ Consumer closed input channel or an error occurred.  events=0x8
05-08 17:28:18.918: E/InputDispatcher(13046): channel '437f75b8 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveLauncher (server)' ~ Channel is unrecoverably broken and will be disposed!
05-08 17:28:18.949: D/Zygote(13020): Process 12062 terminated by signal (11)
05-08 17:28:19.105: D/dalvikvm(13046): GC_FOR_ALLOC freed 691K, 6% free 46071K/48903K, paused 200ms
05-08 17:28:19.105: I/WindowManager(13046): WIN DEATH: Window{438815e0 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveNative paused=false}
05-08 17:28:19.105: I/ActivityManager(13046): Process net.schattenkind.nativelove (pid 12062) has died.
05-08 17:28:19.105: W/WindowManager(13046): Force-removing child win Window{428e6268 SurfaceView paused=false} from container Window{438815e0 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveNative paused=false}
05-08 17:28:19.113: W/InputDispatcher(13046): Attempted to unregister already unregistered input channel '437f75b8 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveLauncher (server)'
05-08 17:28:19.113: I/WindowManager(13046): WIN DEATH: Window{437f75b8 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveLauncher paused=false}
05-08 17:28:19.113: W/WindowManager(13046): Failed looking up window
05-08 17:28:19.113: W/WindowManager(13046): java.lang.IllegalArgumentException: Requested window android.os.BinderProxy@42c4a9c0 does not exist
05-08 17:28:19.113: W/WindowManager(13046): 	at com.android.server.wm.WindowManagerService.windowForClientLocked(WindowManagerService.java:7176)
05-08 17:28:19.113: W/WindowManager(13046): 	at com.android.server.wm.WindowManagerService.windowForClientLocked(WindowManagerService.java:7167)
05-08 17:28:19.113: W/WindowManager(13046): 	at com.android.server.wm.WindowState$DeathRecipient.binderDied(WindowState.java:1545)
05-08 17:28:19.113: W/WindowManager(13046): 	at android.os.BinderProxy.sendDeathNotice(Binder.java:417)
05-08 17:28:19.113: W/WindowManager(13046): 	at dalvik.system.NativeStart.run(Native Method)
05-08 17:28:19.113: I/WindowManager(13046): WIN DEATH: null
05-08 17:28:19.113: I/WindowManager(13046): WINDOW DIED Window{437f75b8 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveLauncher paused=false}
05-08 17:28:19.113: W/ActivityManager(13046): Force removing ActivityRecord{42900dc8 net.schattenkind.nativelove/.LoveLauncher}: app died, no saved state
These main events are a little different, be sure to take an extra look at the time differences.
[+00.000] onMouseDown, deinit followed shortly afterwards.
[+00.281] C code crashed, signal 11 received.
[+02.544] Process killed with signal 11.
[+02.700] Process died. SurfaceView force-removed from LoveNative Window.
[+02.718] Win-Death between LoveNative and LoveLauncher, LoveNative is null (as it was finished before? Time of termination is unknown) and LoveLauncher is killed.
[+02.718] Re-starting of Activity [LoveLauncher] cancelled, with the same reason as the other logcat (event #4), Activity killed without instance state saved.

(Important notice)
Both of the logcat contains information about an InputDispatcher channel being closed by the consumer. According to this e-mail by Dianne Hackborn (a very respected developer of Android), this means that the consumer in someway closed the channel, or that it was closed from the result of some crash.
This message only appears once on the logcat for the modified version (#2, for LoveLauncher only), while it appears twice on the logcat for the Github version (#1, for both LoveLauncher and LoveNative). Could this be the result from the modified version finishing LoveNative itself and doesn't rely on the crash (?!!) for closing the app? (The first channel message seems quite unimportant at the moment as it is called *before* the process dies.)

While comparing these logcats, you can clearly see that the one representing the modified one does its thing more or less a second faster than the Github one. This could be explained by the Activity [LoveNative] being finished using code instead of closing itself because of a crash. This hypothesis is however not proved in a fair way and the longer execution time could just as well be a placebo. What is more interesting is what Activity is affected by the crash. When LoveNative exists, it is closed, but when it doesn't, the LoveLauncher is closed. Another interesting (but not really surprising) thing is that the SurfaceView is removed first when the Activity [LoveNative] is shut down is some way or another.

The last thing that is of use in these logcats are the message W/ActivityManager(13046): Force removing ActivityRecord{Activity details}: app died, no saved state. This basically means that the Bundle which is being referred to in onCreate was never created, most probably because the Activity crashed instead of being shut down correctly. While this could seem like a problem, it is also a source for a faster (just 20 - 30 ms or something) and semi-functional quick-fix (read "quick-fix", this is by far not a permanent solution, more about this further down in the post).

Section 2 - Summary
While there is some read-worthy text up ahead, a short summary is always good. So, I will try to keep it short here.
  • There is something in the C code that crashes, no doubt about that.
  • This crash results in some active Activity being killed afterwards.
  • The activity killing gives a fake impression that the LoveLauncher activity is being finished with my proposed removal of the comments (Which I suppose was what you meant in the first place, Moe. That it closes down the LoveLauncher indirectly using that?).
  • There is a (temporary) solution available.
Section 3 - The Solution
Even if the fix of this problem will require some C "hacking" sooner or later, there is a little solution you can utilize. This solution does remove the delay between love.event.push('q') and the termination of the LoveNative activity, it will however cause a little delay later (hey, no temporary solution is perfect ;) ). To make it clearer, the solution is just moving one problem to another place, causing LoveLauncher to be "crashed" instead of LoveNative.

The solution involves ignoring the saved state bundle, and is fixed by adding one line to the AndroidManifest.xml. This line will tell the app to never require this saved state for the LoveLauncher activity and therefore make a normal startup a little quicker. When love.event.push('q') is later called, the LoveNative will exit right away from the .finish() inside LoveJNI.java (which has been un-commented). A little later, the LoveLauncher activity "crashes", but is started right away as there is no (at this point non-existent) saved state required.

Implementing the solution
  • Un-comment the code (obviously) inside of exitLove() in LoveJNI.java.
  • Add the android:stateNotNeeded="true" to the LoveLauncher activity tag in AndroidManifest.xml.

    Code: Select all

            <activity
                android:theme="@android:style/Theme.NoTitleBar"
                android:label="LÖVE"
                android:stateNotNeeded="true" <!-- Line to be added -->
                android:name="LoveLauncher">
                <intent-filter >
                    <action android:name="android.intent.action.MAIN" />
    
                    <category android:name="android.intent.category.LAUNCHER" />
                </intent-filter>
            </activity>
    
Please don't commit this solution to Github as it is a quite ugly fix and does not fight the problem but walk around it.

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

Anyway, this should be it. It didn't turn out to be that short comment you talked about Moe ^^ (I will also make sure to test things before I start arguing about what does what and what does not next time). I've tried to include everything important in this analysis and I hope it might clear some things up. Feel free to ask me of anything I talked about, or if you need to make me clarify anything (I didn't have time to check the text at the moment). ;)
Moe
Party member
Posts: 115
Joined: Thu Dec 22, 2011 10:20 pm

Re: In a nutshell, what's not working actually ?

Post by Moe »

Thank you for all this detailed bug report. I hope that I will have some time this week-end to analyse this in detail, I cannot see on the first sight what is causing the problem - maybe I did not see the issues because I tested on a fast device only. More on that when I investigated this in detail, for now I can guess only.
kalle2990 wrote: Dianne Hackborn (a very respected developer of Android)
I know her, she loved to tell me that my ideas to change/extend Android are stupid, because my last employer did not allow me to post more details than necessary. :D
User avatar
T-Bone
Inner party member
Posts: 1492
Joined: Thu Jun 09, 2011 9:03 am

Re: In a nutshell, what's not working actually ?

Post by T-Bone »

I can also mention that the device I tested on was a HTC Desire, a pretty slow phone by today's standards.
User avatar
T-Bone
Inner party member
Posts: 1492
Joined: Thu Jun 09, 2011 9:03 am

Re: In a nutshell, what's not working actually ?

Post by T-Bone »

Another thing I just remembered. There is another hugely important feature missing right now, and that is writing/reading files with love.filesystem. Logcat says that "E/liblove (28473): [string "writesettings.lua"]:47: Could not set write directory."

So all in all, my game requires
1. Saving/loading
2. Multitouch
3. Being able to leave the app and return (or some workaround)
User avatar
hagish
Citizen
Posts: 85
Joined: Thu May 01, 2008 12:51 pm
Contact:

Re: In a nutshell, what's not working actually ?

Post by hagish »

Moe and I meet and worked on the port.

Changes:
* TTF font rendering works
* some not so important fixes (eg. launcher crashed without sdcard)
* Changing the orientation works, but currently there is no notification
* Leaving and returning to the app works (not very elegant but working :) )

Known issues:
* leaving a game, returning to the game launcher and starting a game again crashes most of the times. You need to restart the whole app.
User avatar
T-Bone
Inner party member
Posts: 1492
Joined: Thu Jun 09, 2011 9:03 am

Re: In a nutshell, what's not working actually ?

Post by T-Bone »

hagish wrote:Moe and I meet and worked on the port.

Changes:
* TTF font rendering works
* some not so important fixes (eg. launcher crashed without sdcard)
* Changing the orientation works, but currently there is no notification
* Leaving and returning to the app works (not very elegant but working :) )

Known issues:
* leaving a game, returning to the game launcher and starting a game again crashes most of the times. You need to restart the whole app.
That is AWESOME! Now, only two more things to go :neko:
User avatar
T-Bone
Inner party member
Posts: 1492
Joined: Thu Jun 09, 2011 9:03 am

Re: In a nutshell, what's not working actually ?

Post by T-Bone »

I downloaded the latest version, compiled it, and it crashes when I try to open a .love. The only thing I changed in the source code was to force landscape orientation, everything else was left as it was. My phone is an HTC Desire, running CM7.1.

Here's logcat:

Code: Select all

W/InputManagerService(  165): Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@408961a0
I/ActivityManager(  165): Starting: Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=net.schattenkind.nativelove/.LoveLauncher bnds=[5,586][115,704] } from pid 259
I/ActivityManager(  165): Start proc net.schattenkind.nativelove for activity net.schattenkind.nativelove/.LoveLauncher: pid=1890 uid=10060 gids={3003, 1015}
D/szipinf ( 1890): Initializing inflate state
D/dalvikvm( 1890): GC_EXTERNAL_ALLOC freed 50K, 51% free 2688K/5379K, external 0K/0K, paused 42ms
D/dalvikvm( 1890): GC_EXTERNAL_ALLOC freed 10K, 50% free 2712K/5379K, external 498K/513K, paused 74ms
I/ActivityManager(  165): Displayed net.schattenkind.nativelove/.LoveLauncher: +386ms (total +1m43s184ms)
I/ActivityManager(  165): Starting: Intent { dat=file:///mnt/sdcard/Android/data/com.dropbox.android/files/scratch/lua/loopbox/loopbox.love flg=0x10000000 cmp=net.schattenkind.nativelove/.LoveNative } from pid 1890
I/WindowManager(  165): Setting rotation to 1, animFlags=1
I/ActivityManager(  165): Config changed: { scale=1.05 imsi=240/8 loc=sv_SE touch=3 keys=1/1/2 nav=3/1 orien=2 layout=34 uiMode=17 seq=12 themeResource=null}
W/dalvikvm( 1890): Exception Ljava/lang/UnsatisfiedLinkError; thrown while initializing Lnet/schattenkind/nativelove/LoveJNI;
D/AndroidRuntime( 1890): Shutting down VM
W/dalvikvm( 1890): threadid=1: thread exiting with uncaught exception (group=0x401b7560)
E/AndroidRuntime( 1890): FATAL EXCEPTION: main
E/AndroidRuntime( 1890): java.lang.ExceptionInInitializerError
E/AndroidRuntime( 1890): 	at net.schattenkind.nativelove.LoveNative.onCreate(LoveNative.java:40)
E/AndroidRuntime( 1890): 	at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1047)
E/AndroidRuntime( 1890): 	at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:1722)
E/AndroidRuntime( 1890): 	at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:1784)
E/AndroidRuntime( 1890): 	at android.app.ActivityThread.access$1500(ActivityThread.java:123)
E/AndroidRuntime( 1890): 	at android.app.ActivityThread$H.handleMessage(ActivityThread.java:939)
E/AndroidRuntime( 1890): 	at android.os.Handler.dispatchMessage(Handler.java:99)
E/AndroidRuntime( 1890): 	at android.os.Looper.loop(Looper.java:130)
E/AndroidRuntime( 1890): 	at android.app.ActivityThread.main(ActivityThread.java:3835)
E/AndroidRuntime( 1890): 	at java.lang.reflect.Method.invokeNative(Native Method)
E/AndroidRuntime( 1890): 	at java.lang.reflect.Method.invoke(Method.java:507)
E/AndroidRuntime( 1890): 	at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:847)
E/AndroidRuntime( 1890): 	at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:605)
E/AndroidRuntime( 1890): 	at dalvik.system.NativeStart.main(Native Method)
E/AndroidRuntime( 1890): Caused by: java.lang.UnsatisfiedLinkError: Couldn't load ogg: findLibrary returned null
E/AndroidRuntime( 1890): 	at java.lang.Runtime.loadLibrary(Runtime.java:429)
E/AndroidRuntime( 1890): 	at java.lang.System.loadLibrary(System.java:554)
E/AndroidRuntime( 1890): 	at net.schattenkind.nativelove.LoveJNI.<clinit>(LoveJNI.java:9)
E/AndroidRuntime( 1890): 	... 14 more
I/PowerWidget(  242): Clearing any old widget stuffs
W/ActivityManager(  165):   Force finishing activity net.schattenkind.nativelove/.LoveNative
W/ActivityManager(  165):   Force finishing activity net.schattenkind.nativelove/.LoveLauncher
I/WindowManager(  165): Setting rotation to 0, animFlags=1
I/ActivityManager(  165): Config changed: { scale=1.05 imsi=240/8 loc=sv_SE touch=3 keys=1/1/2 nav=3/1 orien=1 layout=34 uiMode=17 seq=13 themeResource=null}
I/PowerWidget(  242): Setting up widget
I/PowerWidget(  242): Button list: toggleWifi|toggleGPS|toggleAutoRotate|toggleSound
I/PowerWidget(  242): Setting up button: toggleWifi
I/PowerWidget(  242): Setting up button: toggleGPS
I/PowerWidget(  242): Setting up button: toggleAutoRotate
I/PowerWidget(  242): Setting up button: toggleSound
I/PowerWidget(  242): Clearing any old widget stuffs
I/PowerWidget(  242): Setting up widget
I/PowerWidget(  242): Button list: toggleWifi|toggleGPS|toggleAutoRotate|toggleSound
I/PowerWidget(  242): Setting up button: toggleWifi
I/PowerWidget(  242): Setting up button: toggleGPS
I/PowerWidget(  242): Setting up button: toggleAutoRotate
I/PowerWidget(  242): Setting up button: toggleSound
D/dalvikvm(  242): GC_CONCURRENT freed 535K, 50% free 3158K/6215K, external 1549K/1860K, paused 3ms+4ms
W/ActivityManager(  165): Activity pause timeout for HistoryRecord{4096ed88 net.schattenkind.nativelove/.LoveNative}
W/InputConnectionWrapper.ICC(  270): Timed out waiting on IInputContextCallback
D/dalvikvm(  165): GC_EXPLICIT freed 831K, 41% free 6797K/11399K, external 4626K/5719K, paused 115ms
I/Process ( 1890): Sending signal. PID: 1890 SIG: 9
I/ActivityManager(  165): Process net.schattenkind.nativelove (pid 1890) has died.
I/WindowManager(  165): WIN DEATH: Window{409b4338 net.schattenkind.nativelove/net.schattenkind.nativelove.LoveLauncher paused=true}
^C
t-bone@tbonesdumburk:~/android/platform-tools$ 

The "no game" screen causes the same error.

EDIT: Can now confirm that the same error appears even if I compile the source completely untouched.
User avatar
hagish
Citizen
Posts: 85
Joined: Thu May 01, 2008 12:51 pm
Contact:

Re: In a nutshell, what's not working actually ?

Post by hagish »

Could you try the version without audio? Try removing you ogg playback:

-> Caused by: java.lang.UnsatisfiedLinkError: Couldn't load ogg: findLibrary returned null

Still crashing then?
Moe
Party member
Posts: 115
Joined: Thu Dec 22, 2011 10:20 pm

Re: In a nutshell, what's not working actually ?

Post by Moe »

This sounds actually like the build failed somewhere. Unfortunately, Eclipse continues to build the apk in error cases. If libogg is not within the love-apk, something went terribly wrong.
Please try to delete the apk in Eclipse and see if it works after refreshing/reinstall (so that it is rebuild).
If it still does not work, can you please provide the build log? Or try to launch the ndk-build from console and check the output?
User avatar
T-Bone
Inner party member
Posts: 1492
Joined: Thu Jun 09, 2011 9:03 am

Re: In a nutshell, what's not working actually ?

Post by T-Bone »

I tried to recompile it, same result. I then tried to compile in terminal, got this output

Code: Select all

t-bone@tbonesdumburk:~/android/lol/hagish-love-native-android-4bba952$ sudo ~/android/android-ndk-r8/ndk-build 
Compile thumb  : devil <= il_sun.c
jni/devil-1.7.8/src-IL/src/il_sun.c:15:25: error: il_internal.h: No such file or directory
jni/devil-1.7.8/src-IL/src/il_sun.c:17:21: error: il_bits.h: No such file or directory
jni/devil-1.7.8/src-IL/src/il_sun.c:19: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'iLoadSunInternal'
jni/devil-1.7.8/src-IL/src/il_sun.c:20: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'iIsValidSun'
jni/devil-1.7.8/src-IL/src/il_sun.c:21: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'iSunGetRle'
jni/devil-1.7.8/src-IL/src/il_sun.c:25: error: expected specifier-qualifier-list before 'ILuint'
jni/devil-1.7.8/src-IL/src/il_sun.c:51: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'ilIsValidSun'
jni/devil-1.7.8/src-IL/src/il_sun.c:78: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'ilIsValidSunF'
jni/devil-1.7.8/src-IL/src/il_sun.c:93: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'ilIsValidSunL'
jni/devil-1.7.8/src-IL/src/il_sun.c:101: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'iGetSunHead'
jni/devil-1.7.8/src-IL/src/il_sun.c:117: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'iCheckSun'
jni/devil-1.7.8/src-IL/src/il_sun.c:142: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'iIsValidSun'
jni/devil-1.7.8/src-IL/src/il_sun.c:155: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'ilLoadSun'
jni/devil-1.7.8/src-IL/src/il_sun.c:177: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'ilLoadSunF'
jni/devil-1.7.8/src-IL/src/il_sun.c:192: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'ilLoadSunL'
jni/devil-1.7.8/src-IL/src/il_sun.c:199: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'iLoadSunInternal'
jni/devil-1.7.8/src-IL/src/il_sun.c:362: error: expected '=', ',', ';', 'asm' or '__attribute__' before 'iSunGetRle'
make: *** [obj/local/armeabi/objs/devil/devil-1.7.8/src-IL/src/il_sun.o] Error 1

Locked

Who is online

Users browsing this forum: No registered users and 19 guests