Closed Bug 1455484 Opened 8 years ago Closed 8 years ago

Intermittent position-dynamic-changes/vertical/fromauto-topA-heightN-bottomA.html?padding_abspos == position-dynamic-changes/vertical/topA-heightN-bottomA-ref.html?padding_abspos | application ran for longer than allowed maximum time

Categories

(Firefox for Android Graveyard :: Testing, defect, P5)

Product:
Firefox for Android Graveyard
Component:
Testing
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: csabou [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=174655970&repo=autoland https://queue.taskcluster.net/v1/task/UsOkG0IcR8yNnc7dC88PNw/runs/0/artifacts/public/logs/live_backing.log https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/UsOkG0IcR8yNnc7dC88PNw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1 [task 2018-04-20T00:13:20.241Z] 00:13:20 INFO - REFTEST TEST-START | http://10.0.2.2:8888/tests/layout/reftests/position-dynamic-changes/vertical/fromauto-topA-heightN-bottomA.html?padding_abspos == http://10.0.2.2:8888/tests/layout/reftests/position-dynamic-changes/vertical/topA-heightN-bottomA-ref.html?padding_abspos [task 2018-04-20T00:13:20.241Z] 00:13:20 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8888/tests/layout/reftests/position-dynamic-changes/vertical/fromauto-topA-heightN-bottomA.html?padding_abspos | 250 / 266 (93%) [task 2018-04-20T00:13:21.047Z] 00:13:21 INFO - Browser unexpectedly found running. Killing... [task 2018-04-20T00:13:21.048Z] 00:13:21 INFO - REFTEST TEST-INFO | started process screentopng [task 2018-04-20T00:13:21.724Z] 00:13:21 INFO - REFTEST TEST-INFO | screentopng: exit 0 [task 2018-04-20T00:13:36.778Z] 00:13:36 WARNING - TEST-UNEXPECTED-FAIL | http://10.0.2.2:8888/tests/layout/reftests/position-dynamic-changes/vertical/fromauto-topA-heightN-bottomA.html?padding_abspos == http://10.0.2.2:8888/tests/layout/reftests/position-dynamic-changes/vertical/topA-heightN-bottomA-ref.html?padding_abspos | application ran for longer than allowed maximum time [task 2018-04-20T00:13:36.778Z] 00:13:36 INFO - INFO | automation.py | Application ran for: 1:30:42.671007 [task 2018-04-20T00:13:36.779Z] 00:13:36 INFO - INFO | zombiecheck | Reading PID log: /tmp/tmpVk2oITpidlog [task 2018-04-20T00:13:37.089Z] 00:13:37 INFO - Contents of /data/anr/traces.txt: [task 2018-04-20T00:13:37.090Z] 00:13:37 INFO - ----- pid 774 at 2018-04-19 17:13:21 ----- [task 2018-04-20T00:13:37.091Z] 00:13:37 INFO - Cmd line: org.mozilla.fennec_aurora [task 2018-04-20T00:13:37.091Z] 00:13:37 INFO - JNI: CheckJNI is on; workarounds are off; pins=0; globals=299 [task 2018-04-20T00:13:37.092Z] 00:13:37 INFO - DALVIK THREADS: [task 2018-04-20T00:13:37.092Z] 00:13:37 INFO - (mutexes: tll=0 tsl=0 tscl=0 ghl=0) [task 2018-04-20T00:13:37.092Z] 00:13:37 INFO - "main" prio=5 tid=1 NATIVE [task 2018-04-20T00:13:37.093Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x414c9578 self=0x2a00d090 [task 2018-04-20T00:13:37.094Z] 00:13:37 INFO - | sysTid=774 nice=0 sched=0/0 cgrp=apps handle=1073811452 [task 2018-04-20T00:13:37.094Z] 00:13:37 INFO - | state=S schedstat=( 421453596371 302458712079 421207 ) utm=24449 stm=17696 core=0 [task 2018-04-20T00:13:37.094Z] 00:13:37 INFO - #00 pc 0001c5a4 /system/lib/libc.so (__futex_syscall3+8) [task 2018-04-20T00:13:37.095Z] 00:13:37 INFO - #01 pc 0000e688 /system/lib/libc.so (__pthread_cond_timedwait_relative+48) [task 2018-04-20T00:13:37.095Z] 00:13:37 INFO - #02 pc 0000e6e8 /system/lib/libc.so (__pthread_cond_timedwait+64) [task 2018-04-20T00:13:37.095Z] 00:13:37 INFO - #03 pc 00052e97 /system/lib/libdvm.so [task 2018-04-20T00:13:37.096Z] 00:13:37 INFO - #04 pc 00053461 /system/lib/libdvm.so (dvmChangeStatus(Thread*, ThreadStatus)+30) [task 2018-04-20T00:13:37.096Z] 00:13:37 INFO - #05 pc 00048d29 /system/lib/libdvm.so [task 2018-04-20T00:13:37.097Z] 00:13:37 INFO - #06 pc 00039afd /system/lib/libdvm.so [task 2018-04-20T00:13:37.098Z] 00:13:37 INFO - #07 pc 0004b7a9 /system/lib/libandroid_runtime.so (android::AndroidRuntime::getJNIEnv()+16) [task 2018-04-20T00:13:37.099Z] 00:13:37 INFO - #08 pc 0005eed5 /system/lib/libandroid_runtime.so (android::NativeDisplayEventReceiver::dispatchVsync(long long, int, unsigned int)+20) [task 2018-04-20T00:13:37.099Z] 00:13:37 INFO - #09 pc 0005f0b9 /system/lib/libandroid_runtime.so (android::NativeDisplayEventReceiver::handleEvent(int, int, void*)+80) [task 2018-04-20T00:13:37.099Z] 00:13:37 INFO - #10 pc 00015129 /system/lib/libutils.so (android::Looper::pollInner(int)+468) [task 2018-04-20T00:13:37.100Z] 00:13:37 INFO - #11 pc 000151d5 /system/lib/libutils.so (android::Looper::pollOnce(int, int*, int*, void**)+92) [task 2018-04-20T00:13:37.101Z] 00:13:37 INFO - #12 pc 00067b69 /system/lib/libandroid_runtime.so (android::NativeMessageQueue::pollOnce(_JNIEnv*, int)+22) [task 2018-04-20T00:13:37.102Z] 00:13:37 INFO - #13 pc 0001dc4c /system/lib/libdvm.so (dvmPlatformInvoke+112) [task 2018-04-20T00:13:37.102Z] 00:13:37 INFO - #14 pc 0004dcab /system/lib/libdvm.so (dvmCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+394) [task 2018-04-20T00:13:37.103Z] 00:13:37 INFO - #15 pc 000385e1 /system/lib/libdvm.so (dvmCheckCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+8) [task 2018-04-20T00:13:37.103Z] 00:13:37 INFO - #16 pc 00027060 /system/lib/libdvm.so [task 2018-04-20T00:13:37.104Z] 00:13:37 INFO - #17 pc 0002b580 /system/lib/libdvm.so (dvmInterpret(Thread*, Method const*, JValue*)+184) [task 2018-04-20T00:13:37.105Z] 00:13:37 INFO - #18 pc 0005ff7b /system/lib/libdvm.so (dvmInvokeMethod(Object*, Method const*, ArrayObject*, ArrayObject*, ClassObject*, bool)+350) [task 2018-04-20T00:13:37.105Z] 00:13:37 INFO - #19 pc 00067a9f /system/lib/libdvm.so [task 2018-04-20T00:13:37.105Z] 00:13:37 INFO - #20 pc 00027060 /system/lib/libdvm.so [task 2018-04-20T00:13:37.106Z] 00:13:37 INFO - #21 pc 0002b580 /system/lib/libdvm.so (dvmInterpret(Thread*, Method const*, JValue*)+184) [task 2018-04-20T00:13:37.106Z] 00:13:37 INFO - #22 pc 0005fcbd /system/lib/libdvm.so (dvmCallMethodV(Thread*, Method const*, Object*, bool, JValue*, std::__va_list)+292) [task 2018-04-20T00:13:37.106Z] 00:13:37 INFO - #23 pc 000499ab /system/lib/libdvm.so [task 2018-04-20T00:13:37.107Z] 00:13:37 INFO - #24 pc 0003cb3d /system/lib/libdvm.so [task 2018-04-20T00:13:37.108Z] 00:13:37 INFO - #25 pc 0004b68f /system/lib/libandroid_runtime.so [task 2018-04-20T00:13:37.108Z] 00:13:37 INFO - #26 pc 0004c30f /system/lib/libandroid_runtime.so (android::AndroidRuntime::start(char const*, char const*)+378) [task 2018-04-20T00:13:37.108Z] 00:13:37 INFO - #27 pc 0000105b /system/bin/app_process [task 2018-04-20T00:13:37.109Z] 00:13:37 INFO - #28 pc 0000db4f /system/lib/libc.so (__libc_init+50) [task 2018-04-20T00:13:37.109Z] 00:13:37 INFO - #29 pc 00000d7c /system/bin/app_process [task 2018-04-20T00:13:37.109Z] 00:13:37 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) [task 2018-04-20T00:13:37.110Z] 00:13:37 INFO - at android.os.MessageQueue.next(MessageQueue.java:132) [task 2018-04-20T00:13:37.110Z] 00:13:37 INFO - at android.os.Looper.loop(Looper.java:124) [task 2018-04-20T00:13:37.110Z] 00:13:37 INFO - at android.app.ActivityThread.main(ActivityThread.java:5103) [task 2018-04-20T00:13:37.111Z] 00:13:37 INFO - at java.lang.reflect.Method.invokeNative(Native Method) [task 2018-04-20T00:13:37.111Z] 00:13:37 INFO - at java.lang.reflect.Method.invoke(Method.java:525) [task 2018-04-20T00:13:37.111Z] 00:13:37 INFO - at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:737) [task 2018-04-20T00:13:37.111Z] 00:13:37 INFO - at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:553) [task 2018-04-20T00:13:37.112Z] 00:13:37 INFO - at dalvik.system.NativeStart.main(Native Method) [task 2018-04-20T00:13:37.112Z] 00:13:37 INFO - "Thread-81" prio=5 tid=19 NATIVE [task 2018-04-20T00:13:37.113Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41a48200 self=0x2a428bc0 [task 2018-04-20T00:13:37.113Z] 00:13:37 INFO - | sysTid=888 nice=0 sched=0/0 cgrp=apps handle=708690960 [task 2018-04-20T00:13:37.114Z] 00:13:37 INFO - | state=S schedstat=( 600764718087 432826888455 429375 ) utm=37393 stm=22683 core=0 [task 2018-04-20T00:13:37.114Z] 00:13:37 INFO - #00 pc 0001c5a4 /system/lib/libc.so (__futex_syscall3+8) [task 2018-04-20T00:13:37.114Z] 00:13:37 INFO - #01 pc 0000e688 /system/lib/libc.so (__pthread_cond_timedwait_relative+48) [task 2018-04-20T00:13:37.115Z] 00:13:37 INFO - #02 pc 0000e6e8 /system/lib/libc.so (__pthread_cond_timedwait+64) [task 2018-04-20T00:13:37.115Z] 00:13:37 INFO - #03 pc 003ee87b /data/data/org.mozilla.fennec_aurora/cache/libxul.so [task 2018-04-20T00:13:37.115Z] 00:13:37 INFO - at dalvik.system.NativeStart.run(Native Method) [task 2018-04-20T00:13:37.116Z] 00:13:37 INFO - "Picasso-refQueue" daemon prio=5 tid=31 WAIT [task 2018-04-20T00:13:37.116Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41b50b28 self=0x2a3f3e10 [task 2018-04-20T00:13:37.117Z] 00:13:37 INFO - | sysTid=852 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=707567552 [task 2018-04-20T00:13:37.117Z] 00:13:37 INFO - | state=S schedstat=( 690986 21738632 3 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.117Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.118Z] 00:13:37 INFO - - waiting on <0x41a6ade0> (a java.lang.ref.ReferenceQueue) [task 2018-04-20T00:13:37.118Z] 00:13:37 INFO - at java.lang.Object.wait(Object.java:401) [task 2018-04-20T00:13:37.118Z] 00:13:37 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102) [task 2018-04-20T00:13:37.119Z] 00:13:37 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73) [task 2018-04-20T00:13:37.119Z] 00:13:37 INFO - at com.squareup.picasso.Picasso$CleanupThread.run(Picasso.java:352) [task 2018-04-20T00:13:37.120Z] 00:13:37 INFO - "Picasso-Dispatcher" prio=5 tid=30 NATIVE [task 2018-04-20T00:13:37.120Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41e96eb0 self=0x2a2cd1e0 [task 2018-04-20T00:13:37.120Z] 00:13:37 INFO - | sysTid=851 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=708574728 [task 2018-04-20T00:13:37.121Z] 00:13:37 INFO - | state=S schedstat=( 2467191 42359381 7 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.121Z] 00:13:37 INFO - #00 pc 0001c3dc /system/lib/libc.so (epoll_wait+12) [task 2018-04-20T00:13:37.121Z] 00:13:37 INFO - #01 pc 00014fb1 /system/lib/libutils.so (android::Looper::pollInner(int)+92) [task 2018-04-20T00:13:37.122Z] 00:13:37 INFO - #02 pc 000151d5 /system/lib/libutils.so (android::Looper::pollOnce(int, int*, int*, void**)+92) [task 2018-04-20T00:13:37.123Z] 00:13:37 INFO - #03 pc 00067b69 /system/lib/libandroid_runtime.so (android::NativeMessageQueue::pollOnce(_JNIEnv*, int)+22) [task 2018-04-20T00:13:37.123Z] 00:13:37 INFO - #04 pc 0001dc4c /system/lib/libdvm.so (dvmPlatformInvoke+112) [task 2018-04-20T00:13:37.123Z] 00:13:37 INFO - #05 pc 0004dcab /system/lib/libdvm.so (dvmCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+394) [task 2018-04-20T00:13:37.124Z] 00:13:37 INFO - #06 pc 000385e1 /system/lib/libdvm.so (dvmCheckCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+8) [task 2018-04-20T00:13:37.124Z] 00:13:37 INFO - #07 pc 00027060 /system/lib/libdvm.so [task 2018-04-20T00:13:37.125Z] 00:13:37 INFO - #08 pc 0002b580 /system/lib/libdvm.so (dvmInterpret(Thread*, Method const*, JValue*)+184) [task 2018-04-20T00:13:37.125Z] 00:13:37 INFO - #09 pc 0005fcbd /system/lib/libdvm.so (dvmCallMethodV(Thread*, Method const*, Object*, bool, JValue*, std::__va_list)+292) [task 2018-04-20T00:13:37.125Z] 00:13:37 INFO - #10 pc 0005fce7 /system/lib/libdvm.so (dvmCallMethod(Thread*, Method const*, Object*, JValue*, ...)+20) [task 2018-04-20T00:13:37.126Z] 00:13:37 INFO - #11 pc 00054a6f /system/lib/libdvm.so [task 2018-04-20T00:13:37.126Z] 00:13:37 INFO - #12 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.126Z] 00:13:37 INFO - #13 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.127Z] 00:13:37 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) [task 2018-04-20T00:13:37.127Z] 00:13:37 INFO - at android.os.MessageQueue.next(MessageQueue.java:132) [task 2018-04-20T00:13:37.128Z] 00:13:37 INFO - at android.os.Looper.loop(Looper.java:124) [task 2018-04-20T00:13:37.128Z] 00:13:37 INFO - at android.os.HandlerThread.run(HandlerThread.java:61) [task 2018-04-20T00:13:37.128Z] 00:13:37 INFO - "Picasso-Stats" prio=5 tid=29 NATIVE [task 2018-04-20T00:13:37.129Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41e96c90 self=0x2a369b78 [task 2018-04-20T00:13:37.129Z] 00:13:37 INFO - | sysTid=850 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=708266344 [task 2018-04-20T00:13:37.130Z] 00:13:37 INFO - | state=S schedstat=( 4174963 39330618 7 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.130Z] 00:13:37 INFO - #00 pc 0001c3dc /system/lib/libc.so (epoll_wait+12) [task 2018-04-20T00:13:37.131Z] 00:13:37 INFO - #01 pc 00014fb1 /system/lib/libutils.so (android::Looper::pollInner(int)+92) [task 2018-04-20T00:13:37.131Z] 00:13:37 INFO - #02 pc 000151d5 /system/lib/libutils.so (android::Looper::pollOnce(int, int*, int*, void**)+92) [task 2018-04-20T00:13:37.132Z] 00:13:37 INFO - #03 pc 00067b69 /system/lib/libandroid_runtime.so (android::NativeMessageQueue::pollOnce(_JNIEnv*, int)+22) [task 2018-04-20T00:13:37.132Z] 00:13:37 INFO - #04 pc 0001dc4c /system/lib/libdvm.so (dvmPlatformInvoke+112) [task 2018-04-20T00:13:37.133Z] 00:13:37 INFO - #05 pc 0004dcab /system/lib/libdvm.so (dvmCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+394) [task 2018-04-20T00:13:37.133Z] 00:13:37 INFO - #06 pc 000385e1 /system/lib/libdvm.so (dvmCheckCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+8) [task 2018-04-20T00:13:37.133Z] 00:13:37 INFO - #07 pc 00027060 /system/lib/libdvm.so [task 2018-04-20T00:13:37.133Z] 00:13:37 INFO - #08 pc 0002b580 /system/lib/libdvm.so (dvmInterpret(Thread*, Method const*, JValue*)+184) [task 2018-04-20T00:13:37.134Z] 00:13:37 INFO - #09 pc 0005fcbd /system/lib/libdvm.so (dvmCallMethodV(Thread*, Method const*, Object*, bool, JValue*, std::__va_list)+292) [task 2018-04-20T00:13:37.134Z] 00:13:37 INFO - #10 pc 0005fce7 /system/lib/libdvm.so (dvmCallMethod(Thread*, Method const*, Object*, JValue*, ...)+20) [task 2018-04-20T00:13:37.135Z] 00:13:37 INFO - #11 pc 00054a6f /system/lib/libdvm.so [task 2018-04-20T00:13:37.135Z] 00:13:37 INFO - #12 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.135Z] 00:13:37 INFO - #13 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.136Z] 00:13:37 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) [task 2018-04-20T00:13:37.136Z] 00:13:37 INFO - at android.os.MessageQueue.next(MessageQueue.java:132) [task 2018-04-20T00:13:37.136Z] 00:13:37 INFO - at android.os.Looper.loop(Looper.java:124) [task 2018-04-20T00:13:37.137Z] 00:13:37 INFO - at android.os.HandlerThread.run(HandlerThread.java:61) [task 2018-04-20T00:13:37.137Z] 00:13:37 INFO - "AsyncTask #5" prio=5 tid=28 WAIT [task 2018-04-20T00:13:37.138Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41e13190 self=0x2a3cdf70 [task 2018-04-20T00:13:37.138Z] 00:13:37 INFO - | sysTid=834 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=708633536 [task 2018-04-20T00:13:37.139Z] 00:13:37 INFO - | state=S schedstat=( 26135675 2179443360 27 ) utm=2 stm=0 core=0 [task 2018-04-20T00:13:37.139Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.139Z] 00:13:37 INFO - - waiting on <0x41e132e8> (a java.lang.VMThread) held by tid=28 (AsyncTask #5) [task 2018-04-20T00:13:37.140Z] 00:13:37 INFO - at java.lang.Thread.parkFor(Thread.java:1205) [task 2018-04-20T00:13:37.140Z] 00:13:37 INFO - at sun.misc.Unsafe.park(Unsafe.java:325) [task 2018-04-20T00:13:37.140Z] 00:13:37 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) [task 2018-04-20T00:13:37.141Z] 00:13:37 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) [task 2018-04-20T00:13:37.142Z] 00:13:37 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) [task 2018-04-20T00:13:37.142Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) [task 2018-04-20T00:13:37.142Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) [task 2018-04-20T00:13:37.143Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) [task 2018-04-20T00:13:37.143Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.143Z] 00:13:37 INFO - "AsyncTask #4" prio=5 tid=27 WAIT [task 2018-04-20T00:13:37.144Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41dcd6d8 self=0x2a3989c8 [task 2018-04-20T00:13:37.144Z] 00:13:37 INFO - | sysTid=833 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=708623584 [task 2018-04-20T00:13:37.144Z] 00:13:37 INFO - | state=S schedstat=( 32192455 2085571104 37 ) utm=3 stm=0 core=0 [task 2018-04-20T00:13:37.145Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.145Z] 00:13:37 INFO - - waiting on <0x41dcd7f8> (a java.lang.VMThread) held by tid=27 (AsyncTask #4) [task 2018-04-20T00:13:37.145Z] 00:13:37 INFO - at java.lang.Thread.parkFor(Thread.java:1205) [task 2018-04-20T00:13:37.146Z] 00:13:37 INFO - at sun.misc.Unsafe.park(Unsafe.java:325) [task 2018-04-20T00:13:37.146Z] 00:13:37 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) [task 2018-04-20T00:13:37.146Z] 00:13:37 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) [task 2018-04-20T00:13:37.147Z] 00:13:37 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) [task 2018-04-20T00:13:37.147Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) [task 2018-04-20T00:13:37.148Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) [task 2018-04-20T00:13:37.148Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) [task 2018-04-20T00:13:37.148Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.150Z] 00:13:37 INFO - "AsyncTask #3" prio=5 tid=26 WAIT [task 2018-04-20T00:13:37.150Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41b52a18 self=0x2a374308 [task 2018-04-20T00:13:37.150Z] 00:13:37 INFO - | sysTid=831 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=708540976 [task 2018-04-20T00:13:37.150Z] 00:13:37 INFO - | state=S schedstat=( 20993263 1639116545 25 ) utm=2 stm=0 core=0 [task 2018-04-20T00:13:37.151Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.151Z] 00:13:37 INFO - - waiting on <0x41b03eb0> (a java.lang.VMThread) held by tid=26 (AsyncTask #3) [task 2018-04-20T00:13:37.151Z] 00:13:37 INFO - at java.lang.Thread.parkFor(Thread.java:1205) [task 2018-04-20T00:13:37.151Z] 00:13:37 INFO - at sun.misc.Unsafe.park(Unsafe.java:325) [task 2018-04-20T00:13:37.151Z] 00:13:37 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) [task 2018-04-20T00:13:37.152Z] 00:13:37 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) [task 2018-04-20T00:13:37.152Z] 00:13:37 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) [task 2018-04-20T00:13:37.153Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) [task 2018-04-20T00:13:37.153Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) [task 2018-04-20T00:13:37.153Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) [task 2018-04-20T00:13:37.154Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.154Z] 00:13:37 INFO - "AsyncTask #2" prio=5 tid=25 WAIT [task 2018-04-20T00:13:37.154Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41b510c8 self=0x2a3a9d70 [task 2018-04-20T00:13:37.154Z] 00:13:37 INFO - | sysTid=825 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=708241336 [task 2018-04-20T00:13:37.155Z] 00:13:37 INFO - | state=S schedstat=( 17101204 1839455978 19 ) utm=1 stm=0 core=0 [task 2018-04-20T00:13:37.155Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.155Z] 00:13:37 INFO - - waiting on <0x41a61ea0> (a java.lang.VMThread) held by tid=25 (AsyncTask #2) [task 2018-04-20T00:13:37.155Z] 00:13:37 INFO - at java.lang.Thread.parkFor(Thread.java:1205) [task 2018-04-20T00:13:37.156Z] 00:13:37 INFO - at sun.misc.Unsafe.park(Unsafe.java:325) [task 2018-04-20T00:13:37.156Z] 00:13:37 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) [task 2018-04-20T00:13:37.156Z] 00:13:37 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) [task 2018-04-20T00:13:37.157Z] 00:13:37 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) [task 2018-04-20T00:13:37.157Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) [task 2018-04-20T00:13:37.157Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) [task 2018-04-20T00:13:37.158Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) [task 2018-04-20T00:13:37.158Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.158Z] 00:13:37 INFO - "Picasso-refQueue" daemon prio=5 tid=24 WAIT [task 2018-04-20T00:13:37.159Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41ae5418 self=0x2a317998 [task 2018-04-20T00:13:37.159Z] 00:13:37 INFO - | sysTid=818 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=707886568 [task 2018-04-20T00:13:37.159Z] 00:13:37 INFO - | state=S schedstat=( 837816 363581227 4 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.159Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.159Z] 00:13:37 INFO - - waiting on <0x41a7cf50> (a java.lang.ref.ReferenceQueue) [task 2018-04-20T00:13:37.160Z] 00:13:37 INFO - at java.lang.Object.wait(Object.java:401) [task 2018-04-20T00:13:37.160Z] 00:13:37 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102) [task 2018-04-20T00:13:37.160Z] 00:13:37 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73) [task 2018-04-20T00:13:37.161Z] 00:13:37 INFO - at com.squareup.picasso.Picasso$CleanupThread.run(Picasso.java:352) [task 2018-04-20T00:13:37.161Z] 00:13:37 INFO - "Picasso-Dispatcher" prio=5 tid=23 NATIVE [task 2018-04-20T00:13:37.161Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41bca7d8 self=0x2a316868 [task 2018-04-20T00:13:37.162Z] 00:13:37 INFO - | sysTid=817 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=707882168 [task 2018-04-20T00:13:37.162Z] 00:13:37 INFO - | state=S schedstat=( 2038932 447498285 8 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.162Z] 00:13:37 INFO - #00 pc 0001c3dc /system/lib/libc.so (epoll_wait+12) [task 2018-04-20T00:13:37.163Z] 00:13:37 INFO - #01 pc 00014fb1 /system/lib/libutils.so (android::Looper::pollInner(int)+92) [task 2018-04-20T00:13:37.163Z] 00:13:37 INFO - #02 pc 000151d5 /system/lib/libutils.so (android::Looper::pollOnce(int, int*, int*, void**)+92) [task 2018-04-20T00:13:37.163Z] 00:13:37 INFO - #03 pc 00067b69 /system/lib/libandroid_runtime.so (android::NativeMessageQueue::pollOnce(_JNIEnv*, int)+22) [task 2018-04-20T00:13:37.164Z] 00:13:37 INFO - #04 pc 0001dc4c /system/lib/libdvm.so (dvmPlatformInvoke+112) [task 2018-04-20T00:13:37.164Z] 00:13:37 INFO - #05 pc 0004dcab /system/lib/libdvm.so (dvmCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+394) [task 2018-04-20T00:13:37.165Z] 00:13:37 INFO - #06 pc 000385e1 /system/lib/libdvm.so (dvmCheckCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+8) [task 2018-04-20T00:13:37.165Z] 00:13:37 INFO - #07 pc 00027060 /system/lib/libdvm.so [task 2018-04-20T00:13:37.166Z] 00:13:37 INFO - #08 pc 0002b580 /system/lib/libdvm.so (dvmInterpret(Thread*, Method const*, JValue*)+184) [task 2018-04-20T00:13:37.166Z] 00:13:37 INFO - #09 pc 0005fcbd /system/lib/libdvm.so (dvmCallMethodV(Thread*, Method const*, Object*, bool, JValue*, std::__va_list)+292) [task 2018-04-20T00:13:37.166Z] 00:13:37 INFO - #10 pc 0005fce7 /system/lib/libdvm.so (dvmCallMethod(Thread*, Method const*, Object*, JValue*, ...)+20) [task 2018-04-20T00:13:37.166Z] 00:13:37 INFO - #11 pc 00054a6f /system/lib/libdvm.so [task 2018-04-20T00:13:37.167Z] 00:13:37 INFO - #12 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.167Z] 00:13:37 INFO - #13 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.167Z] 00:13:37 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) [task 2018-04-20T00:13:37.168Z] 00:13:37 INFO - at android.os.MessageQueue.next(MessageQueue.java:132) [task 2018-04-20T00:13:37.168Z] 00:13:37 INFO - at android.os.Looper.loop(Looper.java:124) [task 2018-04-20T00:13:37.168Z] 00:13:37 INFO - at android.os.HandlerThread.run(HandlerThread.java:61) [task 2018-04-20T00:13:37.169Z] 00:13:37 INFO - "Picasso-Stats" prio=5 tid=22 NATIVE [task 2018-04-20T00:13:37.169Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41a8c2d0 self=0x2a316418 [task 2018-04-20T00:13:37.170Z] 00:13:37 INFO - | sysTid=816 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=707307824 [task 2018-04-20T00:13:37.170Z] 00:13:37 INFO - | state=S schedstat=( 1792689 439217991 7 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.170Z] 00:13:37 INFO - #00 pc 0001c3dc /system/lib/libc.so (epoll_wait+12) [task 2018-04-20T00:13:37.171Z] 00:13:37 INFO - #01 pc 00014fb1 /system/lib/libutils.so (android::Looper::pollInner(int)+92) [task 2018-04-20T00:13:37.171Z] 00:13:37 INFO - #02 pc 000151d5 /system/lib/libutils.so (android::Looper::pollOnce(int, int*, int*, void**)+92) [task 2018-04-20T00:13:37.171Z] 00:13:37 INFO - #03 pc 00067b69 /system/lib/libandroid_runtime.so (android::NativeMessageQueue::pollOnce(_JNIEnv*, int)+22) [task 2018-04-20T00:13:37.172Z] 00:13:37 INFO - #04 pc 0001dc4c /system/lib/libdvm.so (dvmPlatformInvoke+112) [task 2018-04-20T00:13:37.172Z] 00:13:37 INFO - #05 pc 0004dcab /system/lib/libdvm.so (dvmCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+394) [task 2018-04-20T00:13:37.173Z] 00:13:37 INFO - #06 pc 000385e1 /system/lib/libdvm.so (dvmCheckCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+8) [task 2018-04-20T00:13:37.173Z] 00:13:37 INFO - #07 pc 00027060 /system/lib/libdvm.so [task 2018-04-20T00:13:37.173Z] 00:13:37 INFO - #08 pc 0002b580 /system/lib/libdvm.so (dvmInterpret(Thread*, Method const*, JValue*)+184) [task 2018-04-20T00:13:37.174Z] 00:13:37 INFO - #09 pc 0005fcbd /system/lib/libdvm.so (dvmCallMethodV(Thread*, Method const*, Object*, bool, JValue*, std::__va_list)+292) [task 2018-04-20T00:13:37.174Z] 00:13:37 INFO - #10 pc 0005fce7 /system/lib/libdvm.so (dvmCallMethod(Thread*, Method const*, Object*, JValue*, ...)+20) [task 2018-04-20T00:13:37.174Z] 00:13:37 INFO - #11 pc 00054a6f /system/lib/libdvm.so [task 2018-04-20T00:13:37.175Z] 00:13:37 INFO - #12 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.175Z] 00:13:37 INFO - #13 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.176Z] 00:13:37 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) [task 2018-04-20T00:13:37.176Z] 00:13:37 INFO - at android.os.MessageQueue.next(MessageQueue.java:132) [task 2018-04-20T00:13:37.176Z] 00:13:37 INFO - at android.os.Looper.loop(Looper.java:124) [task 2018-04-20T00:13:37.177Z] 00:13:37 INFO - at android.os.HandlerThread.run(HandlerThread.java:61) [task 2018-04-20T00:13:37.177Z] 00:13:37 INFO - "AsyncTask #1" prio=5 tid=21 WAIT [task 2018-04-20T00:13:37.177Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41bcda00 self=0x2a2fcca0 [task 2018-04-20T00:13:37.178Z] 00:13:37 INFO - | sysTid=815 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=707781192 [task 2018-04-20T00:13:37.178Z] 00:13:37 INFO - | state=S schedstat=( 342229777 18063332613 186 ) utm=31 stm=3 core=0 [task 2018-04-20T00:13:37.178Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.179Z] 00:13:37 INFO - - waiting on <0x41bcdb98> (a java.lang.VMThread) held by tid=21 (AsyncTask #1) [task 2018-04-20T00:13:37.179Z] 00:13:37 INFO - at java.lang.Thread.parkFor(Thread.java:1205) [task 2018-04-20T00:13:37.180Z] 00:13:37 INFO - at sun.misc.Unsafe.park(Unsafe.java:325) [task 2018-04-20T00:13:37.180Z] 00:13:37 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) [task 2018-04-20T00:13:37.180Z] 00:13:37 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) [task 2018-04-20T00:13:37.181Z] 00:13:37 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) [task 2018-04-20T00:13:37.181Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) [task 2018-04-20T00:13:37.182Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) [task 2018-04-20T00:13:37.182Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) [task 2018-04-20T00:13:37.182Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.183Z] 00:13:37 INFO - "ModernAsyncTask #3" prio=5 tid=20 WAIT [task 2018-04-20T00:13:37.183Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41ab7c70 self=0x2a26a2c0 [task 2018-04-20T00:13:37.184Z] 00:13:37 INFO - | sysTid=813 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=707773048 [task 2018-04-20T00:13:37.184Z] 00:13:37 INFO - | state=S schedstat=( 72750124 12884065473 175 ) utm=7 stm=0 core=0 [task 2018-04-20T00:13:37.184Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.186Z] 00:13:37 INFO - - waiting on <0x41ab8bf8> (a java.lang.VMThread) held by tid=20 (ModernAsyncTask #3) [task 2018-04-20T00:13:37.186Z] 00:13:37 INFO - at java.lang.Thread.parkFor(Thread.java:1205) [task 2018-04-20T00:13:37.186Z] 00:13:37 INFO - at sun.misc.Unsafe.park(Unsafe.java:325) [task 2018-04-20T00:13:37.186Z] 00:13:37 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) [task 2018-04-20T00:13:37.186Z] 00:13:37 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) [task 2018-04-20T00:13:37.187Z] 00:13:37 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) [task 2018-04-20T00:13:37.187Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) [task 2018-04-20T00:13:37.187Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) [task 2018-04-20T00:13:37.188Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) [task 2018-04-20T00:13:37.188Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.189Z] 00:13:37 INFO - "ModernAsyncTask #2" prio=5 tid=17 WAIT [task 2018-04-20T00:13:37.189Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41a74488 self=0x2a27c140 [task 2018-04-20T00:13:37.190Z] 00:13:37 INFO - | sysTid=812 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=706662384 [task 2018-04-20T00:13:37.190Z] 00:13:37 INFO - | state=S schedstat=( 626044630 27362324931 318 ) utm=54 stm=8 core=0 [task 2018-04-20T00:13:37.190Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.191Z] 00:13:37 INFO - - waiting on <0x41b7e118> (a java.lang.VMThread) held by tid=17 (ModernAsyncTask #2) [task 2018-04-20T00:13:37.191Z] 00:13:37 INFO - at java.lang.Thread.parkFor(Thread.java:1205) [task 2018-04-20T00:13:37.191Z] 00:13:37 INFO - at sun.misc.Unsafe.park(Unsafe.java:325) [task 2018-04-20T00:13:37.191Z] 00:13:37 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) [task 2018-04-20T00:13:37.192Z] 00:13:37 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) [task 2018-04-20T00:13:37.192Z] 00:13:37 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) [task 2018-04-20T00:13:37.193Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) [task 2018-04-20T00:13:37.193Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) [task 2018-04-20T00:13:37.193Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) [task 2018-04-20T00:13:37.193Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.194Z] 00:13:37 INFO - "Binder_3" prio=5 tid=18 NATIVE [task 2018-04-20T00:13:37.194Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41ad5fd0 self=0x2a249ea8 [task 2018-04-20T00:13:37.195Z] 00:13:37 INFO - | sysTid=803 nice=0 sched=0/0 cgrp=apps handle=706662968 [task 2018-04-20T00:13:37.195Z] 00:13:37 INFO - | state=S schedstat=( 3264463 89721792 6 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.195Z] 00:13:37 INFO - #00 pc 0001b2d4 /system/lib/libc.so (__ioctl+8) [task 2018-04-20T00:13:37.196Z] 00:13:37 INFO - #01 pc 0002b94b /system/lib/libc.so (ioctl+14) [task 2018-04-20T00:13:37.197Z] 00:13:37 INFO - #02 pc 0001b539 /system/lib/libbinder.so (android::IPCThreadState::talkWithDriver(bool)+140) [task 2018-04-20T00:13:37.197Z] 00:13:37 INFO - #03 pc 0001bcd7 /system/lib/libbinder.so (android::IPCThreadState::joinThreadPool(bool)+154) [task 2018-04-20T00:13:37.197Z] 00:13:37 INFO - #04 pc 0001faa5 /system/lib/libbinder.so [task 2018-04-20T00:13:37.197Z] 00:13:37 INFO - #05 pc 00011a75 /system/lib/libutils.so (android::Thread::_threadLoop(void*)+216) [task 2018-04-20T00:13:37.198Z] 00:13:37 INFO - #06 pc 0004b629 /system/lib/libandroid_runtime.so (android::AndroidRuntime::javaThreadShell(void*)+68) [task 2018-04-20T00:13:37.198Z] 00:13:37 INFO - #07 pc 00011569 /system/lib/libutils.so [task 2018-04-20T00:13:37.199Z] 00:13:37 INFO - #08 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.199Z] 00:13:37 INFO - #09 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.200Z] 00:13:37 INFO - at dalvik.system.NativeStart.run(Native Method) [task 2018-04-20T00:13:37.200Z] 00:13:37 INFO - "GeckoIconTask" prio=5 tid=16 WAIT [task 2018-04-20T00:13:37.200Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41b84ba8 self=0x2a2343a0 [task 2018-04-20T00:13:37.201Z] 00:13:37 INFO - | sysTid=801 nice=0 sched=0/0 cgrp=apps handle=706955248 [task 2018-04-20T00:13:37.201Z] 00:13:37 INFO - | state=S schedstat=( 890000368 3421865632 961 ) utm=77 stm=12 core=0 [task 2018-04-20T00:13:37.202Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.202Z] 00:13:37 INFO - - waiting on <0x41b84cd0> (a java.lang.VMThread) held by tid=16 (GeckoIconTask) [task 2018-04-20T00:13:37.203Z] 00:13:37 INFO - at java.lang.Thread.parkFor(Thread.java:1205) [task 2018-04-20T00:13:37.203Z] 00:13:37 INFO - at sun.misc.Unsafe.park(Unsafe.java:325) [task 2018-04-20T00:13:37.204Z] 00:13:37 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) [task 2018-04-20T00:13:37.204Z] 00:13:37 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) [task 2018-04-20T00:13:37.205Z] 00:13:37 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) [task 2018-04-20T00:13:37.205Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) [task 2018-04-20T00:13:37.206Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) [task 2018-04-20T00:13:37.206Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) [task 2018-04-20T00:13:37.206Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.207Z] 00:13:37 INFO - "ModernAsyncTask #1" prio=5 tid=15 WAIT [task 2018-04-20T00:13:37.207Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41b78668 self=0x2a1d1a80 [task 2018-04-20T00:13:37.208Z] 00:13:37 INFO - | sysTid=800 nice=10 sched=0/0 cgrp=apps/bg_non_interactive handle=706957848 [task 2018-04-20T00:13:37.208Z] 00:13:37 INFO - | state=S schedstat=( 9725141 32552718 3 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.209Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.209Z] 00:13:37 INFO - - waiting on <0x41b78850> (a java.lang.VMThread) held by tid=15 (ModernAsyncTask #1) [task 2018-04-20T00:13:37.209Z] 00:13:37 INFO - at java.lang.Thread.parkFor(Thread.java:1205) [task 2018-04-20T00:13:37.209Z] 00:13:37 INFO - at sun.misc.Unsafe.park(Unsafe.java:325) [task 2018-04-20T00:13:37.210Z] 00:13:37 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) [task 2018-04-20T00:13:37.211Z] 00:13:37 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) [task 2018-04-20T00:13:37.211Z] 00:13:37 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) [task 2018-04-20T00:13:37.211Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) [task 2018-04-20T00:13:37.212Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) [task 2018-04-20T00:13:37.212Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) [task 2018-04-20T00:13:37.212Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.213Z] 00:13:37 INFO - "actionMode" prio=5 tid=14 WAIT [task 2018-04-20T00:13:37.213Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41a59b28 self=0x2a1d0df8 [task 2018-04-20T00:13:37.214Z] 00:13:37 INFO - | sysTid=797 nice=0 sched=0/0 cgrp=apps handle=706566880 [task 2018-04-20T00:13:37.215Z] 00:13:37 INFO - | state=S schedstat=( 894233 32313264 4 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.215Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.215Z] 00:13:37 INFO - - waiting on <0x41a59b28> (a java.util.Timer$TimerImpl) [task 2018-04-20T00:13:37.215Z] 00:13:37 INFO - at java.lang.Object.wait(Object.java:364) [task 2018-04-20T00:13:37.215Z] 00:13:37 INFO - at java.util.Timer$TimerImpl.run(Timer.java:214) [task 2018-04-20T00:13:37.216Z] 00:13:37 INFO - "Gecko" prio=5 tid=13 NATIVE [task 2018-04-20T00:13:37.216Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41796870 self=0x2a1e96f8 [task 2018-04-20T00:13:37.216Z] 00:13:37 INFO - | sysTid=796 nice=0 sched=0/0 cgrp=apps handle=706648904 [task 2018-04-20T00:13:37.217Z] 00:13:37 INFO - | state=R schedstat=( 3083064038517 1645333197737 734725 ) utm=292191 stm=16115 core=0 [task 2018-04-20T00:13:37.217Z] 00:13:37 INFO - #00 pc 022f1ef8 /data/data/org.mozilla.fennec_aurora/cache/libxul.so [task 2018-04-20T00:13:37.218Z] 00:13:37 INFO - #01 pc 027d3b4b /data/data/org.mozilla.fennec_aurora/cache/libxul.so [task 2018-04-20T00:13:37.218Z] 00:13:37 INFO - at org.mozilla.gecko.mozglue.GeckoLoader.nativeRun(Native Method) [task 2018-04-20T00:13:37.219Z] 00:13:37 INFO - at org.mozilla.gecko.GeckoThread.run(GeckoThread.java:448) [task 2018-04-20T00:13:37.219Z] 00:13:37 INFO - "pool-1-thread-1" prio=5 tid=12 WAIT [task 2018-04-20T00:13:37.219Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x41756948 self=0x2a1e58f0 [task 2018-04-20T00:13:37.219Z] 00:13:37 INFO - | sysTid=794 nice=0 sched=0/0 cgrp=apps handle=706634600 [task 2018-04-20T00:13:37.219Z] 00:13:37 INFO - | state=S schedstat=( 151885861 118529999 114 ) utm=8 stm=7 core=0 [task 2018-04-20T00:13:37.220Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.220Z] 00:13:37 INFO - - waiting on <0x4175f900> (a java.lang.VMThread) held by tid=12 (pool-1-thread-1) [task 2018-04-20T00:13:37.220Z] 00:13:37 INFO - at java.lang.Thread.parkFor(Thread.java:1205) [task 2018-04-20T00:13:37.220Z] 00:13:37 INFO - at sun.misc.Unsafe.park(Unsafe.java:325) [task 2018-04-20T00:13:37.221Z] 00:13:37 INFO - at java.util.concurrent.locks.LockSupport.park(LockSupport.java:159) [task 2018-04-20T00:13:37.221Z] 00:13:37 INFO - at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2019) [task 2018-04-20T00:13:37.222Z] 00:13:37 INFO - at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:413) [task 2018-04-20T00:13:37.222Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1013) [task 2018-04-20T00:13:37.223Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1073) [task 2018-04-20T00:13:37.223Z] 00:13:37 INFO - at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573) [task 2018-04-20T00:13:37.223Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.224Z] 00:13:37 INFO - "GeckoBackgroundThread" daemon prio=5 tid=11 NATIVE [task 2018-04-20T00:13:37.224Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x4173eb10 self=0x2a04fd20 [task 2018-04-20T00:13:37.225Z] 00:13:37 INFO - | sysTid=789 nice=0 sched=0/0 cgrp=apps handle=705013960 [task 2018-04-20T00:13:37.226Z] 00:13:37 INFO - | state=S schedstat=( 1761857562 5608950468 1261 ) utm=154 stm=22 core=0 [task 2018-04-20T00:13:37.226Z] 00:13:37 INFO - #00 pc 0001c3dc /system/lib/libc.so (epoll_wait+12) [task 2018-04-20T00:13:37.227Z] 00:13:37 INFO - #01 pc 00014fb1 /system/lib/libutils.so (android::Looper::pollInner(int)+92) [task 2018-04-20T00:13:37.227Z] 00:13:37 INFO - #02 pc 000151d5 /system/lib/libutils.so (android::Looper::pollOnce(int, int*, int*, void**)+92) [task 2018-04-20T00:13:37.228Z] 00:13:37 INFO - #03 pc 00067b69 /system/lib/libandroid_runtime.so (android::NativeMessageQueue::pollOnce(_JNIEnv*, int)+22) [task 2018-04-20T00:13:37.228Z] 00:13:37 INFO - #04 pc 0001dc4c /system/lib/libdvm.so (dvmPlatformInvoke+112) [task 2018-04-20T00:13:37.228Z] 00:13:37 INFO - #05 pc 0004dcab /system/lib/libdvm.so (dvmCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+394) [task 2018-04-20T00:13:37.229Z] 00:13:37 INFO - #06 pc 000385e1 /system/lib/libdvm.so (dvmCheckCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+8) [task 2018-04-20T00:13:37.229Z] 00:13:37 INFO - #07 pc 00027060 /system/lib/libdvm.so [task 2018-04-20T00:13:37.229Z] 00:13:37 INFO - #08 pc 0002b580 /system/lib/libdvm.so (dvmInterpret(Thread*, Method const*, JValue*)+184) [task 2018-04-20T00:13:37.229Z] 00:13:37 INFO - #09 pc 0005fcbd /system/lib/libdvm.so (dvmCallMethodV(Thread*, Method const*, Object*, bool, JValue*, std::__va_list)+292) [task 2018-04-20T00:13:37.230Z] 00:13:37 INFO - #10 pc 0005fce7 /system/lib/libdvm.so (dvmCallMethod(Thread*, Method const*, Object*, JValue*, ...)+20) [task 2018-04-20T00:13:37.230Z] 00:13:37 INFO - #11 pc 00054a6f /system/lib/libdvm.so [task 2018-04-20T00:13:37.230Z] 00:13:37 INFO - #12 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.231Z] 00:13:37 INFO - #13 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.232Z] 00:13:37 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) [task 2018-04-20T00:13:37.232Z] 00:13:37 INFO - at android.os.MessageQueue.next(MessageQueue.java:132) [task 2018-04-20T00:13:37.232Z] 00:13:37 INFO - at android.os.Looper.loop(Looper.java:124) [task 2018-04-20T00:13:37.232Z] 00:13:37 INFO - at org.mozilla.gecko.util.GeckoBackgroundThread.run(GeckoBackgroundThread.java:43) [task 2018-04-20T00:13:37.233Z] 00:13:37 INFO - "Binder_2" prio=5 tid=10 NATIVE [task 2018-04-20T00:13:37.233Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x4173fa50 self=0x2a04ec70 [task 2018-04-20T00:13:37.234Z] 00:13:37 INFO - | sysTid=786 nice=0 sched=0/0 cgrp=apps handle=704965160 [task 2018-04-20T00:13:37.234Z] 00:13:37 INFO - | state=S schedstat=( 16218195 305050901 20 ) utm=1 stm=0 core=0 [task 2018-04-20T00:13:37.234Z] 00:13:37 INFO - #00 pc 0001b2d4 /system/lib/libc.so (__ioctl+8) [task 2018-04-20T00:13:37.235Z] 00:13:37 INFO - #01 pc 0002b94b /system/lib/libc.so (ioctl+14) [task 2018-04-20T00:13:37.235Z] 00:13:37 INFO - #02 pc 0001b539 /system/lib/libbinder.so (android::IPCThreadState::talkWithDriver(bool)+140) [task 2018-04-20T00:13:37.236Z] 00:13:37 INFO - #03 pc 0001bcd7 /system/lib/libbinder.so (android::IPCThreadState::joinThreadPool(bool)+154) [task 2018-04-20T00:13:37.236Z] 00:13:37 INFO - #04 pc 0001faa5 /system/lib/libbinder.so [task 2018-04-20T00:13:37.237Z] 00:13:37 INFO - #05 pc 00011a75 /system/lib/libutils.so (android::Thread::_threadLoop(void*)+216) [task 2018-04-20T00:13:37.237Z] 00:13:37 INFO - #06 pc 0004b629 /system/lib/libandroid_runtime.so (android::AndroidRuntime::javaThreadShell(void*)+68) [task 2018-04-20T00:13:37.238Z] 00:13:37 INFO - #07 pc 00011569 /system/lib/libutils.so [task 2018-04-20T00:13:37.238Z] 00:13:37 INFO - #08 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.238Z] 00:13:37 INFO - #09 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.239Z] 00:13:37 INFO - at dalvik.system.NativeStart.run(Native Method) [task 2018-04-20T00:13:37.239Z] 00:13:37 INFO - "Binder_1" prio=5 tid=9 NATIVE [task 2018-04-20T00:13:37.239Z] 00:13:37 INFO - | group="main" sCount=1 dsCount=0 obj=0x4173e7e0 self=0x2a04e3d0 [task 2018-04-20T00:13:37.240Z] 00:13:37 INFO - | sysTid=785 nice=0 sched=0/0 cgrp=apps handle=704962952 [task 2018-04-20T00:13:37.240Z] 00:13:37 INFO - | state=S schedstat=( 19190153 22344588 14 ) utm=1 stm=0 core=0 [task 2018-04-20T00:13:37.240Z] 00:13:37 INFO - #00 pc 0001b2d4 /system/lib/libc.so (__ioctl+8) [task 2018-04-20T00:13:37.240Z] 00:13:37 INFO - #01 pc 0002b94b /system/lib/libc.so (ioctl+14) [task 2018-04-20T00:13:37.241Z] 00:13:37 INFO - #02 pc 0001b539 /system/lib/libbinder.so (android::IPCThreadState::talkWithDriver(bool)+140) [task 2018-04-20T00:13:37.242Z] 00:13:37 INFO - #03 pc 0001bcd7 /system/lib/libbinder.so (android::IPCThreadState::joinThreadPool(bool)+154) [task 2018-04-20T00:13:37.242Z] 00:13:37 INFO - #04 pc 0001faa5 /system/lib/libbinder.so [task 2018-04-20T00:13:37.242Z] 00:13:37 INFO - #05 pc 00011a75 /system/lib/libutils.so (android::Thread::_threadLoop(void*)+216) [task 2018-04-20T00:13:37.242Z] 00:13:37 INFO - #06 pc 0004b629 /system/lib/libandroid_runtime.so (android::AndroidRuntime::javaThreadShell(void*)+68) [task 2018-04-20T00:13:37.243Z] 00:13:37 INFO - #07 pc 00011569 /system/lib/libutils.so [task 2018-04-20T00:13:37.244Z] 00:13:37 INFO - #08 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.244Z] 00:13:37 INFO - #09 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.244Z] 00:13:37 INFO - at dalvik.system.NativeStart.run(Native Method) [task 2018-04-20T00:13:37.244Z] 00:13:37 INFO - "FinalizerWatchdogDaemon" daemon prio=5 tid=8 WAIT [task 2018-04-20T00:13:37.245Z] 00:13:37 INFO - | group="system" sCount=1 dsCount=0 obj=0x4173b5a0 self=0x2a026328 [task 2018-04-20T00:13:37.246Z] 00:13:37 INFO - | sysTid=784 nice=0 sched=0/0 cgrp=apps handle=704800632 [task 2018-04-20T00:13:37.246Z] 00:13:37 INFO - | state=S schedstat=( 2481532 22189516 15 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.246Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.246Z] 00:13:37 INFO - - waiting on <0x414d16d8> (a java.lang.Daemons$FinalizerWatchdogDaemon) [task 2018-04-20T00:13:37.247Z] 00:13:37 INFO - at java.lang.Object.wait(Object.java:364) [task 2018-04-20T00:13:37.248Z] 00:13:37 INFO - at java.lang.Daemons$FinalizerWatchdogDaemon.waitForObject(Daemons.java:230) [task 2018-04-20T00:13:37.248Z] 00:13:37 INFO - at java.lang.Daemons$FinalizerWatchdogDaemon.run(Daemons.java:207) [task 2018-04-20T00:13:37.248Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.248Z] 00:13:37 INFO - "FinalizerDaemon" daemon prio=5 tid=7 WAIT [task 2018-04-20T00:13:37.249Z] 00:13:37 INFO - | group="system" sCount=1 dsCount=0 obj=0x4173b3f0 self=0x2a025a88 [task 2018-04-20T00:13:37.249Z] 00:13:37 INFO - | sysTid=783 nice=0 sched=0/0 cgrp=apps handle=704798424 [task 2018-04-20T00:13:37.250Z] 00:13:37 INFO - | state=S schedstat=( 177486296 263532990 97 ) utm=13 stm=4 core=0 [task 2018-04-20T00:13:37.250Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.250Z] 00:13:37 INFO - - waiting on <0x414ba568> (a java.lang.ref.ReferenceQueue) [task 2018-04-20T00:13:37.250Z] 00:13:37 INFO - at java.lang.Object.wait(Object.java:401) [task 2018-04-20T00:13:37.252Z] 00:13:37 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:102) [task 2018-04-20T00:13:37.252Z] 00:13:37 INFO - at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:73) [task 2018-04-20T00:13:37.252Z] 00:13:37 INFO - at java.lang.Daemons$FinalizerDaemon.run(Daemons.java:170) [task 2018-04-20T00:13:37.252Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.253Z] 00:13:37 INFO - "ReferenceQueueDaemon" daemon prio=5 tid=6 WAIT [task 2018-04-20T00:13:37.253Z] 00:13:37 INFO - | group="system" sCount=1 dsCount=0 obj=0x4173b288 self=0x2a025430 [task 2018-04-20T00:13:37.255Z] 00:13:37 INFO - | sysTid=782 nice=0 sched=0/0 cgrp=apps handle=704931736 [task 2018-04-20T00:13:37.255Z] 00:13:37 INFO - | state=S schedstat=( 18236666 9875351 54 ) utm=1 stm=0 core=0 [task 2018-04-20T00:13:37.255Z] 00:13:37 INFO - at java.lang.Object.wait(Native Method) [task 2018-04-20T00:13:37.255Z] 00:13:37 INFO - - waiting on <0x414ba490> [task 2018-04-20T00:13:37.255Z] 00:13:37 INFO - at java.lang.Object.wait(Object.java:364) [task 2018-04-20T00:13:37.256Z] 00:13:37 INFO - at java.lang.Daemons$ReferenceQueueDaemon.run(Daemons.java:130) [task 2018-04-20T00:13:37.256Z] 00:13:37 INFO - at java.lang.Thread.run(Thread.java:841) [task 2018-04-20T00:13:37.256Z] 00:13:37 INFO - "Compiler" daemon prio=5 tid=5 VMWAIT [task 2018-04-20T00:13:37.256Z] 00:13:37 INFO - | group="system" sCount=1 dsCount=0 obj=0x4173b198 self=0x2a0455e8 [task 2018-04-20T00:13:37.257Z] 00:13:37 INFO - | sysTid=781 nice=0 sched=0/0 cgrp=apps handle=704923128 [task 2018-04-20T00:13:37.257Z] 00:13:37 INFO - | state=S schedstat=( 2094545163 1733788519 3671 ) utm=113 stm=96 core=0 [task 2018-04-20T00:13:37.257Z] 00:13:37 INFO - #00 pc 0001c5a4 /system/lib/libc.so (__futex_syscall3+8) [task 2018-04-20T00:13:37.259Z] 00:13:37 INFO - #01 pc 0000e688 /system/lib/libc.so (__pthread_cond_timedwait_relative+48) [task 2018-04-20T00:13:37.259Z] 00:13:37 INFO - #02 pc 0000e6e8 /system/lib/libc.so (__pthread_cond_timedwait+64) [task 2018-04-20T00:13:37.259Z] 00:13:37 INFO - #03 pc 00072c9b /system/lib/libdvm.so [task 2018-04-20T00:13:37.259Z] 00:13:37 INFO - #04 pc 00054151 /system/lib/libdvm.so [task 2018-04-20T00:13:37.259Z] 00:13:37 INFO - #05 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.260Z] 00:13:37 INFO - #06 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.260Z] 00:13:37 INFO - at dalvik.system.NativeStart.run(Native Method) [task 2018-04-20T00:13:37.260Z] 00:13:37 INFO - "JDWP" daemon prio=5 tid=4 VMWAIT [task 2018-04-20T00:13:37.260Z] 00:13:37 INFO - | group="system" sCount=1 dsCount=0 obj=0x4173b0b0 self=0x2a045198 [task 2018-04-20T00:13:37.261Z] 00:13:37 INFO - | sysTid=780 nice=0 sched=0/0 cgrp=apps handle=704928656 [task 2018-04-20T00:13:37.262Z] 00:13:37 INFO - | state=S schedstat=( 1453950 17925383 7 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.262Z] 00:13:37 INFO - #00 pc 0001c078 /system/lib/libc.so (recvmsg+8) [task 2018-04-20T00:13:37.262Z] 00:13:37 INFO - #01 pc 00060797 /system/lib/libdvm.so [task 2018-04-20T00:13:37.262Z] 00:13:37 INFO - #02 pc 000609cb /system/lib/libdvm.so [task 2018-04-20T00:13:37.264Z] 00:13:37 INFO - #03 pc 00063687 /system/lib/libdvm.so [task 2018-04-20T00:13:37.264Z] 00:13:37 INFO - #04 pc 00054151 /system/lib/libdvm.so [task 2018-04-20T00:13:37.264Z] 00:13:37 INFO - #05 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.264Z] 00:13:37 INFO - #06 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.264Z] 00:13:37 INFO - at dalvik.system.NativeStart.run(Native Method) [task 2018-04-20T00:13:37.265Z] 00:13:37 INFO - "Signal Catcher" daemon prio=5 tid=3 RUNNABLE [task 2018-04-20T00:13:37.265Z] 00:13:37 INFO - | group="system" sCount=0 dsCount=0 obj=0x4173afb8 self=0x2a044d48 [task 2018-04-20T00:13:37.266Z] 00:13:37 INFO - | sysTid=779 nice=0 sched=0/0 cgrp=apps handle=704716120 [task 2018-04-20T00:13:37.266Z] 00:13:37 INFO - | state=R schedstat=( 118367027 407339112 34 ) utm=9 stm=2 core=0 [task 2018-04-20T00:13:37.266Z] 00:13:37 INFO - at dalvik.system.NativeStart.run(Native Method) [task 2018-04-20T00:13:37.267Z] 00:13:37 INFO - "GC" daemon prio=5 tid=2 VMWAIT [task 2018-04-20T00:13:37.267Z] 00:13:37 INFO - | group="system" sCount=1 dsCount=0 obj=0x4173aed8 self=0x2a046348 [task 2018-04-20T00:13:37.268Z] 00:13:37 INFO - | sysTid=778 nice=0 sched=0/0 cgrp=apps handle=704913184 [task 2018-04-20T00:13:37.269Z] 00:13:37 INFO - | state=S schedstat=( 544813743 1078083996 158 ) utm=46 stm=8 core=0 [task 2018-04-20T00:13:37.269Z] 00:13:37 INFO - #00 pc 0001c5a4 /system/lib/libc.so (__futex_syscall3+8) [task 2018-04-20T00:13:37.269Z] 00:13:37 INFO - #01 pc 0000e688 /system/lib/libc.so (__pthread_cond_timedwait_relative+48) [task 2018-04-20T00:13:37.270Z] 00:13:37 INFO - #02 pc 0000e6e8 /system/lib/libc.so (__pthread_cond_timedwait+64) [task 2018-04-20T00:13:37.270Z] 00:13:37 INFO - #03 pc 0007197f /system/lib/libdvm.so [task 2018-04-20T00:13:37.270Z] 00:13:37 INFO - #04 pc 00054151 /system/lib/libdvm.so [task 2018-04-20T00:13:37.270Z] 00:13:37 INFO - #05 pc 0000ca58 /system/lib/libc.so (__thread_entry+72) [task 2018-04-20T00:13:37.272Z] 00:13:37 INFO - #06 pc 0000cbd4 /system/lib/libc.so (pthread_create+208) [task 2018-04-20T00:13:37.272Z] 00:13:37 INFO - at dalvik.system.NativeStart.run(Native Method) [task 2018-04-20T00:13:37.272Z] 00:13:37 INFO - NATIVE THREADS: [task 2018-04-20T00:13:37.272Z] 00:13:37 INFO - "<no name>" sysTid=849 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.272Z] 00:13:37 INFO - | state=S schedstat=( 7342132 45185042 3 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.272Z] 00:13:37 INFO - "<no name>" sysTid=853 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.272Z] 00:13:37 INFO - | state=S schedstat=( 16220786079 11676935314 11811 ) utm=1192 stm=430 core=0 [task 2018-04-20T00:13:37.273Z] 00:13:37 INFO - "<no name>" sysTid=854 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.274Z] 00:13:37 INFO - | state=S schedstat=( 1408722241 2545822173 5521 ) utm=60 stm=80 core=0 [task 2018-04-20T00:13:37.274Z] 00:13:37 INFO - "<no name>" sysTid=855 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.274Z] 00:13:37 INFO - | state=S schedstat=( 13466843443 15319062659 3351 ) utm=1039 stm=307 core=0 [task 2018-04-20T00:13:37.274Z] 00:13:37 INFO - "<no name>" sysTid=856 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.275Z] 00:13:37 INFO - | state=S schedstat=( 15492037832 16122288606 3452 ) utm=1150 stm=399 core=0 [task 2018-04-20T00:13:37.275Z] 00:13:37 INFO - "<no name>" sysTid=861 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.276Z] 00:13:37 INFO - | state=S schedstat=( 20152771063 16380154703 93062 ) utm=870 stm=1145 core=0 [task 2018-04-20T00:13:37.277Z] 00:13:37 INFO - "<no name>" sysTid=865 nice=1 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.277Z] 00:13:37 INFO - | state=S schedstat=( 1152668 9446587 2 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.278Z] 00:13:37 INFO - "<no name>" sysTid=866 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.278Z] 00:13:37 INFO - | state=S schedstat=( 66683617576 96219244536 80562 ) utm=5100 stm=1568 core=0 [task 2018-04-20T00:13:37.279Z] 00:13:37 INFO - "<no name>" sysTid=867 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.279Z] 00:13:37 INFO - | state=S schedstat=( 217126618 294234322 39 ) utm=17 stm=4 core=0 [task 2018-04-20T00:13:37.280Z] 00:13:37 INFO - "<no name>" sysTid=875 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.281Z] 00:13:37 INFO - | state=S schedstat=( 1485634 10237108 2 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.281Z] 00:13:37 INFO - "<no name>" sysTid=877 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.281Z] 00:13:37 INFO - | state=S schedstat=( 4352229212 650939370 9893 ) utm=352 stm=83 core=0 [task 2018-04-20T00:13:37.281Z] 00:13:37 INFO - "<no name>" sysTid=884 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.281Z] 00:13:37 INFO - | state=S schedstat=( 705365479 941574826 86 ) utm=58 stm=12 core=0 [task 2018-04-20T00:13:37.282Z] 00:13:37 INFO - "<no name>" sysTid=886 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.282Z] 00:13:37 INFO - | state=S schedstat=( 17868082 26530519 5 ) utm=1 stm=0 core=0 [task 2018-04-20T00:13:37.282Z] 00:13:37 INFO - "<no name>" sysTid=887 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.283Z] 00:13:37 INFO - | state=S schedstat=( 238598656210 242313307006 330663 ) utm=12121 stm=11738 core=0 [task 2018-04-20T00:13:37.283Z] 00:13:37 INFO - "<no name>" sysTid=889 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.284Z] 00:13:37 INFO - | state=S schedstat=( 39615939223 83867408055 123435 ) utm=2211 stm=1750 core=0 [task 2018-04-20T00:13:37.284Z] 00:13:37 INFO - "<no name>" sysTid=890 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.284Z] 00:13:37 INFO - | state=S schedstat=( 1748862 1634198 2 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.285Z] 00:13:37 INFO - "<no name>" sysTid=891 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.286Z] 00:13:37 INFO - | state=S schedstat=( 1078290 19900363 2 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.286Z] 00:13:37 INFO - "<no name>" sysTid=898 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.286Z] 00:13:37 INFO - | state=S schedstat=( 2204026 11016304 6 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.286Z] 00:13:37 INFO - "<no name>" sysTid=912 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.286Z] 00:13:37 INFO - | state=S schedstat=( 4696183397 847749074 7426 ) utm=378 stm=91 core=0 [task 2018-04-20T00:13:37.286Z] 00:13:37 INFO - "<no name>" sysTid=922 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.287Z] 00:13:37 INFO - | state=S schedstat=( 100403511 35292839 56 ) utm=6 stm=4 core=0 [task 2018-04-20T00:13:37.287Z] 00:13:37 INFO - "<no name>" sysTid=935 nice=1 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.288Z] 00:13:37 INFO - | state=S schedstat=( 127677555 234972140 48 ) utm=9 stm=3 core=0 [task 2018-04-20T00:13:37.288Z] 00:13:37 INFO - "<no name>" sysTid=936 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.288Z] 00:13:37 INFO - | state=S schedstat=( 6818610 23178643 3 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.289Z] 00:13:37 INFO - "<no name>" sysTid=937 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.289Z] 00:13:37 INFO - | state=S schedstat=( 2818923 18054376 3 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.289Z] 00:13:37 INFO - "<no name>" sysTid=938 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.290Z] 00:13:37 INFO - | state=S schedstat=( 1782001 16782015 2 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.290Z] 00:13:37 INFO - "<no name>" sysTid=939 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.291Z] 00:13:37 INFO - | state=S schedstat=( 1913634 6203141 2 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.291Z] 00:13:37 INFO - "<no name>" sysTid=940 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.292Z] 00:13:37 INFO - | state=S schedstat=( 5395901 4244696 5 ) utm=0 stm=0 core=0 [task 2018-04-20T00:13:37.292Z] 00:13:37 INFO - "<no name>" sysTid=941 nice=0 sched=0/0 cgrp=apps [task 2018-04-20T00:13:37.293Z] 00:13:37 INFO - | state=S schedstat=( 396909079 835790650 153 ) utm=31 stm=8 core=0
I think this error just means the whole job took longer than some threshold that we set -- but we weren't running this particular test for an especially long time. (There's only 1 second between the last REFTEST TEST-LOAD line and the "Browser unexpectedly found running. Killing..." line) This is Android debug, so we know it's gonna take a while. Maybe we need a higher threshold, or to bucketize more? (And maybe something really is unexpectedly taking a long time... but we do eventually get past it, apparently, because we're not stuck waiting on a long test when this message fires. So even if that's the case, it's probably undesirable to arbitrarily fail partway through an arbitrary later test.)
Component: Layout: R & A Pos → Layout
Component: Layout → Testing
Product: Core → Firefox for Android
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 8 years ago
Resolution: --- → INCOMPLETE
Product: Firefox for Android → Firefox for Android Graveyard
You need to log in before you can comment on or make changes to this bug.