Closed Bug 1495166 Opened 7 years ago Closed 6 years ago

Intermittent dom/quota/test/test_simpledb.html | application timed out after 370 seconds with no output

Categories

(Core :: Storage: Quota Manager, defect, P5)

Product:
Core
Component:
Storage: Quota Manager
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: rgurzau [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=202317735&repo=autoland https://queue.taskcluster.net/v1/task/AlePIeHwSzO31d6tIgO8Kw/runs/0/artifacts/public/logs/live_backing.log [task 2018-09-28T23:50:13.992Z] 23:50:13 INFO - 153 INFO TEST-START | dom/promise/tests/test_webassembly_compile.html [task 2018-09-28T23:51:27.319Z] 23:51:27 INFO - 154 INFO TEST-OK | dom/promise/tests/test_webassembly_compile.html | took 71807ms [task 2018-09-28T23:51:37.634Z] 23:51:37 INFO - 155 INFO TEST-START | Shutdown [task 2018-09-28T23:51:37.635Z] 23:51:37 INFO - 156 INFO Passed: 642 [task 2018-09-28T23:51:37.636Z] 23:51:37 INFO - 157 INFO Failed: 0 [task 2018-09-28T23:51:37.637Z] 23:51:37 INFO - 158 INFO Todo: 13 [task 2018-09-28T23:51:37.637Z] 23:51:37 INFO - 159 INFO Mode: non-e10s [task 2018-09-28T23:51:37.637Z] 23:51:37 INFO - 160 INFO Slowest: 71808ms - /tests/dom/promise/tests/test_webassembly_compile.html [task 2018-09-28T23:51:37.637Z] 23:51:37 INFO - 161 INFO SimpleTest FINISHED [task 2018-09-28T23:52:18.439Z] 23:52:18 INFO - wait for org.mozilla.fennec_aurora complete; top activity=com.android.launcher [task 2018-09-28T23:52:18.644Z] 23:52:18 INFO - INFO | automation.py | Application ran for: 0:05:32.843549 [task 2018-09-28T23:52:18.645Z] 23:52:18 INFO - INFO | zombiecheck | Reading PID log: /tmp/tmpoQjTl0pidlog [task 2018-09-28T23:52:19.163Z] 23:52:19 INFO - /data/tombstones does not exist; tombstone check skipped [task 2018-09-28T23:52:19.782Z] 23:52:19 INFO - Stopping web server [task 2018-09-28T23:52:19.786Z] 23:52:19 INFO - Stopping web socket server [task 2018-09-28T23:52:19.807Z] 23:52:19 INFO - Stopping ssltunnel [task 2018-09-28T23:52:19.827Z] 23:52:19 INFO - leakcheck | refcount logging is off, so leaks can't be detected! [task 2018-09-28T23:52:19.827Z] 23:52:19 INFO - runtests.py | Running tests: end. [task 2018-09-28T23:52:20.559Z] 23:52:20 INFO - Buffered messages finished [task 2018-09-28T23:52:20.560Z] 23:52:20 INFO - Running manifest: dom/quota/test/mochitest.ini [task 2018-09-28T23:52:21.878Z] 23:52:21 INFO - adb Ignoring attempt to chmod external storage [task 2018-09-28T23:52:21.902Z] 23:52:21 INFO - runtests.py | Failed to copy /builds/worker/workspace/build/tests/mochitest/hyphenation to profile [task 2018-09-28T23:52:22.013Z] 23:52:22 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL [task 2018-09-28T23:52:22.132Z] 23:52:22 INFO - MochitestServer : launching [u'/builds/worker/workspace/build/hostutils/host-utils-61.0a1.en-US.linux-x86_64/xpcshell', '-g', '/builds/worker/workspace/build/hostutils/host-utils-61.0a1.en-US.linux-x86_64', '-f', '/builds/worker/workspace/build/hostutils/host-utils-61.0a1.en-US.linux-x86_64/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmp3uGluP.mozrunner'; const _SERVER_PORT = '8854'; const _SERVER_ADDR = '10.0.2.2'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js'] [task 2018-09-28T23:52:22.133Z] 23:52:22 INFO - runtests.py | Server pid: 5517 [task 2018-09-28T23:52:22.145Z] 23:52:22 INFO - runtests.py | Websocket server pid: 5520 [task 2018-09-28T23:52:22.149Z] 23:52:22 INFO - runtests.py | SSL tunnel pid: 5523 [task 2018-09-28T23:52:25.493Z] 23:52:25 INFO - adb Ignoring attempt to chmod external storage [task 2018-09-28T23:52:25.493Z] 23:52:25 INFO - runtests.py | Running with e10s: False [task 2018-09-28T23:52:25.493Z] 23:52:25 INFO - runtests.py | Running tests: start. [task 2018-09-28T23:52:25.702Z] 23:52:25 INFO - adb launch_application: am start -W -n org.mozilla.fennec_aurora/org.mozilla.gecko.BrowserApp -a android.intent.action.VIEW --es env9 MOZ_CRASHREPORTER_NO_REPORT=1 --es env8 R_LOG_DESTINATION=stderr --es args '-no-remote -profile /sdcard/tests/profile//' --es env3 DISABLE_UNSAFE_CPOW_WARNINGS=1 --es env2 R_LOG_VERBOSE=1 --es env1 XPCOM_DEBUG_BREAK=stack --es env0 MOZ_CRASHREPORTER=1 --es env7 MOZ_LOG_FILE=/sdcard/tests/mozlog/moz.log --es env6 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env5 MOZ_IN_AUTOMATION=1 --es env4 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env12 MOZ_HIDE_RESULTS_TABLE=1 --es env11 R_LOG_LEVEL=6 --es env10 MOZ_PROCESS_LOG=/tmp/tmpkvc_xwpidlog -d 'http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&logFile=%2Fsdcard%2Ftests%2Flogs%2Fmochitest.log&fileLevel=INFO&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fsdcard%2Ftests' [task 2018-09-28T23:52:33.930Z] 23:52:33 INFO - INFO | automation.py | Application pid: 4584 [task 2018-09-28T23:54:13.015Z] 23:54:13 INFO - 162 INFO SimpleTest START [task 2018-09-28T23:54:13.015Z] 23:54:13 INFO - 163 INFO TEST-START | dom/quota/test/test_simpledb.html [task 2018-09-28T23:54:23.225Z] 23:54:23 INFO - 164 INFO TEST-OK | dom/quota/test/test_simpledb.html | took 18208ms [task 2018-09-28T23:54:23.226Z] 23:54:23 INFO - 165 INFO TEST-START | Shutdown [task 2018-09-28T23:54:23.226Z] 23:54:23 INFO - 166 INFO Passed: 3 [task 2018-09-28T23:54:23.227Z] 23:54:23 INFO - 167 INFO Failed: 0 [task 2018-09-28T23:54:23.227Z] 23:54:23 INFO - 168 INFO Todo: 1 [task 2018-09-28T23:54:23.227Z] 23:54:23 INFO - 169 INFO Mode: non-e10s [task 2018-09-28T23:54:23.936Z] 23:54:23 INFO - 170 INFO Slowest: 18202ms - /tests/dom/quota/test/test_simpledb.html [task 2018-09-28T23:54:23.936Z] 23:54:23 INFO - 171 INFO SimpleTest FINISHED [task 2018-09-28T23:55:11.952Z] 23:55:11 INFO - wait for org.mozilla.fennec_aurora complete; top activity=com.android.launcher [task 2018-09-28T23:55:12.156Z] 23:55:12 INFO - INFO | automation.py | Application ran for: 0:02:46.662583 [task 2018-09-28T23:55:12.156Z] 23:55:12 INFO - INFO | zombiecheck | Reading PID log: /tmp/tmpkvc_xwpidlog [task 2018-09-28T23:55:12.675Z] 23:55:12 INFO - /data/tombstones does not exist; tombstone check skipped [task 2018-09-28T23:55:16.530Z] 23:55:16 INFO - adb Ignoring attempt to chmod external storage [task 2018-09-28T23:55:16.530Z] 23:55:16 INFO - runtests.py | Running with e10s: False [task 2018-09-28T23:55:16.530Z] 23:55:16 INFO - runtests.py | Running tests: start. [task 2018-09-28T23:55:16.741Z] 23:55:16 INFO - adb launch_application: am start -W -n org.mozilla.fennec_aurora/org.mozilla.gecko.BrowserApp -a android.intent.action.VIEW --es env9 MOZ_CRASHREPORTER_NO_REPORT=1 --es env8 R_LOG_DESTINATION=stderr --es args '-no-remote -profile /sdcard/tests/profile//' --es env3 DISABLE_UNSAFE_CPOW_WARNINGS=1 --es env2 R_LOG_VERBOSE=1 --es env1 XPCOM_DEBUG_BREAK=stack --es env0 MOZ_CRASHREPORTER=1 --es env7 MOZ_LOG_FILE=/sdcard/tests/mozlog/moz.log --es env6 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env5 MOZ_IN_AUTOMATION=1 --es env4 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env12 MOZ_HIDE_RESULTS_TABLE=1 --es env11 R_LOG_LEVEL=6 --es env10 MOZ_PROCESS_LOG=/tmp/tmpriUDtJpidlog -d 'https://example.com:443/tests?autorun=1&closeWhenDone=1&logFile=%2Fsdcard%2Ftests%2Flogs%2Fmochitest.log&fileLevel=INFO&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fsdcard%2Ftests&autorun=1&closeWhenDone=1&logFile=%2Fsdcard%2Ftests%2Flogs%2Fmochitest.log&fileLevel=INFO&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fsdcard%2Ftests' [task 2018-09-28T23:55:25.465Z] 23:55:25 INFO - INFO | automation.py | Application pid: 4974 [task 2018-09-28T23:55:25.674Z] 23:55:25 INFO - 172 INFO SimpleTest START [task 2018-09-28T23:55:25.675Z] 23:55:25 INFO - 173 INFO TEST-START | dom/quota/test/test_simpledb.html [task 2018-09-28T23:55:25.676Z] 23:55:25 INFO - 174 INFO TEST-OK | dom/quota/test/test_simpledb.html | took 18208ms [task 2018-09-28T23:55:25.677Z] 23:55:25 INFO - 175 INFO TEST-START | Shutdown [task 2018-09-28T23:55:25.678Z] 23:55:25 INFO - 176 INFO Passed: 3 [task 2018-09-28T23:55:25.678Z] 23:55:25 INFO - 177 INFO Failed: 0 [task 2018-09-28T23:55:25.679Z] 23:55:25 INFO - 178 INFO Todo: 1 [task 2018-09-28T23:55:25.680Z] 23:55:25 INFO - 179 INFO Mode: non-e10s [task 2018-09-28T23:55:25.680Z] 23:55:25 INFO - 180 INFO Slowest: 18202ms - /tests/dom/quota/test/test_simpledb.html [task 2018-09-28T23:55:25.681Z] 23:55:25 INFO - 181 INFO SimpleTest FINISHED [task 2018-09-29T00:08:56.898Z] 00:08:56 INFO - wait for org.mozilla.fennec_aurora complete; top activity=org.mozilla.fennec_aurora [task 2018-09-29T00:08:57.303Z] 00:08:57 INFO - Browser unexpectedly found running. Killing... [task 2018-09-29T00:08:57.304Z] 00:08:57 INFO - TEST-INFO | started process screentopng [task 2018-09-29T00:08:57.663Z] 00:08:57 INFO - TEST-INFO | screentopng: exit 0 [task 2018-09-29T00:09:11.814Z] 00:09:11 WARNING - TEST-UNEXPECTED-FAIL | dom/quota/test/test_simpledb.html | application timed out after 370 seconds with no output [task 2018-09-29T00:09:11.815Z] 00:09:11 INFO - INFO | automation.py | Application ran for: 0:13:55.283206 [task 2018-09-29T00:09:11.815Z] 00:09:11 INFO - INFO | zombiecheck | Reading PID log: /tmp/tmpriUDtJpidlog [task 2018-09-29T00:09:12.126Z] 00:09:12 INFO - Contents of /data/anr/traces.txt: [task 2018-09-29T00:09:12.126Z] 00:09:12 INFO - ----- pid 4974 at 2018-09-28 17:08:57 ----- [task 2018-09-29T00:09:12.126Z] 00:09:12 INFO - Cmd line: org.mozilla.fennec_aurora [task 2018-09-29T00:09:12.127Z] 00:09:12 INFO - JNI: CheckJNI is on; workarounds are off; pins=0; globals=278 [task 2018-09-29T00:09:12.127Z] 00:09:12 INFO - DALVIK THREADS: [task 2018-09-29T00:09:12.129Z] 00:09:12 INFO - (mutexes: tll=0 tsl=0 tscl=0 ghl=0) [task 2018-09-29T00:09:12.129Z] 00:09:12 INFO - "main" prio=5 tid=1 NATIVE [task 2018-09-29T00:09:12.129Z] 00:09:12 INFO - | group="main" sCount=1 dsCount=0 obj=0x414c9578 self=0x2a00d090 [task 2018-09-29T00:09:12.129Z] 00:09:12 INFO - | sysTid=4974 nice=0 sched=0/0 cgrp=apps handle=1073811452 [task 2018-09-29T00:09:12.129Z] 00:09:12 INFO - | state=S schedstat=( 52262771534 22411117627 82893 ) utm=3858 stm=1368 core=0 [task 2018-09-29T00:09:12.130Z] 00:09:12 INFO - #00 pc 0001c5a4 /system/lib/libc.so (__futex_syscall3+8) [task 2018-09-29T00:09:12.130Z] 00:09:12 INFO - #01 pc 0000e688 /system/lib/libc.so (__pthread_cond_timedwait_relative+48) [task 2018-09-29T00:09:12.131Z] 00:09:12 INFO - #02 pc 0000e6e8 /system/lib/libc.so (__pthread_cond_timedwait+64) [task 2018-09-29T00:09:12.131Z] 00:09:12 INFO - #03 pc 00052e97 /system/lib/libdvm.so [task 2018-09-29T00:09:12.131Z] 00:09:12 INFO - #04 pc 00053461 /system/lib/libdvm.so (dvmChangeStatus(Thread*, ThreadStatus)+30) [task 2018-09-29T00:09:12.131Z] 00:09:12 INFO - #05 pc 00048d29 /system/lib/libdvm.so [task 2018-09-29T00:09:12.131Z] 00:09:12 INFO - #06 pc 00039afd /system/lib/libdvm.so [task 2018-09-29T00:09:12.132Z] 00:09:12 INFO - #07 pc 0004b7a9 /system/lib/libandroid_runtime.so (android::AndroidRuntime::getJNIEnv()+16) [task 2018-09-29T00:09:12.132Z] 00:09:12 INFO - #08 pc 0005eed5 /system/lib/libandroid_runtime.so (android::NativeDisplayEventReceiver::dispatchVsync(long long, int, unsigned int)+20) [task 2018-09-29T00:09:12.132Z] 00:09:12 INFO - #09 pc 0005f0b9 /system/lib/libandroid_runtime.so (android::NativeDisplayEventReceiver::handleEvent(int, int, void*)+80) [task 2018-09-29T00:09:12.133Z] 00:09:12 INFO - #10 pc 00015129 /system/lib/libutils.so (android::Looper::pollInner(int)+468) [task 2018-09-29T00:09:12.133Z] 00:09:12 INFO - #11 pc 000151d5 /system/lib/libutils.so (android::Looper::pollOnce(int, int*, int*, void**)+92) [task 2018-09-29T00:09:12.134Z] 00:09:12 INFO - #12 pc 00067b69 /system/lib/libandroid_runtime.so (android::NativeMessageQueue::pollOnce(_JNIEnv*, int)+22) [task 2018-09-29T00:09:12.134Z] 00:09:12 INFO - #13 pc 0001dc4c /system/lib/libdvm.so (dvmPlatformInvoke+112) [task 2018-09-29T00:09:12.134Z] 00:09:12 INFO - #14 pc 0004dcab /system/lib/libdvm.so (dvmCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+394) [task 2018-09-29T00:09:12.135Z] 00:09:12 INFO - #15 pc 000385e1 /system/lib/libdvm.so (dvmCheckCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+8) [task 2018-09-29T00:09:12.135Z] 00:09:12 INFO - #16 pc 00027060 /system/lib/libdvm.so [task 2018-09-29T00:09:12.135Z] 00:09:12 INFO - #17 pc 0002b580 /system/lib/libdvm.so (dvmInterpret(Thread*, Method const*, JValue*)+184) [task 2018-09-29T00:09:12.136Z] 00:09:12 INFO - #18 pc 0005ff7b /system/lib/libdvm.so (dvmInvokeMethod(Object*, Method const*, ArrayObject*, ArrayObject*, ClassObject*, bool)+350) [task 2018-09-29T00:09:12.136Z] 00:09:12 INFO - #19 pc 00067a9f /system/lib/libdvm.so [task 2018-09-29T00:09:12.136Z] 00:09:12 INFO - #20 pc 00027060 /system/lib/libdvm.so [task 2018-09-29T00:09:12.137Z] 00:09:12 INFO - #21 pc 0002b580 /system/lib/libdvm.so (dvmInterpret(Thread*, Method const*, JValue*)+184) [task 2018-09-29T00:09:12.137Z] 00:09:12 INFO - #22 pc 0005fcbd /system/lib/libdvm.so (dvmCallMethodV(Thread*, Method const*, Object*, bool, JValue*, std::__va_list)+292) [task 2018-09-29T00:09:12.138Z] 00:09:12 INFO - #23 pc 000499ab /system/lib/libdvm.so [task 2018-09-29T00:09:12.138Z] 00:09:12 INFO - #24 pc 0003cb3d /system/lib/libdvm.so [task 2018-09-29T00:09:12.138Z] 00:09:12 INFO - #25 pc 0004b68f /system/lib/libandroid_runtime.so [task 2018-09-29T00:09:12.139Z] 00:09:12 INFO - #26 pc 0004c30f /system/lib/libandroid_runtime.so (android::AndroidRuntime::start(char const*, char const*)+378) [task 2018-09-29T00:09:12.139Z] 00:09:12 INFO - #27 pc 0000105b /system/bin/app_process [task 2018-09-29T00:09:12.139Z] 00:09:12 INFO - #28 pc 0000db4f /system/lib/libc.so (__libc_init+50) [task 2018-09-29T00:09:12.141Z] 00:09:12 INFO - #29 pc 00000d7c /system/bin/app_process [task 2018-09-29T00:09:12.141Z] 00:09:12 INFO - at android.os.MessageQueue.nativePollOnce(Native Method) [task 2018-09-29T00:09:12.141Z] 00:09:12 INFO - at android.os.MessageQueue.next(MessageQueue.java:132) [task 2018-09-29T00:09:12.141Z] 00:09:12 INFO - at android.os.Looper.loop(Looper.java:124) [task 2018-09-29T00:09:12.141Z] 00:09:12 INFO - at android.app.ActivityThread.main(ActivityThread.java:5103) [task 2018-09-29T00:09:12.141Z] 00:09:12 INFO - at java.lang.reflect.Method.invokeNative(Native Method) [task 2018-09-29T00:09:12.141Z] 00:09:12 INFO - at java.lang.reflect.Method.invoke(Method.java:525) [task 2018-09-29T00:09:12.141Z] 00:09:12 INFO - at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:737) [task 2018-09-29T00:09:12.142Z] 00:09:12 INFO - at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:553) [task 2018-09-29T00:09:12.142Z] 00:09:12 INFO - at dalvik.system.NativeStart.main(Native Method) [task 2018-09-29T00:09:12.142Z] 00:09:12 INFO - "GeckoIconTask" prio=5 tid=18 WAIT
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.