Bug: app terminates while database communiction

Dataverde · Aug 8, 2011

Hey there,

My app terminates without any kind of errormessage while updating the sqlite db. it worked well before the 1.6 Update.

As you can see in the log of the working version the file witch is read is huge, about 200.000 lines of sqlite statements.

The not working version has the warning
"HeapWorker may be wedged: 5244ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V"
and the error
"HeapWorker is wedged: 10394ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V"
in it, so i assume there is something like a timout kicking me out of the sqlite service.

hopfully anyone knows what to do on this issue.

Thanks for your time,

Lennart

My code:

B4X:

Sub updatePart(filepath As String, filename As String)
      Dim List1 As List
Log("vor read list")
      Dim tmpline As String
      Dim txtReader As TextReader
      
      txtReader.Initialize(File.OpenInput(filepath, filename))
      
Log("nach read list")
Log("sqlUpdate:")
      SQL.Database.BeginTransaction
Log("nach begin transaction")
      i = 0
      errors = 0
      tmpline = txtReader.ReadLine
   
      Do While tmpline <> Null
         i = i+1
         
         If Not (SQL.runStatement(tmpline)) Then 
            Log("SqlError on: "&tmpline)
            errors = errors + 1
            label3.text = errors
            label3.Invalidate
            DoEvents
         End If

         If i Mod 1000 = 0 Then 
            Log(i)
            label2.Text = i
            label2.Invalidate
            DoEvents
         End If
         tmpline = txtReader.ReadLine
      Loop
      txtreader.Close

Log("vor trans successful")
      SQL.Database.TransactionSuccessful
Log("vor en transaction")
      SQL.Database.EndTransaction
Log("delete")
      If File.Delete(filepath,filename) Then
         Log("file deleted: "&filename)
      Else
         Log("file delete failed: "&filename)
      End If
End Sub

The Logs from when the crash happens:

B4X:

Starting activity: Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=dv.quatCount/.main bnds=[125,446][235,564] }
setHidden false
setHidden false
Start proc dv.quatCount for activity dv.quatCount/.main: pid=3701 uid=10088 gids={1015}
Debugger thread not active, ignoring DDM send (t=0x41504e4d l=38)
********************** resuming: 1167012576
setHidden false
setHidden false
setHidden false
Debugger thread not active, ignoring DDM send (t=0x41504e4d l=28)
setHidden false
Trying to load lib lib_glossary.so 0x0
Unable to dlopen(lib_glossary.so): Cannot load library: load_library[1051]: Library 'lib_glossary.so' not found
lib_glossary.so can't be loaded
** Activity (main) Create, isFirst = true **
Density: 1.5
Main: Hey there app started
checkfiles:
GC freed 7215 objects / 336120 bytes in 55ms
setHidden false
LBA
2
false
true
true
/dataverdeExport
false
false
9
setHidden false
Displayed activity dv.quatCount/.main: 496 ms (total 496 ms)
setHidden false
setHidden false
log level set to 0
enqueueToast pkg=dv.quatCount callback=android.app.ITransientNotification$Stub$Proxy@457cc4b0 duration=1
** Activity (main) Resume **
setHidden false
setHidden false
setHidden false
Can't open keycharmap file
Error loading keycharmap file '/system/usr/keychars/qtouch-touchscreen.kcm.bin'. hw.keyboards.65538.devname='qtouch-touchscreen'
Using default keymap: /system/usr/keychars/qwerty.kcm.bin
setHidden false
setHidden false
update menu button clicked
vor read list
nach read list
sqlUpdate:
nach begin transaction
GC freed 169 objects / 11872 bytes in 87ms
GC freed 8602 objects / 504304 bytes in 39ms
1000
setHidden false
GC freed 13823 objects / 914408 bytes in 63ms
setHidden false
2000
Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@457b3890
setHidden false
GC freed 12996 objects / 877232 bytes in 51ms
setHidden false
GC freed 13047 objects / 878360 bytes in 39ms
3000
GC freed 9390 objects / 454896 bytes in 63ms
GC freed 13032 objects / 877576 bytes in 40ms
4000
GC freed 12894 objects / 877728 bytes in 39ms
5000
GC freed 12952 objects / 877280 bytes in 39ms
6000
GC freed 12809 objects / 878416 bytes in 39ms
7000
GC freed 13008 objects / 877336 bytes in 40ms
8000
GC freed 12907 objects / 877112 bytes in 40ms
9000
HeapWorker may be wedged: 5244ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 12894 objects / 878632 bytes in 39ms
10000
HeapWorker may be wedged: 5748ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 12934 objects / 877288 bytes in 39ms
11000
HeapWorker may be wedged: 6260ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 12866 objects / 878400 bytes in 40ms
12000
HeapWorker may be wedged: 6762ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 12847 objects / 877224 bytes in 40ms
GC freed 1395 objects / 71664 bytes in 60ms
HeapWorker may be wedged: 7332ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 12971 objects / 878568 bytes in 39ms
13000
HeapWorker may be wedged: 7840ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 12835 objects / 877600 bytes in 39ms
14000
HeapWorker may be wedged: 8348ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 12727 objects / 877160 bytes in 39ms
15000
HeapWorker may be wedged: 8851ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 12795 objects / 878080 bytes in 39ms
16000
HeapWorker may be wedged: 9371ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 12764 objects / 878608 bytes in 40ms
17000
HeapWorker may be wedged: 9879ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 12797 objects / 877152 bytes in 39ms
18000
HeapWorker is wedged: 10394ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
DALVIK THREADS:
"main" prio=5 tid=3 RUNNABLE
  | group="main" sCount=0 dsCount=0 s=N obj=0x4001e3c0 self=0xbe78
  | sysTid=3701 nice=0 sched=0/0 cgrp=unknown handle=-1343993120
  at org.apache.harmony.luni.util.NumberConverter.<init>(NumberConverter.java:~27)
  at org.apache.harmony.luni.util.NumberConverter.getConverter(NumberConverter.java:45)
  at org.apache.harmony.luni.util.NumberConverter.convert(NumberConverter.java:49)
  at java.lang.Double.toString(Double.java:308)
  at anywheresoftware.b4a.BA.NumberToString(BA.java:363)
  at dv.quatCount.main._updatepart(main.java:1254)
  at dv.quatCount.main._preformupdate(main.java:674)
  at dv.quatCount.main._dbupdate_click(main.java:451)
  at java.lang.reflect.Method.invokeNative(Native Method)
  at java.lang.reflect.Method.invoke(Method.java:521)
  at anywheresoftware.b4a.BA.raiseEvent2(BA.java:105)
  at anywheresoftware.b4a.BA.raiseEvent(BA.java:89)
  at dv.quatCount.main$B4AMenuItemsClickListener.onMenuItemClick(main.java:117)
  at com.android.internal.view.menu.MenuItemImpl.invoke(MenuItemImpl.java:133)
  at com.android.internal.view.menu.MenuBuilder.performItemAction(MenuBuilder.java:855)
  at com.android.internal.view.menu.IconMenuView.invokeItem(IconMenuView.java:529)
  at com.android.internal.view.menu.IconMenuItemView.performClick(IconMenuItemView.java:138)
  at android.view.View.onTouchEvent(View.java:4270)
  at android.widget.TextView.onTouchEvent(TextView.java:6784)
  at com.android.internal.view.menu.IconMenuItemView.onTouchEvent(IconMenuItemView.java:338)
  at android.view.View.dispatchTouchEvent(View.java:3796)
  at android.view.ViewGroup.dispatchTouchEvent(ViewGroup.java:888)
  at android.view.ViewGroup.dispatchTouchEvent(ViewGroup.java:888)
  at com.android.internal.policy.impl.PhoneWindow$DecorView.dispatchTouchEvent(PhoneWindow.java:1657)
  at android.view.ViewRoot.handleMessage(ViewRoot.java:1758)
  at android.os.Handler.dispatchMessage(Handler.java:99)
  at android.os.Looper.loop(Looper.java:136)
  at android.app.ActivityThread.main(ActivityThread.java:4425)
  at java.lang.reflect.Method.invokeNative(Native Method)
  at java.lang.reflect.Method.invoke(Method.java:521)
  at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:850)
  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:608)
  at dalvik.system.NativeStart.main(Native Method)

"Binder Thread #2" prio=5 tid=11 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x45761468 self=0x131a58
  | sysTid=3705 nice=0 sched=0/0 cgrp=unknown handle=1394424
  at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #1" prio=5 tid=9 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x4575f900 self=0x151808
  | sysTid=3704 nice=0 sched=0/0 cgrp=unknown handle=1387560
  at dalvik.system.NativeStart.run(Native Method)

"Signal Catcher" daemon prio=5 tid=7 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x457591e8 self=0x152af8
  | sysTid=3703 nice=0 sched=0/0 cgrp=unknown handle=1399392
  at dalvik.system.NativeStart.run(Native Method)

"HeapWorker" daemon prio=5 tid=5 WAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x43bc5e98 self=0x154758
  | sysTid=3702 nice=0 sched=0/0 cgrp=unknown handle=1399232
  at java.lang.Object.wait(Native Method)
  - waiting on <0x30f580> (a java.lang.VMThread)
  at java.lang.Thread.parkFor(Thread.java:1535)
  at java.lang.LangAccessImpl.parkFor(LangAccessImpl.java:48)
  at sun.misc.Unsafe.park(Unsafe.java:317)
  at java.util.concurrent.locks.LockSupport.park(LockSupport.java:131)
  at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:790)
  at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireQueued(AbstractQueuedSynchronizer.java:823)
  at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:1153)
  at java.util.concurrent.locks.ReentrantLock$FairSync.lock(ReentrantLock.java:200)
  at java.util.concurrent.locks.ReentrantLock.lock(ReentrantLock.java:261)
  at android.database.sqlite.SQLiteDatabase.lock(SQLiteDatabase.java:330)
  at android.database.sqlite.SQLiteCursor.close(SQLiteCursor.java:541)
  at android.database.sqlite.SQLiteCursor.finalize(SQLiteCursor.java:643)
  at dalvik.system.NativeStart.run(Native Method)

VM aborting
*** *** *** *** *** *** *** *** *** *** *** *** *** *** *** ***
Build fingerprint: 'moto/MB525_TMDE/umts_jordan/jordan:2.1-update1/JRDNEM_U3_2.51.1/293163443:user/release-keys'
pid: 3701, tid: 3701  >>> dv.quatCount <<<
signal 11 (SIGSEGV), fault addr deadd00d
 r0 00000000  r1 00000026  r2 00000005  r3 00000000
 r4 ad082f50  r5 ad06be57  r6 00159ab8  r7 ad068c64
 r8 000002fc  r9 ad083bb8  10 ad083bb8  fp 00000000
 ip deadd00d  sp beae85f0  lr ad035eef  pc ad035f02  cpsr 08000030
         #00  pc 00035f02  /system/lib/libdvm.so
         #01  pc 000453fe  /system/lib/libdvm.so
         #02  pc 000164e4  /system/lib/libdvm.so
         #03  pc 00016d98  /system/lib/libdvm.so
         #04  pc 00016f2c  /system/lib/libdvm.so
         #05  pc 00058438  /system/lib/libdvm.so
         #06  pc 00058594  /system/lib/libdvm.so
         #07  pc 000586a8  /system/lib/libdvm.so
         #08  pc 000131fc  /system/lib/libdvm.so
         #09  pc 000196b8  /system/lib/libdvm.so
         #10  pc 00018aa0  /system/lib/libdvm.so
         #11  pc 0004f386  /system/lib/libdvm.so
         #12  pc 00057698  /system/lib/libdvm.so
         #13  pc 00013bb8  /system/lib/libdvm.so
         #14  pc 000196b8  /system/lib/libdvm.so
         #15  pc 00018aa0  /system/lib/libdvm.so
         #16  pc 0004f386  /system/lib/libdvm.so
         #17  pc 00057698  /system/lib/libdvm.so
         #18  pc 00013bb8  /system/lib/libdvm.so
         #19  pc 000196b8  /system/lib/libdvm.so
         #20  pc 00018aa0  /system/lib/libdvm.so
         #21  pc 0004f6b0  /system/lib/libdvm.so
         #22  pc 0003b386  /system/lib/libdvm.so
         #23  pc 000283c8  /system/lib/libandroid_runtime.so
         #24  pc 00029134  /system/lib/libandroid_runtime.so
         #25  pc 00008cd8  /system/bin/app_process
         #26  pc 0000c278  /system/lib/libc.so

code around pc:
ad035ef0 fdd2f00b 2100b110 f906f00b c028f8df 
ad035f00 f88c2126 f7d81000 bf00ee66 0004d0a8 
ad035f10 000002fc fffe5d14 fffe8eec 0000030c 

code around lr:
ad035edc 182018a5 f7d8462a 4668ed4c f7d84629 
ad035eec b128eef8 fdd2f00b 2100b110 f906f00b 
ad035efc c028f8df f88c2126 f7d81000 bf00ee66 

stack:
    beae85b0  00000015  
    beae85b4  40000f24  /dev/ashmem/system_properties (deleted)
    beae85b8  beae85f0  [stack]
    beae85bc  40000f20  /dev/ashmem/system_properties (deleted)
    beae85c0  00000000  
    beae85c4  beae85f0  [stack]
    beae85c8  beae85f0  [stack]
    beae85cc  ad06be57  /system/lib/libdvm.so
    beae85d0  ad068c64  /system/lib/libdvm.so
    beae85d4  afe230b3  /system/lib/libc.so
    beae85d8  ad082f50  /system/lib/libdvm.so
    beae85dc  afb05c17  /system/lib/libcutils.so
    beae85e0  ad082f50  /system/lib/libdvm.so
    beae85e4  ad06be57  /system/lib/libdvm.so
    beae85e8  df002777  
    beae85ec  e3a070ad  
#00 beae85f0  72657375  
    beae85f4  6e6b6e00  
    beae85f8  006e776f  [heap]
    beae85fc  afe0f158  /system/lib/libc.so
    beae8600  0000be78  [heap]
    beae8604  ad082f50  /system/lib/libdvm.so
    beae8608  00000000  
    beae860c  afe0f070  /system/lib/libc.so
    beae8610  00000000  
    beae8614  beae8644  [stack]
    beae8618  00000001  
    beae861c  ad068c64  /system/lib/libdvm.so
    beae8620  000002fc  
    beae8624  ad04134b  /system/lib/libdvm.so
    beae8628  00000000  
    beae862c  ad041ce5  /system/lib/libdvm.so
    beae8630  beae8644  [stack]
    beae8634  00000001  
    beae8638  00159ab8  [heap]
    beae863c  ad041d19  /system/lib/libdvm.so
    beae8640  00002bfc  
    beae8644  00000001  
    beae8648  00000004  
    beae864c  19afb53f  
    beae8650  4000c1e8  /dev/ashmem/mspace/dalvik-heap/zygote/0 (deleted)
    beae8654  ad070151  /system/lib/libdvm.so
    beae8658  4000c1e8  /dev/ashmem/mspace/dalvik-heap/zygote/0 (deleted)
    beae865c  ad045403  /system/lib/libdvm.so
#01 beae8660  0000289a  
    beae8664  00000000  
    beae8668  42c99d12  /system/framework/framework.odex
    beae866c  42d2cff0  /system/framework/framework.odex
    beae8670  00159ab8  [heap]
    beae8674  ffffffff  
    beae8678  000002fc  
    beae867c  00154758  [heap]
    beae8680  009e99bb  [heap]
    beae8684  00000000  
    beae8688  00000001  
    beae868c  00000000  
    beae8690  0000030c  
    beae8694  4000c1e8  /dev/ashmem/mspace/dalvik-heap/zygote/0 (deleted)
    beae8698  ad082f50  /system/lib/libdvm.so
    beae869c  0000030c  
    beae86a0  00002710  
    beae86a4  ad0164e8  /system/lib/libdvm.so
Process dv.quatCount (pid 3701) has died.
setHidden false
WIN DEATH: Window{45890a30 dv.quatCount/dv.quatCount.main paused=false}
setHidden false
Unexpected resume of com.motorola.blur.home while already resumed in dv.quatCount
********************** resuming: 1169979848
Process 3701 terminated by signal (11)

The log on the same file to read, on erlier compiled code(before 1.6) the source code of the Sub updatePart did not change:

B4X:

Main: Hey there app started
checkfiles:
GC freed 7024 objects / 327248 bytes in 61ms


setHidden false


AAA


1


true
true
true
/dataverdeExport
true
true
9
ohne quartier
setHidden false


Displayed activity dv.quatCount/.main: 509 ms (total 509 ms)
setHidden false
setHidden false
log level set to 0
start tts
enqueueToast pkg=dv.quatCount callback=android.app.ITransientNotification$Stub$Proxy@45a548c8 duration=1


** Activity (main) Resume **
setHidden false
setHidden false
setHidden false


Can't open keycharmap file


Error loading keycharmap file '/system/usr/keychars/qtouch-touchscreen.kcm.bin'. hw.keyboards.65538.devname='qtouch-touchscreen'
Using default keymap: /system/usr/keychars/qwerty.kcm.bin
setHidden false


setHidden false


update menu button clicked


vor read list


nach read list
sqlUpdate:
nach begin transaction
GC freed 2225 objects / 117960 bytes in 102ms


GC freed 8243 objects / 490128 bytes in 39ms


1000


setHidden false


setHidden false


GC freed 14321 objects / 954240 bytes in 57ms


2000


Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@45b6fd38


setHidden false
GC freed 13144 objects / 886152 bytes in 60ms


setHidden false


3000


GC freed 13217 objects / 887976 bytes in 42ms


GC freed 13153 objects / 885808 bytes in 40ms


4000


GC freed 12985 objects / 884552 bytes in 40ms


5000


GC freed 13094 objects / 885624 bytes in 40ms


6000


GC freed 12911 objects / 885976 bytes in 40ms


7000


GC freed 13174 objects / 885864 bytes in 40ms


8000


GC freed 13009 objects / 886280 bytes in 40ms


9000


GC freed 13015 objects / 885248 bytes in 41ms


10000


GC freed 13049 objects / 884728 bytes in 40ms


11000


GC freed 12951 objects / 886168 bytes in 40ms


12000


GC freed 12957 objects / 884600 bytes in 41ms


13000


GC freed 13077 objects / 885784 bytes in 40ms


GC freed 12967 objects / 885936 bytes in 40ms


14000


GC freed 12851 objects / 885568 bytes in 39ms


15000


GC freed 12912 objects / 885624 bytes in 40ms


16000


GC freed 12831 objects / 885584 bytes in 40ms


Couldn't get kernel wake lock stats


updateBatteryTemp temperature = 330, Threshhold = 3
handleBatteryUpdate


plugged = true,mBatteryPlugged=true
updateBattery level=60 plugged=true mBatteryPlugged=true mBatteryLevel=60
Dismissing Bt dialog in updateBattery
 dismissBtDialog mBtTurnOffDialog=null
17000


GC freed 13005 objects / 885824 bytes in 40ms


18000


GC freed 12842 objects / 885000 bytes in 40ms


19000


GC freed 12274 objects / 885024 bytes in 40ms


20000


GC freed 12512 objects / 884888 bytes in 40ms


handle message:1


handle message:1000


getRawNetworkRegistrationState
getRawGPRSRegistrationState
queryNetworkRac
handle message:700
getRawNetworkRegistrationState
handle message:800


handle message:1
getRawGPRSRegistrationState
handle message:1000
handle message:1
getRawNetworkRegistrationState
phone state: 0 home Telekom.de Telekom.de 26201 (manual) UMTS CSS not supported -1 -1RoamInd: -1DefRoamInd: -1


disableMlbs: NOT disabling because not ON!
onReceive() - START
Intent received: Intent { act=android.intent.action.SERVICE_STATE cmp=com.motorola.blur.conversations/.transaction.MmsSystemEventReceiver (has extras) }
MMS-STATUS - SERVICE_STATE no changed
onReceive() - END
onReceive - START
Intent Received:Intent { act=android.intent.action.SERVICE_STATE cmp=com.motorola.blur.conversations/.transaction.SmsReceiver (has extras) }
beginStartingService - START
beginStartingService - synchronized
beginStartingService - Power waked lock acquire request
beginStartingService - Power waked lock acquire done
MSG-THREAD START - startService, intent=Intent { act=android.intent.action.SERVICE_STATE cmp=com.motorola.blur.conversations/.transaction.SmsReceiverService (has extras) }
getRawGPRSRegistrationState


queryNetworkRac
handle message:900
EVENT_QUERY_RAC_DONE rac=1
onDataConnectionStateChanged 3
handle message:1000
NABService@ : GPRS state previous = 2 new = 2
Receive a broadcast: android.intent.action.ANY_DATA_STATE


Received android.intent.action.ANY_DATA_STATE broadcast - state = CONNECTED, unavailable = false
The APN name is: internet.t-mobile and the interface is: rmnet0
This APN has been recorded before yet.
This APN has been active yet. the corresponding interface is: rmnet0
default Received state= CONNECTED, old= CONNECTED, reason= nwTypeChanged, apnTypeList= default
setDetailed state, old =CONNECTED and new state=CONNECTED
beginStartingService - END
onReceive - END
onReceive() - START
Intent received: Intent { act=android.intent.action.ANY_DATA_STATE cmp=com.motorola.blur.conversations/.transaction.MmsSystemEventReceiver (has extras) }
ACTION_ANY_DATA_CONNECTION_STATE_CHANGED event received: state=CONNECTED, reason=nwTypeChanged, APN: internet.t-mobile, APN types: default, interface name: rmnet0
Ignore ACTION_ANY_DATA_CONNECTION_STATE_CHANGED - ACTION_ANY_DATA_CONNECTION_STATE_CHANGED event received: state=CONNECTED, reason=nwTypeChanged, APN: internet.t-mobile, APN types: default, interface name: rmnet0
onReceive() - END
onCreate() - START
onCellLocationChanged [5776,1638176]
getRawNetworkRegistrationState


MSG-THREAD END - startService
handle message:700
getRawGPRSRegistrationState
queryNetworkRac
getRawNetworkRegistrationState
onCreate() - END
onStartCommand() - START, startId=1, flags=0, intent=Intent { act=android.intent.action.SERVICE_STATE cmp=com.motorola.blur.conversations/.transaction.SmsReceiverService (has extras) }
onStartCommand() - END
Handling incoming message - START: { what=0 when=148514831 arg1=1 obj=Intent { act=android.intent.action.SERVICE_STATE cmp=com.motorola.blur.conversations/.transaction.SmsReceiverService (has extras) } }
handle message:800


getRawGPRSRegistrationState
SMS-STATUS - SERVICE_STATE no changed
handle message:900
EVENT_QUERY_RAC_DONE rac=1
handle message:700
getRawNetworkRegistrationState
finishStartingService - START


onDestroy()
handle message:800
getRawGPRSRegistrationState
finishStartingService - release
handle message:900
EVENT_QUERY_RAC_DONE rac=1
finishStartingService - END


Handling incoming message - END
21000


GC freed 13105 objects / 885600 bytes in 42ms


22000


GC freed 12828 objects / 886056 bytes in 40ms


GC freed 12895 objects / 884904 bytes in 40ms


23000


GC freed 12952 objects / 884792 bytes in 39ms


24000


GC freed 12787 objects / 884480 bytes in 40ms


25000


GC freed 12384 objects / 886176 bytes in 40ms


26000


GC freed 12509 objects / 884608 bytes in 41ms


27000


GC freed 12787 objects / 885552 bytes in 40ms


28000


GC freed 12500 objects / 886048 bytes in 41ms


29000


GC freed 12592 objects / 885096 bytes in 40ms


30000


GC freed 12512 objects / 885248 bytes in 40ms


GC freed 12378 objects / 885144 bytes in 40ms


31000


GC freed 12364 objects / 884824 bytes in 40ms


32000


GC freed 12369 objects / 885744 bytes in 40ms


Couldn't get kernel wake lock stats


updateBatteryTemp temperature = 320, Threshhold = 3
handleBatteryUpdate


plugged = true,mBatteryPlugged=true
updateBattery level=60 plugged=true mBatteryPlugged=true mBatteryLevel=60
Dismissing Bt dialog in updateBattery
 dismissBtDialog mBtTurnOffDialog=null
33000


GC freed 12720 objects / 885792 bytes in 40ms


34000


GC freed 13047 objects / 885032 bytes in 41ms


35000


GC freed 13039 objects / 884672 bytes in 39ms


36000


GC freed 12982 objects / 885528 bytes in 40ms


37000


GC freed 12922 objects / 885808 bytes in 40ms


38000


GC freed 12995 objects / 885224 bytes in 40ms


GC freed 12786 objects / 885096 bytes in 40ms


39000


GC freed 12774 objects / 884624 bytes in 40ms


40000


GC freed 13214 objects / 886240 bytes in 41ms


41000


GC freed 11837 objects / 884440 bytes in 41ms


42000


GC freed 11874 objects / 886256 bytes in 39ms


43000


GC freed 11838 objects / 884824 bytes in 41ms


44000


GC freed 11804 objects / 885800 bytes in 40ms


GC freed 11916 objects / 886000 bytes in 40ms


45000


GC freed 12099 objects / 885176 bytes in 40ms


46000


GC freed 12020 objects / 884568 bytes in 40ms


47000


GC freed 12028 objects / 885696 bytes in 39ms


48000


GC freed 12029 objects / 886264 bytes in 45ms


49000


GC freed 12029 objects / 884568 bytes in 40ms


GC freed 12041 objects / 885952 bytes in 40ms


50000


GC freed 12069 objects / 885760 bytes in 39ms


51000


*** set_screen_state 0


onScreenTurnedOff(2)
setting alarm to turn off keyguard, seq = 213
About to give-up screen, flinger = 0x12a458


disableMlbs: NOT disabling because not ON!


no ack
Receiver: Screen Off


Start: Received action: android.intent.action.batteryprofile.SCR_OFF
Algo:Run: current state --> 0Arg --> 3
Algo:Run:Idle: We are plugged, nothing to do..
Algo:Run: new state is --> 0
GC freed 11951 objects / 885752 bytes in 43ms


Activity pause timeout for HistoryRecord{45a76608 dv.quatCount/.main}


52000


GC freed 12013 objects / 885368 bytes in 40ms


53000


GC freed 11967 objects / 885624 bytes in 44ms


54000


GC freed 11942 objects / 885360 bytes in 39ms


GC freed 12030 objects / 885432 bytes in 39ms


55000


GC freed 12018 objects / 885784 bytes in 39ms


56000


GC freed 12002 objects / 885992 bytes in 40ms


57000


GC freed 11976 objects / 885632 bytes in 39ms


58000


GC freed 11974 objects / 885816 bytes in 40ms


doKeyguard: showing the lock screen


showLocked
handleShow
show(); mKeyguardView==null
keyguard view is null, creating it...
GC freed 10865 objects / 495488 bytes in 175ms


adjustUserActivityLocked mShowing: true mHidden: false


********************** closing dialogs: lock


received close system dialog event
setHidden false
setHidden false
purging 196K from font cache [10 entries]


setHidden false


setHidden false
59000


GC freed 11961 objects / 885864 bytes in 40ms


60000


GC freed 12037 objects / 887624 bytes in 40ms


GC freed 11841 objects / 873296 bytes in 39ms


61000


GC freed 11805 objects / 873104 bytes in 39ms





MTP:MtpHandler.c:405, processCommandMtpHandle(), pid: 10553.   0x9305,len=0x14,type=0x1,code=0x9305,numParam=0x2.



MTP:MtpHandler.c:405, processCommandMtpHandle(), pid: 10553.   0x9305,len=0x14,type=0x1,code=0x9305,numParam=0x2.
62000


GC freed 11778 objects / 872728 bytes in 39ms


63000


GC freed 11808 objects / 873624 bytes in 41ms


64000


GC freed 11870 objects / 874400 bytes in 40ms


GC freed 11907 objects / 873352 bytes in 41ms


65000


GC freed 33 objects / 1176 bytes in 88ms


GC freed 11821 objects / 872744 bytes in 39ms


66000


GC freed 11695 objects / 873656 bytes in 39ms


67000


GC freed 11727 objects / 874000 bytes in 39ms


68000


GC freed 11705 objects / 873832 bytes in 39ms


69000


GC freed 11736 objects / 873648 bytes in 39ms


GC freed 11797 objects / 874424 bytes in 40ms


70000


GC freed 11728 objects / 872600 bytes in 39ms


71000


GC freed 11884 objects / 873632 bytes in 40ms


72000


GC freed 11791 objects / 873784 bytes in 40ms


GC freed 344 objects / 18904 bytes in 66ms


73000


GC freed 11771 objects / 874336 bytes in 39ms


GC freed 11745 objects / 873248 bytes in 39ms


74000


GC freed 11693 objects / 872736 bytes in 45ms


75000


GC freed 11730 objects / 873728 bytes in 39ms


76000


GC freed 11712 objects / 874096 bytes in 40ms


77000


GC freed 11744 objects / 873936 bytes in 39ms


78000


GC freed 11790 objects / 874352 bytes in 39ms


GC freed 11714 objects / 872840 bytes in 39ms


79000


GC freed 11691 objects / 873648 bytes in 39ms


80000


GC freed 11720 objects / 873768 bytes in 40ms


81000


GC freed 11734 objects / 873992 bytes in 39ms


82000


GC freed 11721 objects / 873816 bytes in 40ms


GC freed 11757 objects / 873776 bytes in 85ms


83000


GC freed 11709 objects / 873704 bytes in 39ms


84000


GC freed 11699 objects / 874008 bytes in 39ms


85000


GC freed 11743 objects / 874120 bytes in 45ms


Work Queue Table Delete - Selection=((status <= 2) AND (control <= 2))SelectionArgs=null


Work Queue Table Delete - Selection=((command IN (1,3,4,8,11,12,13)) AND (status==3) AND (control <= 2))SelectionArgs=null


Work Queue Table Delete - Selection=((status==3) AND (control <= 2) AND (error >= 20000))SelectionArgs=null
Work Queue Cleaner removed: 0 completed, 0 error, 0 permanent error.


86000


GC freed 11695 objects / 872824 bytes in 39ms


87000


GC freed 11673 objects / 873992 bytes in 39ms


GC freed 11671 objects / 874440 bytes in 39ms


88000


GC freed 11749 objects / 873256 bytes in 41ms


89000


GC freed 11719 objects / 872848 bytes in 39ms


90000


GC freed 11755 objects / 873792 bytes in 39ms


91000


GC freed 11721 objects / 874088 bytes in 40ms


GC freed 11711 objects / 873632 bytes in 39ms


92000


GC freed 11684 objects / 873776 bytes in 39ms


93000


GC freed 11689 objects / 874208 bytes in 39ms


94000


GC freed 11696 objects / 873248 bytes in 39ms


95000


GC freed 11665 objects / 873376 bytes in 39ms


96000


GC freed 11676 objects / 873264 bytes in 39ms


GC freed 11675 objects / 872720 bytes in 40ms


97000


GC freed 11682 objects / 873600 bytes in 39ms


98000


GC freed 11667 objects / 873616 bytes in 39ms


99000


GC freed 11731 objects / 874432 bytes in 39ms


100000


GC freed 11684 objects / 872992 bytes in 39ms


GC freed 11694 objects / 874232 bytes in 40ms


101000


GC freed 11694 objects / 872552 bytes in 40ms


102000


GC freed 11723 objects / 874376 bytes in 41ms


103000


GC freed 11732 objects / 873104 bytes in 39ms


104000


GC freed 11656 objects / 872808 bytes in 49ms


105000


GC freed 11744 objects / 873632 bytes in 39ms


GC freed 11704 objects / 873976 bytes in 41ms


106000


GC freed 11662 objects / 874008 bytes in 42ms


107000


GC freed 11655 objects / 873560 bytes in 40ms


108000


GC freed 11734 objects / 873840 bytes in 39ms


109000


GC freed 11709 objects / 874168 bytes in 39ms


GC freed 11682 objects / 872560 bytes in 39ms


110000


GC freed 11668 objects / 874184 bytes in 39ms


111000


GC freed 11666 objects / 872944 bytes in 41ms


112000


GC freed 11686 objects / 874368 bytes in 39ms


113000


GC freed 11759 objects / 873064 bytes in 39ms


114000


GC freed 11914 objects / 874128 bytes in 40ms


GC freed 11798 objects / 873152 bytes in 39ms


115000


GC freed 11813 objects / 873120 bytes in 39ms


116000


GC freed 11721 objects / 873408 bytes in 46ms


117000


GC freed 11739 objects / 872936 bytes in 39ms


118000


GC freed 11725 objects / 874312 bytes in 40ms


GC freed 11755 objects / 872952 bytes in 40ms


119000


GC freed 11785 objects / 874224 bytes in 39ms


120000


GC freed 11737 objects / 873328 bytes in 42ms


121000


GC freed 11749 objects / 873288 bytes in 39ms


122000


GC freed 11766 objects / 873000 bytes in 40ms


123000


GC freed 11728 objects / 873944 bytes in 40ms


GC freed 11647 objects / 873792 bytes in 39ms


124000


GC freed 11650 objects / 874128 bytes in 39ms


125000


GC freed 11664 objects / 873240 bytes in 49ms


126000


GC freed 11647 objects / 872984 bytes in 39ms


127000


GC freed 11714 objects / 873888 bytes in 39ms


GC freed 11668 objects / 874024 bytes in 39ms


128000


GC freed 11657 objects / 874216 bytes in 45ms


129000


GC freed 11650 objects / 872592 bytes in 42ms


130000


GC freed 11653 objects / 874144 bytes in 95ms


131000


GC freed 11649 objects / 872624 bytes in 39ms


132000


GC freed 11652 objects / 874424 bytes in 40ms


GC freed 11648 objects / 873352 bytes in 39ms


133000


GC freed 11639 objects / 872728 bytes in 39ms


134000


GC freed 11653 objects / 873576 bytes in 39ms


135000


GC freed 11674 objects / 874088 bytes in 40ms


136000


GC freed 11656 objects / 874344 bytes in 43ms


GC freed 11660 objects / 873064 bytes in 39ms


137000


GC freed 11616 objects / 873768 bytes in 39ms


138000


GC freed 11653 objects / 874384 bytes in 39ms


139000


GC freed 11648 objects / 872656 bytes in 40ms


140000


GC freed 11666 objects / 874192 bytes in 41ms


GC freed 11659 objects / 873232 bytes in 39ms


141000


GC freed 11628 objects / 872776 bytes in 39ms


142000


GC freed 11665 objects / 873680 bytes in 39ms


143000


GC freed 11827 objects / 874112 bytes in 40ms


144000


GC freed 11683 objects / 874248 bytes in 40ms


145000


GC freed 11628 objects / 872872 bytes in 40ms


GC freed 11742 objects / 874320 bytes in 39ms


146000


GC freed 11851 objects / 872752 bytes in 39ms


147000


GC freed 11802 objects / 873616 bytes in 39ms


148000


GC freed 11925 objects / 874016 bytes in 40ms


149000


GC freed 11664 objects / 874328 bytes in 42ms


GC freed 11726 objects / 872632 bytes in 41ms


150000


onWakeKeyWhenKeyguardShowing(26)


wakeWhenReadyLocked(26)
handleWakeWhenReady(26)


wakeWhenReady(26)
pokeWakelock(10000)
*** set_screen_state 1
using sensors (name=sensors)


android_open numFds = 1


android_open fd = 0x495add8c pfd = 0x495add88
onScreenTurnedOn, seq = 214


notifyScreenOnLocked
No focus window, dropping: KeyEvent{action=1 code=26 repeat=0 meta=0 scancode=107 mFlags=8}


handleNotifyScreenOn


onScreenTurnedOn()
shouldWeShowTheKeyguard: mSecurityPinSetupStatus=0 mSecurityLockSettingStatus=0 mScreenLockSettingStatus=true mScreenLockBypassEnabled=false
GC freed 11708 objects / 873624 bytes in 186ms
Screen about to return, flinger = 0x12a458


********************** resuming: 1168598536


setHidden false
setHidden false
ACTION_SCREEN_ON -- Checked by Moto


no ack
Receiver: Screen On


Start: Received action: android.intent.action.batteryprofile.SCR_ON
Algo:Run: current state --> 0Arg --> 2
noteCap: 60,true,0
Algo:Run: new state is --> 0
151000


GC freed 11668 objects / 873960 bytes in 50ms


152000


GC freed 11678 objects / 874264 bytes in 39ms


153000


GC freed 11644 objects / 873200 bytes in 39ms


154000


GC freed 11656 objects / 872752 bytes in 39ms


GC freed 11660 objects / 873840 bytes in 41ms


155000


GC freed 11681 objects / 874096 bytes in 47ms


156000


Pointer down received while already down in: Window{459ff730 Keyguard paused=false}


keyguardDone(true)


handleKeyguardDone
handleHide
hide()
adjustUserActivityLocked mShowing: false mHidden: false
handleKeyguardDone USER_PRESENT intent Broadcast send, Is system Ready : true


onReceive(), received intent:android.intent.action.USER_PRESENT
setHidden false
setHidden false
poll_thread : Before sending to uinput 


GC freed 11665 objects / 874088 bytes in 65ms


setHidden false


setHidden false


157000


Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@45a794b8


GC freed 11647 objects / 873504 bytes in 40ms


158000


GC freed 11719 objects / 873728 bytes in 41ms


GC freed 11688 objects / 873856 bytes in 40ms


159000


GC freed 11694 objects / 873928 bytes in 40ms


160000


GC freed 11671 objects / 873576 bytes in 40ms


161000


GC freed 11693 objects / 873832 bytes in 39ms


162000


GC freed 11731 objects / 873800 bytes in 43ms


handleTimeout


163000


GC freed 11756 objects / 873704 bytes in 41ms


Activity idle timeout for HistoryRecord{45a76608 dv.quatCount/.main}


GC freed 11761 objects / 873896 bytes in 39ms


164000


GC freed 11753 objects / 873520 bytes in 39ms


165000


GC freed 11679 objects / 872312 bytes in 39ms


166000


GC freed 11703 objects / 872992 bytes in 41ms


167000


GC freed 11708 objects / 874744 bytes in 42ms


GC freed 11685 objects / 875240 bytes in 40ms


168000


GC freed 11761 objects / 874392 bytes in 42ms


169000


GC freed 11816 objects / 875568 bytes in 39ms


170000


GC freed 93 objects / 8936 bytes in 67ms


GC freed 11796 objects / 874936 bytes in 39ms


171000


GC freed 11813 objects / 874896 bytes in 40ms


172000


GC freed 11793 objects / 874360 bytes in 40ms


GC freed 11829 objects / 875808 bytes in 43ms


173000


GC freed 11823 objects / 874640 bytes in 41ms


174000


GC freed 11800 objects / 875104 bytes in 39ms


175000


GC freed 11818 objects / 874392 bytes in 46ms


176000


GC freed 11834 objects / 875936 bytes in 42ms


GC freed 11818 objects / 874560 bytes in 40ms


177000


GC freed 11805 objects / 875208 bytes in 39ms


178000


GC freed 11868 objects / 875048 bytes in 39ms


179000


GC freed 11784 objects / 874896 bytes in 40ms


180000


GC freed 11714 objects / 874880 bytes in 41ms


181000


GC freed 11790 objects / 874720 bytes in 45ms


GC freed 11761 objects / 874448 bytes in 39ms


182000


GC freed 11765 objects / 876152 bytes in 41ms


183000


GC freed 11749 objects / 875184 bytes in 39ms


184000


GC freed 11753 objects / 874960 bytes in 40ms


185000


GC freed 11751 objects / 874816 bytes in 39ms


186000


GC freed 11761 objects / 874792 bytes in 41ms


GC freed 11707 objects / 874416 bytes in 39ms


187000


GC freed 11801 objects / 875680 bytes in 39ms


188000


GC freed 11754 objects / 875104 bytes in 45ms


189000


GC freed 11772 objects / 874576 bytes in 40ms


190000


GC freed 11815 objects / 874760 bytes in 39ms


GC freed 11845 objects / 874408 bytes in 41ms


191000


vor trans successful


vor en transaction


Key dispatching timed out sending to dv.quatCount/dv.quatCount.main


Dispatch state: {{KeyEvent{action=1 code=82 repeat=0 meta=0 scancode=139 mFlags=72} to Window{45ad0f30 dv.quatCount/dv.quatCount.main paused=false} @ 1312791774549 lw=Window{45ad0f30 dv.quatCount/dv.quatCount.main paused=false} lb=android.os.BinderProxy@45ac0e68 fin=false gfw=true ed=true tts=0 wf=false fp=false mcf=Window{45ad0f30 dv.quatCount/dv.quatCount.main paused=false}}}


Current state:  {{null to Window{45ad0f30 dv.quatCount/dv.quatCount.main paused=false} @ 1312791932705 lw=Window{45ad0f30 dv.quatCount/dv.quatCount.main paused=false} lb=android.os.BinderProxy@45ac0e68 fin=false gfw=true ed=true tts=0 wf=false fp=false mcf=Window{45ad0f30 dv.quatCount/dv.quatCount.main paused=false}}}
delete


file deleted: dataverde.sql

Erel · Aug 9, 2011

Seems like you are hitting some internal limit due to the number of SQL statements.
Can you please declare i as an Int and post the logs that you see when the error happens?
Can you post the code of SQL.runstatement?

Dataverde · Aug 9, 2011

Here you go:
app now terminating around 23000 lines of sql.
also nearby 10.000ms - maybe a 10s timeout?

Thanks for taking a look!

SQL.runStatement:

B4X:

Sub runStatement(statement As String) As Boolean
   Try
      database.ExecNonQuery(statement)
      Return True
   Catch
Log("sql intern error")
      Return False
   End Try
End Sub

logs with "dim i as int" in Sub updatePart:

B4X:

sqlUpdate:
nach begin transaction
GC freed 7147 objects / 503336 bytes in 38ms
1000
setHidden false
setHidden false
2000
Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@45c62950
3000
4000
onReceive
Received intent Intent { act=android.appwidget.action.APPWIDGET_UPDATE cmp=com.motorola.blur.home.clock/.ClockWidget }
onStart
updateWidget: isWidgetCreated: Value is >>>>>false 3 1
BuildUpdate widgetId=30
Orientation landscape=false
fetching: widget=30
type1
fetched: widget=30 returned= _id=1, widgetId=30, isAnalogStyle=false, bkClor=1
updateWidget Created settings   _id=1, widgetId=30, isAnalogStyle=false, bkClor=1
clockSettings= _id=1, widgetId=30, isAnalogStyle=false, bkClor=1:3:1
buildUpdate
x=3 y=1 color=1 landscape=false
PrepareView x=3 y=1 color=1 landscape=false
PrepareView_3
x=3 y=1 color=1 landscape=false
x=3 y=1 color=1 landscape=false
24hrs time format
Add Date
fetching: widget=30
type1
fetched: widget=30 returned= _id=1, widgetId=30, isAnalogStyle=false, bkClor=1
GC freed 3083 objects / 177968 bytes in 93ms
GC freed 7433 objects / 900616 bytes in 39ms
5000
6000
7000
GC freed 6881 objects / 881264 bytes in 38ms
8000
9000
10000
GC freed 6884 objects / 881256 bytes in 39ms
11000
12000
13000
14000
HeapWorker may be wedged: 5951ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V
GC freed 6988 objects / 881192 bytes in 42ms
15000
GC freed 44 objects / 2080 bytes in 65ms

MTP:MtpHandler.c:405, processCommandMtpHandle(), pid: 1570.   0x9305,len=0x14,type=0x1,code=0x9305,numParam=0x2.

MTP:MtpHandler.c:405, processCommandMtpHandle(), pid: 1570.   0x9305,len=0x14,type=0x1,code=0x9305,numParam=0x2.
16000
17000


HeapWorker may be wedged: 7395ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V


GC freed 6791 objects / 874168 bytes in 40ms


18000


19000


20000


HeapWorker may be wedged: 8697ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V


GC freed 6299 objects / 881136 bytes in 39ms


21000


22000


23000


HeapWorker is wedged: 10104ms spent inside Landroid/database/sqlite/SQLiteCursor;.finalize()V


DALVIK THREADS:


"main" prio=5 tid=3 RUNNABLE
  | group="main" sCount=0 dsCount=0 s=N obj=0x4001e3c0 self=0xbe78
  | sysTid=4154 nice=0 sched=0/0 cgrp=unknown handle=-1343993120
  at java.lang.String.<init>(String.java:~513)
  at java.io.BufferedReader.readLine(BufferedReader.java:361)
  at anywheresoftware.b4a.objects.streams.File$TextReaderWrapper.ReadLine(File.java:535)
  at dv.quatCount.main._updatepart(main.java:1282)
  at dv.quatCount.main._preformupdate(main.java:674)
  at dv.quatCount.main._dbupdate_click(main.java:451)
  at java.lang.reflect.Method.invokeNative(Native Method)
  at java.lang.reflect.Method.invoke(Method.java:521)
  at anywheresoftware.b4a.BA.raiseEvent2(BA.java:105)
  at anywheresoftware.b4a.BA.raiseEvent(BA.java:89)
  at dv.quatCount.main$B4AMenuItemsClickListener.onMenuItemClick(main.java:117)
  at com.android.internal.view.menu.MenuItemImpl.invoke(MenuItemImpl.java:133)
  at com.android.internal.view.menu.MenuBuilder.performItemAction(MenuBuilder.java:855)
  at com.android.internal.view.menu.IconMenuView.invokeItem(IconMenuView.java:529)
  at com.android.internal.view.menu.IconMenuItemView.performClick(IconMenuItemView.java:138)
  at android.view.View.onTouchEvent(View.java:4270)
  at android.widget.TextView.onTouchEvent(TextView.java:6784)
  at com.android.internal.view.menu.IconMenuItemView.onTouchEvent(IconMenuItemView.java:338)
  at android.view.View.dispatchTouchEvent(View.java:3796)
  at android.view.ViewGroup.dispatchTouchEvent(ViewGroup.java:888)
  at android.view.ViewGroup.dispatchTouchEvent(ViewGroup.java:888)
  at com.android.internal.policy.impl.PhoneWindow$DecorView.dispatchTouchEvent(PhoneWindow.java:1657)
  at android.view.ViewRoot.handleMessage(ViewRoot.java:1758)
  at android.os.Handler.dispatchMessage(Handler.java:99)
  at android.os.Looper.loop(Looper.java:136)
  at android.app.ActivityThread.main(ActivityThread.java:4425)
  at java.lang.reflect.Method.invokeNative(Native Method)
  at java.lang.reflect.Method.invoke(Method.java:521)
  at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:850)
  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:608)
  at dalvik.system.NativeStart.main(Native Method)

"Binder Thread #2" prio=5 tid=11 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x45761940 self=0x131378
  | sysTid=4159 nice=0 sched=0/0 cgrp=unknown handle=1381824
  at dalvik.system.NativeStart.run(Native Method)

"Binder Thread #1" prio=5 tid=9 NATIVE
  | group="main" sCount=1 dsCount=0 s=N obj=0x4575f948 self=0x130ee8
  | sysTid=4158 nice=0 sched=0/0 cgrp=unknown handle=1394392
  at dalvik.system.NativeStart.run(Native Method)

"Signal Catcher" daemon prio=5 tid=7 VMWAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x457591e8 self=0x154528
  | sysTid=4157 nice=0 sched=0/0 cgrp=unknown handle=1390952
  at dalvik.system.NativeStart.run(Native Method)

"HeapWorker" daemon prio=5 tid=5 WAIT
  | group="system" sCount=1 dsCount=0 s=N obj=0x43bc6328 self=0x153818
  | sysTid=4156 nice=0 sched=0/0 cgrp=unknown handle=1394904
  at java.lang.Object.wait(Native Method)
  - waiting on <0x30b798> (a java.lang.VMThread)
  at java.lang.Thread.parkFor(Thread.java:1535)
  at java.lang.LangAccessImpl.parkFor(LangAccessImpl.java:48)
  at sun.misc.Unsafe.park(Unsafe.java:317)
  at java.util.concurrent.locks.LockSupport.park(LockSupport.java:131)
  at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:790)
  at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireQueued(AbstractQueuedSynchronizer.java:823)
  at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:1153)
  at java.util.concurrent.locks.ReentrantLock$FairSync.lock(ReentrantLock.java:200)
  at java.util.concurrent.locks.ReentrantLock.lock(ReentrantLock.java:261)
  at android.database.sqlite.SQLiteDatabase.lock(SQLiteDatabase.java:330)
  at android.database.sqlite.SQLiteCursor.close(SQLiteCursor.java:541)
  at android.database.sqlite.SQLiteCursor.finalize(SQLiteCursor.java:643)
  at dalvik.system.NativeStart.run(Native Method)

VM aborting
*** *** *** *** *** *** *** *** *** *** *** *** *** *** *** ***


Build fingerprint: 'moto/MB525_TMDE/umts_jordan/jordan:2.1-update1/JRDNEM_U3_2.51.1/293163443:user/release-keys'
pid: 4154, tid: 4154  >>> dv.quatCount <<<
signal 11 (SIGSEGV), fault addr deadd00d
 r0 00000000  r1 00000026  r2 00000005  r3 00000000
 r4 ad082f50  r5 ad06be57  r6 0030bdd0  r7 ad068c64
 r8 000002fc  r9 ad083bb8  10 ad083bb8  fp 00000000
 ip deadd00d  sp beae85f0  lr ad035eef  pc ad035f02  cpsr 08000030
         #00  pc 00035f02  /system/lib/libdvm.so
         #01  pc 000453fe  /system/lib/libdvm.so
         #02  pc 000164e4  /system/lib/libdvm.so
         #03  pc 00016d98  /system/lib/libdvm.so
         #04  pc 00016f2c  /system/lib/libdvm.so
         #05  pc 00058438  /system/lib/libdvm.so
         #06  pc 00058594  /system/lib/libdvm.so
         #07  pc 000586a8  /system/lib/libdvm.so
         #08  pc 000131fc  /system/lib/libdvm.so
         #09  pc 000196b8  /system/lib/libdvm.so
         #10  pc 00018aa0  /system/lib/libdvm.so
         #11  pc 0004f386  /system/lib/libdvm.so
         #12  pc 00057698  /system/lib/libdvm.so
         #13  pc 00013bb8  /system/lib/libdvm.so
         #14  pc 000196b8  /system/lib/libdvm.so
         #15  pc 00018aa0  /system/lib/libdvm.so
         #16  pc 0004f386  /system/lib/libdvm.so
         #17  pc 00057698  /system/lib/libdvm.so
         #18  pc 00013bb8  /system/lib/libdvm.so
         #19  pc 000196b8  /system/lib/libdvm.so
         #20  pc 00018aa0  /system/lib/libdvm.so
         #21  pc 0004f6b0  /system/lib/libdvm.so
         #22  pc 0003b386  /system/lib/libdvm.so
         #23  pc 000283c8  /system/lib/libandroid_runtime.so
         #24  pc 00029134  /system/lib/libandroid_runtime.so
         #25  pc 00008cd8  /system/bin/app_process
         #26  pc 0000c278  /system/lib/libc.so

code around pc:
ad035ef0 fdd2f00b 2100b110 f906f00b c028f8df 
ad035f00 f88c2126 f7d81000 bf00ee66 0004d0a8 
ad035f10 000002fc fffe5d14 fffe8eec 0000030c 

code around lr:
ad035edc 182018a5 f7d8462a 4668ed4c f7d84629 
ad035eec b128eef8 fdd2f00b 2100b110 f906f00b 
ad035efc c028f8df f88c2126 f7d81000 bf00ee66 

stack:
    beae85b0  00000015  
    beae85b4  40000f24  /dev/ashmem/system_properties (deleted)
    beae85b8  beae85f0  [stack]
    beae85bc  40000f20  /dev/ashmem/system_properties (deleted)
    beae85c0  00000000  
    beae85c4  beae85f0  [stack]
    beae85c8  beae85f0  [stack]
    beae85cc  ad06be57  /system/lib/libdvm.so
    beae85d0  ad068c64  /system/lib/libdvm.so
    beae85d4  afe230b3  /system/lib/libc.so
    beae85d8  ad082f50  /system/lib/libdvm.so
    beae85dc  afb05c17  /system/lib/libcutils.so
    beae85e0  ad082f50  /system/lib/libdvm.so
    beae85e4  ad06be57  /system/lib/libdvm.so
    beae85e8  df002777  
    beae85ec  e3a070ad  
#00 beae85f0  72657375  
    beae85f4  6e6b6e00  
    beae85f8  006e776f  [heap]
    beae85fc  afe0f158  /system/lib/libc.so
    beae8600  0000be78  [heap]
    beae8604  ad082f50  /system/lib/libdvm.so
    beae8608  00000000  
    beae860c  afe0f070  /system/lib/libc.so
    beae8610  00000000  
    beae8614  beae8644  [stack]
    beae8618  00000001  
    beae861c  ad068c64  /system/lib/libdvm.so
    beae8620  000002fc  
    beae8624  ad04134b  /system/lib/libdvm.so
    beae8628  00000000  
    beae862c  ad041ce5  /system/lib/libdvm.so
    beae8630  beae8644  [stack]
    beae8634  00000001  
    beae8638  0030bdd0  [heap]
    beae863c  ad041d19  /system/lib/libdvm.so
    beae8640  00002bfc  
    beae8644  00000001  
    beae8648  00000004  
    beae864c  19afb53f  
    beae8650  4000c1e8  /dev/ashmem/mspace/dalvik-heap/zygote/0 (deleted)
    beae8654  ad070151  /system/lib/libdvm.so
    beae8658  4000c1e8  /dev/ashmem/mspace/dalvik-heap/zygote/0 (deleted)
    beae865c  ad045403  /system/lib/libdvm.so
#01 beae8660  00002778  
    beae8664  00000000  
    beae8668  42c99d12  /system/framework/framework.odex
    beae866c  42d2cff0  /system/framework/framework.odex
    beae8670  0030bdd0  [heap]
    beae8674  ffffffff  
    beae8678  000002fc  


    beae867c  00153818  [heap]
    beae8680  009a2e32  [heap]
    beae8684  00000000  
    beae8688  00000001  
    beae868c  00000000  
    beae8690  0000030c  
    beae8694  4000c1e8  /dev/ashmem/mspace/dalvik-heap/zygote/0 (deleted)
    beae8698  ad082f50  /system/lib/libdvm.so
    beae869c  0000030c  
    beae86a0  00002710  
    beae86a4  ad0164e8  /system/lib/libdvm.so
WIN DEATH: Window{457b76a0 dv.quatCount/dv.quatCount.main paused=false}
setHidden false
Process dv.quatCount (pid 4154) has died.
setHidden false
Unexpected resume of com.motorola.blur.home while already resumed in dv.quatCount


********************** resuming: 1169979848
Process 4154 terminated by signal (11)
setHidden false
setHidden false


setHidden false
setHidden false
setHidden false
Got RemoteException sending setActive(false) notification to pid 4154 uid 10088


GC freed 45 objects / 2136 bytes in 69ms


GC freed 168 objects / 11856 bytes in 71ms


GC freed 9550 objects / 460744 bytes in 71ms


GC freed 1177 objects / 61024 bytes in 96ms


GC freed 6655 objects / 293984 bytes in 76ms


GC freed 15419 objects / 651904 bytes in 96ms


GC freed 2007 objects / 118496 bytes in 67ms


GC freed 1021 objects / 48272 bytes in 67ms


GC freed 5795 objects / 318832 bytes in 89ms

Erel · Aug 9, 2011

Which types of SQL statements are you executing? Did you try to run with debugger disabled (uncheck Project - Attach debugger)?

Dataverde · Aug 9, 2011

statements are DROP, CREATE and INSERT mostly, i'll check that.
It is the same problem without the debugger attached.

Dataverde · Aug 9, 2011

The statements Drop all the tables and recreate them like in this example:

B4X:

DROP TABLE IF EXISTS ARTIKEL;
CREATE TABLE ARTIKEL ([ARTNR] numeric(8), [PFLNR] numeric(8), [QUALNR] numeric(5), [FSIZE] numeric(5), [USIZE] numeric(4), [ARTMC1] varchar(20), [WAGRNR] numeric(3), [SMLARTNR] numeric(8), [LARGARTNR] numeric(8), [KAT1] numeric(7), [KAT2] numeric(7), [KAT3] numeric(7), [KAT4] numeric(7), [MAXRAB] numeric(2), [STATUS] numeric, [PVK1] numeric(7), [PVK2] numeric(7), [PVK3] numeric(7), [PVK4] numeric(7), [NET1] numeric(7), [NET2] numeric(7), [NET3] numeric(7), [NET4] numeric(7)); 
CREATE INDEX ARTNR_INDEX ON ARTIKEL ([ARTNR]);
CREATE INDEX ARTMC_INDEX ON ARTIKEL ([ARTMC1]);

And insert stuff to the created tables like in this example:

B4X:

INSERT INTO PFLANZEN VALUES (39530, "BFI", "Bracteantha 'Fireball'  -R-", "Strohblume", 0);

Erel · Aug 9, 2011

I assume that somewhere in your code you are opening a Cursor and you are not closing it. Later when the garbage collector tries to release this cursor resources it needs to acquire an internal lock. This happens while you are submitting 200k statements. Therefore the GC thread is getting blocked. After 10 seconds bad things start to happen.

Dataverde · Aug 9, 2011

I am not shure, but i think the GC works as it frees data every 1000 submitted statements for there is a DoEvents every 1000 statements for updating a label.

4000
GC freed 12894 objects / 877728 bytes in 39ms
5000
GC freed 12952 objects / 877280 bytes in 39ms
6000
GC freed 12809 objects / 878416 bytes in 39ms
7000
GC freed 13008 objects / 877336 bytes in 40ms
8000
GC freed 12907 objects / 877112 bytes in 40ms

Also any idea why it works with the earlier compiled version?

Edit: Of course i will check for unclosed cursors!

Erel · Aug 9, 2011

The DoEvents is not related to the GC. I also don't think that this issue is specific to B4A v1.6.

Dataverde · Aug 9, 2011

Hey!

I closed some Cursors and guess what, now everything works just fine!

So the error occured beacause of this Sub is the only in the Programm witch takes longer than 10s for something. maybe you should add a comment to the SQL Documentation about this.

Thank you for your help!

Bug: app terminates while database communiction

Dataverde

Member

Erel

B4X founder

Dataverde

Member

Erel

B4X founder

Dataverde

Member

Dataverde

Member

Erel

B4X founder

Dataverde

Member

Erel

B4X founder

Dataverde

Member