Closed Bug 1429748 Opened 8 years ago Closed 7 years ago

Intermittent http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-4.js | load failed: timed out waiting for reftest-wait to be removed

Categories

(Testing :: Reftest, defect, P5)

Product:
Testing
Component:
Reftest
Version:
Version 3
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=155543968&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/S3x0ziNmRnec2fl0uDygxg/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/S3x0ziNmRnec2fl0uDygxg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1 [task 2018-01-11T05:44:27.257Z] 05:44:27 INFO - REFTEST TEST-START | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-4.js [task 2018-01-11T05:44:27.257Z] 05:44:27 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-4.js | 232 / 302 (76%) [task 2018-01-11T05:54:22.790Z] 05:54:22 INFO - REFTEST TEST-UNEXPECTED-FAIL | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-4.js | load failed: timed out waiting for reftest-wait to be removed [task 2018-01-11T05:54:22.791Z] 05:54:22 INFO - REFTEST INFO | Saved log: START http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-4.js [task 2018-01-11T05:54:22.791Z] 05:54:22 INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd [task 2018-01-11T05:54:22.792Z] 05:54:22 INFO - REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners [task 2018-01-11T05:54:22.793Z] 05:54:22 INFO - REFTEST INFO | Saved log: Initializing canvas snapshot [task 2018-01-11T05:54:22.793Z] 05:54:22 INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT [task 2018-01-11T05:54:22.794Z] 05:54:22 INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate [task 2018-01-11T05:54:22.794Z] 05:54:22 INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL [task 2018-01-11T05:54:22.795Z] 05:54:22 INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed [task 2018-01-11T05:54:22.795Z] 05:54:22 INFO - REFTEST TEST-END | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-4.js [task 2018-01-11T05:54:33.288Z] 05:54:33 INFO - REFTEST TEST-START | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-5.js [task 2018-01-11T05:54:33.288Z] 05:54:33 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-5.js | 233 / 302 (77%) [task 2018-01-11T06:04:27.698Z] 06:04:27 INFO - REFTEST TEST-UNEXPECTED-FAIL | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-5.js | load failed: timed out after 600000 ms waiting for 'load' event for http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-5.js [task 2018-01-11T06:04:27.698Z] 06:04:27 INFO - REFTEST INFO | Saved log: START http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-5.js [task 2018-01-11T06:04:27.699Z] 06:04:27 INFO - REFTEST TEST-END | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-5.js [task 2018-01-11T06:04:27.699Z] 06:04:27 INFO - REFTEST TEST-START | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-6.js [task 2018-01-11T06:04:27.699Z] 06:04:27 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-6.js | 234 / 302 (77%) [task 2018-01-11T06:14:32.577Z] 06:14:32 INFO - REFTEST TEST-UNEXPECTED-FAIL | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-6.js | load failed: timed out after 600000 ms waiting for 'load' event for http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-6.js [task 2018-01-11T06:14:32.578Z] 06:14:32 INFO - REFTEST INFO | Saved log: START http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-6.js [task 2018-01-11T06:14:32.578Z] 06:14:32 INFO - REFTEST TEST-END | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-6.js [task 2018-01-11T06:14:32.578Z] 06:14:32 INFO - REFTEST TEST-START | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-7.js [task 2018-01-11T06:14:32.578Z] 06:14:32 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-7.js | 235 / 302 (77%) [task 2018-01-11T06:24:29.870Z] 06:24:29 INFO - REFTEST TEST-UNEXPECTED-FAIL | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-7.js | load failed: timed out after 600000 ms waiting for 'load' event for http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-7.js [task 2018-01-11T06:24:29.871Z] 06:24:29 INFO - REFTEST INFO | Saved log: START http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-7.js [task 2018-01-11T06:24:29.872Z] 06:24:29 INFO - REFTEST TEST-END | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-7.js [task 2018-01-11T06:24:29.872Z] 06:24:29 INFO - REFTEST TEST-START | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-8.js [task 2018-01-11T06:24:29.872Z] 06:24:29 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-8.js | 236 / 302 (78%) [task 2018-01-11T06:34:36.487Z] 06:34:36 INFO - REFTEST TEST-UNEXPECTED-FAIL | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-8.js | load failed: timed out after 600000 ms waiting for 'load' event for http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-8.js [task 2018-01-11T06:34:36.488Z] 06:34:36 INFO - REFTEST INFO | Saved log: START http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-8.js [task 2018-01-11T06:34:36.488Z] 06:34:36 INFO - REFTEST TEST-END | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-8.js [task 2018-01-11T06:34:36.489Z] 06:34:36 INFO - REFTEST TEST-START | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-9.js [task 2018-01-11T06:34:36.490Z] 06:34:36 INFO - REFTEST TEST-LOAD | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-9.js | 237 / 302 (78%) [task 2018-01-11T06:44:00.943Z] 06:44:00 INFO - Browser unexpectedly found running. Killing... [task 2018-01-11T06:44:00.943Z] 06:44:00 INFO - REFTEST TEST-INFO | started process screentopng [task 2018-01-11T06:44:01.311Z] 06:44:01 INFO - REFTEST TEST-INFO | screentopng: exit 0 [task 2018-01-11T06:44:04.953Z] 06:44:04 INFO - org.mozilla.fennec_aurora still alive after SIGABRT: waiting... [task 2018-01-11T06:44:10.076Z] 06:44:10 WARNING - TEST-UNEXPECTED-FAIL | http://10.0.2.2:8854/jsreftest/tests/jsreftest.html?test=test262/built-ins/Array/prototype/some/15.4.4.17-7-c-iii-9.js | application ran for longer than allowed maximum time [task 2018-01-11T06:44:10.077Z] 06:44:10 INFO - INFO | automation.py | Application ran for: 1:30:29.260611 [task 2018-01-11T06:44:10.077Z] 06:44:10 INFO - INFO | zombiecheck | Reading PID log: /tmp/tmpLaAtmVpidlog [task 2018-01-11T06:44:10.180Z] 06:44:10 INFO - Contents of /data/anr/traces.txt: [task 2018-01-11T06:44:10.181Z] 06:44:10 INFO - ----- pid 784 at 2018-01-10 22:44:01 ----- [task 2018-01-11T06:44:10.181Z] 06:44:10 INFO - Cmd line: org.mozilla.fennec_aurora [task 2018-01-11T06:44:10.182Z] 06:44:10 INFO - JNI: CheckJNI is on; workarounds are off; pins=0; globals=297 [task 2018-01-11T06:44:10.183Z] 06:44:10 INFO - DALVIK THREADS: [task 2018-01-11T06:44:10.183Z] 06:44:10 INFO - (mutexes: tll=0 tsl=0 tscl=0 ghl=0) [task 2018-01-11T06:44:10.183Z] 06:44:10 INFO - "main" prio=5 tid=1 NATIVE [task 2018-01-11T06:44:10.183Z] 06:44:10 INFO - | group="main" sCount=1 dsCount=0 obj=0x414c9578 self=0x2a00d090 [task 2018-01-11T06:44:10.184Z] 06:44:10 INFO - | sysTid=784 nice=0 sched=0/0 cgrp=apps handle=1073811452 [task 2018-01-11T06:44:10.184Z] 06:44:10 INFO - | state=S schedstat=( 281048152374 98190952017 534958 ) utm=16713 stm=11391 core=0 [task 2018-01-11T06:44:10.185Z] 06:44:10 INFO - #00 pc 0001c5a4 /system/lib/libc.so (__futex_syscall3+8) [task 2018-01-11T06:44:10.185Z] 06:44:10 INFO - #01 pc 0000e688 /system/lib/libc.so (__pthread_cond_timedwait_relative+48) [task 2018-01-11T06:44:10.186Z] 06:44:10 INFO - #02 pc 0000e6e8 /system/lib/libc.so (__pthread_cond_timedwait+64) [task 2018-01-11T06:44:10.186Z] 06:44:10 INFO - #03 pc 00052e97 /system/lib/libdvm.so [task 2018-01-11T06:44:10.186Z] 06:44:10 INFO - #04 pc 00053461 /system/lib/libdvm.so (dvmChangeStatus(Thread*, ThreadStatus)+30) [task 2018-01-11T06:44:10.187Z] 06:44:10 INFO - #05 pc 00048d29 /system/lib/libdvm.so [task 2018-01-11T06:44:10.187Z] 06:44:10 INFO - #06 pc 00039afd /system/lib/libdvm.so [task 2018-01-11T06:44:10.187Z] 06:44:10 INFO - #07 pc 0004b7a9 /system/lib/libandroid_runtime.so (android::AndroidRuntime::getJNIEnv()+16) [task 2018-01-11T06:44:10.187Z] 06:44:10 INFO - #08 pc 0005eed5 /system/lib/libandroid_runtime.so (android::NativeDisplayEventReceiver::dispatchVsync(long long, int, unsigned int)+20) [task 2018-01-11T06:44:10.188Z] 06:44:10 INFO - #09 pc 0005f0b9 /system/lib/libandroid_runtime.so (android::NativeDisplayEventReceiver::handleEvent(int, int, void*)+80) [task 2018-01-11T06:44:10.188Z] 06:44:10 INFO - #10 pc 00015129 /system/lib/libutils.so (android::Looper::pollInner(int)+468) [task 2018-01-11T06:44:10.188Z] 06:44:10 INFO - #11 pc 000151d5 /system/lib/libutils.so (android::Looper::pollOnce(int, int*, int*, void**)+92) [task 2018-01-11T06:44:10.188Z] 06:44:10 INFO - #12 pc 00067b69 /system/lib/libandroid_runtime.so (android::NativeMessageQueue::pollOnce(_JNIEnv*, int)+22) [task 2018-01-11T06:44:10.189Z] 06:44:10 INFO - #13 pc 0001dc4c /system/lib/libdvm.so (dvmPlatformInvoke+112) [task 2018-01-11T06:44:10.189Z] 06:44:10 INFO - #14 pc 0004dcab /system/lib/libdvm.so (dvmCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+394) [task 2018-01-11T06:44:10.190Z] 06:44:10 INFO - #15 pc 000385e1 /system/lib/libdvm.so (dvmCheckCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+8) [task 2018-01-11T06:44:10.190Z] 06:44:10 INFO - #16 pc 00000214 /dev/ashmem/dalvik-jit-code-cache (deleted) [task 2018-01-11T06:44:10.190Z] 06:44:10 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) [task 2018-01-11T06:44:10.191Z] 06:44:10 INFO - at android.os.MessageQueue.next(MessageQueue.java:132) [task 2018-01-11T06:44:10.191Z] 06:44:10 INFO - at android.os.Looper.loop(Looper.java:124) [task 2018-01-11T06:44:10.191Z] 06:44:10 INFO - at android.app.ActivityThread.main(ActivityThread.java:5103) [task 2018-01-11T06:44:10.192Z] 06:44:10 INFO - at java.lang.reflect.Method.invokeNative(Native Method) [task 2018-01-11T06:44:10.192Z] 06:44:10 INFO - at java.lang.reflect.Method.invoke(Method.java:525) [task 2018-01-11T06:44:10.193Z] 06:44:10 INFO - at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:737) [task 2018-01-11T06:44:10.193Z] 06:44:10 INFO - at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:553) [task 2018-01-11T06:44:10.194Z] 06:44:10 INFO - at dalvik.system.NativeStart.main(Native Method) [task 2018-01-11T06:44:10.194Z] 06:44:10 INFO - "Thread-80" prio=5 tid=31 NATIVE [task 2018-01-11T06:44:10.194Z] 06:44:10 INFO - | group="main" sCount=1 dsCount=0 obj=0x4216ebd8 self=0x2a412600 [task 2018-01-11T06:44:10.195Z] 06:44:10 INFO - | sysTid=871 nice=0 sched=0/0 cgrp=apps handle=707359488 [task 2018-01-11T06:44:10.195Z] 06:44:10 INFO - | state=S schedstat=( 14137455602 9319747095 35375 ) utm=1091 stm=322 core=0 [task 2018-01-11T06:44:10.195Z] 06:44:10 INFO - #00 pc 0001c5a4 /system/lib/libc.so (__futex_syscall3+8) [task 2018-01-11T06:44:10.196Z] 06:44:10 INFO - #01 pc 0000e688 /system/lib/libc.so (__pthread_cond_timedwait_relative+48) [task 2018-01-11T06:44:10.196Z] 06:44:10 INFO - #02 pc 0000e6e8 /system/lib/libc.so (__pthread_cond_timedwait+64) [task 2018-01-11T06:44:10.197Z] 06:44:10 INFO - #03 pc 003de883 /data/data/org.mozilla.fennec_aurora/cache/libxul.so [task 2018-01-11T06:44:10.197Z] 06:44:10 INFO - at dalvik.system.NativeStart.run(Native Method) [task 2018-01-11T06:44:10.197Z] 06:44:10 INFO - "Picasso-refQueue" daemon prio=5 tid=30 WAIT
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.