1578080 - Intermittent Wd | <random> - AssertionError: unknown error (500): permission denied

Status: RESOLVED DUPLICATE of bug 1517538

(Remote Protocol :: Marionette, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=264453913&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/LEQwdGypRxOzNReCoa3szw/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-09-01T12:13:21.231Z] 12:13:21 INFO - TEST-START | /webdriver/tests/new_session/merge.py
[task 2019-09-01T12:13:21.286Z] 12:13:21 INFO - STDOUT: ============================= test session starts =============================
[task 2019-09-01T12:13:21.286Z] 12:13:21 INFO - STDOUT: platform win32 -- Python 2.7.11, pytest-unknown, py-1.5.2, pluggy-0.5.3.dev -- Z:\task_1567337240\build\venv\Scripts\python.exe
[task 2019-09-01T12:13:21.286Z] 12:13:21 INFO - STDOUT: rootdir: Z:\task_1567337240\build, inifile:
[task 2019-09-01T12:13:21.286Z] 12:13:21 INFO - STDOUT: collecting ...
[task 2019-09-01T12:13:21.301Z] 12:13:21 INFO - STDOUT: collected 9 items
[task 2019-09-01T12:13:21.301Z] 12:13:21 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/merge.py::test_platform_name[<lambda>0]
[task 2019-09-01T12:13:21.305Z] 12:13:21 INFO - PID 4044 | 1567340001295 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"platformName": "windows", "moz:firefoxOptions": {"binary": "Z:\task_1567337240\build\application\firefox\firefox.exe", "prefs": {"media.peerconnection.mtransport_process": false, "network.dns.localDomains": "www1.xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.www1.web-platform.test,www.www2.not-web-platform.test,www2.www2.not-web-platform.test,xn--lve-6lad.www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.not-web-platform.test,not-web-platform.test,www1.www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.web-platform.test,www2.not-web-platform.test,www1.not-web-platform.test,www1.www1.web-platform.test,www2.xn--lve-6lad.web-platform.test,www1.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www1.www2.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.not-web-platform.test,www2.www1.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.not-web-platform.test,xn--lve-6lad.www.web-platform.test,www.www.web-platform.test,www1.xn--lve-6lad.web-platform.test,xn--lve-6lad.www2.web-platform.test,www.www2.web-platform.test,www2.www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.not-web-platform.test,www1.www.web-platform.test,www.xn--lve-6lad.web-platform.test,www.www.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.web-platform.test,xn--lve-6lad.www1.not-web-platform.test,www1.www1.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.not-web-platform.test,www2.www2.web-platform.test,xn--lve-6lad.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.web-platform.test,xn--lve-6lad.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www2.xn--lve-6lad.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.web-platform.test,www1.xn--lve-6lad.not-web-platform.test,www1.web-platform.test,www.www1.web-platform.test,www2.web-platform.test,www.xn--lve-6lad.not-web-platform.test,www.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www1.www2.not-web-platform.test,www2.www1.web-platform.test,www.www1.not-web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.www2.not-web-platform.test,www2.www.not-web-platform.test", "network.process.enabled": false}}}}}
[task 2019-09-01T12:13:21.305Z] 12:13:21 INFO - PID 4044 | 1567340001299 mozrunner::runner INFO Running command: "Z:\task_1567337240\build\application\firefox\firefox.exe" "-marionette" "-foreground" "-no-remote" "-profile" "C:\Users\task_1567337240\AppData\Local\Temp\rust_mozprofilef2wv82"
[task 2019-09-01T12:13:21.320Z] 12:13:21 INFO - PID 4044 | 1567340001304 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1:2828
[task 2019-09-01T12:13:21.627Z] 12:13:21 INFO - PID 4044 | [5064, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/extensions/permissions/nsPermissionManager.cpp, line 2883
[task 2019-09-01T12:13:21.762Z] 12:13:21 INFO - PID 4044 | [5064, Main Thread] WARNING: Workers don't support the 'mem.mem.' preference!: file z:/build/build/src/dom/workers/RuntimeService.cpp, line 544
[task 2019-09-01T12:13:22.197Z] 12:13:22 INFO - PID 4044 | [5064, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/widget/windows/nsLookAndFeel.cpp, line 853
[task 2019-09-01T12:13:22.380Z] 12:13:22 INFO - PID 4044 | 1567340002376 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-09-01T12:13:22.381Z] 12:13:22 INFO - PID 4044 | 1567340002377 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: telemetry
[task 2019-09-01T12:13:22.381Z] 12:13:22 INFO - PID 4044 | 1567340002377 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-09-01T12:13:22.381Z] 12:13:22 INFO - PID 4044 | 1567340002378 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: about:reader*
[task 2019-09-01T12:13:22.656Z] 12:13:22 INFO - PID 4044 | [5064, GMPThread] WARNING: Failed to delete GMP storage directory: file z:/build/build/src/dom/media/gmp/GMPServiceParent.cpp, line 1571
[task 2019-09-01T12:13:22.679Z] 12:13:22 INFO - PID 4044 | 1567340002672 Marionette TRACE Received observer notification profile-after-change
[task 2019-09-01T12:13:22.694Z] 12:13:22 INFO - PID 4044 | [5064, Main Thread] WARNING: Failed to acquire a DXGI adapter for enumerating outputs.: file z:/build/build/src/gfx/thebes/DeviceManagerDx.cpp, line 146
[task 2019-09-01T12:13:22.694Z] 12:13:22 INFO - PID 4044 | [5064, Main Thread] WARNING: DWM not enabled, falling back to software vsync: file z:/build/build/src/gfx/thebes/gfxWindowsPlatform.cpp, line 1939
[task 2019-09-01T12:13:22.709Z] 12:13:22 INFO - PID 4044 | ++DOCSHELL 0EAE4C00 == 1 [pid = 5064] [id = {7cefcc1e-d8db-4a7e-924f-2d59684832c1}]
[task 2019-09-01T12:13:22.709Z] 12:13:22 INFO - PID 4044 | ++DOMWINDOW == 1 (0F7384C0) [pid = 5064] [serial = 1] [outer = 00000000]
[task 2019-09-01T12:13:22.709Z] 12:13:22 INFO - PID 4044 | ++DOMWINDOW == 2 (10523C00) [pid = 5064] [serial = 2] [outer = 0F7384C0]
[task 2019-09-01T12:13:22.724Z] 12:13:22 INFO - PID 4044 | 1567340002710 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-09-01T12:13:22.799Z] 12:13:22 INFO - PID 4044 | 1567340002784 Marionette TRACE Received observer notification command-line-startup
[task 2019-09-01T12:13:22.799Z] 12:13:22 INFO - PID 4044 | 1567340002784 Marionette TRACE Received observer notification nsPref:changed
[task 2019-09-01T12:13:22.799Z] 12:13:22 INFO - PID 4044 | 1567340002785 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false)
[task 2019-09-01T12:13:22.819Z] 12:13:22 INFO - PID 4044 | [5064, Main Thread] WARNING: 'mIndex >= Count()', file z:/build/build/src/xpcom/ds/nsStringEnumerator.cpp, line 202
[task 2019-09-01T12:13:22.994Z] 12:13:22 INFO - PID 4044 | ++DOCSHELL 1052F000 == 2 [pid = 5064] [id = {430bfbcc-c52f-4466-805d-a0300ed2522b}]
[task 2019-09-01T12:13:22.994Z] 12:13:22 INFO - PID 4044 | ++DOMWINDOW == 3 (11F145E0) [pid = 5064] [serial = 3] [outer = 00000000]
[task 2019-09-01T12:13:22.994Z] 12:13:22 INFO - PID 4044 | ++DOMWINDOW == 4 (10530800) [pid = 5064] [serial = 4] [outer = 11F145E0][task 2019-09-01T12:13:42.293Z] 12:13:42 INFO - PID 4044 | [Parent 4440, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file z:/build/build/src/xpcom/base/nsCycleCollector.cpp, line 3361
[task 2019-09-01T12:13:42.336Z] 12:13:42 INFO - PID 4044 | [Parent 4440, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file z:/build/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-09-01T12:13:42.337Z] 12:13:42 INFO - PID 4044 | [Parent 4440, Main Thread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file z:/build/build/src/xpcom/base/nsTraceRefcnt.cpp, line 198
[task 2019-09-01T12:13:42.337Z] 12:13:42 INFO - PID 4044 | nsStringStats
[task 2019-09-01T12:13:42.338Z] 12:13:42 INFO - PID 4044 | => mAllocCount: 85426
[task 2019-09-01T12:13:42.338Z] 12:13:42 INFO - PID 4044 | => mReallocCount: 0
[task 2019-09-01T12:13:42.339Z] 12:13:42 INFO - PID 4044 | => mFreeCount: 85426
[task 2019-09-01T12:13:42.340Z] 12:13:42 INFO - PID 4044 | => mShareCount: 79931
[task 2019-09-01T12:13:42.340Z] 12:13:42 INFO - PID 4044 | => mAdoptCount: 2254
[task 2019-09-01T12:13:42.340Z] 12:13:42 INFO - PID 4044 | => mAdoptFreeCount: 2302
[task 2019-09-01T12:13:42.340Z] 12:13:42 INFO - PID 4044 | => Process ID: 4440, Thread ID: 4636
[task 2019-09-01T12:13:42.375Z] 12:13:42 INFO - PID 4044 | 1567340022362 geckodriver::marionette DEBUG Browser process stopped: exit code: 0
[task 2019-09-01T12:13:42.478Z] 12:13:42 INFO - PID 4044 | 1567340022461 webdriver::server DEBUG <- 200 OK {"value":null}
[task 2019-09-01T12:13:42.478Z] 12:13:42 INFO - STDOUT: tests/web-platform/tests/webdriver/tests/new_session/merge.py::test_merge_browserName
[task 2019-09-01T12:13:42.481Z] 12:13:42 INFO - PID 4044 | 1567340022466 webdriver::server DEBUG -> POST /session {"capabilities": {"alwaysMatch": {"moz:firefoxOptions": {"binary": "Z:\task_1567337240\build\application\firefox\firefox.exe", "prefs": {"media.peerconnection.mtransport_process": false, "network.dns.localDomains": "www1.xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.www1.web-platform.test,www.www2.not-web-platform.test,www2.www2.not-web-platform.test,xn--lve-6lad.www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.not-web-platform.test,not-web-platform.test,www1.www.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.web-platform.test,www2.not-web-platform.test,www1.not-web-platform.test,www1.www1.web-platform.test,www2.xn--lve-6lad.web-platform.test,www1.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www1.www2.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.not-web-platform.test,www2.www1.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.not-web-platform.test,xn--lve-6lad.www.web-platform.test,www.www.web-platform.test,www1.xn--lve-6lad.web-platform.test,xn--lve-6lad.www2.web-platform.test,www.www2.web-platform.test,www2.www.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.not-web-platform.test,www1.www.web-platform.test,www.xn--lve-6lad.web-platform.test,www.www.not-web-platform.test,xn--lve-6lad.xn--lve-6lad.web-platform.test,xn--lve-6lad.www1.not-web-platform.test,www1.www1.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.www2.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www1.not-web-platform.test,www2.www2.web-platform.test,xn--lve-6lad.web-platform.test,xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--n8j6ds53lwwkrqhv28a.www.web-platform.test,xn--lve-6lad.not-web-platform.test,www2.xn--n8j6ds53lwwkrqhv28a.not-web-platform.test,www2.xn--lve-6lad.not-web-platform.test,xn--n8j6ds53lwwkrqhv28a.xn--lve-6lad.web-platform.test,www1.xn--lve-6lad.not-web-platform.test,www1.web-platform.test,www.www1.web-platform.test,www2.web-platform.test,www.xn--lve-6lad.not-web-platform.test,www.web-platform.test,xn--lve-6lad.xn--n8j6ds53lwwkrqhv28a.web-platform.test,www1.www2.not-web-platform.test,www2.www1.web-platform.test,www.www1.not-web-platform.test,www.xn--n8j6ds53lwwkrqhv28a.web-platform.test,xn--lve-6lad.www2.not-web-platform.test,www2.www.not-web-platform.test", "network.process.enabled": false}}}}}
[task 2019-09-01T12:13:42.481Z] 12:13:42 INFO - PID 4044 | 1567340022470 mozrunner::runner INFO Running command: "Z:\task_1567337240\build\application\firefox\firefox.exe" "-marionette" "-foreground" "-no-remote" "-profile" "C:\Users\task_1567337240\AppData\Local\Temp\rust_mozprofile50Qzyh"
[task 2019-09-01T12:13:42.481Z] 12:13:42 INFO - PID 4044 | 1567340022476 geckodriver::marionette DEBUG Waiting 60s to connect to browser on 127.0.0.1:2828
[task 2019-09-01T12:13:42.816Z] 12:13:42 INFO - PID 4044 | [4468, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/extensions/permissions/nsPermissionManager.cpp, line 2883
[task 2019-09-01T12:13:42.817Z] 12:13:42 INFO - PID 4044 | Hit MOZ_CRASH(Bad canary value 0x534dc0f5 in FromData) at z:/build/build/src/xpcom/string/nsSubstring.cpp:330
[task 2019-09-01T12:13:42.937Z] 12:13:42 INFO - PID 4044 | #01: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x4ffcb]
[task 2019-09-01T12:13:42.938Z] 12:13:42 INFO - PID 4044 | #02: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x5601c]
[task 2019-09-01T12:13:42.939Z] 12:13:42 INFO - PID 4044 | #03: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x4e325]
[task 2019-09-01T12:13:42.939Z] 12:13:42 INFO - PID 4044 | #04: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0xd4155]
[task 2019-09-01T12:13:42.940Z] 12:13:42 INFO - PID 4044 | #05: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x242520]
[task 2019-09-01T12:13:42.940Z] 12:13:42 INFO - PID 4044 | #06: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x23cce8]
[task 2019-09-01T12:13:42.941Z] 12:13:42 INFO - PID 4044 | #07: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x1a1bb6]
[task 2019-09-01T12:13:42.941Z] 12:13:42 INFO - PID 4044 | #08: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x1c0291]
[task 2019-09-01T12:13:42.942Z] 12:13:42 INFO - PID 4044 | #09: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x14eb5d]
[task 2019-09-01T12:13:42.942Z] 12:13:42 INFO - PID 4044 | #10: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x14941a]
[task 2019-09-01T12:13:42.943Z] 12:13:42 INFO - PID 4044 | #11: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x14c4d8]
[task 2019-09-01T12:13:42.943Z] 12:13:42 INFO - PID 4044 | #12: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x8198ba]
[task 2019-09-01T12:13:42.944Z] 12:13:42 INFO - PID 4044 | #13: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x7c7531]
[task 2019-09-01T12:13:42.944Z] 12:13:42 INFO - PID 4044 | #14: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x7c7453]
[task 2019-09-01T12:13:42.945Z] 12:13:42 INFO - PID 4044 | #15: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x7c7311]
[task 2019-09-01T12:13:42.945Z] 12:13:42 INFO - PID 4044 | #16: Ordinal0[Z:\task_1567337240\build\application\firefox\xul.dll +0x145d56]
[task 2019-09-01T12:13:42.946Z] 12:13:42 INFO - PID 4044 | #17: PRP_TryLock[Z:\task_1567337240\build\application\firefox\nss3.dll +0x13aa98]
[task 2019-09-01T12:13:42.946Z] 12:13:42 INFO - PID 4044 | #18: PR_MD_UNLOCK[Z:\task_1567337240\build\application\firefox\nss3.dll +0x12917d]
[task 2019-09-01T12:13:42.947Z] 12:13:42 INFO - PID 4044 | #19: o____lc_collate_cp_func[Z:\task_1567337240\build\application\firefox\ucrtbase.DLL +0x3e16f]
[task 2019-09-01T12:13:42.947Z] 12:13:42 INFO - PID 4044 | #20: BaseThreadInitThunk[C:\windows\system32\kernel32.dll +0x4ef3c]
[task 2019-09-01T12:13:42.948Z] 12:13:42 INFO - PID 4044 | #21: DllBlocklist_Initialize[Z:\task_1567337240\build\application\firefox\mozglue.dll +0x6c09][task 2019-09-01T12:13:42.948Z] 12:13:42 INFO - PID 4044 | #22: RtlInitializeExceptionChain[C:\windows\SYSTEM32\ntdll.dll +0x63618]
[task 2019-09-01T12:13:42.948Z] 12:13:42 INFO - PID 4044 | #23: RtlInitializeExceptionChain[C:\windows\SYSTEM32\ntdll.dll +0x635eb]
[task 2019-09-01T12:13:43.596Z] 12:13:43 INFO - PID 4044 | 1567340023575 mozrunner::runner DEBUG Killing process 524
[task 2019-09-01T12:13:43.597Z] 12:13:43 INFO - PID 4044 | 1567340023575 webdriver::server DEBUG <- 500 Internal Server Error {"value":{"error":"unknown error","message":"permission denied","stacktrace":""}}
[task 2019-09-01T12:13:43.598Z] 12:13:43 INFO - STDOUT: FAILED
[task 2019-09-01T12:13:43.598Z] 12:13:43 INFO - STDOUT: ================================== FAILURES ===================================
[task 2019-09-01T12:13:43.599Z] 12:13:43 INFO - STDOUT: ___________________________ test_merge_browserName ____________________________
[task 2019-09-01T12:13:43.599Z] 12:13:43 INFO - STDOUT: new_session = <function new_session at 0x04510AF0>
[task 2019-09-01T12:13:43.600Z] 12:13:43 INFO - STDOUT: add_browser_capabilities = <function add_browser_capabilities at 0x04510B70>
[task 2019-09-01T12:13:43.601Z] 12:13:43 INFO - STDOUT: def test_merge_browserName(new_session, add_browser_capabilities):
[task 2019-09-01T12:13:43.601Z] 12:13:43 INFO - STDOUT: response, session = new_session({"capabilities": {"alwaysMatch": add_browser_capabilities({})}})
[task 2019-09-01T12:13:43.602Z] 12:13:43 INFO - STDOUT: > value = assert_success(response)
[task 2019-09-01T12:13:43.602Z] 12:13:43 INFO - STDOUT: add_browser_capabilities = <function add_browser_capabilities at 0x04510B70>
[task 2019-09-01T12:13:43.603Z] 12:13:43 INFO - STDOUT: new_session = <function new_session at 0x04510AF0>
[task 2019-09-01T12:13:43.604Z] 12:13:43 INFO - STDOUT: response = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2019-09-01T12:13:43.604Z] 12:13:43 INFO - STDOUT: session = None
[task 2019-09-01T12:13:43.605Z] 12:13:43 INFO - STDOUT: tests\web-platform\tests\webdriver\tests\new_session\merge.py
[task 2019-09-01T12:13:43.605Z] 12:13:43 INFO - STDOUT: :63:
[task 2019-09-01T12:13:43.606Z] 12:13:43 INFO - STDOUT: _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
[task 2019-09-01T12:13:43.606Z] 12:13:43 INFO - STDOUT: response = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2019-09-01T12:13:43.606Z] 12:13:43 INFO - STDOUT: value = None
[task 2019-09-01T12:13:43.607Z] 12:13:43 INFO - STDOUT: def assert_success(response, value=None):
[task 2019-09-01T12:13:43.607Z] 12:13:43 INFO - STDOUT: """
[task 2019-09-01T12:13:43.608Z] 12:13:43 INFO - STDOUT: Verify that the provided webdriver.Response instance described
[task 2019-09-01T12:13:43.608Z] 12:13:43 INFO - STDOUT: a valid success response as defined by dfn-send-a-response and
[task 2019-09-01T12:13:43.608Z] 12:13:43 INFO - STDOUT: the provided response value.
[task 2019-09-01T12:13:43.609Z] 12:13:43 INFO - STDOUT:
[task 2019-09-01T12:13:43.609Z] 12:13:43 INFO - STDOUT: :param response: webdriver.Response instance.
[task 2019-09-01T12:13:43.610Z] 12:13:43 INFO - STDOUT: :param value: Expected value of the response body, if any.
[task 2019-09-01T12:13:43.610Z] 12:13:43 INFO - STDOUT: """
[task 2019-09-01T12:13:43.611Z] 12:13:43 INFO - STDOUT: > assert response.status == 200, str(response.error)
[task 2019-09-01T12:13:43.611Z] 12:13:43 INFO - STDOUT: E AssertionError: unknown error (500): permission denied
[task 2019-09-01T12:13:43.611Z] 12:13:43 INFO - STDOUT: E
[task 2019-09-01T12:13:43.612Z] 12:13:43 INFO - STDOUT: E assert 500 == 200
[task 2019-09-01T12:13:43.612Z] 12:13:43 INFO - STDOUT: E + where 500 = <Response status=500 error=<UnknownErrorException http_status=500>>.status
[task 2019-09-01T12:13:43.613Z] 12:13:43 INFO - STDOUT: response = <Response status=500 error=<UnknownErrorException http_status=500>>
[task 2019-09-01T12:13:43.613Z] 12:13:43 INFO - STDOUT: value = None
[task 2019-09-01T12:13:43.613Z] 12:13:43 INFO - STDOUT: tests\web-platform\tests\webdriver\tests\support\asserts.py
[task 2019-09-01T12:13:43.613Z] 12:13:43 INFO - STDOUT: :67: AssertionError[task 2019-09-01T12:13:43.613Z] 12:13:43 INFO - STDOUT: ============================== warnings summary ===============================
[task 2019-09-01T12:13:43.613Z] 12:13:43 INFO - STDOUT: <undetermined location>
[task 2019-09-01T12:13:43.614Z] 12:13:43 INFO - STDOUT: Module already imported so cannot be rewritten: mozlog
[task 2019-09-01T12:13:43.614Z] 12:13:43 INFO - STDOUT: Module already imported so cannot be rewritten: tests.support.fixtures
[task 2019-09-01T12:13:43.614Z] 12:13:43 INFO - STDOUT: -- Docs: http://doc.pytest.org/en/latest/warnings.html
[task 2019-09-01T12:13:43.614Z] 12:13:43 INFO - STDOUT: =============== 1 failed, 8 passed, 2 warnings in 22.33 seconds ===============
[task 2019-09-01T12:13:43.658Z] 12:13:43 INFO -
[task 2019-09-01T12:13:43.658Z] 12:13:43 INFO - TEST-PASS | /webdriver/tests/new_session/merge.py | test_platform_name[<lambda>0]
[task 2019-09-01T12:13:43.658Z] 12:13:43 INFO - TEST-PASS | /webdriver/tests/new_session/merge.py | test_platform_name[<lambda>1]
[task 2019-09-01T12:13:43.658Z] 12:13:43 INFO - TEST-PASS | /webdriver/tests/new_session/merge.py | test_merge_invalid[acceptInsecureCerts-value0]
[task 2019-09-01T12:13:43.658Z] 12:13:43 INFO - TEST-PASS | /webdriver/tests/new_session/merge.py | test_merge_invalid[unhandledPromptBehavior-value1]
[task 2019-09-01T12:13:43.658Z] 12:13:43 INFO - TEST-PASS | /webdriver/tests/new_session/merge.py | test_merge_invalid[unhandledPromptBehavior-value2]
[task 2019-09-01T12:13:43.658Z] 12:13:43 INFO - TEST-PASS | /webdriver/tests/new_session/merge.py | test_merge_invalid[timeouts-value3]
[task 2019-09-01T12:13:43.658Z] 12:13:43 INFO - TEST-PASS | /webdriver/tests/new_session/merge.py | test_merge_invalid[timeouts-value4]
[task 2019-09-01T12:13:43.658Z] 12:13:43 INFO - TEST-PASS | /webdriver/tests/new_session/merge.py | test_merge_platformName
[task 2019-09-01T12:13:43.658Z] 12:13:43 INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/new_session/merge.py | test_merge_browserName - AssertionError: unknown error (500): permission denied

Comment 1

[Intermittent Failures Robot]

1 failures in 5047 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1578080&startday=2019-08-26&endday=2019-09-01&tree=all

Comment 2

[Henrik Skupin [:whimboo][⌚️UTC+1]]

This happened due to:

[task 2019-09-01T12:13:42.817Z] 12:13:42 INFO - PID 4044 | Hit MOZ_CRASH(Bad canary value 0x534dc0f5 in FromData) at z:/build/build/src/xpcom/string/nsSubstring.cpp:330

Comment 4

[BMO Automation]

Moving bug to Remote Protocol::Marionette component per bug 1815831.