Closed
Bug 1811243
Opened 2 years ago
Closed 2 years ago
Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/get_page_source/source.py | expected OK
Categories
(Remote Protocol :: Marionette, defect, P5)
Remote Protocol
Marionette
Tracking
(Not tracked)
RESOLVED
DUPLICATE
of bug 1790325
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=402872266&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/daKNqFEYRYqLWE-l09kzGw/runs/0/artifacts/public/logs/live_backing.log
[task 2023-01-19T13:22:57.167Z] 13:22:57 INFO - TEST-START | /webdriver/tests/get_page_source/source.py
[task 2023-01-19T13:22:57.340Z] 13:22:57 INFO - STDOUT: ============================= test session starts ==============================
[task 2023-01-19T13:22:57.340Z] 13:22:57 INFO - STDOUT: platform linux -- Python 3.6.9, pytest-7.0.1, pluggy-unknown -- /builds/worker/workspace/build/venv/bin/python
[task 2023-01-19T13:22:57.341Z] 13:22:57 INFO - STDOUT: rootdir: /builds/worker/workspace/build
[task 2023-01-19T13:22:57.342Z] 13:22:57 INFO - STDOUT: plugins: asyncio-0.14.0
[task 2023-01-19T13:22:57.342Z] 13:22:57 INFO - STDOUT: collecting ...
[task 2023-01-19T13:22:57.346Z] 13:22:57 INFO - STDOUT: collected 3 items
[task 2023-01-19T13:22:57.347Z] 13:22:57 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/get_page_source/source.py::test_no_top_browsing_context
[task 2023-01-19T13:22:57.348Z] 13:22:57 INFO - PID 3282 | 1674134577347 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"args": ["--profile", "/tmp/tmpf4nzpclq.mozrunner"] ... -5554", "env": {"MOZ_CRASHREPORTER": "1", "MOZ_CRASHREPORTER_SHUTDOWN": "1", "STYLO_THREADS": "1", "RUST_BACKTRACE": "1"}}}}}
[task 2023-01-19T13:22:57.348Z] 13:22:57 INFO - PID 3282 | 1674134577347 mozdevice TRACE adb server response was "emulator-5554 device product:sdk_phone_x86_64 model:Android_SDK_built_for_x86_64 device:generic_x86_64 transport_id:2\n"
[task 2023-01-19T13:22:57.349Z] 13:22:57 INFO - PID 3282 | 1674134577348 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:57.349Z] 13:22:57 INFO - PID 3282 | 1674134577348 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:57.349Z] 13:22:57 INFO - PID 3282 | 1674134577348 mozdevice TRACE execute_host_command: >> "shell:id"
[task 2023-01-19T13:22:57.357Z] 13:22:57 INFO - PID 3282 | 1674134577356 mozdevice TRACE execute_host_command: << "uid=0(root) gid=0(root) groups=0(root),1004(input),1007(log),1011(adb),1015(sdcard_rw),1028(sdcard_r),3001(net_bt_admin),3002(net_bt),3003(inet),3006(net_bw_stats),3009(readproc) context=u:r:su:s0\n"
[task 2023-01-19T13:22:57.357Z] 13:22:57 INFO - PID 3282 | 1674134577356 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:57.357Z] 13:22:57 INFO - PID 3282 | 1674134577356 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:57.358Z] 13:22:57 INFO - PID 3282 | 1674134577356 mozdevice TRACE execute_host_command: >> "shell:su 0 id"
[task 2023-01-19T13:22:57.365Z] 13:22:57 INFO - PID 3282 | 1674134577364 mozdevice TRACE execute_host_command: << "uid=0(root) gid=0(root) groups=0(root),1004(input),1007(log),1011(adb),1015(sdcard_rw),1028(sdcard_r),3001(net_bt_admin),3002(net_bt),3003(inet),3006(net_bw_stats),3009(readproc) context=u:r:su:s0\n"
[task 2023-01-19T13:22:57.365Z] 13:22:57 INFO - PID 3282 | 1674134577364 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:57.366Z] 13:22:57 INFO - PID 3282 | 1674134577364 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:57.366Z] 13:22:57 INFO - PID 3282 | 1674134577364 mozdevice TRACE execute_host_command: >> "shell:su -c id"
[task 2023-01-19T13:22:57.371Z] 13:22:57 INFO - PID 3282 | 1674134577371 mozdevice TRACE execute_host_command: << "su: invalid uid/gid '-c'\n"
[task 2023-01-19T13:22:57.372Z] 13:22:57 INFO - PID 3282 | 1674134577371 mozdevice INFO Device is rooted
[task 2023-01-19T13:22:57.372Z] 13:22:57 INFO - PID 3282 | 1674134577371 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:57.372Z] 13:22:57 INFO - PID 3282 | 1674134577371 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:57.373Z] 13:22:57 INFO - PID 3282 | 1674134577371 mozdevice TRACE execute_host_command: >> "shell:setenforce permissive"
[task 2023-01-19T13:22:57.378Z] 13:22:57 INFO - PID 3282 | 1674134577377 mozdevice TRACE execute_host_command: << ""
[task 2023-01-19T13:22:57.378Z] 13:22:57 INFO - PID 3282 | 1674134577377 geckodriver::android DEBUG Marionette port forward (44420 -> 2829) started[task 2023-01-19T13:22:57.379Z] 13:22:57 INFO - PID 3282 | 1674134577377 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:57.379Z] 13:22:57 INFO - PID 3282 | 1674134577377 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:57.379Z] 13:22:57 INFO - PID 3282 | 1674134577377 mozdevice TRACE execute_host_command: >> "shell:echo $EXTERNAL_STORAGE"
[task 2023-01-19T13:22:57.383Z] 13:22:57 INFO - PID 3282 | 1674134577382 mozdevice TRACE execute_host_command: << "/sdcard\n"
[task 2023-01-19T13:22:57.383Z] 13:22:57 INFO - PID 3282 | 1674134577382 geckodriver::android DEBUG Connecting: options=AndroidOptions { activity: None, device_serial: Some("emulator-5554"), intent_arguments: Some(["-a", "and ... Auto }, storage=Sdcard) test_root=/sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root, run_as_package=None
[task 2023-01-19T13:22:57.384Z] 13:22:57 INFO - PID 3282 | 1674134577382 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:57.384Z] 13:22:57 INFO - PID 3282 | 1674134577382 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:57.384Z] 13:22:57 INFO - PID 3282 | 1674134577382 mozdevice TRACE execute_host_command: >> "shell:pm list packages org.mozilla.geckoview.test_runner"
[task 2023-01-19T13:22:57.550Z] 13:22:57 INFO - PID 3282 | 1674134577550 mozdevice TRACE execute_host_command: << "package:org.mozilla.geckoview.test_runner\n"
[task 2023-01-19T13:22:57.551Z] 13:22:57 INFO - PID 3282 | 1674134577550 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:57.551Z] 13:22:57 INFO - PID 3282 | 1674134577550 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:57.552Z] 13:22:57 INFO - PID 3282 | 1674134577550 mozdevice TRACE execute_host_command: >> "shell:cmd package resolve-activity --brief org.mozilla.geckoview.test_runner"
[task 2023-01-19T13:22:57.560Z] 13:22:57 INFO - PID 3282 | 1674134577560 mozdevice TRACE execute_host_command: << "priority=0 preferredOrder=0 match=0x108000 specificIndex=-1 isDefault=true\norg.mozilla.geckoview.test_runner/.App\n"
[task 2023-01-19T13:22:57.561Z] 13:22:57 INFO - PID 3282 | 1674134577560 geckodriver::browser DEBUG Backing up prefs to "/tmp/tmpf4nzpclq.mozrunner/user.geckodriver_backup"
[task 2023-01-19T13:22:57.563Z] 13:22:57 INFO - PID 3282 | 1674134577562 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:57.563Z] 13:22:57 INFO - PID 3282 | 1674134577562 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:57.564Z] 13:22:57 INFO - PID 3282 | 1674134577562 mozdevice TRACE execute_host_command: >> "shell:pm clear org.mozilla.geckoview.test_runner"
[task 2023-01-19T13:22:57.759Z] 13:22:57 INFO - PID 3282 | 1674134577759 mozdevice TRACE execute_host_command: << "Success\n"
[task 2023-01-19T13:22:57.760Z] 13:22:57 INFO - PID 3282 | 1674134577759 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:57.760Z] 13:22:57 INFO - PID 3282 | 1674134577759 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:57.761Z] 13:22:57 INFO - PID 3282 | 1674134577759 mozdevice TRACE execute_host_command: >> "shell:pm grant org.mozilla.geckoview.test_runner android.permission.READ_EXTERNAL_STORAGE"
[task 2023-01-19T13:22:57.928Z] 13:22:57 INFO - PID 3282 | 1674134577928 mozdevice TRACE execute_host_command: << ""
[task 2023-01-19T13:22:57.929Z] 13:22:57 INFO - PID 3282 | 1674134577928 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:57.929Z] 13:22:57 INFO - PID 3282 | 1674134577928 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:57.930Z] 13:22:57 INFO - PID 3282 | 1674134577928 mozdevice TRACE execute_host_command: >> "shell:pm grant org.mozilla.geckoview.test_runner android.permission.WRITE_EXTERNAL_STORAGE"
[task 2023-01-19T13:22:58.090Z] 13:22:58 INFO - PID 3282 | 1674134578089 mozdevice TRACE execute_host_command: << ""
[task 2023-01-19T13:22:58.090Z] 13:22:58 INFO - PID 3282 | 1674134578089 mozdevice DEBUG Creating /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root
[task 2023-01-19T13:22:58.091Z] 13:22:58 INFO - PID 3282 | 1674134578089 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.091Z] 13:22:58 INFO - PID 3282 | 1674134578089 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.091Z] 13:22:58 INFO - PID 3282 | 1674134578089 mozdevice TRACE execute_host_command: >> "shell:mkdir -p /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root"
[task 2023-01-19T13:22:58.099Z] 13:22:58 INFO - PID 3282 | 1674134578099 mozdevice TRACE execute_host_command: << ""[task 2023-01-19T13:22:58.100Z] 13:22:58 INFO - PID 3282 | 1674134578099 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.100Z] 13:22:58 INFO - PID 3282 | 1674134578099 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.100Z] 13:22:58 INFO - PID 3282 | 1674134578099 mozdevice TRACE execute_host_command: >> "shell:chmod -R 777 /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root"
[task 2023-01-19T13:22:58.108Z] 13:22:58 INFO - PID 3282 | 1674134578107 mozdevice TRACE execute_host_command: << ""
[task 2023-01-19T13:22:58.108Z] 13:22:58 INFO - PID 3282 | 1674134578108 mozdevice DEBUG Deleting /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile
[task 2023-01-19T13:22:58.109Z] 13:22:58 INFO - PID 3282 | 1674134578108 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.109Z] 13:22:58 INFO - PID 3282 | 1674134578108 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.110Z] 13:22:58 INFO - PID 3282 | 1674134578108 mozdevice TRACE execute_host_command: >> "shell:rm -rf /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile"
[task 2023-01-19T13:22:58.115Z] 13:22:58 INFO - PID 3282 | 1674134578115 mozdevice TRACE execute_host_command: << ""
[task 2023-01-19T13:22:58.116Z] 13:22:58 INFO - PID 3282 | 1674134578115 mozdevice DEBUG Pushing /tmp/tmpf4nzpclq.mozrunner to /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile
[task 2023-01-19T13:22:58.116Z] 13:22:58 INFO - PID 3282 | 1674134578115 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.117Z] 13:22:58 INFO - PID 3282 | 1674134578115 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.117Z] 13:22:58 INFO - PID 3282 | 1674134578115 mozdevice TRACE execute_host_command: >> "shell:ls /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile"
[task 2023-01-19T13:22:58.123Z] 13:22:58 INFO - PID 3282 | 1674134578122 mozdevice TRACE execute_host_command: << "ls: /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile: No such file or directory\n"
[task 2023-01-19T13:22:58.124Z] 13:22:58 INFO - PID 3282 | 1674134578123 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.124Z] 13:22:58 INFO - PID 3282 | 1674134578123 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.125Z] 13:22:58 INFO - PID 3282 | 1674134578123 mozdevice TRACE execute_host_command: >> "shell:ls /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root"
[task 2023-01-19T13:22:58.132Z] 13:22:58 INFO - PID 3282 | 1674134578132 mozdevice TRACE execute_host_command: << ""
[task 2023-01-19T13:22:58.133Z] 13:22:58 INFO - PID 3282 | 1674134578132 mozdevice DEBUG Creating /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile
[task 2023-01-19T13:22:58.133Z] 13:22:58 INFO - PID 3282 | 1674134578132 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.134Z] 13:22:58 INFO - PID 3282 | 1674134578132 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.134Z] 13:22:58 INFO - PID 3282 | 1674134578132 mozdevice TRACE execute_host_command: >> "shell:mkdir -p /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile"
[task 2023-01-19T13:22:58.141Z] 13:22:58 INFO - PID 3282 | 1674134578140 mozdevice TRACE execute_host_command: << ""
[task 2023-01-19T13:22:58.141Z] 13:22:58 INFO - PID 3282 | 1674134578140 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.141Z] 13:22:58 INFO - PID 3282 | 1674134578140 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.142Z] 13:22:58 INFO - PID 3282 | 1674134578140 mozdevice TRACE execute_host_command: >> "shell:chmod -R 777 /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile"
[task 2023-01-19T13:22:58.150Z] 13:22:58 INFO - PID 3282 | 1674134578149 mozdevice TRACE execute_host_command: << ""
[task 2023-01-19T13:22:58.161Z] 13:22:58 INFO - PID 3282 | 1674134578161 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.162Z] 13:22:58 INFO - PID 3282 | 1674134578161 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.162Z] 13:22:58 INFO - PID 3282 | 1674134578161 mozdevice TRACE execute_host_command: >> "shell:ls /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile"
[task 2023-01-19T13:22:58.170Z] 13:22:58 INFO - PID 3282 | 1674134578169 mozdevice TRACE execute_host_command: << "key4.db\n"
[task 2023-01-19T13:22:58.213Z] 13:22:58 INFO - PID 3282 | 1674134578212 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.213Z] 13:22:58 INFO - PID 3282 | 1674134578212 mozdevice TRACE execute_host_command: << [][task 2023-01-19T13:22:58.214Z] 13:22:58 INFO - PID 3282 | 1674134578212 mozdevice TRACE execute_host_command: >> "shell:ls /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile"
[task 2023-01-19T13:22:58.224Z] 13:22:58 INFO - PID 3282 | 1674134578223 mozdevice TRACE execute_host_command: << "key4.db\n"
[task 2023-01-19T13:22:58.269Z] 13:22:58 INFO - PID 3282 | 1674134578268 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.269Z] 13:22:58 INFO - PID 3282 | 1674134578268 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.270Z] 13:22:58 INFO - PID 3282 | 1674134578268 mozdevice TRACE execute_host_command: >> "shell:ls /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile"
[task 2023-01-19T13:22:58.280Z] 13:22:58 INFO - PID 3282 | 1674134578280 mozdevice TRACE execute_host_command: << "key4.db prefs.js \n"
[task 2023-01-19T13:22:58.325Z] 13:22:58 INFO - PID 3282 | 1674134578325 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.326Z] 13:22:58 INFO - PID 3282 | 1674134578325 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.326Z] 13:22:58 INFO - PID 3282 | 1674134578325 mozdevice TRACE execute_host_command: >> "shell:ls /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile"
[task 2023-01-19T13:22:58.337Z] 13:22:58 INFO - PID 3282 | 1674134578336 mozdevice TRACE execute_host_command: << "key4.db prefs.js user.geckodriver_backup \n"
[task 2023-01-19T13:22:58.381Z] 13:22:58 INFO - PID 3282 | 1674134578381 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.382Z] 13:22:58 INFO - PID 3282 | 1674134578381 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.382Z] 13:22:58 INFO - PID 3282 | 1674134578381 mozdevice TRACE execute_host_command: >> "shell:ls /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile"
[task 2023-01-19T13:22:58.393Z] 13:22:58 INFO - PID 3282 | 1674134578393 mozdevice TRACE execute_host_command: << "key4.db pkcs11.txt prefs.js user.geckodriver_backup \n"
[task 2023-01-19T13:22:58.438Z] 13:22:58 INFO - PID 3282 | 1674134578437 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.438Z] 13:22:58 INFO - PID 3282 | 1674134578437 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.438Z] 13:22:58 INFO - PID 3282 | 1674134578437 mozdevice TRACE execute_host_command: >> "shell:ls /sdcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile"
[task 2023-01-19T13:22:58.451Z] 13:22:58 INFO - PID 3282 | 1674134578450 mozdevice TRACE execute_host_command: << "key4.db pkcs11.txt prefs.js user.geckodriver_backup user.js \n"
[task 2023-01-19T13:22:58.460Z] 13:22:58 INFO - PID 3282 | 1674134578460 geckodriver::android DEBUG Content of generated GeckoView config file:
[task 2023-01-19T13:22:58.461Z] 13:22:58 INFO - PID 3282 | ## GeckoView configuration YAML
[task 2023-01-19T13:22:58.461Z] 13:22:58 INFO - PID 3282 | ##
[task 2023-01-19T13:22:58.461Z] 13:22:58 INFO - PID 3282 | ## Auto-generated by geckodriver.
[task 2023-01-19T13:22:58.462Z] 13:22:58 INFO - PID 3282 | ## See https ... _runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile
[task 2023-01-19T13:22:58.462Z] 13:22:58 INFO - PID 3282 | - "--profile"
[task 2023-01-19T13:22:58.462Z] 13:22:58 INFO - PID 3282 | - /tmp/tmpf4nzpclq.mozrunner[task 2023-01-19T13:22:58.464Z] 13:22:58 INFO - PID 3282 | 1674134578460 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.464Z] 13:22:58 INFO - PID 3282 | 1674134578460 mozdevice TRACE execute_host_command: >> "shell:ls /data/local/tmp"
[task 2023-01-19T13:22:58.468Z] 13:22:58 INFO - PID 3282 | 1674134578467 mozdevice TRACE execute_host_command: << "test_root\n"
[task 2023-01-19T13:22:58.509Z] 13:22:58 INFO - PID 3282 | 1674134578509 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.510Z] 13:22:58 INFO - PID 3282 | 1674134578509 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.510Z] 13:22:58 INFO - PID 3282 | 1674134578509 mozdevice TRACE execute_host_command: >> "shell:am set-debug-app --persistent org.mozilla.geckoview.test_runner"
[task 2023-01-19T13:22:58.680Z] 13:22:58 INFO - PID 3282 | 1674134578680 mozdevice TRACE execute_host_command: << ""
[task 2023-01-19T13:22:58.681Z] 13:22:58 INFO - PID 3282 | 1674134578680 geckodriver::android DEBUG Launching org.mozilla.geckoview.test_runner/.App
[task 2023-01-19T13:22:58.681Z] 13:22:58 INFO - PID 3282 | 1674134578680 mozdevice TRACE execute_host_command: >> "host:transport:emulator-5554"
[task 2023-01-19T13:22:58.681Z] 13:22:58 INFO - PID 3282 | 1674134578680 mozdevice TRACE execute_host_command: << []
[task 2023-01-19T13:22:58.682Z] 13:22:58 INFO - PID 3282 | 1674134578680 mozdevice TRACE execute_host_command: >> "shell:am start -W -n org.mozilla.geckoview.test_runner/.App -a android.intent.action.VIEW -d about: ... dcard/Android/data/org.mozilla.geckoview.test_runner/files/test_root/org.mozilla.geckoview.test_runner-geckodriver-profile\""
[task 2023-01-19T13:22:59.588Z] 13:22:59 INFO - PID 3282 | 1674134579587 mozdevice TRACE execute_host_command: << "Starting: Intent { act=android.intent.action.VIEW dat=about:blank cmp=org.mozilla.geckoview.test_ru ... s) }\nStatus: ok\nActivity: org.mozilla.geckoview.test_runner/.App\nThisTime: 731\nTotalTime: 731\nWaitTime: 739\nComplete\n"
[task 2023-01-19T13:22:59.588Z] 13:22:59 INFO - PID 3282 | 1674134579587 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1
[task 2023-01-19T13:22:59.683Z] 13:22:59 INFO - PID 3282 | 1674134579682 geckodriver::marionette DEBUG Connection to Marionette established on 127.0.0.1:44420.
[task 2023-01-19T13:23:00.350Z] 13:23:00 INFO - PID 3282 | 1674134580349 webdriver::server DEBUG <- 200 OK {"value":{"sessionId":"eab640c3-3f06-4256-b97b-d177ef53e5d5","capabilities":{"acceptInsecureCerts":false,"browserNa ... ctability":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"unhandledPromptBehavior":"dismiss and notify"}}}
[task 2023-01-19T13:23:00.351Z] 13:23:00 INFO - PID 3282 | 1674134580350 webdriver::server DEBUG -> GET /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window
[task 2023-01-19T13:23:00.369Z] 13:23:00 INFO - PID 3282 | 1674134580369 webdriver::server DEBUG <- 200 OK {"value":"c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"}
[task 2023-01-19T13:23:00.370Z] 13:23:00 INFO - PID 3282 | 1674134580369 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window/new {"type": "tab"}
[task 2023-01-19T13:23:00.667Z] 13:23:00 INFO - PID 3282 | 1674134580666 webdriver::server DEBUG <- 200 OK {"value":{"handle":"fad87ca8-7494-4422-8912-f0c3f4450fb3","type":"tab"}}
[task 2023-01-19T13:23:00.668Z] 13:23:00 INFO - PID 3282 | 1674134580667 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window {"handle": "fad87ca8-7494-4422-8912-f0c3f4450fb3"}
[task 2023-01-19T13:23:00.678Z] 13:23:00 INFO - PID 3282 | 1674134580677 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-01-19T13:23:00.679Z] 13:23:00 INFO - PID 3282 | 1674134580678 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/url {"url": "http://web-platform.test:8000/webdriver/tests/support/inli ... 3C%21doctype+html%3E%0A%3Cmeta+charset%3DUTF-8%3E%0A%3Cinput+id%3D%27a%27+value%3D%27b%27%3E&mime=text%2Fhtml&charset=UTF-8"}
[task 2023-01-19T13:23:00.787Z] 13:23:00 INFO - PID 3282 | 1674134580787 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-01-19T13:23:00.788Z] 13:23:00 INFO - PID 3282 | 1674134580787 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/element {"using": "css selector", "value": "input"}
[task 2023-01-19T13:23:00.814Z] 13:23:00 INFO - PID 3282 | 1674134580813 webdriver::server DEBUG <- 200 OK {"value":{"element-6066-11e4-a52e-4f735466cecf":"bd24d125-3e73-4087-80f2-2a6510d1068f"}}
[task 2023-01-19T13:23:00.814Z] 13:23:00 INFO - PID 3282 | 1674134580814 webdriver::server DEBUG -> DELETE /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window
[task 2023-01-19T13:23:01.174Z] 13:23:01 INFO - PID 3282 | 1674134581173 webdriver::server DEBUG <- 200 OK {"value":["c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"]}
[task 2023-01-19T13:23:01.174Z] 13:23:01 INFO - PID 3282 | 1674134581174 webdriver::server DEBUG -> GET /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window/handles
[task 2023-01-19T13:23:01.184Z] 13:23:01 INFO - PID 3282 | 1674134581183 webdriver::server DEBUG <- 200 OK {"value":["c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"]}
[task 2023-01-19T13:23:01.185Z] 13:23:01 INFO - PID 3282 | 1674134581184 webdriver::server DEBUG -> GET /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/source
[task 2023-01-19T13:23:01.204Z] 13:23:01 INFO - PID 3282 | 1674134581203 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such window","message":"Browsing context has been discarded","stacktrace":"RemoteError ... ontent/marionette/server.sys.mjs:250:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:496:20\n"}}
[task 2023-01-19T13:23:01.205Z] 13:23:01 INFO - STDOUT: PASSED[task 2023-01-19T13:23:01.206Z] 13:23:01 INFO - PID 3282 | 1674134581205 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window {"handle": "c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"}
[task 2023-01-19T13:23:01.213Z] 13:23:01 INFO - PID 3282 | 1674134581212 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-01-19T13:23:01.214Z] 13:23:01 INFO - PID 3282 | 1674134581213 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/timeouts {"implicit": 0}
[task 2023-01-19T13:23:01.221Z] 13:23:01 INFO - PID 3282 | 1674134581220 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-01-19T13:23:01.222Z] 13:23:01 INFO - PID 3282 | 1674134581221 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/timeouts {"pageLoad": 300000}
[task 2023-01-19T13:23:01.228Z] 13:23:01 INFO - PID 3282 | 1674134581228 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-01-19T13:23:01.229Z] 13:23:01 INFO - PID 3282 | 1674134581228 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/timeouts {"script": 30000}
[task 2023-01-19T13:23:01.234Z] 13:23:01 INFO - PID 3282 | 1674134581234 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-01-19T13:23:01.235Z] 13:23:01 INFO - PID 3282 | 1674134581234 webdriver::server DEBUG -> GET /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window
[task 2023-01-19T13:23:01.240Z] 13:23:01 INFO - PID 3282 | 1674134581240 webdriver::server DEBUG <- 200 OK {"value":"c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"}
[task 2023-01-19T13:23:01.241Z] 13:23:01 INFO - PID 3282 | 1674134581240 webdriver::server DEBUG -> GET /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window
[task 2023-01-19T13:23:01.247Z] 13:23:01 INFO - PID 3282 | 1674134581247 webdriver::server DEBUG <- 200 OK {"value":"c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"}
[task 2023-01-19T13:23:01.248Z] 13:23:01 INFO - PID 3282 | 1674134581247 webdriver::server DEBUG -> GET /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window/handles
[task 2023-01-19T13:23:01.253Z] 13:23:01 INFO - PID 3282 | 1674134581252 webdriver::server DEBUG <- 200 OK {"value":["c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"]}
[task 2023-01-19T13:23:01.253Z] 13:23:01 INFO - PID 3282 | 1674134581253 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window {"handle": "c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"}
[task 2023-01-19T13:23:01.260Z] 13:23:01 INFO - PID 3282 | 1674134581260 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-01-19T13:23:01.261Z] 13:23:01 INFO - PID 3282 | 1674134581260 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/alert/dismiss {}
[task 2023-01-19T13:23:01.268Z] 13:23:01 INFO - PID 3282 | 1674134581267 webdriver::server DEBUG <- 404 Not Found {"value":{"error":"no such alert","message":"","stacktrace":"RemoteError@chrome://remote/content/shared/Remo ... ontent/marionette/server.sys.mjs:250:9\n_onJSONObjectReady/<@chrome://remote/content/marionette/transport.sys.mjs:496:20\n"}}
[task 2023-01-19T13:23:01.268Z] 13:23:01 INFO - PID 3282 | 1674134581268 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window {"handle": "c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"}
[task 2023-01-19T13:23:01.274Z] 13:23:01 INFO - PID 3282 | 1674134581274 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-01-19T13:23:01.275Z] 13:23:01 INFO - PID 3282 | 1674134581274 webdriver::server DEBUG -> GET /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window
[task 2023-01-19T13:23:01.280Z] 13:23:01 INFO - PID 3282 | 1674134581280 webdriver::server DEBUG <- 200 OK {"value":"c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"}
[task 2023-01-19T13:23:01.281Z] 13:23:01 INFO - PID 3282 | 1674134581280 webdriver::server DEBUG -> GET /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window/handles
[task 2023-01-19T13:23:01.287Z] 13:23:01 INFO - PID 3282 | 1674134581286 webdriver::server DEBUG <- 200 OK {"value":["c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"]}
[task 2023-01-19T13:23:01.288Z] 13:23:01 INFO - PID 3282 | 1674134581287 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window {"handle": "c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"}
[task 2023-01-19T13:23:01.294Z] 13:23:01 INFO - PID 3282 | 1674134581293 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-01-19T13:23:01.294Z] 13:23:01 INFO - PID 3282 | 1674134581294 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/frame {"id": null}
[task 2023-01-19T13:23:01.312Z] 13:23:01 INFO - PID 3282 | 1674134581312 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2023-01-19T13:23:01.314Z] 13:23:01 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/get_page_source/source.py::test_no_browsing_context
[task 2023-01-19T13:23:01.314Z] 13:23:01 INFO - PID 3282 | 1674134581313 webdriver::server DEBUG -> GET /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window
[task 2023-01-19T13:23:01.320Z] 13:23:01 INFO - PID 3282 | 1674134581319 webdriver::server DEBUG <- 200 OK {"value":"c8f5c6a3-d2b1-4ab9-a1e0-4623eb568763"}
[task 2023-01-19T13:23:01.320Z] 13:23:01 INFO - PID 3282 | 1674134581319 webdriver::server DEBUG -> POST /session/eab640c3-3f06-4256-b97b-d177ef53e5d5/window/new {"type": "tab"}
[task 2023-01-19T13:24:42.169Z] 13:24:42 INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/get_page_source/source.py | expected OK[task 2023-01-19T13:24:42.169Z] 13:24:42 INFO - TEST-INFO took 105002ms
[task 2023-01-19T13:24:42.297Z] 13:24:42 INFO - Closing logging queue
[task 2023-01-19T13:24:42.298Z] 13:24:42 INFO - queue closed
[task 2023-01-19T13:24:42.369Z] 13:24:42 INFO - Starting runner
[task 2023-01-19T13:24:42.369Z] 13:24:42 INFO - PID 3339 | 1674134682367 geckodriver INFO Listening on 127.0.0.1:44853
[task 2023-01-19T13:24:42.560Z] 13:24:42 INFO - TEST-START | /webdriver/tests/get_page_source/user_prompts.py
|
||
Updated•2 years ago
|
|
||
Comment 2•2 years ago
|
||
Moving bug to Remote Protocol::Marionette component per bug 1815831.
Component: geckodriver → Marionette
Product: Testing → Remote Protocol
You need to log in
before you can comment on or make changes to this bug.
Description
•