search

CartoDB / mobile-sdk

CARTO Mobile SDK core project
https://carto.com/docs/carto-engine/mobile-sdk/
BSD 3-Clause "New" or "Revised" License
185 stars 67 forks source link

android crash #361

Closed farfromrefug closed 4 years ago

farfromrefug commented 4 years ago

With the latest version my app sometimes hangs. I am not sure but i think it happens while release a mapview. I quickly open/close fragments to finally get it to hang.

I have no really error log to report, and i cant debug with android studio. Seems to be an openGL state error. I can see this in logcat though at the time of the hanging. 

E/emuglGLESv2_enc: device/generic/goldfish-opengl/system/GLESv2_enc/GL2Encoder.cpp:s_glUseProgram:2041 GL error 0x501

To reproduce it faster i call delete on the android mapview myself.

another log after force delete:

05-04 11:08:50.906 10839 10839 I carto-mobile-sdk: MapView.onDetachedFromWindow
05-04 11:08:50.906 10839 10839 I carto-mobile-sdk: BaseMapView::onSurfaceDestroyed()
05-04 11:08:50.981 10839 10933 D carto-mobile-sdk: ConfigChooser.chooseConfig: Model: Android SDK built for x86, board: goldfish_x86, product: sdk_phone_x86
05-04 11:08:50.981 10839 10933 D         : HostConnection::get() New Host Connection established 0xbe569d30, tid 10933
05-04 11:08:50.982 10839 10933 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-04 11:08:50.982 10839 10933 D carto-mobile-sdk: ConfigChooser.chooseConfig: Selected display configuration: 0
05-04 11:08:50.982 10839 10933 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-04 11:08:50.982 10839 10933 D EGL_emulation: eglCreateContext: 0xbebff720: maj 3 min 0 rcv 3
05-04 11:08:50.985  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-04 11:08:50.993 10839 10933 D EGL_emulation: eglMakeCurrent: 0xbebff720: ver 3 0 (tinfo 0xe12bce80)
05-04 11:08:50.996 10839 10933 I carto-mobile-sdk: BaseMapView::onSurfaceCreated()
05-04 11:08:51.014 10839 10933 I carto-mobile-sdk: BaseMapView::onSurfaceChanged(): width: 1440, height: 525
05-04 11:08:51.022 10839 10933 E emuglGLESv2_enc: device/generic/goldfish-opengl/system/GLESv2_enc/GL2Encoder.cpp:s_glUseProgram:2041 GL error 0x501
05-04 11:08:51.022 10839 10928 I carto-mobile-sdk: GeoJSONVectorTileDataSource::loadTile: Loading MapTile [x=33811, y=23525, zoom=16, frameNr=0, id=2973423976]
05-04 11:08:51.022 10839 10921 I carto-mobile-sdk: GeoJSONVectorTileDataSource::loadTile: Loading MapTile [x=16905, y=11762, zoom=15, frameNr=0, id=743348062]
05-04 11:08:51.284  1880  1970 W InputDispatcher: channel 'f872c0d com.akylas.cairnmobile/com.tns.NativeScriptActivity (server)' ~ Consumer closed input channel or an error occurred.  events=0xd
05-04 11:08:51.284  1880  1970 E InputDispatcher: channel 'f872c0d com.akylas.cairnmobile/com.tns.NativeScriptActivity (server)' ~ Channel is unrecoverably broken and will be disposed!
05-04 11:08:51.288  1623  1623 I Zygote  : Process 10839 exited due to signal 11 (Segmentation fault)
05-04 11:08:51.288  1880  1898 I ActivityManager: Process com.akylas.cairnmobile (pid 10839) has died: fore TOP
05-04 11:08:51.290  1880  1910 I libprocessgroup: Successfully killed process cgroup uid 10188 pid 10839 in 0ms
05-04 11:08:51.290  1880  1898 W ActivityTaskManager: Force removing ActivityRecord{30b50ae u0 com.akylas.cairnmobile/com.tns.NativeScriptActivity t926}: app died, no saved state
05-04 11:08:51.295  1880  1898 W InputDispatcher: Attempted to unregister already unregistered input channel 'f872c0d com.akylas.cairnmobile/com.tns.NativeScriptActivity (server)'
05-04 11:08:51.308  1880  2675 W WindowManager: Failed looking up window callers=com.android.server.wm.WindowManagerService.windowForClientLocked:5281 com.android.server.wm.WindowState$DeathRecipient.binderDied:2491 android.os.BinderProxy.sendDeathNotice:620
05-04 11:08:51.308  1880  2675 I WindowManager: WIN DEATH: null
farfromrefug commented 4 years ago

BTW in the course of that debugging i released that on on android onSurfaceDestroyed was never called on the native basemapview.

I think this should be good:

 @Override
    protected void onDetachedFromWindow() {
        com.carto.utils.Log.info("MapView.onDetachedFromWindow");
        if (baseMapView != null) {
            baseMapView.onSurfaceDestroyed();
            // this.delete();
        }
        super.onDetachedFromWindow();
    }
mtehver commented 4 years ago

Thanks for reporting this. Which SDK version did you use? There was a deadlock bug in SDK 4.3.0RC2 that was fixed in 4.3.0RC3. Can you reproduce it with the official SDK 4.3.0?

Also, calling onSurfaceDestroyed is not needed and in fact I would suggest against this. The resources will be properly disposed anyway, so there should be no leaks.

farfromrefug commented 4 years ago

@mtehver reproduced it again on the emulator. Simply opening and closing fragments with maps triggers it. I keep on seeing that gl error.

05-05 09:57:00.638 14084 14084 I carto-mobile-sdk: AndroidUtils::AttachJVM: Attaching JVM from current JNIEnv
05-05 09:57:00.642 14084 14084 W carto-mobile-sdk: MapView: MapView created before MapView.registerLicense is called
05-05 09:57:00.648 14084 14084 I carto-mobile-sdk: BaseMapView: Build: android-|master|release/v4.3.0, time: May  4 2020 10:51:29, device type: Android SDK built for x86, device OS: 10
05-05 09:57:00.911 14084 14159 D         : HostConnection::get() New Host Connection established 0xd914d140, tid 14159
05-05 09:57:00.911 14084 14159 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:00.912 14084 14159 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:00.912 14084 14159 D EGL_emulation: eglCreateContext: 0xd911bf80: maj 3 min 0 rcv 3
05-05 09:57:00.913  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:00.919 14084 14159 D EGL_emulation: eglMakeCurrent: 0xd911bf80: ver 3 0 (tinfo 0xd910f030)
05-05 09:57:00.975  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:01.007  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:01.617  1628  3528 W audio_hw_generic: Not supplying enough data to HAL, expected position 69500307 , only wrote 69500184
05-05 09:57:01.671  1650  1812 D AF::Track: interceptBuffer: took 331us to intercept 0 tracks
05-05 09:57:01.674 14084 14165 D         : HostConnection::get() New Host Connection established 0xd914d140, tid 14165
05-05 09:57:01.674 14084 14165 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:01.674 14084 14165 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:01.677 14084 14165 D EGL_emulation: eglCreateContext: 0xd911bf80: maj 3 min 0 rcv 3
05-05 09:57:01.678  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:01.683 14084 14165 D EGL_emulation: eglMakeCurrent: 0xd911bf80: ver 3 0 (tinfo 0xd910fcd0)
05-05 09:57:01.708  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:01.728  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:02.268 14084 14172 D         : HostConnection::get() New Host Connection established 0xd914fa80, tid 14172
05-05 09:57:02.268 14084 14172 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:02.269 14084 14172 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:02.269 14084 14172 D EGL_emulation: eglCreateContext: 0xd911c040: maj 3 min 0 rcv 3
05-05 09:57:02.270  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:02.274 14084 14172 D EGL_emulation: eglMakeCurrent: 0xd911c040: ver 3 0 (tinfo 0xd910f030)
05-05 09:57:02.314  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:02.347  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:02.905  1650  1812 D AF::Track: interceptBuffer: took 432us to intercept 0 tracks
05-05 09:57:02.910 14084 14176 D         : HostConnection::get() New Host Connection established 0xd914d190, tid 14176
05-05 09:57:02.910 14084 14176 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:02.911 14084 14176 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:02.911 14084 14176 D EGL_emulation: eglCreateContext: 0xd911c040: maj 3 min 0 rcv 3
05-05 09:57:02.911  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:02.916 14084 14176 D EGL_emulation: eglMakeCurrent: 0xd911c040: ver 3 0 (tinfo 0xd910f040)
05-05 09:57:02.947  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:02.966  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:03.440 14084 14183 D         : HostConnection::get() New Host Connection established 0xd914d190, tid 14183
05-05 09:57:03.440 14084 14183 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:03.441 14084 14183 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:03.441 14084 14183 D EGL_emulation: eglCreateContext: 0xd911b6e0: maj 3 min 0 rcv 3
05-05 09:57:03.443  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:03.447 14084 14183 D EGL_emulation: eglMakeCurrent: 0xd911b6e0: ver 3 0 (tinfo 0xd910f130)
05-05 09:57:03.471  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:03.514  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:04.171 14084 14187 D         : HostConnection::get() New Host Connection established 0xd914d190, tid 14187
05-05 09:57:04.171 14084 14187 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:04.186 14084 14187 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:04.186 14084 14187 D EGL_emulation: eglCreateContext: 0xb17139c0: maj 3 min 0 rcv 3
05-05 09:57:04.187  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:04.192 14084 14187 D EGL_emulation: eglMakeCurrent: 0xb17139c0: ver 3 0 (tinfo 0xd910f040)
05-05 09:57:04.223  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:04.239  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:04.296  1880  1893 I system_server: Background young concurrent copying GC freed 82859(4187KB) AllocSpace objects, 61(1792KB) LOS objects, 20% free, 23MB/29MB, paused 732us total 144.745ms
05-05 09:57:04.649 14084 14194 D         : HostConnection::get() New Host Connection established 0xd914d190, tid 14194
05-05 09:57:04.649 14084 14194 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:04.650 14084 14194 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:04.650 14084 14194 D EGL_emulation: eglCreateContext: 0xb17139c0: maj 3 min 0 rcv 3
05-05 09:57:04.650  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:04.654 14084 14194 D EGL_emulation: eglMakeCurrent: 0xb17139c0: ver 3 0 (tinfo 0xd910f130)
05-05 09:57:04.691  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:04.721  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:04.764 14084 14099 I las.cairnmobil: NativeAlloc concurrent copying GC freed 19867(1786KB) AllocSpace objects, 0(0B) LOS objects, 37% free, 10MB/16MB, paused 1.283ms total 123.342ms
05-05 09:57:05.318 14084 14198 D         : HostConnection::get() New Host Connection established 0xd914d190, tid 14198
05-05 09:57:05.318 14084 14198 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:05.337 14084 14198 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:05.337 14084 14198 D EGL_emulation: eglCreateContext: 0xb17139c0: maj 3 min 0 rcv 3
05-05 09:57:05.338  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:05.341 14084 14198 D EGL_emulation: eglMakeCurrent: 0xb17139c0: ver 3 0 (tinfo 0xd910f110)
05-05 09:57:05.366  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:05.385  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:05.941 14084 14205 D         : HostConnection::get() New Host Connection established 0xd914daa0, tid 14205
05-05 09:57:05.942 14084 14205 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:05.943 14084 14205 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:05.943 14084 14205 D EGL_emulation: eglCreateContext: 0xb1715d00: maj 3 min 0 rcv 3
05-05 09:57:05.944  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:05.947 14084 14205 D EGL_emulation: eglMakeCurrent: 0xb1715d00: ver 3 0 (tinfo 0xbd7e2520)
05-05 09:57:05.981  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:06.022  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:06.436 14084 14209 D         : HostConnection::get() New Host Connection established 0xd914daa0, tid 14209
05-05 09:57:06.436 14084 14209 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:06.438 14084 14209 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:06.438 14084 14209 D EGL_emulation: eglCreateContext: 0xb1715d00: maj 3 min 0 rcv 3
05-05 09:57:06.438  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:06.444 14084 14209 D EGL_emulation: eglMakeCurrent: 0xb1715d00: ver 3 0 (tinfo 0xd910f110)
05-05 09:57:06.474  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:06.493  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:06.905 14084 14216 D         : HostConnection::get() New Host Connection established 0xd914daa0, tid 14216
05-05 09:57:06.905 14084 14216 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:06.906 14084 14216 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:06.906 14084 14216 D EGL_emulation: eglCreateContext: 0xd911c460: maj 3 min 0 rcv 3
05-05 09:57:06.908  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:06.915 14084 14216 D EGL_emulation: eglMakeCurrent: 0xd911c460: ver 3 0 (tinfo 0xd910f110)
05-05 09:57:06.959  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:06.970  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:06.971 14084 14101 W System  : A resource failed to call close.
05-05 09:57:06.978 14084 14101 I chatty  : uid=10190(com.akylas.cairnmobile) FinalizerDaemon identical 40 lines
05-05 09:57:06.978 14084 14101 W System  : A resource failed to call close.
05-05 09:57:07.379 14084 14220 D         : HostConnection::get() New Host Connection established 0xd914daa0, tid 14220
05-05 09:57:07.379 14084 14220 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:07.380 14084 14220 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:07.380 14084 14220 D EGL_emulation: eglCreateContext: 0xd911c460: maj 3 min 0 rcv 3
05-05 09:57:07.381  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:07.384 14084 14220 D EGL_emulation: eglMakeCurrent: 0xd911c460: ver 3 0 (tinfo 0xd910f060)
05-05 09:57:07.414  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:07.431  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:07.950 14084 14227 D         : HostConnection::get() New Host Connection established 0xd914daa0, tid 14227
05-05 09:57:07.950 14084 14227 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:07.951 14084 14227 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:07.951 14084 14227 D EGL_emulation: eglCreateContext: 0xd911c460: maj 3 min 0 rcv 3
05-05 09:57:07.953  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:07.958 14084 14227 D EGL_emulation: eglMakeCurrent: 0xd911c460: ver 3 0 (tinfo 0xd910f060)
05-05 09:57:08.008  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:08.016  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:08.329 14084 14101 W System  : A resource failed to call close.
05-05 09:57:08.329 14084 14101 W System  : A resource failed to call close.
05-05 09:57:08.421 14084 14231 D         : HostConnection::get() New Host Connection established 0xd914daa0, tid 14231
05-05 09:57:08.421 14084 14231 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:08.422 14084 14231 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:08.422 14084 14231 D EGL_emulation: eglCreateContext: 0xd911c460: maj 3 min 0 rcv 3
05-05 09:57:08.423  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:08.426 14084 14231 D EGL_emulation: eglMakeCurrent: 0xd911c460: ver 3 0 (tinfo 0xd910f110)
05-05 09:57:08.451  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:08.470  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:08.922 14084 14238 D         : HostConnection::get() New Host Connection established 0xd914d140, tid 14238
05-05 09:57:08.922 14084 14238 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:08.923 14084 14238 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:08.923 14084 14238 D EGL_emulation: eglCreateContext: 0xd911c460: maj 3 min 0 rcv 3
05-05 09:57:08.928  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:08.932 14084 14238 D EGL_emulation: eglMakeCurrent: 0xd911c460: ver 3 0 (tinfo 0xd910f060)
05-05 09:57:08.965  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:08.977  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:09.385 14084 14242 D         : HostConnection::get() New Host Connection established 0xd914d140, tid 14242
05-05 09:57:09.385 14084 14242 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:09.385 14084 14242 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:09.385 14084 14242 D EGL_emulation: eglCreateContext: 0xd911c460: maj 3 min 0 rcv 3
05-05 09:57:09.386  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 3026944
05-05 09:57:09.395 14084 14242 D EGL_emulation: eglMakeCurrent: 0xd911c460: ver 3 0 (tinfo 0xd910f110)
05-05 09:57:09.410 14084 14242 E emuglGLESv2_enc: device/generic/goldfish-opengl/system/GLESv2_enc/GL2Encoder.cpp:s_glUseProgram:2041 GL error 0x501
05-05 09:57:09.423  1650  1812 D AF::Track: interceptBuffer: took 220us to intercept 0 tracks
05-05 09:57:10.054  2526  2543 W com.android.se: Reducing the number of considered missed Gc histogram windows from 17717 to 100
05-05 09:57:12.486  1628  1628 W audio_hw_generic: Not supplying enough data to HAL, expected position 70456839 , only wrote 69979409
05-05 09:57:15.056  2549  2566 W .ims.rcsservic: Reducing the number of considered missed Gc histogram windows from 17717 to 100
05-05 09:57:15.966  1880  1970 I InputDispatcher: Application is not responding: Window{549978d u0 com.akylas.cairnmobile/com.tns.NativeScriptActivity}.  It has been 5008.5ms since event, 5007.2ms since wait started.  Reason: Waiting to send non-key event because the touched window has not finished processing certain input events that were delivered to it over 500.0ms ago.  Wait queue length: 5.  Wait queue head age: 6288.2ms.
05-05 09:57:15.969  1880  1970 I WindowManager: Input event dispatching timed out sending to com.akylas.cairnmobile/com.tns.NativeScriptActivity.  Reason: Waiting to send non-key event because the touched window has not finished processing certain input events that were delivered to it over 500.0ms ago.  Wait queue length: 5.  Wait queue head age: 6288.2ms.
05-05 09:57:16.002  1880  1970 I ActivityManager: dumpStackTraces pids={2426=true, 2616=true, 2712=true, 12358=true} nativepids=[1527, 1650, 1653, 1663, 1664, 1670, 1671, 1672, 1673, 1674, 1677, 1684]
05-05 09:57:16.316  1880  1970 I ActivityManager: Skipping next CPU consuming process, not a java proc: 1880
05-05 09:57:16.316  1880  1970 I ActivityManager: Skipping next CPU consuming process, not a java proc: 1653
05-05 09:57:16.316  1880  1970 I ActivityManager: Skipping next CPU consuming process, not a java proc: 8
05-05 09:57:16.316  1880  1970 I ActivityManager: Skipping next CPU consuming process, not a java proc: 2029
05-05 09:57:16.319  1880  1970 I ActivityManager: Dumping to /data/anr/anr_2020-05-05-09-57-16-318
05-05 09:57:16.319  1880  1970 I ActivityManager: Collecting stacks for pid 14084
05-05 09:57:16.320  1880  1970 I system_server: libdebuggerd_client: started dumping process 14084
05-05 09:57:16.322  1687  1687 I /system/bin/tombstoned: registered intercept for pid 14084 and type kDebuggerdJavaBacktrace
05-05 09:57:16.322 14084 14097 I las.cairnmobil: Thread[4,tid=14097,WaitingInMainSignalCatcherLoop,Thread*=0xd911ec00,peer=0x16d80000,"Signal Catcher"]: reacting to signal 3
05-05 09:57:16.322 14084 14097 I las.cairnmobil:
05-05 09:57:19.394  1798  1798 E netmgr  : Failed to open QEMU pipe 'qemud:network': Invalid argument
05-05 09:57:19.394  1798  1798 E netmgr  : WifiForwarder unable to open QEMU pipe: Invalid argument
05-05 09:57:20.059  2573  2599 W or.multidispla: Reducing the number of considered missed Gc histogram windows from 17718 to 100
05-05 09:57:25.083  2712  2730 W android.smspus: Reducing the number of considered missed Gc histogram windows from 17718 to 100
05-05 09:57:26.333  1687  1687 I /system/bin/tombstoned: intercept for pid 14084 and type kDebuggerdJavaBacktrace terminated: due to timeout
05-05 09:57:26.333  1880  1970 E system_server: libdebuggerd_client: failed to read status response from tombstoned: timeout reached?
05-05 09:57:26.334  1880  1970 W ActivityManager: Dumping Java threads failed, initiating native stack dump.
05-05 09:57:26.335  1880  1970 I system_server: libdebuggerd_client: started dumping process 14084
05-05 09:57:26.336  1687  1687 I /system/bin/tombstoned: registered intercept for pid 14084 and type kDebuggerdNativeBacktrace
05-05 09:57:26.336 14084 14084 I libc    : Requested dump for tid 14084 (las.cairnmobile)
05-05 09:57:26.440 14247 14247 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdNativeBacktrace
05-05 09:57:26.441  1687  1687 I /system/bin/tombstoned: received crash request for pid 14084
05-05 09:57:26.441  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 14084 and type kDebuggerdNativeBacktrace
05-05 09:57:26.441 14247 14247 I crash_dump32: performing dump of process 14084 (target tid = 14084)
05-05 09:57:26.853  1880  1970 I system_server: libdebuggerd_client: done dumping process 14084
05-05 09:57:26.855  1880  1970 I ActivityManager: Collecting stacks for pid 1880
05-05 09:57:26.855  1880  1970 I system_server: libdebuggerd_client: started dumping process 1880
05-05 09:57:26.855  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1880 and type kDebuggerdJavaBacktrace
05-05 09:57:26.856  1880  1891 I system_server: Thread[7,tid=1891,WaitingInMainSignalCatcherLoop,Thread*=0xd5b62400,peer=0x12c00258,"Signal Catcher"]: reacting to signal 3
05-05 09:57:26.856  1880  1891 I system_server:
05-05 09:57:27.064  1687  1687 I /system/bin/tombstoned: received crash request for pid 1880
05-05 09:57:27.064  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1880 and type kDebuggerdJavaBacktrace
05-05 09:57:27.064  1880  1891 I system_server: Wrote stack traces to tombstoned
05-05 09:57:27.071  1880  1970 I system_server: libdebuggerd_client: done dumping process 1880
05-05 09:57:27.075  1880  1970 I ActivityManager: Collecting stacks for pid 2367
05-05 09:57:27.075  1880  1970 I system_server: libdebuggerd_client: started dumping process 2367
05-05 09:57:27.075  1687  1687 I /system/bin/tombstoned: registered intercept for pid 2367 and type kDebuggerdJavaBacktrace
05-05 09:57:27.076  2367  2379 I putmethod.lati: Thread[4,tid=2379,WaitingInMainSignalCatcherLoop,Thread*=0xd9108c00,peer=0x12f00220,"Signal Catcher"]: reacting to signal 3
05-05 09:57:27.076  2367  2379 I putmethod.lati:
05-05 09:57:27.133  1687  1687 I /system/bin/tombstoned: received crash request for pid 2367
05-05 09:57:27.133  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 2367 and type kDebuggerdJavaBacktrace
05-05 09:57:27.133  2367  2379 I putmethod.lati: Wrote stack traces to tombstoned
05-05 09:57:27.135  1880  1970 I system_server: libdebuggerd_client: done dumping process 2367
05-05 09:57:27.137  1880  1970 I ActivityManager: Collecting stacks for pid 2573
05-05 09:57:27.137  1880  1970 I system_server: libdebuggerd_client: started dumping process 2573
05-05 09:57:27.137  1687  1687 I /system/bin/tombstoned: registered intercept for pid 2573 and type kDebuggerdJavaBacktrace
05-05 09:57:27.138  2573  2584 I or.multidispla: Thread[4,tid=2584,WaitingInMainSignalCatcherLoop,Thread*=0xd9108c00,peer=0x12d001a8,"Signal Catcher"]: reacting to signal 3
05-05 09:57:27.138  2573  2584 I or.multidispla:
05-05 09:57:27.162  1687  1687 I /system/bin/tombstoned: received crash request for pid 2573
05-05 09:57:27.162  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 2573 and type kDebuggerdJavaBacktrace
05-05 09:57:27.163  2573  2584 I or.multidispla: Wrote stack traces to tombstoned
05-05 09:57:27.165  1880  1970 I system_server: libdebuggerd_client: done dumping process 2573
05-05 09:57:27.167  1880  1970 I ActivityManager: Collecting stacks for pid 2549
05-05 09:57:27.167  1880  1970 I system_server: libdebuggerd_client: started dumping process 2549
05-05 09:57:27.167  1687  1687 I /system/bin/tombstoned: registered intercept for pid 2549 and type kDebuggerdJavaBacktrace
05-05 09:57:27.168  2549  2561 I .ims.rcsservic: Thread[3,tid=2561,WaitingInMainSignalCatcherLoop,Thread*=0xec434e00,peer=0x12d401a8,"Signal Catcher"]: reacting to signal 3
05-05 09:57:27.168  2549  2561 I .ims.rcsservic:
05-05 09:57:27.194  1687  1687 I /system/bin/tombstoned: received crash request for pid 2549
05-05 09:57:27.194  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 2549 and type kDebuggerdJavaBacktrace
05-05 09:57:27.195  2549  2561 I .ims.rcsservic: Wrote stack traces to tombstoned
05-05 09:57:27.196  1880  1970 I system_server: libdebuggerd_client: done dumping process 2549
05-05 09:57:27.198  1880  1970 I ActivityManager: Collecting stacks for pid 2526
05-05 09:57:27.198  1880  1970 I system_server: libdebuggerd_client: started dumping process 2526
05-05 09:57:27.199  1687  1687 I /system/bin/tombstoned: registered intercept for pid 2526 and type kDebuggerdJavaBacktrace
05-05 09:57:27.199  2526  2540 I com.android.se: Thread[3,tid=2540,WaitingInMainSignalCatcherLoop,Thread*=0xec434e00,peer=0x12d001a8,"Signal Catcher"]: reacting to signal 3
05-05 09:57:27.199  2526  2540 I com.android.se:
05-05 09:57:27.229  1687  1687 I /system/bin/tombstoned: received crash request for pid 2526
05-05 09:57:27.229  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 2526 and type kDebuggerdJavaBacktrace
05-05 09:57:27.229  2526  2540 I com.android.se: Wrote stack traces to tombstoned
05-05 09:57:27.231  1880  1970 I system_server: libdebuggerd_client: done dumping process 2526
05-05 09:57:27.233  1880  1970 I ActivityManager: Collecting stacks for pid 2269
05-05 09:57:27.234  1880  1970 I system_server: libdebuggerd_client: started dumping process 2269
05-05 09:57:27.234  1687  1687 I /system/bin/tombstoned: registered intercept for pid 2269 and type kDebuggerdJavaBacktrace
05-05 09:57:27.234  2269  2280 I m.android.phon: Thread[2,tid=2280,WaitingInMainSignalCatcherLoop,Thread*=0xd9100000,peer=0x13740830,"Signal Catcher"]: reacting to signal 3
05-05 09:57:27.235  2269  2280 I m.android.phon:
05-05 09:57:27.287  1687  1687 I /system/bin/tombstoned: received crash request for pid 2269
05-05 09:57:27.287  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 2269 and type kDebuggerdJavaBacktrace
05-05 09:57:27.287  2269  2280 I m.android.phon: Wrote stack traces to tombstoned
05-05 09:57:27.290  1880  1970 I system_server: libdebuggerd_client: done dumping process 2269
05-05 09:57:27.293  1880  1970 I ActivityManager: Collecting stacks for pid 2209
05-05 09:57:27.293  1880  1970 I system_server: libdebuggerd_client: started dumping process 2209
05-05 09:57:27.293  1687  1687 I /system/bin/tombstoned: registered intercept for pid 2209 and type kDebuggerdJavaBacktrace
05-05 09:57:27.294  2209  2224 I id.networkstac: Thread[4,tid=2224,WaitingInMainSignalCatcherLoop,Thread*=0xd9108c00,peer=0x12f00220,"Signal Catcher"]: reacting to signal 3
05-05 09:57:27.294  2209  2224 I id.networkstac:
05-05 09:57:27.331  1687  1687 I /system/bin/tombstoned: received crash request for pid 2209
05-05 09:57:27.331  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 2209 and type kDebuggerdJavaBacktrace
05-05 09:57:27.333  2209  2224 I id.networkstac: Wrote stack traces to tombstoned
05-05 09:57:27.335  1880  1970 I system_server: libdebuggerd_client: done dumping process 2209
05-05 09:57:27.336  1880  1970 I ActivityManager: Collecting stacks for pid 2029
05-05 09:57:27.336  1880  1970 I system_server: libdebuggerd_client: started dumping process 2029
05-05 09:57:27.337  1687  1687 I /system/bin/tombstoned: registered intercept for pid 2029 and type kDebuggerdJavaBacktrace
05-05 09:57:27.337  2029  2042 I ndroid.systemu: Thread[6,tid=2042,WaitingInMainSignalCatcherLoop,Thread*=0xd9120800,peer=0x13c80078,"Signal Catcher"]: reacting to signal 3
05-05 09:57:27.337  2029  2042 I ndroid.systemu:
05-05 09:57:27.441  1687  1687 I /system/bin/tombstoned: received crash request for pid 2029
05-05 09:57:27.441  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 2029 and type kDebuggerdJavaBacktrace
05-05 09:57:27.442  2029  2042 I ndroid.systemu: Wrote stack traces to tombstoned
05-05 09:57:27.446  1880  1970 I system_server: libdebuggerd_client: done dumping process 2029
05-05 09:57:27.448  1880  1970 I ActivityManager: Collecting stacks for native pid 1527
05-05 09:57:27.448  1880  1970 I system_server: libdebuggerd_client: started dumping process 1527
05-05 09:57:27.448  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1527 and type kDebuggerdNativeBacktrace
05-05 09:57:27.449  1527  1527 I libc    : Requested dump for tid 1527 (Binder:1527_2)
05-05 09:57:27.457 14252 14252 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdNativeBacktrace
05-05 09:57:27.457  1687  1687 I /system/bin/tombstoned: received crash request for pid 1527
05-05 09:57:27.457  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1527 and type kDebuggerdNativeBacktrace
05-05 09:57:27.458 14252 14252 I crash_dump32: performing dump of process 1527 (target tid = 1527)
05-05 09:57:27.477  1880  1970 I system_server: libdebuggerd_client: done dumping process 1527
05-05 09:57:27.479  1880  1970 I ActivityManager: Collecting stacks for native pid 1650
05-05 09:57:27.479  1880  1970 I system_server: libdebuggerd_client: started dumping process 1650
05-05 09:57:27.479  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1650 and type kDebuggerdNativeBacktrace
05-05 09:57:27.480  1650  1650 I libc    : Requested dump for tid 1650 (audioserver)
05-05 09:57:27.495 14257 14257 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdNativeBacktrace
05-05 09:57:27.496  1687  1687 I /system/bin/tombstoned: received crash request for pid 1650
05-05 09:57:27.496  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1650 and type kDebuggerdNativeBacktrace
05-05 09:57:27.496 14257 14257 I crash_dump32: performing dump of process 1650 (target tid = 1650)
05-05 09:57:27.510  1880  1970 I system_server: libdebuggerd_client: done dumping process 1650
05-05 09:57:27.512  1880  1970 I ActivityManager: Collecting stacks for native pid 1653
05-05 09:57:27.512  1880  1970 I system_server: libdebuggerd_client: started dumping process 1653
05-05 09:57:27.512  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1653 and type kDebuggerdNativeBacktrace
05-05 09:57:27.513  1653  1653 I libc    : Requested dump for tid 1653 (surfaceflinger)
05-05 09:57:27.530 14262 14262 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdNativeBacktrace
05-05 09:57:27.531  1687  1687 I /system/bin/tombstoned: received crash request for pid 1653
05-05 09:57:27.531  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1653 and type kDebuggerdNativeBacktrace
05-05 09:57:27.531 14262 14262 I crash_dump32: performing dump of process 1653 (target tid = 1653)
05-05 09:57:27.550  1880  1970 I system_server: libdebuggerd_client: done dumping process 1653
05-05 09:57:27.553  1880  1970 I ActivityManager: Collecting stacks for native pid 1663
05-05 09:57:27.553  1880  1970 I system_server: libdebuggerd_client: started dumping process 1663
05-05 09:57:27.553  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1663 and type kDebuggerdNativeBacktrace
05-05 09:57:27.553  1663  1663 I libc    : Requested dump for tid 1663 (cameraserver)
05-05 09:57:27.565 14267 14267 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdNativeBacktrace
05-05 09:57:27.566  1687  1687 I /system/bin/tombstoned: received crash request for pid 1663
05-05 09:57:27.566  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1663 and type kDebuggerdNativeBacktrace
05-05 09:57:27.566 14267 14267 I crash_dump32: performing dump of process 1663 (target tid = 1663)
05-05 09:57:27.581  1880  1970 I system_server: libdebuggerd_client: done dumping process 1663
05-05 09:57:27.585  1880  1970 I ActivityManager: Collecting stacks for native pid 1664
05-05 09:57:27.585  1880  1970 I system_server: libdebuggerd_client: started dumping process 1664
05-05 09:57:27.585  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1664 and type kDebuggerdNativeBacktrace
05-05 09:57:27.586  1664  1664 I libc    : Requested dump for tid 1664 (drmserver)
05-05 09:57:27.594 14272 14272 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdNativeBacktrace
05-05 09:57:27.595  1687  1687 I /system/bin/tombstoned: received crash request for pid 1664
05-05 09:57:27.595  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1664 and type kDebuggerdNativeBacktrace
05-05 09:57:27.595 14272 14272 I crash_dump32: performing dump of process 1664 (target tid = 1664)
05-05 09:57:27.606  1880  1970 I system_server: libdebuggerd_client: done dumping process 1664
05-05 09:57:27.608  1880  1970 I ActivityManager: Collecting stacks for native pid 1670
05-05 09:57:27.608  1880  1970 I system_server: libdebuggerd_client: started dumping process 1670
05-05 09:57:27.608  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1670 and type kDebuggerdNativeBacktrace
05-05 09:57:27.609  1670  1670 I libc    : Requested dump for tid 1670 (mediadrmserver)
05-05 09:57:27.617 14277 14277 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdNativeBacktrace
05-05 09:57:27.618  1687  1687 I /system/bin/tombstoned: received crash request for pid 1670
05-05 09:57:27.618  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1670 and type kDebuggerdNativeBacktrace
05-05 09:57:27.618 14277 14277 I crash_dump32: performing dump of process 1670 (target tid = 1670)
05-05 09:57:27.628  1880  1970 I system_server: libdebuggerd_client: done dumping process 1670
05-05 09:57:27.630  1880  1970 I ActivityManager: Collecting stacks for native pid 1671
05-05 09:57:27.630  1880  1970 I system_server: libdebuggerd_client: started dumping process 1671
05-05 09:57:27.630  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1671 and type kDebuggerdNativeBacktrace
05-05 09:57:27.631  1687  1687 I /system/bin/tombstoned: received crash request for pid 1671
05-05 09:57:27.631  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1671 and type kDebuggerdNativeBacktrace
05-05 09:57:27.651  1880  1970 I system_server: libdebuggerd_client: done dumping process 1671
05-05 09:57:27.653  1880  1970 I ActivityManager: Collecting stacks for native pid 1672
05-05 09:57:27.653  1880  1970 I system_server: libdebuggerd_client: started dumping process 1672
05-05 09:57:27.653  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1672 and type kDebuggerdNativeBacktrace
05-05 09:57:27.654  1672  1672 I libc    : Requested dump for tid 1672 (mediametrics)
05-05 09:57:27.662 14282 14282 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdNativeBacktrace
05-05 09:57:27.662  1687  1687 I /system/bin/tombstoned: received crash request for pid 1672
05-05 09:57:27.662  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1672 and type kDebuggerdNativeBacktrace
05-05 09:57:27.663 14282 14282 I crash_dump32: performing dump of process 1672 (target tid = 1672)
05-05 09:57:27.671  1880  1970 I system_server: libdebuggerd_client: done dumping process 1672
05-05 09:57:27.673  1880  1970 I ActivityManager: Collecting stacks for native pid 1673
05-05 09:57:27.673  1880  1970 I system_server: libdebuggerd_client: started dumping process 1673
05-05 09:57:27.674  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1673 and type kDebuggerdNativeBacktrace
05-05 09:57:27.674  1673  1673 I libc    : Requested dump for tid 1673 (mediaserver)
05-05 09:57:27.684 14287 14287 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdNativeBacktrace
05-05 09:57:27.684  1687  1687 I /system/bin/tombstoned: received crash request for pid 1673
05-05 09:57:27.684  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1673 and type kDebuggerdNativeBacktrace
05-05 09:57:27.684 14287 14287 I crash_dump32: performing dump of process 1673 (target tid = 1673)
05-05 09:57:27.691  1880  1970 I system_server: libdebuggerd_client: done dumping process 1673
05-05 09:57:27.693  1880  1970 I ActivityManager: Collecting stacks for native pid 1674
05-05 09:57:27.693  1880  1970 I system_server: libdebuggerd_client: started dumping process 1674
05-05 09:57:27.694  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1674 and type kDebuggerdNativeBacktrace
05-05 09:57:27.694  1674  1674 I libc    : Requested dump for tid 1674 (Binder:1674_2)
05-05 09:57:27.702 14292 14292 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdNativeBacktrace
05-05 09:57:27.702  1687  1687 I /system/bin/tombstoned: received crash request for pid 1674
05-05 09:57:27.702  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1674 and type kDebuggerdNativeBacktrace
05-05 09:57:27.703 14292 14292 I crash_dump32: performing dump of process 1674 (target tid = 1674)
05-05 09:57:27.723  1880  1970 I system_server: libdebuggerd_client: done dumping process 1674
05-05 09:57:27.724  1880  1970 I ActivityManager: Collecting stacks for native pid 1677
05-05 09:57:27.725  1880  1970 I system_server: libdebuggerd_client: started dumping process 1677
05-05 09:57:27.725  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1677 and type kDebuggerdNativeBacktrace
05-05 09:57:27.726  1687  1687 I /system/bin/tombstoned: received crash request for pid 1677
05-05 09:57:27.726  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1677 and type kDebuggerdNativeBacktrace
05-05 09:57:27.761  1880  1970 I system_server: libdebuggerd_client: done dumping process 1677
05-05 09:57:27.764  1880  1970 I ActivityManager: Collecting stacks for native pid 1684
05-05 09:57:27.764  1880  1970 I system_server: libdebuggerd_client: started dumping process 1684
05-05 09:57:27.764  1687  1687 I /system/bin/tombstoned: registered intercept for pid 1684 and type kDebuggerdNativeBacktrace
05-05 09:57:27.765  1687  1687 I /system/bin/tombstoned: received crash request for pid 1684
05-05 09:57:27.766  1687  1687 I /system/bin/tombstoned: found intercept fd 512 for pid 1684 and type kDebuggerdNativeBacktrace
05-05 09:57:27.784  1880  1970 I system_server: libdebuggerd_client: done dumping process 1684
05-05 09:57:27.786  1880  1970 I ActivityManager: Done dumping
05-05 09:57:27.797  1880  1970 E ActivityManager: ANR in com.akylas.cairnmobile (com.akylas.cairnmobile/com.tns.NativeScriptActivity)
05-05 09:57:27.797  1880  1970 E ActivityManager: PID: 14084
05-05 09:57:27.797  1880  1970 E ActivityManager: Reason: Input dispatching timed out (Waiting to send non-key event because the touched window has not finished processing certain input events that were delivered to it over 500.0ms ago.  Wait queue length: 5.  Wait queue head age: 6288.2ms.)
05-05 09:57:27.797  1880  1970 E ActivityManager: Parent: com.akylas.cairnmobile/com.tns.NativeScriptActivity
05-05 09:57:27.797  1880  1970 E ActivityManager: Load: 1.39 / 0.77 / 0.33
05-05 09:57:27.797  1880  1970 E ActivityManager: CPU usage from 0ms to 11797ms later (2020-05-05 09:57:15.989 to 2020-05-05 09:57:27.786):
05-05 09:57:27.797  1880  1970 E ActivityManager:   8.3% 1653/surfaceflinger: 0.3% user + 7.9% kernel / faults: 59 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:   5.5% 1880/system_server: 1.4% user + 4.1% kernel / faults: 2033 minor 36 major
05-05 09:57:27.797  1880  1970 E ActivityManager:   4.8% 2029/com.android.systemui: 2.3% user + 2.4% kernel / faults: 1268 minor 23 major
05-05 09:57:27.797  1880  1970 E ActivityManager:   1% 1642/android.hardware.sensors@1.0-service: 0% user + 0.9% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.7% 1626/android.system.suspend@1.0-service: 0.3% user + 0.4% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.6% 2269/com.android.phone: 0% user + 0.5% kernel / faults: 231 minor 7 major
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.2% 13231/kworker/u8:0: 0% user + 0.2% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 2573/com.android.emulator.multidisplay: 0% user + 0% kernel / faults: 648 minor 163 major
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.5% 1639/android.hardware.graphics.composer@2.1-service: 0% user + 0.5% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.5% 1659/adbd: 0% user + 0.5% kernel / faults: 1 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 2712/com.android.smspush: 0% user + 0% kernel / faults: 524 minor 261 major
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.5% 14084/com.akylas.cairnmobile: 0% user + 0.5% kernel / faults: 50 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.4% 2367/com.android.inputmethod.latin: 0.2% user + 0.1% kernel / faults: 230 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.3% 2209/com.android.networkstack: 0.1% user + 0.1% kernel / faults: 219 minor 1 major
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.2% 1522/logd: 0.2% user + 0% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 1677/media.codec: 0% user + 0% kernel / faults: 2150 minor 5 major
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.2% 2526/com.android.se: 0.1% user + 0% kernel / faults: 488 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:   0.2% 2549/com.android.ims.rcsservice: 0.1% user + 0% kernel / faults: 481 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 1671/media.extractor: 0% user + 0% kernel / faults: 985 minor 3 major
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 12905/kworker/u8:1: 0% user + 0% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 1/init: 0% user + 0% kernel / faults: 1 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 8/rcu_preempt: 0% user + 0% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 1650/audioserver: 0% user + 0% kernel / faults: 49 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 1652/lmkd: 0% user + 0% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 1654/logcat: 0% user + 0% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 1674/statsd: 0% user + 0% kernel / faults: 35 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 1684/media.swcodec: 0% user + 0% kernel / faults: 583 minor 9 major
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 1687/tombstoned: 0% user + 0% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 1798/netmgr: 0% user + 0% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 12940/kworker/u8:2: 0% user + 0% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   0% 13223/logcat: 0% user + 0% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager: 4.2% TOTAL: 1.4% user + 2.6% kernel + 0% iowait + 0% softirq
05-05 09:57:27.797  1880  1970 E ActivityManager: CPU usage from 12ms to 310ms later (2020-05-05 09:57:16.002 to 2020-05-05 09:57:16.300):
05-05 09:57:27.797  1880  1970 E ActivityManager:   23% 1880/system_server: 0% user + 23% kernel / faults: 198 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:     19% 1970/InputDispatcher: 3.8% user + 15% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:     3.8% 1956/SensorService: 0% user + 3.8% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   7.4% 1653/surfaceflinger: 0% user + 7.4% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:     3.7% 1751/DispSync: 0% user + 3.7% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:     3.7% 1753/app: 0% user + 3.7% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:     3.7% 2366/Binder:1653_5: 0% user + 3.7% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   3.3% 8/rcu_preempt: 0% user + 3.3% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager:   3.9% 2029/com.android.systemui: 0% user + 3.9% kernel / faults: 1 minor
05-05 09:57:27.797  1880  1970 E ActivityManager:     3.9% 2029/ndroid.systemui: 0% user + 3.9% kernel
05-05 09:57:27.797  1880  1970 E ActivityManager: 9.6% TOTAL: 3.5% user + 6.1% kernel
05-05 09:57:27.799  1880  1970 I InputDispatcher: Dropped event because it is stale.
05-05 09:57:27.821  1880 14295 I DropBoxManagerService: add tag=data_app_anr isTagEnabled=true flags=0x2
05-05 09:57:27.813  1880  1970 I chatty  : uid=1000(system) InputDispatcher identical 44 lines
05-05 09:57:27.813  1880  1970 I InputDispatcher: Dropped event because it is stale.
05-05 09:57:27.865  1880  3675 D         : HostConnection::get() New Host Connection established 0xd9166d10, tid 3675
05-05 09:57:27.865  1880  3675 D         : HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_async_unmap_buffer GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_gles_max_version_3_0
05-05 09:57:27.866  1880  3675 W OpenGLRenderer: Failed to choose config with EGL_SWAP_BEHAVIOR_PRESERVED, retrying without...
05-05 09:57:27.867  1880  3675 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 0 0
05-05 09:57:27.867  1880  3675 D EGL_emulation: eglCreateContext: 0xd911c400: maj 3 min 0 rcv 3
05-05 09:57:27.867  1880  3675 D EGL_emulation: eglMakeCurrent: 0xd911c400: ver 3 0 (tinfo 0xd91f2510)
05-05 09:57:27.877  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 5783552
05-05 09:57:27.883  1638  1720 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 5783552
05-05 09:57:27.887  1880  3675 D EGL_emulation: eglMakeCurrent: 0xd911c400: ver 3 0 (tinfo 0xd91f2510)
05-05 09:57:27.888  1638  1638 D gralloc_ranchu: gralloc_alloc: Creating ashmem region of size 5783552
05-05 09:57:27.891  1880  3675 D eglCodecCommon: setVertexArrayObject: set vao to 0 (0) 1 0
05-05 09:57:27.959  1880  1900 W Looper  : Slow dispatch took 115ms android.ui h=android.view.Choreographer$FrameHandler c=android.view.Choreographer$FrameDisplayEventReceiver@fdfc0ae m=0
05-05 09:57:28.006  1880  1893 I system_server: Background concurrent copying GC freed 78665(3757KB) AllocSpace objects, 28(2936KB) LOS objects, 20% free, 23MB/29MB, paused 809us total 190.735ms
05-05 09:57:30.111  2426  2459 W id.ext.service: Reducing the number of considered missed Gc histogram windows from 17719 to 100
mtehver commented 4 years ago

Does this happen if you do not call onSurfaceDestroyed? This method is intended to be called from GL rendering thread, not UI thread.

Could you make a simple Java/Kotlin sample demonstrating this?

farfromrefug commented 4 years ago

@mtehver yes it happens with your build, no change at all. I am right now trying to run your sample app but i dont think it uses fragment. chkecing...

mtehver commented 4 years ago

@farfromrefug With the 4.3.0 build, do you still get hang or just GL errors in the log? Also, if you remove onSurfaceDestroyed call, does it still persist?

farfromrefug commented 4 years ago

@mtehver i dont use onSurfaceDestroyed i tried in a custom build of the sdk. Not doing that anymore. It hangs and crashes. But now i suspect it comes from my side. Having now a very simple fragment example for Carto helps me to see the diferent behaviors. I leave this open for now, but dont really worry i am really starting to think it is not the sdk!

It seems to be related to your latest changes

farfromrefug commented 4 years ago

@mtehver might need a little bit of help still. I think i found the reason of the issue.

arto-mobile-sdk  W  GLResourceManager::~GLResourceManager: Method called from wrong thread!
                         D  GLResourceManager::~GLResourceManager: Delete queue size: 7

i added a log in the destructor which triggers if not on the correct thread. So the queue wont get destroyed. And i see that with the sample app too. Actually it seems to never be in the right thread.

mtehver commented 4 years ago

@farfromrefug The log message 'GLResourceManager::~GLResourceManager: Method called from wrong thread' is something you added to your build? It is quite pointless, as normally the destructor is called from non-render thread and it is perfectly ok.

Can you summarize what happens with final 4.3.0? I think there are 2 questions:

1) Do you get a crash or deadlock? 2) Do you get any GL errors in the log?

Also, a simple test would be helpful to really understand what is going on.

farfromrefug commented 4 years ago

@mtehver well it is pointless indeed but still the resources are not cleared right? I mean if i see the second log here it means that the _deleteQueue is not empty and so this was not called. I wondering if that is normal and when those resources will be delete as this called is in the destructor.

mtehver commented 4 years ago

@farfromrefug Typically the destructor is called when the GL context is already destroyed (or will be destroyed very soon). Thus the resources are deleted/will be deleted with the context.

farfromrefug commented 4 years ago

@mtehver so it cant be a source of memory leak? Which is what i am seeing in this crash. Something wrong with the GL context (possibly out of memory)

mtehver commented 4 years ago

@farfromrefug No, this is should not cause a leak. Regarding the crash, any simple way to reproduce this? Is this an 'assert' in debug build or something that happens with 'official' SDK binaries?

farfromrefug commented 4 years ago

@mtehver not easy in the sense that it seems to happen only in my app built from a native framework (Nativescript). So a lot of things in between. Right now i have pin pointed it to an issue with fragment exit animation. I can see some weird behaviors with the MapView being detached but then attached/detached again. Consequently some frames are being drawn and the GLContext created again. I am first trying to see why this behavior is happening while not happening in my android native fragment example (the dev app i use with the framework).

farfromrefug commented 4 years ago

@mtehver ok so after a lot of testing and everything, something fixed it in the sense i cant get the app to crash anymore. Might be some gradle caching issue ... So i am sorry and surely 4.3.0 fixed it

farfromrefug commented 4 years ago

@mtehver i sadly have to reopen that issue. I am seeing this again. Both on device and emulator. Here is full log https://hastebin.com/raw/ferekozidu. The one persistent thing i see at the moment of the crash is this:

E/emuglGLESv2_enc: device/generic/goldfish-opengl/system/GLESv2_enc/GL2Encoder.cpp:s_glUseProgram:2041 GL error 0x501

I also see that the GL resource management system does its thing correctly. I triggered it by going back and forth to a fragment with the map. Sometimes it happens on the first time i "close" the fragment with the map I did not managed to easily reproduce it in a simple example app yet. But i am starting to think it could come from the the vector layer my map is using. On my map i am using a VectorTileLayer backed by a local MBVectorTileDecoder to show geojson data.

I don't have time right now to create the sample app but i will try to do it. It seems like a big bug to me as it happens randomly and sometimes right away!

mtehver commented 4 years ago

@farfromrefug Thanks for reporting this. I have fixed a potential cause for this: https://github.com/CartoDB/mobile-sdk/commit/000559c78a65074ccc5f404df2ce0e6ed14ce810

Could you test the fix and give us feedback? If the issue still persists, a sample would be very helpful.

farfromrefug commented 4 years ago

@mtehver thanks for the potential quick fix. I will build try and report!

farfromrefug commented 4 years ago

@mtehver just ran some tests with your latest change, and i think you might have fixed it. I have been clicking like a mad man and i cant get it to crash anymore. Switching to main release and it crashes in a few clicks (opening/closing fragment). i think it is best to close it for now and reopen (or new issue) if necessary?

mtehver commented 4 years ago

@farfromrefug Thanks for testing. So it should be fixed now. But let's keep this open until 4.3.1 is released.

mtehver commented 4 years ago

Closing this, as 4.3.1 is now released.