Open Bug 1695384 Opened 3 years ago Updated 1 year ago

Intermittent Android 8.0 AArch tests/jit-test/jit-test/tests/<random> | timeout: exec /data/local/tmp/test_root/bin/js: Permission denied (code 127, args "") [0.0 s]

Categories

(Core :: JavaScript Engine, defect, P5)

defect

Tracking

()

ASSIGNED
Tracking Status
firefox-esr78 --- unaffected
firefox86 --- unaffected
firefox87 --- unaffected
firefox88 --- affected

People

(Reporter: intermittent-bug-filer, Assigned: nbp, NeedInfo)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure, leave-open)

Attachments

(1 file)

Filed by: abutkovits [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=331424430&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/F5q5Qg_cRqaeEFpiSguOVA/runs/0/artifacts/public/logs/live_backing.log


[task 2021-02-27T01:11:31.518Z] 01:11:29     INFO - Found Android bogomips: 38
[task 2021-02-27T01:11:31.518Z] 01:11:29     INFO - adb -s FA84C1A00121 logcat -v threadtime Trace:S StrictMode:S ExchangeService:S
[task 2021-02-27T01:11:31.518Z] 01:11:31     INFO - verify_device complete
[task 2021-02-27T01:11:31.518Z] 01:11:31     INFO - Running post-action listener: _resource_record_post_action
[task 2021-02-27T01:11:31.518Z] 01:11:31     INFO - [mozharness: 2021-02-27 01:11:31.421928Z] Finished verify-device step (success)
[task 2021-02-27T01:11:31.518Z] 01:11:31     INFO - [mozharness: 2021-02-27 01:11:31.421989Z] Running install step.
[task 2021-02-27T01:11:31.518Z] 01:11:31     INFO - Running pre-action listener: _resource_record_pre_action
[task 2021-02-27T01:12:39.054Z] 01:11:31     INFO - Running main action method: install
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - Finished installing apps for pixel2-16
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - Running post-action listener: _resource_record_post_action
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - [mozharness: 2021-02-27 01:11:52.912059Z] Finished install step (success)
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - [mozharness: 2021-02-27 01:11:52.912113Z] Running run-tests step.
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - Running pre-action listener: _resource_record_pre_action
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - Running pre-action listener: _set_gcov_prefix
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - Running pre-action listener: timed_screenshots
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - Running main action method: run_tests
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - Running on pixel2-16 the command /builds/task_161438793697515/workspace/build/venv/bin/python -u /builds/task_161438793697515/workspace/build/tests/jit-test/jit-test/jit_test.py ../../bin/js --remote -j 1 --localLib=../../bin --no-slow --no-progress --format=automation --jitflags=debug --deviceSerial=FA84C1A00121 --this-chunk 9 --total-chunks 10
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - ##### jittest log begins
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - Running command: ['/builds/task_161438793697515/workspace/build/venv/bin/python', '-u', '/builds/task_161438793697515/workspace/build/tests/jit-test/jit-test/jit_test.py', '../../bin/js', '--remote', '-j', '1', '--localLib=../../bin', '--no-slow', '--no-progress', '--format=automation', '--jitflags=debug', '--deviceSerial=FA84C1A00121', '--this-chunk', '9', '--total-chunks', '10'] in /builds/task_161438793697515/workspace/build/tests/jit-test/jit-test
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - Copy/paste: /builds/task_161438793697515/workspace/build/venv/bin/python -u /builds/task_161438793697515/workspace/build/tests/jit-test/jit-test/jit_test.py ../../bin/js --remote -j 1 --localLib=../../bin --no-slow --no-progress --format=automation --jitflags=debug --deviceSerial=FA84C1A00121 --this-chunk 9 --total-chunks 10
[task 2021-02-27T01:12:39.054Z] 01:11:52     INFO - Using env: (same as previous command)
[task 2021-02-27T01:12:39.054Z] 01:11:53     INFO -  Using adb 1.0.41
[task 2021-02-27T01:12:39.054Z] 01:11:53     INFO -  /system/bin/ls -1A supported
[task 2021-02-27T01:12:39.054Z] 01:11:56     INFO -  Native cp support: True
[task 2021-02-27T01:12:39.054Z] 01:11:56     INFO -  Native chmod -R support: True
[task 2021-02-27T01:12:39.054Z] 01:11:56     INFO -  Native chown -R support: True
[task 2021-02-27T01:12:39.054Z] 01:11:57     INFO -  Native normal pidof support: True
[task 2021-02-27T01:12:39.054Z] 01:11:57     INFO -  adbd not restarted as root
[task 2021-02-27T01:12:39.054Z] 01:11:57     INFO -  su -c setenforce 0 exitcode 0, stdout: None
[task 2021-02-27T01:12:39.054Z] 01:11:57     INFO -  su -c supported
[task 2021-02-27T01:12:39.054Z] 01:11:58     INFO -  Setting SELinux Permissive
[task 2021-02-27T01:12:39.054Z] 01:12:03     INFO -  Setting test_root to /data/local/tmp/test_root
[task 2021-02-27T01:12:39.054Z] 01:12:37     INFO -  tasks_adb_remote.py : Device initialization completed
[task 2021-02-27T01:12:39.054Z] 01:12:37     INFO -  tasks_adb_remote.py : Create batch script
[task 2021-02-27T01:12:39.054Z] 01:12:38     INFO -  tasks_adb_remote.py : Batch script created
[task 2021-02-27T01:12:39.054Z] 01:12:38     INFO -  timeout: exec /data/local/tmp/test_root/bin/js: Permission denied
[task 2021-02-27T01:12:39.054Z] 01:12:38     INFO -  timeout: exec /data/local/tmp/test_root/bin/js: Permission denied
[task 2021-02-27T01:12:39.054Z] 01:12:38     INFO -  Exit code: 127
[task 2021-02-27T01:12:39.054Z] 01:12:38     INFO -  FAIL - jaeger/loops/property-1.js
[task 2021-02-27T01:12:39.054Z] 01:12:38  WARNING -  TEST-UNEXPECTED-FAIL | tests/jit-test/jit-test/tests/jaeger/loops/property-1.js | timeout: exec /data/local/tmp/test_root/bin/js: Permission denied (code 127, args "") [0.0 s]```
Summary: Intermittent [tier2] tests/jit-test/jit-test/tests/jaeger/loops/property-1.js | timeout: exec /data/local/tmp/test_root/bin/js: Permission denied (code 127, args "") [0.0 s] → Intermittent Android 8.0 AArch tests/jit-test/jit-test/tests/<random> | timeout: exec /data/local/tmp/test_root/bin/js: Permission denied (code 127, args "") [0.0 s]

Could these failures be a regression from bug 1690570?

Flags: needinfo?(nicolas.b.pierron)

This is the first time I see this issue, in all the testing I've done so far on these patches this sounds very unlikely, and the error message is clear enough to not be confused with other issues.

It sounds like the chmod / upload did not work as expected, and that we might have ignored the error status.
I will look at this intermittent if it re-appear in the future.

(In reply to Nicolas B. Pierron [:nbp] from comment #5)

I will look at this intermittent if it re-appear in the future.

I just noticed the duplicates of this bug which did not appear in the orange factor, I will look at this bug later this week.

Bug 1686068 suggests this bug existed, but instead of having a binary it was a directory.

I will add assertions to check that we are pushing files to the device. This would help us bisect if this is an issue of adb or an issue in the build output.

Assignee: nobody → nicolas.b.pierron
Status: NEW → ASSIGNED

(setting leave-open as the current patch is not meant to fix this issue, but to bisect where this issue happens)

Keywords: leave-open

Since we have the helper script on the device, we could have it check that the js file exists and check its permissions (reporting something useful). There are also some verbose logs in the adb stack we could consider enabling in automation but that is a bit annoying.

Pushed by npierron@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/a0f0682883e1
Android JS tests: Investigate intermittent non-executable binary. r=tcampbell

This is also happening on non-AArch Android 8.0 platforms: https://treeherder.mozilla.org/logviewer?job_id=349717198&repo=mozilla-central&lineNumber=1523

Nicolas, should we modify this bug's summary to include those as well, or create a new one for them?

You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: