1965098 - Intermittent Uncaught exception: Traceback (most recent call last): | avb_user_verity.c:205: ERROR: Error writing to partition 'vbmeta'

Status: RESOLVED DUPLICATE of bug 1675603

(Firefox Build System :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2025-05-07T19:07:35.416Z] Granting important runtime permissions to org.mozilla.geckoview.test_runner
[task 2025-05-07T19:07:35.571Z] launch_application: am start -W -n org.mozilla.geckoview.test_runner/org.mozilla.geckoview.test_runner.TestRunnerActivity -a android.intent.action.MAIN --es env0 MOZ_CRASHREPORTER=1 --es env1 MOZ_CRASHREPORTER_NO_REPORT=1 --es env2 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env3 MOZ_HIDE_RESULTS_TABLE=1 --es env4 MOZ_IN_AUTOMATION=1 --es env5 MOZ_LOG=signaling:3,mtransport:4,DataChannel:4,jsep:4 --es env6 R_LOG_LEVEL=6 --es env7 R_LOG_DESTINATION=stderr --es env8 R_LOG_VERBOSE=1 --es env9 MOZ_PROCESS_LOG=/tmp/tmpudi1p1p9pidlog --es env10 XPCOM_DEBUG_BREAK=warn --es env11 MOZ_JAR_LOG_FILE=/data/local/tmp/test_root/pgo_profile/en-US.log --es env12 LLVM_PROFILE_FILE=/data/local/tmp/test_root/pgo_profile/default_%p_random_%m.profraw --es arg0 -no-remote --es arg1 -profile --es arg2 /data/local/tmp/test_root/profile --es arg3 -marionette
[task 2025-05-07T19:18:53.871Z] 19:18:53     INFO - Running post-action listener: _resource_record_post_action
[task 2025-05-07T19:18:53.871Z] 19:18:53     INFO - Running post-action listener: stop_device
[task 2025-05-07T19:18:54.224Z] 19:18:54     INFO - Killing logcat pid 939.
[task 2025-05-07T19:18:54.225Z] 19:18:54     INFO - Killing every process called qemu-system-x86_64
[task 2025-05-07T19:18:54.228Z] 19:18:54     INFO - [mozharness: 2025-05-07 19:18:54.228660Z] Finished run-tests step (failed)
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL - Uncaught exception: Traceback (most recent call last):
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -   File "/builds/worker/checkouts/gecko/testing/mozharness/mozharness/base/script.py", line 2381, in run
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -     self.run_action(action)
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -   File "/builds/worker/checkouts/gecko/testing/mozharness/mozharness/base/script.py", line 2315, in run_action
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -     self._possibly_run_method(method_name, error_if_missing=True)
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -   File "/builds/worker/checkouts/gecko/testing/mozharness/mozharness/base/script.py", line 2267, in _possibly_run_method
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -     return getattr(self, method_name)()
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -   File "/builds/worker/checkouts/gecko/testing/mozharness/scripts/android_emulator_pgo.py", line 236, in run_tests
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -     driver.navigate("http://%s:%d/%s" % (IP, PORT, page))
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 1696, in navigate
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -     self._send_message("WebDriver:Navigate", {"url": url})
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -     return func(*args, **kwargs)
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 771, in _send_message
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -     self._handle_error(err)
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -   File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 783, in _handle_error
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL -     raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL - marionette_driver.errors.TimeoutException: Navigation timed out after 300000 ms
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL - stacktrace:
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL - 	RemoteError@chrome://remote/content/shared/RemoteError.sys.mjs:8:8
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL - 	WebDriverError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:199:5
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL - 	TimeoutError@chrome://remote/content/shared/webdriver/Errors.sys.mjs:812:5
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL - 	bail@chrome://remote/content/shared/Sync.sys.mjs:428:19
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL - 
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL - Running post_fatal callback...
[task 2025-05-07T19:18:54.230Z] 19:18:54    FATAL - Exiting -1
[task 2025-05-07T19:18:54.230Z] 19:18:54     INFO - Running post-run listener: _resource_record_post_run
[task 2025-05-07T19:18:54.474Z] 19:18:54     INFO - Total resource usage - Wall time: 736s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-05-07T19:18:54.474Z] 19:18:54     INFO - TinderboxPrint: I/O read bytes / time<br/>0 / 0
[task 2025-05-07T19:18:54.474Z] 19:18:54     INFO - TinderboxPrint: I/O write bytes / time<br/>0 / 0
[task 2025-05-07T19:18:54.474Z] 19:18:54     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2025-05-07T19:18:54.475Z] 19:18:54     INFO - start-emulator - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-05-07T19:18:54.477Z] 19:18:54     INFO - verify-device - Wall time: 44s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-05-07T19:18:54.479Z] 19:18:54     INFO - install - Wall time: 7s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-05-07T19:18:54.492Z] 19:18:54     INFO - run-tests - Wall time: 684s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2025-05-07T19:18:56.178Z] cleanup aborted: args: /builds/worker/fetches/android-sdk-linux/platform-tools/adb wait-for-device remount, exitcode: 10, stdout: avb_ops_user.cpp:217: ERROR: Error writing data.
[task 2025-05-07T19:18:56.178Z] avb_user_verity.c:205: ERROR: Error writing to partition 'vbmeta'
[task 2025-05-07T19:18:56.178Z] Skipping /system for remount
[task 2025-05-07T19:18:56.178Z] [liblp]bool android::fs_mgr::WritePrimaryMetadata(int, const android::fs_mgr::LpMetadata &, uint32_t, const std::string &, const std::function<bool (int, const std::string &)> &) write 740 bytes failed: Operation not permitted
[task 2025-05-07T19:18:56.178Z] [libfs_mgr]add partition scratch
[task 2025-05-07T19:18:56.178Z] [liblp]bool android::fs_mgr::WritePrimaryMetadata(int, const android::fs_mgr::LpMetadata &, uint32_t, const std::string &, const std::function<bool (int, const std::string &)> &) write 740 bytes failed: Operation not permitted
[task 2025-05-07T19:18:56.178Z] [libfs_mgr]add partition scratch
[task 2025-05-07T19:18:56.178Z] [liblp]bool android::fs_mgr::WritePrimaryMetadata(int, const android::fs_mgr::LpMetadata &, uint32_t, const std::string &, const std::function<bool (int, const std::string &)> &) write 740 bytes failed: Operation not permitted
[task 2025-05-07T19:18:56.178Z] [libfs_mgr]add partition scratch
[task 2025-05-07T19:18:56.178Z] Can not mount overlayfs for partitions: No such file or directory
[task 2025-05-07T19:18:56.178Z] failed to remount partition dev:/dev/block/dm-3 mnt:/vendor: Read-only file system
[task 2025-05-07T19:18:56.178Z] Consider providing all the dependencies to enable overlayfs
[task 2025-05-07T19:18:56.178Z] failed to remount partition dev:/dev/block/dm-2 mnt:/product: Read-only file system
[task 2025-05-07T19:18:56.178Z] Consider providing all the dependencies to enable overlayfs
[task 2025-05-07T19:18:56.178Z] failed to remount partition dev:/dev/block/dm-1 mnt:/system_ext: Read-only file system
[task 2025-05-07T19:18:56.178Z] Consider providing all the dependencies to enable overlayfs
[task 2025-05-07T19:18:56.178Z] remount failed
[task 2025-05-07T19:18:56.371Z] cleanup aborted: args: /builds/worker/fetches/android-sdk-linux/platform-tools/adb wait-for-device remount, exitcode: 10, stdout: avb_ops_user.cpp:217: ERROR: Error writing data.
[task 2025-05-07T19:18:56.371Z] avb_user_verity.c:205: ERROR: Error writing to partition 'vbmeta'
[task 2025-05-07T19:18:56.371Z] Skipping /system for remount
[task 2025-05-07T19:18:56.371Z] [liblp]bool android::fs_mgr::WritePrimaryMetadata(int, const android::fs_mgr::LpMetadata &, uint32_t, const std::string &, const std::function<bool (int, const std::string &)> &) write 740 bytes failed: Operation not permitted
[task 2025-05-07T19:18:56.371Z] [libfs_mgr]add partition scratch
[task 2025-05-07T19:18:56.371Z] [liblp]bool android::fs_mgr::WritePrimaryMetadata(int, const android::fs_mgr::LpMetadata &, uint32_t, const std::string &, const std::function<bool (int, const std::string &)> &) write 740 bytes failed: Operation not permitted
[task 2025-05-07T19:18:56.371Z] [libfs_mgr]add partition scratch
[task 2025-05-07T19:18:56.371Z] [liblp]bool android::fs_mgr::WritePrimaryMetadata(int, const android::fs_mgr::LpMetadata &, uint32_t, const std::string &, const std::function<bool (int, const std::string &)> &) write 740 bytes failed: Operation not permitted
[task 2025-05-07T19:18:56.371Z] [libfs_mgr]add partition scratch
[task 2025-05-07T19:18:56.371Z] Can not mount overlayfs for partitions: No such file or directory
[task 2025-05-07T19:18:56.371Z] failed to remount partition dev:/dev/block/dm-3 mnt:/vendor: Read-only file system
[task 2025-05-07T19:18:56.371Z] Consider providing all the dependencies to enable overlayfs
[task 2025-05-07T19:18:56.371Z] failed to remount partition dev:/dev/block/dm-2 mnt:/product: Read-only file system
[task 2025-05-07T19:18:56.371Z] Consider providing all the dependencies to enable overlayfs
[task 2025-05-07T19:18:56.371Z] failed to remount partition dev:/dev/block/dm-1 mnt:/system_ext: Read-only file system
[task 2025-05-07T19:18:56.371Z] Consider providing all the dependencies to enable overlayfs
[task 2025-05-07T19:18:56.371Z] remount failed
[task 2025-05-07T19:18:56.499Z] cleanup
[task 2025-05-07T19:18:56.499Z] + cleanup
[task 2025-05-07T19:18:56.499Z] + local rv=255
[task 2025-05-07T19:18:56.499Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2025-05-07T19:18:56.499Z] + false
[task 2025-05-07T19:18:56.499Z] + '[' ']'
[task 2025-05-07T19:18:56.499Z] + true
[task 2025-05-07T19:18:56.499Z] + cleanup_xvfb
[task 2025-05-07T19:18:56.500Z] ++ pidof Xvfb
[task 2025-05-07T19:18:56.502Z] + local xvfb_pid=71
[task 2025-05-07T19:18:56.502Z] + local vnc=false
[task 2025-05-07T19:18:56.502Z] + local interactive=false
[task 2025-05-07T19:18:56.502Z] + '[' -n 71 ']'
[task 2025-05-07T19:18:56.502Z] + [[ false == false ]]
[task 2025-05-07T19:18:56.502Z] + [[ false == false ]]
[task 2025-05-07T19:18:56.502Z] + kill 71
[task 2025-05-07T19:18:56.502Z] + exit 255
[taskcluster 2025-05-07 19:18:57.072Z] === Task Finished ===
[taskcluster 2025-05-07 19:18:57.077Z] Artifact "public/logs" not found at "/builds/worker/logs/": (HTTP code 404) no such container - Could not find the file /builds/worker/logs/ in container ec9c42ae2288b6c0301e694d8aefd32a9525d1f3e58ad162fcad62deed34fe3c 
[taskcluster 2025-05-07 19:18:58.438Z] Unsuccessful task run with exit code: 255 completed in 1071.12 seconds

Comment 1

[Cosmin Sabou [:CosminS]]

Failure line here is:
marionette_driver.errors.TimeoutException: Navigation timed out after 300000 ms