Closed Bug 1493289 Opened 7 years ago Closed 7 years ago

Intermittent PermissionError: [Errno 13] Permission denied: 'unity-webgl'

Categories

(Core :: Graphics: CanvasWebGL, defect)

Product:
Core
Component:
Graphics: CanvasWebGL
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell needswork:owner])

Filed by: shindli [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=200835100&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/Q-afvPeYQSGBRZcfjf69zQ/runs/0/artifacts/public/logs/live_backing.log [taskcluster] hostBootTime: 2018-09-21T02:15:12-07:00 [taskcluster] hostname: t-linux64-ms-008.test.releng.mdc1.mozilla.com [taskcluster] runId: 0 [taskcluster] taskId: Q-afvPeYQSGBRZcfjf69zQ [taskcluster] tasksSinceStartup: 52 [taskcluster] workerGroup: mdc1 [taskcluster] workerType: gecko-t-linux-talos-tw [setup 2018-09-21T20:21:32.463Z] run-task started in /home/cltbld [vcs 2018-09-21T20:21:32.463Z] WARNING: vcs checkout path (/home/cltbld/checkouts/gecko) not in cache or volume; performance will likely suffer [vcs 2018-09-21T20:21:32.463Z] WARNING: HG_STORE_PATH (/home/cltbld/checkouts/hg-store) not in cache or volume; performance will likely suffer [vcs 2018-09-21T20:21:32.463Z] executing ['hg', 'robustcheckout', '--sharebase', '/home/cltbld/checkouts/hg-store', '--purge', '--upstream', 'https://hg.mozilla.org/mozilla-unified', '--revision', '0b052fd12e931158888fd1a17882a24b725d88f3', 'https://hg.mozilla.org/integration/mozilla-inbound', '/home/cltbld/checkouts/gecko'] [vcs 2018-09-21T20:21:32.510Z] (using Mercurial 4.4.1) [vcs 2018-09-21T20:21:32.510Z] ensuring https://hg.mozilla.org/integration/mozilla-inbound@0b052fd12e931158888fd1a17882a24b725d88f3 is available at /home/cltbld/checkouts/gecko [vcs 2018-09-21T20:21:32.511Z] (existing repository shared store: /home/cltbld/checkouts/hg-store/8ba995b74e18334ab3707f27e9eb8f4e37ba3d29/.hg) [vcs 2018-09-21T20:21:32.831Z] (pulling to obtain 0b052fd12e931158888fd1a17882a24b725d88f3) [vcs 2018-09-21T20:21:33.116Z] searching for changes [vcs 2018-09-21T20:21:35.229Z] adding changesets [vcs 2018-09-21T20:21:35.243Z] adding manifests [vcs 2018-09-21T20:21:35.317Z] adding file changes [vcs 2018-09-21T20:21:35.617Z] added 67 changesets with 258 changes to 173 files [vcs 2018-09-21T20:21:35.747Z] new changesets 73019d50638c:0b052fd12e93 [vcs 2018-09-21T20:21:35.752Z] (purging working directory) [vcs 2018-09-21T20:21:38.128Z] [vcs 2018-09-21T20:21:38.372Z] updating [===> ] 16/204 [vcs 2018-09-21T20:21:38.378Z] [vcs 2018-09-21T20:21:38.378Z] 188 files updated, 0 files merged, 16 files removed, 0 files unresolved [vcs 2018-09-21T20:21:38.412Z] updated to 0b052fd12e931158888fd1a17882a24b725d88f3 [vcs 2018-09-21T20:21:38.625Z] TinderboxPrint:<a href=https://hg.mozilla.org/integration/mozilla-inbound/rev/0b052fd12e931158888fd1a17882a24b725d88f3 title='Built from mozilla-inbound revision 0b052fd12e931158888fd1a17882a24b725d88f3'>0b052fd12e931158888fd1a17882a24b725d88f3</a> [fetches 2018-09-21T20:21:38.625Z] fetching artifacts Downloading https://queue.taskcluster.net/v1/task/HGQKgkqzTfC9FG1GTnpF6w/artifacts/public/build/target.jsshell.zip to /home/cltbld/fetches/target.jsshell.zip.tmp Downloading https://queue.taskcluster.net/v1/task/ATS0RzxeQDqyRYTEIi5ArA/artifacts/public/d8-6.7.17.zip to /home/cltbld/fetches/d8-6.7.17.zip.tmp Downloading https://queue.taskcluster.net/v1/task/HGQKgkqzTfC9FG1GTnpF6w/artifacts/public/build/target.jsshell.zip Downloading https://queue.taskcluster.net/v1/task/ATS0RzxeQDqyRYTEIi5ArA/artifacts/public/d8-6.7.17.zip https://queue.taskcluster.net/v1/task/ATS0RzxeQDqyRYTEIi5ArA/artifacts/public/d8-6.7.17.zip resolved to 20578358 bytes with sha256 0aa1c4e630de78373185fc1c0fa34bc87826f63fd4cbb664668891d6f6a6b24e in 3.615s Renaming to /home/cltbld/fetches/d8-6.7.17.zip Extracting /home/cltbld/fetches/d8-6.7.17.zip to /home/cltbld/fetches using ['unzip', '-o', '/home/cltbld/fetches/d8-6.7.17.zip'] Archive: /home/cltbld/fetches/d8-6.7.17.zip inflating: d8/libv8_libbase.so.6 inflating: d8/d8 inflating: d8/libv8.so.6 inflating: d8/libv8_libplatform.so.6 inflating: d8/libicui18n.so.60 inflating: d8/libicuuc.so.60 inflating: d8/libicudata.so.60 /home/cltbld/fetches/d8-6.7.17.zip extracted in 0.384s Removing /home/cltbld/fetches/d8-6.7.17.zip https://queue.taskcluster.net/v1/task/HGQKgkqzTfC9FG1GTnpF6w/artifacts/public/build/target.jsshell.zip resolved to 10202649 bytes with sha256 683300b8227245b9f18fc235ffb396de7225b72747dcd0f8543c1dc71aaf8b48 in 137.467s Renaming to /home/cltbld/fetches/target.jsshell.zip Extracting /home/cltbld/fetches/target.jsshell.zip to /home/cltbld/fetches using ['unzip', '-o', '/home/cltbld/fetches/target.jsshell.zip'] Archive: /home/cltbld/fetches/target.jsshell.zip inflating: js inflating: libnspr4.so inflating: libplds4.so inflating: libplc4.so /home/cltbld/fetches/target.jsshell.zip extracted in 0.205s Removing /home/cltbld/fetches/target.jsshell.zip [fetches 2018-09-21T20:23:56.381Z] finished fetching artifacts [task 2018-09-21T20:23:56.381Z] executing ['bash', '-cx', 'cd $GECKO_PATH && ./mach jsshell-bench --perfherder=sm --binary=$JSSHELL ares6\n'] [task 2018-09-21T20:23:56.384Z] + cd /home/cltbld/checkouts/gecko [task 2018-09-21T20:23:56.384Z] + ./mach jsshell-bench --perfherder=sm --binary=/home/cltbld/fetches/js ares6 [task 2018-09-21T20:23:56.749Z] New python executable in /home/cltbld/checkouts/gecko/obj-x86_64-pc-linux-gnu/_virtualenvs/init/bin/python2.7 [task 2018-09-21T20:23:56.749Z] Also creating executable in /home/cltbld/checkouts/gecko/obj-x86_64-pc-linux-gnu/_virtualenvs/init/bin/python [task 2018-09-21T20:23:57.822Z] Installing setuptools, pip, wheel...done. [task 2018-09-21T20:23:58.503Z] running build_ext [task 2018-09-21T20:23:58.503Z] building 'psutil._psutil_linux' extension [task 2018-09-21T20:23:58.503Z] creating build [task 2018-09-21T20:23:58.503Z] creating build/temp.linux-x86_64-2.7 [task 2018-09-21T20:23:58.503Z] creating build/temp.linux-x86_64-2.7/psutil [task 2018-09-21T20:23:58.503Z] x86_64-linux-gnu-gcc -pthread -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fno-strict-aliasing -Wdate-time -D_FORTIFY_SOURCE=2 -g -fstack-protector-strong -Wformat -Werror=format-security -fPIC -DPSUTIL_POSIX=1 -DPSUTIL_VERSION=543 -DPSUTIL_LINUX=1 -I/usr/include/python2.7 -c psutil/_psutil_common.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_common.o [task 2018-09-21T20:23:58.503Z] x86_64-linux-gnu-gcc -pthread -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fno-strict-aliasing -Wdate-time -D_FORTIFY_SOURCE=2 -g -fstack-protector-strong -Wformat -Werror=format-security -fPIC -DPSUTIL_POSIX=1 -DPSUTIL_VERSION=543 -DPSUTIL_LINUX=1 -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o [task 2018-09-21T20:23:58.503Z] x86_64-linux-gnu-gcc -pthread -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fno-strict-aliasing -Wdate-time -D_FORTIFY_SOURCE=2 -g -fstack-protector-strong -Wformat -Werror=format-security -fPIC -DPSUTIL_POSIX=1 -DPSUTIL_VERSION=543 -DPSUTIL_LINUX=1 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o [task 2018-09-21T20:23:58.503Z] creating build/lib.linux-x86_64-2.7 [task 2018-09-21T20:23:58.503Z] creating build/lib.linux-x86_64-2.7/psutil [task 2018-09-21T20:23:58.503Z] x86_64-linux-gnu-gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -Wdate-time -D_FORTIFY_SOURCE=2 -g -fstack-protector-strong -Wformat -Werror=format-security -Wl,-Bsymbolic-functions -Wl,-z,relro -Wdate-time -D_FORTIFY_SOURCE=2 -g -fstack-protector-strong -Wformat -Werror=format-security build/temp.linux-x86_64-2.7/psutil/_psutil_common.o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so [task 2018-09-21T20:23:58.503Z] building 'psutil._psutil_posix' extension [task 2018-09-21T20:23:58.503Z] x86_64-linux-gnu-gcc -pthread -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fno-strict-aliasing -Wdate-time -D_FORTIFY_SOURCE=2 -g -fstack-protector-strong -Wformat -Werror=format-security -fPIC -DPSUTIL_POSIX=1 -DPSUTIL_VERSION=543 -DPSUTIL_LINUX=1 -I/usr/include/python2.7 -c psutil/_psutil_common.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_common.o [task 2018-09-21T20:23:58.503Z] x86_64-linux-gnu-gcc -pthread -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fno-strict-aliasing -Wdate-time -D_FORTIFY_SOURCE=2 -g -fstack-protector-strong -Wformat -Werror=format-security -fPIC -DPSUTIL_POSIX=1 -DPSUTIL_VERSION=543 -DPSUTIL_LINUX=1 -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o [task 2018-09-21T20:23:58.504Z] x86_64-linux-gnu-gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -Wdate-time -D_FORTIFY_SOURCE=2 -g -fstack-protector-strong -Wformat -Werror=format-security -Wl,-Bsymbolic-functions -Wl,-z,relro -Wdate-time -D_FORTIFY_SOURCE=2 -g -fstack-protector-strong -Wformat -Werror=format-security build/temp.linux-x86_64-2.7/psutil/_psutil_common.o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so [task 2018-09-21T20:23:58.504Z] copying build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so -> psutil [task 2018-09-21T20:23:58.504Z] copying build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so -> psutil [task 2018-09-21T20:23:58.504Z] [task 2018-09-21T20:23:58.504Z] Error processing command. Ignoring because optional. (optional:packages.txt:comm/build/virtualenv_packages.txt) [task 2018-09-21T20:23:58.550Z] ARES-6 1.0.1 [task 2018-09-21T20:23:58.550Z] summary: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] firstIteration: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] averageWorstCase: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] steadyState: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] firstIteration: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] averageWorstCase: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] steadyState: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] firstIteration: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] averageWorstCase: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] steadyState: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] firstIteration: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] averageWorstCase: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] steadyState: &#x2b14;ms [task 2018-09-21T20:23:58.550Z] Running... Air ( 6 to go) [task 2018-09-21T20:24:01.969Z] firstIteration: 94.82 ms [task 2018-09-21T20:24:01.970Z] averageWorstCase: 44.68 ms [task 2018-09-21T20:24:01.971Z] steadyState: 16.64 ms [task 2018-09-21T20:24:01.971Z] summary: &#x2b14;ms [task 2018-09-21T20:24:01.971Z] [task 2018-09-21T20:24:01.971Z] [task 2018-09-21T20:24:01.971Z] Running... Basic ( 6 to go) [task 2018-09-21T20:24:10.304Z] firstIteration: 68.75 ms [task 2018-09-21T20:24:10.305Z] averageWorstCase: 45.10 ms [task 2018-09-21T20:24:10.305Z] steadyState: 41.51 ms [task 2018-09-21T20:24:10.305Z] summary: &#x2b14;ms [task 2018-09-21T20:24:10.305Z] [task 2018-09-21T20:24:10.305Z] [task 2018-09-21T20:24:10.305Z] Running... Babylon ( 6 to go) [task 2018-09-21T20:24:11.750Z] firstIteration: 92.94 ms [task 2018-09-21T20:24:11.750Z] averageWorstCase: 30.09 ms [task 2018-09-21T20:24:11.750Z] steadyState: 6.72 ms [task 2018-09-21T20:24:11.750Z] summary: &#x2b14;ms [task 2018-09-21T20:24:11.750Z] [task 2018-09-21T20:24:11.750Z] [task 2018-09-21T20:24:11.750Z] Running... ML ( 6 to go) [task 2018-09-21T20:24:23.753Z] firstIteration: 266.25 ms [task 2018-09-21T20:24:23.753Z] averageWorstCase: 211.04 ms [task 2018-09-21T20:24:23.753Z] steadyState: 198.72 ms [task 2018-09-21T20:24:23.753Z] summary: 59.33 ms [task 2018-09-21T20:24:23.753Z] [task 2018-09-21T20:24:23.753Z] [task 2018-09-21T20:24:23.753Z] Running... Air ( 5 to go) [task 2018-09-21T20:24:27.182Z] firstIteration: 91.37 ms [task 2018-09-21T20:24:27.182Z] averageWorstCase: 47.87 ms [task 2018-09-21T20:24:27.182Z] steadyState: 16.69 ms [task 2018-09-21T20:24:27.182Z] summary: 59.33 ms [task 2018-09-21T20:24:27.182Z] [task 2018-09-21T20:24:27.182Z] [task 2018-09-21T20:24:27.182Z] Running... Basic ( 5 to go) [task 2018-09-21T20:24:35.814Z] firstIteration: 69.52 ms [task 2018-09-21T20:24:35.814Z] averageWorstCase: 46.01 ms [task 2018-09-21T20:24:35.814Z] steadyState: 42.26 ms [task 2018-09-21T20:24:35.814Z] summary: 59.33 ms [task 2018-09-21T20:24:35.814Z] [task 2018-09-21T20:24:35.814Z] [task 2018-09-21T20:24:35.814Z] Running... Babylon ( 5 to go) [task 2018-09-21T20:24:37.238Z] firstIteration: 94.60 ms [task 2018-09-21T20:24:37.238Z] averageWorstCase: 28.95 ms [task 2018-09-21T20:24:37.238Z] steadyState: 6.67 ms [task 2018-09-21T20:24:37.238Z] summary: 59.33 ms [task 2018-09-21T20:24:37.238Z] [task 2018-09-21T20:24:37.238Z] [task 2018-09-21T20:24:37.238Z] Running... ML ( 5 to go) [task 2018-09-21T20:24:49.023Z] firstIteration: 268.04 ms [task 2018-09-21T20:24:49.024Z] averageWorstCase: 204.39 ms [task 2018-09-21T20:24:49.024Z] steadyState: 196.86 ms [task 2018-09-21T20:24:49.024Z] summary: 59.41 ms [task 2018-09-21T20:24:49.024Z] [task 2018-09-21T20:24:49.024Z] [task 2018-09-21T20:24:49.024Z] Running... Air ( 4 to go) [task 2018-09-21T20:24:52.574Z] firstIteration: 90.73 +- 8.98 ms [task 2018-09-21T20:24:52.574Z] averageWorstCase: 49.60 +- 10.85 ms [task 2018-09-21T20:24:52.574Z] steadyState: 16.90 +- 0.92 ms [task 2018-09-21T20:24:52.574Z] summary: 59.41 ms [task 2018-09-21T20:24:52.574Z] [task 2018-09-21T20:24:52.574Z] [task 2018-09-21T20:24:52.574Z] Running... Basic ( 4 to go) [task 2018-09-21T20:25:00.807Z] firstIteration: 68.62 +- 4.32 ms [task 2018-09-21T20:25:00.808Z] averageWorstCase: 45.63 +- 2.77 ms [task 2018-09-21T20:25:00.808Z] steadyState: 41.85 +- 2.55 ms [task 2018-09-21T20:25:00.808Z] summary: 59.41 ms [task 2018-09-21T20:25:00.808Z] [task 2018-09-21T20:25:00.808Z] [task 2018-09-21T20:25:00.808Z] Running... Babylon ( 4 to go) [task 2018-09-21T20:25:02.236Z] firstIteration: 96.24 +- 8.17 ms [task 2018-09-21T20:25:02.236Z] averageWorstCase: 28.26 +- 4.10 ms [task 2018-09-21T20:25:02.236Z] steadyState: 6.65 +- 0.14 ms [task 2018-09-21T20:25:02.236Z] summary: 59.41 ms [task 2018-09-21T20:25:02.236Z] [task 2018-09-21T20:25:02.236Z] [task 2018-09-21T20:25:02.236Z] Running... ML ( 4 to go) [task 2018-09-21T20:25:14.174Z] firstIteration: 271.51 +- 15.58 ms [task 2018-09-21T20:25:14.174Z] averageWorstCase: 203.05 +- 17.47 ms [task 2018-09-21T20:25:14.174Z] steadyState: 197.00 +- 4.68 ms [task 2018-09-21T20:25:14.174Z] summary: 59.44 +- 0.23 ms [task 2018-09-21T20:25:14.174Z] [task 2018-09-21T20:25:14.174Z] [task 2018-09-21T20:25:14.174Z] Running... Air ( 3 to go) [task 2018-09-21T20:25:17.583Z] firstIteration: 90.18 +- 5.02 ms [task 2018-09-21T20:25:17.583Z] averageWorstCase: 50.00 +- 5.82 ms [task 2018-09-21T20:25:17.583Z] steadyState: 16.84 +- 0.51 ms [task 2018-09-21T20:25:17.583Z] summary: 59.44 +- 0.23 ms [task 2018-09-21T20:25:17.583Z] [task 2018-09-21T20:25:17.583Z] [task 2018-09-21T20:25:17.583Z] Running... Basic ( 3 to go) [task 2018-09-21T20:25:26.298Z] firstIteration: 69.84 +- 4.49 ms [task 2018-09-21T20:25:26.300Z] averageWorstCase: 46.17 +- 2.24 ms [task 2018-09-21T20:25:26.300Z] steadyState: 42.24 +- 1.82 ms [task 2018-09-21T20:25:26.301Z] summary: 59.44 +- 0.23 ms [task 2018-09-21T20:25:26.301Z] [task 2018-09-21T20:25:26.301Z] [task 2018-09-21T20:25:26.301Z] Running... Babylon ( 3 to go) [task 2018-09-21T20:25:27.750Z] firstIteration: 96.94 +- 4.83 ms [task 2018-09-21T20:25:27.751Z] averageWorstCase: 27.61 +- 2.97 ms [task 2018-09-21T20:25:27.751Z] steadyState: 6.67 +- 0.09 ms [task 2018-09-21T20:25:27.751Z] summary: 59.44 +- 0.23 ms [task 2018-09-21T20:25:27.751Z] [task 2018-09-21T20:25:27.751Z] [task 2018-09-21T20:25:27.751Z] Running... ML ( 3 to go) [task 2018-09-21T20:25:39.528Z] firstIteration: 269.44 +- 10.47 ms [task 2018-09-21T20:25:39.528Z] averageWorstCase: 202.07 +- 9.65 ms [task 2018-09-21T20:25:39.528Z] steadyState: 196.47 +- 2.98 ms [task 2018-09-21T20:25:39.528Z] summary: 59.48 +- 0.18 ms [task 2018-09-21T20:25:39.528Z] [task 2018-09-21T20:25:39.528Z] [task 2018-09-21T20:25:39.528Z] Running... Air ( 2 to go) [task 2018-09-21T20:25:42.945Z] firstIteration: 90.46 +- 3.49 ms [task 2018-09-21T20:25:42.945Z] averageWorstCase: 50.21 +- 3.98 ms [task 2018-09-21T20:25:42.945Z] steadyState: 16.80 +- 0.36 ms [task 2018-09-21T20:25:42.945Z] summary: 59.48 +- 0.18 ms [task 2018-09-21T20:25:42.945Z] [task 2018-09-21T20:25:42.945Z] [task 2018-09-21T20:25:42.945Z] Running... Basic ( 2 to go) [task 2018-09-21T20:25:51.150Z] firstIteration: 69.89 +- 3.05 ms [task 2018-09-21T20:25:51.150Z] averageWorstCase: 46.01 +- 1.58 ms [task 2018-09-21T20:25:51.150Z] steadyState: 41.97 +- 1.45 ms [task 2018-09-21T20:25:51.150Z] summary: 59.48 +- 0.18 ms [task 2018-09-21T20:25:51.150Z] [task 2018-09-21T20:25:51.150Z] [task 2018-09-21T20:25:51.150Z] Running... Babylon ( 2 to go) [task 2018-09-21T20:25:52.615Z] firstIteration: 97.79 +- 4.02 ms [task 2018-09-21T20:25:52.615Z] averageWorstCase: 27.20 +- 2.31 ms [task 2018-09-21T20:25:52.615Z] steadyState: 6.70 +- 0.10 ms [task 2018-09-21T20:25:52.615Z] summary: 59.48 +- 0.18 ms [task 2018-09-21T20:25:52.615Z] [task 2018-09-21T20:25:52.615Z] [task 2018-09-21T20:25:52.615Z] Running... ML ( 2 to go) [task 2018-09-21T20:26:04.341Z] firstIteration: 269.47 +- 7.09 ms [task 2018-09-21T20:26:04.341Z] averageWorstCase: 201.35 +- 6.84 ms [task 2018-09-21T20:26:04.341Z] steadyState: 195.96 +- 2.46 ms [task 2018-09-21T20:26:04.341Z] summary: 59.42 +- 0.21 ms [task 2018-09-21T20:26:04.341Z] [task 2018-09-21T20:26:04.341Z] [task 2018-09-21T20:26:04.341Z] Running... Air ( 1 to go) [task 2018-09-21T20:26:07.770Z] firstIteration: 90.67 +- 2.69 ms [task 2018-09-21T20:26:07.771Z] averageWorstCase: 50.03 +- 3.04 ms [task 2018-09-21T20:26:07.772Z] steadyState: 16.79 +- 0.28 ms [task 2018-09-21T20:26:07.772Z] summary: 59.42 +- 0.21 ms [task 2018-09-21T20:26:07.772Z] [task 2018-09-21T20:26:07.772Z] [task 2018-09-21T20:26:07.772Z] Running... Basic ( 1 to go) [task 2018-09-21T20:26:16.137Z] firstIteration: 69.65 +- 2.38 ms [task 2018-09-21T20:26:16.137Z] averageWorstCase: 45.99 +- 1.20 ms [task 2018-09-21T20:26:16.137Z] steadyState: 41.92 +- 1.10 ms [task 2018-09-21T20:26:16.137Z] summary: 59.42 +- 0.21 ms [task 2018-09-21T20:26:16.137Z] [task 2018-09-21T20:26:16.137Z] [task 2018-09-21T20:26:16.137Z] Running... Babylon ( 1 to go) [task 2018-09-21T20:26:17.581Z] firstIteration: 97.85 +- 3.04 ms [task 2018-09-21T20:26:17.581Z] averageWorstCase: 27.24 +- 1.74 ms [task 2018-09-21T20:26:17.581Z] steadyState: 6.69 +- 0.07 ms [task 2018-09-21T20:26:17.581Z] summary: 59.42 +- 0.21 ms [task 2018-09-21T20:26:17.581Z] [task 2018-09-21T20:26:17.581Z] [task 2018-09-21T20:26:17.581Z] Running... ML ( 1 to go) [task 2018-09-21T20:26:29.303Z] firstIteration: 270.82 +- 6.39 ms [task 2018-09-21T20:26:29.303Z] averageWorstCase: 201.07 +- 5.21 ms [task 2018-09-21T20:26:29.303Z] steadyState: 195.60 +- 2.08 ms [task 2018-09-21T20:26:29.303Z] summary: 59.41 +- 0.16 ms [task 2018-09-21T20:26:29.303Z] Success! Benchmark is now finished. [task 2018-09-21T20:26:29.303Z] [task 2018-09-21T20:26:29.303Z] [task 2018-09-21T20:26:29.693Z] PERFHERDER_DATA: {"framework": {"name": "js-bench"}, "suites": [{"name": "ares6-sm", "lowerIsBetter": true, "value": 59.41, "shouldAlert": false, "units": "ms", "subtests": [{"name": "ML-firstIteration", "value": 269.255}, {"name": "ML-averageWorstCase", "value": 203.82833333333335}, {"name": "ML-steadyState", "value": 196.76833333333335}, {"name": "Basic-firstIteration", "value": 69.37833333333333}, {"name": "Basic-averageWorstCase", "value": 45.818333333333335}, {"name": "Basic-steadyState", "value": 41.958333333333336}, {"name": "Babylon-firstIteration", "value": 96.06}, {"name": "Babylon-averageWorstCase", "value": 28.224999999999998}, {"name": "Babylon-steadyState", "value": 6.683333333333334}, {"name": "Air-firstIteration", "value": 91.37166666666667}, {"name": "Air-averageWorstCase", "value": 48.73166666666666}, {"name": "Air-steadyState", "value": 16.776666666666667}]}]} [fetches 2018-09-21T20:26:29.710Z] removing /home/cltbld/fetches Traceback (most recent call last): File "./run-task", line 754, in <module> sys.exit(main(sys.argv[1:])) File "./run-task", line 749, in main shutil.rmtree(fetches_dir) File "/usr/lib/python3.5/shutil.py", line 474, in rmtree _rmtree_safe_fd(fd, path, onerror) File "/usr/lib/python3.5/shutil.py", line 408, in _rmtree_safe_fd onerror(os.open, fullname, sys.exc_info()) File "/usr/lib/python3.5/shutil.py", line 406, in _rmtree_safe_fd dirfd = os.open(name, os.O_RDONLY, dir_fd=topfd) PermissionError: [Errno 13] Permission denied: 'unity-webgl'
these seem to cluster on specific machines: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2018-09-26&endday=2018-10-03&tree=all&bug=1493289 t-linux64-ms-003 t-linux64-ms-091 t-linux64-ms-140 t-linux64-ms-487 :dhouse, is it possible to reimage those machines or look at them? I think those 4 machines make up 75% of the failures.
Flags: needinfo?(dhouse)
It looks like this is failing to delete a directory or files after a test has completed: ``` [fetches 2018-09-21T20:26:29.710Z] removing /home/cltbld/fetches Traceback (most recent call last): File "./run-task", line 754, in <module> sys.exit(main(sys.argv[1:])) File "./run-task", line 749, in main shutil.rmtree(fetches_dir) File "/usr/lib/python3.5/shutil.py", line 474, in rmtree _rmtree_safe_fd(fd, path, onerror) File "/usr/lib/python3.5/shutil.py", line 408, in _rmtree_safe_fd onerror(os.open, fullname, sys.exc_info()) File "/usr/lib/python3.5/shutil.py", line 406, in _rmtree_safe_fd dirfd = os.open(name, os.O_RDONLY, dir_fd=topfd) PermissionError: [Errno 13] Permission denied: 'unity-webgl' ``` I'll check on a few of the machines for log entries and leftover files, to see what the permissions on the directory are (or if we can catch one that is stuck/failed, to see if a process is keeping file handles open).
Joel, how could I try running these tests manually (or I'd like to run them on the staging/beta workertype to capture more information about the permissions error)? [task 2018-10-02T22:24:07.223Z] + ./mach jsshell-bench --perfherder=sm --binary=/home/cltbld/fetches/js sunspider I'm not yet familiar with the try arguments and didn't find any reference in TryChooser to "jsshell" or similar. The only reference I could find to "unity-webgl" are in the raptor tests: https://searchfox.org/mozilla-central/source/taskcluster/ci/fetch/benchmarks.yml --- The worker's system log doesn't show anything to cause the problem. (checked for last 3 occurances on 140) And I don't see anything else obvious on those systems yet (imaged at different times, have run different #s of jobs before the failure, on different chassis and in both mdc1 and mdc2).
Flags: needinfo?(jmaher)
to run these on try I use |./mach try fuzzy -q jsshell-bench| and select jsshell-bench tests. Oddly these stopped failing on october 4th, so I assume this will not need any more attention- lets shelve this for now and revisit if the problem picks up.
Flags: needinfo?(jmaher)
Flags: needinfo?(dhouse)
(In reply to Joel Maher ( :jmaher ) (UTC-4) from comment #9) > to run these on try I use |./mach try fuzzy -q jsshell-bench| and select > jsshell-bench tests. Oddly these stopped failing on october 4th, so I > assume this will not need any more attention- lets shelve this for now and > revisit if the problem picks up. Thank you!
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.