1470818 - Intermittent http://10.0.2.2:8888/jsreftest/tests/jsreftest.html?test=test262/language/statements/for-await-of/async-gen-dstr-let-async-obj-ptrn-rest-skip-non-enumerable.js | application ran for longer than allowed maximum time

Status: RESOLVED DUPLICATE of bug 1472032

(Core :: JavaScript Engine, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=184600396&repo=try

https://queue.taskcluster.net/v1/task/GqgnhKxEQNSCWFWqQXR_-Q/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/GqgnhKxEQNSCWFWqQXR_-Q/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2018-06-24T17:16:04.261Z] 17:16:04     INFO -  REFTEST TEST-START | http://10.0.2.2:8888/jsreftest/tests/jsreftest.html?test=test262/language/statements/for-await-of/async-gen-dstr-let-async-obj-ptrn-rest-skip-non-enumerable.js
[task 2018-06-24T17:16:04.261Z] 17:16:04     INFO -  REFTEST TEST-LOAD | http://10.0.2.2:8888/jsreftest/tests/jsreftest.html?test=test262/language/statements/for-await-of/async-gen-dstr-let-async-obj-ptrn-rest-skip-non-enumerable.js | 294 / 305 (96%)
[task 2018-06-24T17:16:06.173Z] 17:16:06     INFO -  Browser unexpectedly found running. Killing...
[task 2018-06-24T17:16:06.174Z] 17:16:06     INFO -  REFTEST TEST-INFO | started process screentopng
[task 2018-06-24T17:16:06.784Z] 17:16:06     INFO -  REFTEST TEST-INFO | screentopng: exit 0
[task 2018-06-24T17:16:22.461Z] 17:16:22  WARNING -  TEST-UNEXPECTED-FAIL | http://10.0.2.2:8888/jsreftest/tests/jsreftest.html?test=test262/language/statements/for-await-of/async-gen-dstr-let-async-obj-ptrn-rest-skip-non-enumerable.js | application ran for longer than allowed maximum time
[task 2018-06-24T17:16:22.462Z] 17:16:22     INFO -  INFO | automation.py | Application ran for: 1:30:37.700750
[task 2018-06-24T17:16:22.462Z] 17:16:22     INFO -  INFO | zombiecheck | Reading PID log: /tmp/tmpVPM4aHpidlog
[task 2018-06-24T17:16:22.780Z] 17:16:22     INFO -  Contents of /data/anr/traces.txt:
[task 2018-06-24T17:16:22.780Z] 17:16:22     INFO -  ----- pid 808 at 2018-06-24 10:16:07 -----
[task 2018-06-24T17:16:22.781Z] 17:16:22     INFO -  Cmd line: org.mozilla.firefox_beta
[task 2018-06-24T17:16:22.782Z] 17:16:22     INFO -  JNI: CheckJNI is on; workarounds are off; pins=0; globals=285
[task 2018-06-24T17:16:22.783Z] 17:16:22     INFO -  DALVIK THREADS:
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -  (mutexes: tll=0 tsl=0 tscl=0 ghl=0)
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -  "main" prio=5 tid=1 NATIVE
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    | group="main" sCount=1 dsCount=0 obj=0x414c9578 self=0x2a00d090
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    | sysTid=808 nice=0 sched=0/0 cgrp=apps handle=1073811452
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    | state=S schedstat=( 518842838596 157827169337 372551 ) utm=29840 stm=22044 core=0
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    #00  pc 0001c5a4  /system/lib/libc.so (__futex_syscall3+8)
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    #01  pc 0000e688  /system/lib/libc.so (__pthread_cond_timedwait_relative+48)
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    #02  pc 0000e6e8  /system/lib/libc.so (__pthread_cond_timedwait+64)
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    #03  pc 00052e97  /system/lib/libdvm.so
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    #04  pc 00053461  /system/lib/libdvm.so (dvmChangeStatus(Thread*, ThreadStatus)+30)
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    #05  pc 00048d29  /system/lib/libdvm.so
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    #06  pc 00039afd  /system/lib/libdvm.so
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    #07  pc 0004b7a9  /system/lib/libandroid_runtime.so (android::AndroidRuntime::getJNIEnv()+16)
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    #08  pc 0005eed5  /system/lib/libandroid_runtime.so (android::NativeDisplayEventReceiver::dispatchVsync(long long, int, unsigned int)+20)
[task 2018-06-24T17:16:22.788Z] 17:16:22     INFO -    #09  pc 0005f0b9  /system/lib/libandroid_runtime.so (android::NativeDisplayEventReceiver::handleEvent(int, int, void*)+80)
[task 2018-06-24T17:16:22.789Z] 17:16:22     INFO -    #10  pc 00015129  /system/lib/libutils.so (android::Looper::pollInner(int)+468)
[task 2018-06-24T17:16:22.789Z] 17:16:22     INFO -    #11  pc 000151d5  /system/lib/libutils.so (android::Looper::pollOnce(int, int*, int*, void**)+92)
[task 2018-06-24T17:16:22.789Z] 17:16:22     INFO -    #12  pc 00067b69  /system/lib/libandroid_runtime.so (android::NativeMessageQueue::pollOnce(_JNIEnv*, int)+22)
[task 2018-06-24T17:16:22.789Z] 17:16:22     INFO -    #13  pc 0001dc4c  /system/lib/libdvm.so (dvmPlatformInvoke+112)
[task 2018-06-24T17:16:22.789Z] 17:16:22     INFO -    #14  pc 0004dcab  /system/lib/libdvm.so (dvmCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+394)
[task 2018-06-24T17:16:22.789Z] 17:16:22     INFO -    #15  pc 000385e1  /system/lib/libdvm.so (dvmCheckCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+8)
[task 2018-06-24T17:16:22.790Z] 17:16:22     INFO -    #16  pc 00000214  /dev/ashmem/dalvik-jit-code-cache (deleted)
[task 2018-06-24T17:16:22.790Z] 17:16:22     INFO -    at android.os.MessageQueue.nativePollOnce(Native Method)
[task 2018-06-24T17:16:22.791Z] 17:16:22     INFO -    at android.os.MessageQueue.next(MessageQueue.java:132)
[task 2018-06-24T17:16:22.791Z] 17:16:22     INFO -    at android.os.Looper.loop(Looper.java:124)
[task 2018-06-24T17:16:22.792Z] 17:16:22     INFO -    at android.app.ActivityThread.main(ActivityThread.java:5103)
[task 2018-06-24T17:16:22.792Z] 17:16:22     INFO -    at java.lang.reflect.Method.invokeNative(Native Method)
[task 2018-06-24T17:16:22.793Z] 17:16:22     INFO -    at java.lang.reflect.Method.invoke(Method.java:525)
[task 2018-06-24T17:16:22.793Z] 17:16:22     INFO -    at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:737)
[task 2018-06-24T17:16:22.794Z] 17:16:22     INFO -    at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:553)
[task 2018-06-24T17:16:22.795Z] 17:16:22     INFO -    at dalvik.system.NativeStart.main(Native Method)
[task 2018-06-24T17:16:22.795Z] 17:16:22     INFO -  "Thread-87" prio=5 tid=34 NATIVE
[task 2018-06-24T17:16:22.796Z] 17:16:22     INFO -    | group="main" sCount=1 dsCount=0 obj=0x41e93488 self=0x2a3fcda0
[task 2018-06-24T17:16:22.796Z] 17:16:22     INFO -    | sysTid=931 nice=0 sched=0/0 cgrp=apps handle=707068072
[task 2018-06-24T17:16:22.797Z] 17:16:22     INFO -    | state=S schedstat=( 29126112799 30802834528 33561 ) utm=2250 stm=662 core=0
[task 2018-06-24T17:16:22.797Z] 17:16:22     INFO -    #00  pc 0001c5a4  /system/lib/libc.so (__futex_syscall3+8)
[task 2018-06-24T17:16:22.798Z] 17:16:22     INFO -    #01  pc 0000e688  /system/lib/libc.so (__pthread_cond_timedwait_relative+48)
[task 2018-06-24T17:16:22.799Z] 17:16:22     INFO -    #02  pc 0000e6e8  /system/lib/libc.so (__pthread_cond_timedwait+64)
[task 2018-06-24T17:16:22.799Z] 17:16:22     INFO -    #03  pc 003ba2c7  /data/data/org.mozilla.firefox_beta/cache/libxul.so
[task 2018-06-24T17:16:22.800Z] 17:16:22     INFO -    at dalvik.system.NativeStart.run(Native Method)