https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=284350984&repo=autoland&lineNumber=4437
https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Bio915r_R6eZsBi6D9mCJQ/runs/0/artifacts/public/logs/live_backing.log
[task 2020-01-10T09:45:57.724Z] 09:45:57 INFO - REFTEST TEST-END | layout/reftests/backgrounds/vector/no-aspect-ratio-wide.html == layout/reftests/backgrounds/vector/no-aspect-ratio-normal.html
[task 2020-01-10T09:45:57.756Z] 09:45:57 INFO - REFTEST INFO | Slowest test took 242ms (file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/backgrounds/vector/tall--32px-auto--nonpercent-width-nonpercent-height.html)
[task 2020-01-10T09:45:57.757Z] 09:45:57 INFO - REFTEST INFO | Total canvas count = 5
[task 2020-01-10T09:45:58.057Z] 09:45:58 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-10T09:45:58.601Z] 09:45:58 INFO - 1578649558589 Marionette TRACE Received observer notification xpcom-will-shutdown
[task 2020-01-10T09:45:58.602Z] 09:45:58 INFO - 1578649558589 Marionette INFO Stopped listening on port 2828
[task 2020-01-10T09:45:58.603Z] 09:45:58 INFO - 1578649558589 Marionette DEBUG Marionette stopped listening
[task 2020-01-10T09:45:58.605Z] 09:45:58 INFO - ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-10T09:45:59.834Z] 09:45:59 INFO - REFTEST INFO | Process mode: e10s
[task 2020-01-10T09:45:59.836Z] 09:45:59 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2020-01-10T09:45:59.836Z] 09:45:59 INFO - REFTEST INFO | Running tests in file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/w3c-css/submitted/text-decor-3/reftest.list
[task 2020-01-10T09:45:59.864Z] 09:45:59 INFO - REFTEST INFO | INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2020-01-10T09:45:59.869Z] 09:45:59 INFO - REFTEST INFO | INFO | runtests.py | ASan running in default memory configuration
[task 2020-01-10T09:45:59.870Z] 09:45:59 INFO - REFTEST INFO | UBSan enabled.
[task 2020-01-10T09:45:59.874Z] 09:45:59 INFO - REFTEST INFO | Running with e10s: True
[task 2020-01-10T09:45:59.875Z] 09:45:59 INFO - REFTEST INFO | Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -profile /tmp/tmpN0QNSw.mozrunner
[task 2020-01-10T09:46:00.871Z] 09:46:00 INFO - 1578649560866 addons.webextension.doh-rollout@mozilla.org WARN Loading extension 'doh-rollout@mozilla.org': Reading manifest: Invalid extension permission: networkStatus
[task 2020-01-10T09:46:00.956Z] 09:46:00 INFO - 1578649560952 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
[task 2020-01-10T09:46:00.960Z] 09:46:00 INFO - 1578649560952 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
[task 2020-01-10T09:46:00.964Z] 09:46:00 INFO - 1578649560953 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2020-01-10T09:46:00.967Z] 09:46:00 INFO - 1578649560953 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
[task 2020-01-10T09:46:01.233Z] 09:46:01 INFO - 1578649561231 Marionette TRACE Received observer notification profile-after-change
[task 2020-01-10T09:46:01.294Z] 09:46:01 INFO - AddressSanitizer:DEADLYSIGNAL
[task 2020-01-10T09:46:01.295Z] 09:46:01 INFO - =================================================================
[task 2020-01-10T09:46:01.295Z] 09:46:01 ERROR - ==3472==ERROR: AddressSanitizer: SEGV on unknown address 0x000000000000 (pc 0x7fe1e5df6785 bp 0x616000010b80 sp 0x7fe1c1b9c760 T4)
[task 2020-01-10T09:46:01.295Z] 09:46:01 INFO - ==3472==The signal is caused by a READ memory access.
[task 2020-01-10T09:46:01.295Z] 09:46:01 INFO - ==3472==Hint: address points to the zero page.
[task 2020-01-10T09:46:01.552Z] 09:46:01 INFO - #0 0x7fe1e5df6784 in getenv /build/glibc-OTsEL5/glibc-2.27/stdlib/getenv.c:75
[task 2020-01-10T09:46:01.561Z] 09:46:01 INFO - #1 0x7fe1e5ddfdf8 in __gconv_load_cache /build/glibc-OTsEL5/glibc-2.27/iconv/gconv_cache.c:56
[task 2020-01-10T09:46:01.561Z] 09:46:01 INFO - #2 0x7fe1e5dd8724 in __gconv_read_conf /build/glibc-OTsEL5/glibc-2.27/iconv/gconv_conf.c:543
[task 2020-01-10T09:46:01.561Z] 09:46:01 INFO - #3 0x7fe1e6eff826 in __pthread_once_slow (/lib/x86_64-linux-gnu/libpthread.so.0+0xf826)
[task 2020-01-10T09:46:01.561Z] 09:46:01 INFO - #4 0x7fe1e5dd7a9e in __gconv_find_transform /build/glibc-OTsEL5/glibc-2.27/iconv/gconv_db.c:732
[task 2020-01-10T09:46:01.562Z] 09:46:01 INFO - #5 0x7fe1e5e7ff8a in __wcsmbs_getfct /build/glibc-OTsEL5/glibc-2.27/wcsmbs/wcsmbsload.c:91
[task 2020-01-10T09:46:01.562Z] 09:46:01 INFO - #6 0x7fe1e5e7ff8a in __wcsmbs_load_conv /build/glibc-OTsEL5/glibc-2.27/wcsmbs/wcsmbsload.c:185
[task 2020-01-10T09:46:01.563Z] 09:46:01 INFO - #7 0x7fe1e5e801bb in get_gconv_fcts /build/glibc-OTsEL5/glibc-2.27/wcsmbs/./wcsmbsload.h:74
[task 2020-01-10T09:46:01.563Z] 09:46:01 INFO - #8 0x7fe1e5e801bb in __wcsmbs_clone_conv /build/glibc-OTsEL5/glibc-2.27/wcsmbs/wcsmbsload.c:221
[task 2020-01-10T09:46:01.563Z] 09:46:01 INFO - #9 0x7fe1e5e3b017 in _IO_fwide /build/glibc-OTsEL5/glibc-2.27/libio/iofwide.c:118
[task 2020-01-10T09:46:01.563Z] 09:46:01 INFO - #10 0x7fe1e5e36e80 in vswprintf /build/glibc-OTsEL5/glibc-2.27/libio/vswprintf.c:108
[task 2020-01-10T09:46:01.843Z] 09:46:01 INFO - #11 0x7fe1c895034e in vswprintf /builds/worker/workspace/build/src/ipc/chromium/src/base/string_util_posix.h:39:10
[task 2020-01-10T09:46:01.843Z] 09:46:01 INFO - #12 0x7fe1c895034e in vsnprintfT /builds/worker/workspace/build/src/ipc/chromium/src/base/string_util.cc:341:10
[task 2020-01-10T09:46:01.843Z] 09:46:01 INFO - #13 0x7fe1c895034e in StringAppendVT<std::basic_string<wchar_t, std::char_traits<wchar_t>, std::allocator<wchar_t> > > /builds/worker/workspace/build/src/ipc/chromium/src/base/string_util.cc:361:16
[task 2020-01-10T09:46:01.843Z] 09:46:01 INFO - #14 0x7fe1c895034e in StringAppendV(std::basic_string<wchar_t, std::char_traits<wchar_t>, std::allocator<wchar_t> >, wchar_t const, __va_list_tag*) /builds/worker/workspace/build/src/ipc/chromium/src/base/string_util.cc:534:3
[task 2020-01-10T09:46:01.843Z] 09:46:01 INFO - #15 0x7fe1c8950154 in StringPrintf(wchar_t const*, ...) /builds/worker/workspace/build/src/ipc/chromium/src/base/string_util.cc:550:3
[task 2020-01-10T09:46:01.843Z] 09:46:01 INFO - #16 0x7fe1c895451c in GenerateUniqueRandomChannelID /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel.cc:36:10
[task 2020-01-10T09:46:01.844Z] 09:46:01 INFO - #17 0x7fe1c895451c in IPC::Channel::GenerateVerifiedChannelID(std::basic_string<wchar_t, std::char_traits<wchar_t>, std::allocator<wchar_t> > const&) /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc:984:20
[task 2020-01-10T09:46:01.844Z] 09:46:01 INFO - #18 0x7fe1c8954102 in ChildProcessHost::CreateChannel() /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/child_process_host.cc:29:17
[task 2020-01-10T09:46:01.860Z] 09:46:01 INFO - #19 0x7fe1c89b1c44 in mozilla::ipc::GeckoChildProcessHost::InitializeChannel() /builds/worker/workspace/build/src/ipc/glue/GeckoChildProcessHost.cpp:758:3
[task 2020-01-10T09:46:01.860Z] 09:46:01 INFO - #20 0x7fe1c89b1184 in mozilla::ipc::BaseProcessLauncher::Launch(mozilla::ipc::GeckoChildProcessHost*) /builds/worker/workspace/build/src/ipc/glue/GeckoChildProcessHost.cpp:1738:10
[task 2020-01-10T09:46:01.860Z] 09:46:01 INFO - #21 0x7fe1c89ce415 in applyImpl<mozilla::ipc::BaseProcessLauncher, RefPtr<mozilla::MozPromise<mozilla::ipc::LaunchResults, mozilla::ipc::LaunchError, false> > (mozilla::ipc::BaseProcessLauncher::)(mozilla::ipc::GeckoChildProcessHost ), StorePtrPassByPtr<mozilla::ipc::GeckoChildProcessHost> , 0> /builds/worker/workspace/build/src/obj-firefox/dist/include/nsThreadUtils.h:1164:12
[task 2020-01-10T09:46:01.860Z] 09:46:01 INFO - #22 0x7fe1c89ce415 in apply<mozilla::ipc::BaseProcessLauncher, RefPtr<mozilla::MozPromise<mozilla::ipc::LaunchResults, mozilla::ipc::LaunchError, false> > (mozilla::ipc::BaseProcessLauncher::)(mozilla::ipc::GeckoChildProcessHost )> /builds/worker/workspace/build/src/obj-firefox/dist/include/nsThreadUtils.h:1170:12
[task 2020-01-10T09:46:01.860Z] 09:46:01 INFO - #23 0x7fe1c89ce415 in mozilla::detail::MethodCall<mozilla::MozPromise<mozilla::ipc::LaunchResults, mozilla::ipc::LaunchError, false>, RefPtr<mozilla::MozPromise<mozilla::ipc::LaunchResults, mozilla::ipc::LaunchError, false> > (mozilla::ipc::BaseProcessLauncher::)(mozilla::ipc::GeckoChildProcessHost), mozilla::ipc::BaseProcessLauncher, mozilla::ipc::GeckoChildProcessHost*>::Invoke() /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/MozPromise.h:1324:47
[task 2020-01-10T09:46:01.861Z] 09:46:01 INFO - #24 0x7fe1c89cdf66 in mozilla::detail::ProxyRunnable<mozilla::MozPromise<mozilla::ipc::LaunchResults, mozilla::ipc::LaunchError, false>, RefPtr<mozilla::MozPromise<mozilla::ipc::LaunchResults, mozilla::ipc::LaunchError, false> > (mozilla::ipc::BaseProcessLauncher::)(mozilla::ipc::GeckoChildProcessHost), mozilla::ipc::BaseProcessLauncher, mozilla::ipc::GeckoChildProcessHost*>::Run() /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/MozPromise.h:1344:42
[task 2020-01-10T09:46:01.862Z] 09:46:01 INFO - #25 0x7fe1c8933082 in MessageLoop::RunTask(already_AddRefed<nsIRunnable>) /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:442:9
[task 2020-01-10T09:46:01.863Z] 09:46:01 INFO - #26 0x7fe1c8933e64 in MessageLoop::DeferOrRunPendingTask(MessageLoop::PendingTask&&) /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:450:5
[task 2020-01-10T09:46:01.863Z] 09:46:01 INFO - #27 0x7fe1c89346bb in MessageLoop::DoWork() /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:523:13
[task 2020-01-10T09:46:01.864Z] 09:46:01 INFO - #28 0x7fe1c893743d in base::MessagePumpLibevent::Run(base::MessagePump::Delegate*) /builds/worker/workspace/build/src/ipc/chromium/src/base/message_pump_libevent.cc:321:31
[task 2020-01-10T09:46:01.865Z] 09:46:01 INFO - #29 0x7fe1c8932c67 in RunInternal /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:315:10
[task 2020-01-10T09:46:01.865Z] 09:46:01 INFO - #30 0x7fe1c8932c67 in RunHandler /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:308:3
[task 2020-01-10T09:46:01.867Z] 09:46:01 INFO - #31 0x7fe1c8932c67 in MessageLoop::Run() /builds/worker/workspace/build/src/ipc/chromium/src/base/message_loop.cc:290:3
[task 2020-01-10T09:46:01.867Z] 09:46:01 INFO - #32 0x7fe1c8951dae in base::Thread::ThreadMain() /builds/worker/workspace/build/src/ipc/chromium/src/base/thread.cc:192:16
[task 2020-01-10T09:46:01.867Z] 09:46:01 INFO - #33 0x7fe1c8943d4c in ThreadFunc(void*) /builds/worker/workspace/build/src/ipc/chromium/src/base/platform_thread_posix.cc:40:13
[task 2020-01-10T09:46:01.868Z] 09:46:01 INFO - #34 0x7fe1e6ef76da in start_thread (/lib/x86_64-linux-gnu/libpthread.so.0+0x76da)
[task 2020-01-10T09:46:01.869Z] 09:46:01 INFO - #35 0x7fe1e5ed588e in clone /build/glibc-OTsEL5/glibc-2.27/misc/../sysdeps/unix/sysv/linux/x86_64/clone.S:95
[task 2020-01-10T09:46:01.869Z] 09:46:01 INFO - AddressSanitizer can not provide additional info.
[task 2020-01-10T09:46:01.870Z] 09:46:01 INFO - SUMMARY: AddressSanitizer: SEGV /build/glibc-OTsEL5/glibc-2.27/stdlib/getenv.c:75 in getenv
[task 2020-01-10T09:46:01.870Z] 09:46:01 INFO - Thread T4 (Gecko_IOThread) created by T0 here:
[task 2020-01-10T09:46:01.877Z] 09:46:01 INFO - #0 0x55a3765cf32a in pthread_create /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/asan/asan_interceptors.cc:209:3
[task 2020-01-10T09:46:01.877Z] 09:46:01 INFO - #1 0x7fe1c893ed7c in CreateThread /builds/worker/workspace/build/src/ipc/chromium/src/base/platform_thread_posix.cc:123:14
[task 2020-01-10T09:46:01.877Z] 09:46:01 INFO - #2 0x7fe1c893ed7c in PlatformThread::Create(unsigned long, PlatformThread::Delegate*, unsigned long*) /builds/worker/workspace/build/src/ipc/chromium/src/base/platform_thread_posix.cc:134:10
[task 2020-01-10T09:46:01.878Z] 09:46:01 INFO - #3 0x7fe1c895158d in base::Thread::StartWithOptions(base::Thread::Options const&) /builds/worker/workspace/build/src/ipc/chromium/src/base/thread.cc:97:8
[task 2020-01-10T09:46:01.878Z] 09:46:01 INFO - #4 0x7fe1c7a4fe6b in NS_InitXPCOM /builds/worker/workspace/build/src/xpcom/build/XPCOMInit.cpp:315:9
[task 2020-01-10T09:46:01.882Z] 09:46:01 INFO - #5 0x7fe1d1fc5903 in ScopedXPCOMStartup::Initialize(bool) /builds/worker/workspace/build/src/toolkit/xre/nsAppRunner.cpp:1258:8
[task 2020-01-10T09:46:01.882Z] 09:46:01 INFO - #6 0x7fe1d1fdd4b9 in XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/workspace/build/src/toolkit/xre/nsAppRunner.cpp:4727:22
[task 2020-01-10T09:46:01.882Z] 09:46:01 INFO - #7 0x7fe1d1fdea13 in XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/workspace/build/src/toolkit/xre/nsAppRunner.cpp:4812:21
[task 2020-01-10T09:46:01.882Z] 09:46:01 INFO - #8 0x55a376617605 in do_main /builds/worker/workspace/build/src/browser/app/nsBrowserApp.cpp:217:22
[task 2020-01-10T09:46:01.882Z] 09:46:01 INFO - #9 0x55a376617605 in main /builds/worker/workspace/build/src/browser/app/nsBrowserApp.cpp:339:16
[task 2020-01-10T09:46:01.882Z] 09:46:01 INFO - #10 0x7fe1e5dd5b96 in __libc_start_main /build/glibc-OTsEL5/glibc-2.27/csu/../csu/libc-start.c:310
[task 2020-01-10T09:46:01.883Z] 09:46:01 INFO - ==3472==ABORTING
[task 2020-01-10T09:48:59.907Z] 09:48:59 ERROR - TEST-UNEXPECTED-FAIL | Last test finished | application terminated with exit code 1
[task 2020-01-10T09:48:59.908Z] 09:48:59 INFO - Traceback (most recent call last):
[task 2020-01-10T09:48:59.908Z] 09:48:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1023, in <module>
[task 2020-01-10T09:48:59.908Z] 09:48:59 INFO - sys.exit(run_test_harness(parser, options))
[task 2020-01-10T09:48:59.908Z] 09:48:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1015, in run_test_harness
[task 2020-01-10T09:48:59.909Z] 09:48:59 INFO - result = reftest.runTests(options.tests, options)
[task 2020-01-10T09:48:59.909Z] 09:48:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 613, in runTests
[task 2020-01-10T09:48:59.909Z] 09:48:59 INFO - return self.runSerialTests(manifests, options, cmdargs)
[task 2020-01-10T09:48:59.909Z] 09:48:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 963, in runSerialTests
[task 2020-01-10T09:48:59.910Z] 09:48:59 INFO - status = run(tests=tests)
[task 2020-01-10T09:48:59.910Z] 09:48:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 940, in run
[task 2020-01-10T09:48:59.911Z] 09:48:59 INFO - **kwargs)
[task 2020-01-10T09:48:59.911Z] 09:48:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 876, in runApp
[task 2020-01-10T09:48:59.911Z] 09:48:59 INFO - raise reraise(exc, value, tb)
[task 2020-01-10T09:48:59.912Z] 09:48:59 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 839, in runApp
[task 2020-01-10T09:48:59.912Z] 09:48:59 INFO - marionette.start_session()
[task 2020-01-10T09:48:59.913Z] 09:48:59 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 36, in _
[task 2020-01-10T09:48:59.913Z] 09:48:59 INFO - m._handle_socket_failure()
[task 2020-01-10T09:48:59.913Z] 09:48:59 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 650, in _handle_socket_failure
[task 2020-01-10T09:48:59.913Z] 09:48:59 INFO - reraise(exc, val, tb)
[task 2020-01-10T09:48:59.914Z] 09:48:59 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2020-01-10T09:48:59.914Z] 09:48:59 INFO - return func(*args, **kwargs)
[task 2020-01-10T09:48:59.915Z] 09:48:59 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1108, in start_session
[task 2020-01-10T09:48:59.915Z] 09:48:59 INFO - self.raise_for_port(timeout=timeout)
[task 2020-01-10T09:48:59.915Z] 09:48:59 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 569, in raise_for_port
[task 2020-01-10T09:48:59.916Z] 09:48:59 INFO - self.host, self.port))
[task 2020-01-10T09:48:59.916Z] 09:48:59 INFO - socket.timeout: Timed out waiting for connection on 127.0.0.1:2828!
[task 2020-01-10T09:48:59.997Z] 09:48:59 ERROR - Return code: 1
Description
•