builder: mozilla-esr52_ubuntu32_vm_test_pgo-web-platform-tests-e10s-5 slave: tst-linux32-spot-021 starttime: 1518760364.48 results: failure (2) buildid: 20180215195134 builduid: 12af308deeac4897bef46fc5d8a1aa52 revision: 457ddb94a90cb2e190f36c90fc05775baf8b0c5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:44.481482) ========= master: http://buildbot-master01.bb.releng.use1.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:44.481944) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:44.483983) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:44.887471) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:44.887758) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056 _=/tools/buildbot/bin/python using PTY: False --2018-02-15 21:52:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 3.87M=0.003s 2018-02-15 21:52:45 (3.87 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.676013 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:45.581114) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:45.581456) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056 _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.064819 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:45.690701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2018-02-15 21:52:45.691072) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-esr52 --rev 457ddb94a90cb2e190f36c90fc05775baf8b0c5a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-esr52 --rev 457ddb94a90cb2e190f36c90fc05775baf8b0c5a --destination scripts --debug'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056 _=/tools/buildbot/bin/python using PTY: False 2018-02-15 21:52:45,923 truncating revision to first 12 chars 2018-02-15 21:52:45,924 Setting DEBUG logging. 2018-02-15 21:52:45,924 attempt 1/10 2018-02-15 21:52:45,925 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-esr52/457ddb94a90c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2018-02-15 21:52:47,225 unpacking tar archive at: mozilla-esr52-457ddb94a90c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.844340 ========= master_lag: 0.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2018-02-15 21:52:48.711302) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:48.711616) ========= script_repo_revision: 457ddb94a90cb2e190f36c90fc05775baf8b0c5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:48.712033) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:48.712329) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2018-02-15 21:52:48.724999) ========= ========= Started '/tools/buildbot/bin/python -u ...' failed (results: 2, elapsed: 2 hrs, 0 secs) (at 2018-02-15 21:52:48.725301) ========= /tools/buildbot/bin/python -u scripts/scripts/web_platform_tests.py --cfg web_platform_tests/prod_config.py --test-type=testharness --e10s --total-chunks 5 --this-chunk 5 --blob-upload-branch mozilla-esr52 --download-symbols ondemand in dir /builds/slave/test/. (timeout 1800 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/web_platform_tests.py', '--cfg', 'web_platform_tests/prod_config.py', '--test-type=testharness', '--e10s', '--total-chunks', '5', '--this-chunk', '5', '--blob-upload-branch', 'mozilla-esr52', '--download-symbols', 'ondemand'] environment: CCACHE_DIR=/builds/ccache CCACHE_UMASK=002 DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_HIDE_RESULTS_TABLE=1 MOZ_NODE_PATH=/usr/bin/node MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_FAIL_ON_TEST_ERRORS=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056 _=/tools/buildbot/bin/python using PTY: False 21:52:49 INFO - MultiFileLogger online at 20180215 21:52:49 in /builds/slave/test 21:52:49 INFO - Run as scripts/scripts/web_platform_tests.py --cfg web_platform_tests/prod_config.py --test-type=testharness --e10s --total-chunks 5 --this-chunk 5 --blob-upload-branch mozilla-esr52 --download-symbols ondemand 21:52:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:52:49 INFO - {'allow_software_gl_layers': False, 21:52:49 INFO - 'append_to_log': False, 21:52:49 INFO - 'base_work_dir': '/builds/slave/test', 21:52:49 INFO - 'blob_upload_branch': 'mozilla-esr52', 21:52:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:52:49 INFO - 'buildbot_json_path': 'buildprops.json', 21:52:49 INFO - 'config_files': ('web_platform_tests/prod_config.py',), 21:52:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:52:49 INFO - 'download_minidump_stackwalk': True, 21:52:49 INFO - 'download_symbols': 'ondemand', 21:52:49 INFO - 'download_tooltool': True, 21:52:49 INFO - 'e10s': True, 21:52:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:52:49 INFO - 'tooltool.py': '/tools/tooltool.py', 21:52:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:52:49 INFO - '/tools/misc-python/virtualenv.py')}, 21:52:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:52:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:52:49 INFO - 'log_level': 'info', 21:52:49 INFO - 'log_to_console': True, 21:52:49 INFO - 'opt_config_files': (), 21:52:49 INFO - 'options': ('--prefs-root=%(test_path)s/prefs', 21:52:49 INFO - '--processes=1', 21:52:49 INFO - '--config=%(test_path)s/wptrunner.ini', 21:52:49 INFO - '--ca-cert-path=%(test_path)s/certs/cacert.pem', 21:52:49 INFO - '--host-key-path=%(test_path)s/certs/web-platform.test.key', 21:52:49 INFO - '--host-cert-path=%(test_path)s/certs/web-platform.test.pem', 21:52:49 INFO - '--certutil-binary=%(test_install_path)s/bin/certutil'), 21:52:49 INFO - 'pip_index': False, 21:52:49 INFO - 'require_test_zip': True, 21:52:49 INFO - 'test_type': ('testharness',), 21:52:49 INFO - 'this_chunk': '5', 21:52:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:52:49 INFO - 'total_chunks': '5', 21:52:49 INFO - 'virtualenv_path': 'venv', 21:52:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:52:49 INFO - 'work_dir': 'build'} 21:52:49 INFO - [mozharness: 2018-02-16 05:52:49.187910Z] Running clobber step. 21:52:49 INFO - Running pre-action listener: _resource_record_pre_action 21:52:49 INFO - Running main action method: clobber 21:52:49 INFO - rmtree: /builds/slave/test/build 21:52:49 INFO - Running post-action listener: _resource_record_post_action 21:52:49 INFO - [mozharness: 2018-02-16 05:52:49.223986Z] Finished clobber step (success) 21:52:49 INFO - [mozharness: 2018-02-16 05:52:49.224703Z] Running read-buildbot-config step. 21:52:49 INFO - Running pre-action listener: _resource_record_pre_action 21:52:49 INFO - Running main action method: read_buildbot_config 21:52:49 INFO - Using buildbot properties: 21:52:49 INFO - { 21:52:49 INFO - "project": "", 21:52:49 INFO - "product": "firefox", 21:52:49 INFO - "script_repo_revision": "production", 21:52:49 INFO - "scheduler": "tests-mozilla-esr52-ubuntu32_vm-pgo-unittest", 21:52:49 INFO - "repository": "", 21:52:49 INFO - "buildername": "Ubuntu VM 12.04 mozilla-esr52 pgo test web-platform-tests-e10s-5", 21:52:49 INFO - "buildid": "20180215195134", 21:52:49 INFO - "pgo_build": "True", 21:52:49 INFO - "basedir": "/builds/slave/test", 21:52:49 INFO - "buildnumber": 13, 21:52:49 INFO - "slavename": "tst-linux32-spot-021", 21:52:49 INFO - "revision": "457ddb94a90cb2e190f36c90fc05775baf8b0c5a", 21:52:49 INFO - "master": "http://buildbot-master01.bb.releng.use1.mozilla.com:8201/", 21:52:49 INFO - "platform": "linux", 21:52:49 INFO - "branch": "mozilla-esr52", 21:52:49 INFO - "repo_path": "releases/mozilla-esr52", 21:52:49 INFO - "moz_repo_path": "", 21:52:49 INFO - "stage_platform": "linux-pgo", 21:52:49 INFO - "builduid": "12af308deeac4897bef46fc5d8a1aa52", 21:52:49 INFO - "slavebuilddir": "test" 21:52:49 INFO - } 21:52:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.tar.bz2. 21:52:49 INFO - Found a test packages url https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.test_packages.json. 21:52:49 INFO - Running post-action listener: _resource_record_post_action 21:52:49 INFO - [mozharness: 2018-02-16 05:52:49.243229Z] Finished read-buildbot-config step (success) 21:52:49 INFO - [mozharness: 2018-02-16 05:52:49.243781Z] Running download-and-extract step. 21:52:49 INFO - Running pre-action listener: _resource_record_pre_action 21:52:49 INFO - Running main action method: download_and_extract 21:52:49 INFO - mkdir: /builds/slave/test/build/tests 21:52:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:52:49 INFO - trying https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.test_packages.json 21:52:49 INFO - Downloading https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.test_packages.json to /builds/slave/test/build/firefox-52.6.1.en-US.linux-i686.test_packages.json 21:52:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.6.1.en-US.linux-i686.test_packages.json'}, attempt #1 21:52:50 INFO - Downloaded 1325 bytes. 21:52:50 INFO - Reading from file /builds/slave/test/build/firefox-52.6.1.en-US.linux-i686.test_packages.json 21:52:50 INFO - Using the following test package requirements: 21:52:50 INFO - {u'common': [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip'], 21:52:50 INFO - u'cppunittest': [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip', 21:52:50 INFO - u'firefox-52.6.1.en-US.linux-i686.cppunittest.tests.zip'], 21:52:50 INFO - u'gtest': [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip', 21:52:50 INFO - u'firefox-52.6.1.en-US.linux-i686.gtest.tests.zip'], 21:52:50 INFO - u'jittest': [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip', 21:52:50 INFO - u'jsshell-linux-i686.zip'], 21:52:50 INFO - u'mochitest': [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip', 21:52:50 INFO - u'firefox-52.6.1.en-US.linux-i686.mochitest.tests.zip'], 21:52:50 INFO - u'mozbase': [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip'], 21:52:50 INFO - u'reftest': [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip', 21:52:50 INFO - u'firefox-52.6.1.en-US.linux-i686.reftest.tests.zip'], 21:52:50 INFO - u'talos': [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip', 21:52:50 INFO - u'firefox-52.6.1.en-US.linux-i686.talos.tests.zip'], 21:52:50 INFO - u'web-platform': [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip', 21:52:50 INFO - u'firefox-52.6.1.en-US.linux-i686.web-platform.tests.zip'], 21:52:50 INFO - u'xpcshell': [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip', 21:52:50 INFO - u'firefox-52.6.1.en-US.linux-i686.xpcshell.tests.zip']} 21:52:50 INFO - Downloading packages: [u'firefox-52.6.1.en-US.linux-i686.common.tests.zip', u'firefox-52.6.1.en-US.linux-i686.web-platform.tests.zip'] for test suite category: web-platform 21:52:50 INFO - Adding 'mach' for extraction from common.tests zip file 21:52:50 INFO - Adding 'mozinfo.json' for extraction from common.tests zip file 21:52:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs bin/*, config/*, mozbase/*, marionette/*, tools/wptserve/*, web-platform/*, mach, mozinfo.json from https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.common.tests.zip 21:52:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.common.tests.zip'}, attempt #1 21:52:50 INFO - Fetch https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.common.tests.zip into memory 21:52:52 INFO - Http code: 200 21:52:52 INFO - via: 1.1 15e35639aa606561dce966229363ebe8.cloudfront.net (CloudFront) 21:52:52 INFO - x-amz-cf-id: -0JmqMJil3s6MFM1fKwNhnfVz1c-dJJ_OBzcnLnt4oKLL1ChaYuosg== 21:52:52 INFO - x-amz-version-id: evX6uGgWMUP.uScVhifuph0piy1Og1Sz 21:52:57 INFO - Expected file size: 21840841 21:52:57 INFO - Obtained file size: 21840841 21:52:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs bin/*, config/*, mozbase/*, marionette/*, tools/wptserve/*, web-platform/*, mach, mozinfo.json from https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.web-platform.tests.zip 21:52:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.web-platform.tests.zip'}, attempt #1 21:52:59 INFO - Fetch https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.web-platform.tests.zip into memory 21:53:01 INFO - Http code: 200 21:53:01 INFO - via: 1.1 2708ce9c9ca82cfc3f3661b87f21b072.cloudfront.net (CloudFront) 21:53:01 INFO - x-amz-cf-id: E-LcOFMCTktStKQEMrvlY8M8bLiOF7fUqgw3n3a45adGX05ybGjJnA== 21:53:01 INFO - x-amz-version-id: 3NssQUkrB723zobxPkDKxX_InRKx.X8V 21:53:11 INFO - Expected file size: 43269060 21:53:11 INFO - Obtained file size: 43269060 21:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:53:35 INFO - trying https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.tar.bz2 21:53:35 INFO - Downloading https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.tar.bz2 to /builds/slave/test/build/firefox-52.6.1.en-US.linux-i686.tar.bz2 21:53:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.6.1.en-US.linux-i686.tar.bz2'}, attempt #1 21:53:50 INFO - Downloaded 58572983 bytes. 21:53:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.tar.bz2 21:53:50 INFO - mkdir: /builds/slave/test/properties 21:53:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:53:50 INFO - Writing to file /builds/slave/test/properties/build_url 21:53:50 INFO - Contents: 21:53:50 INFO - build_url:https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.tar.bz2 21:53:51 INFO - Running post-action listener: _resource_record_post_action 21:53:51 INFO - Running post-action listener: set_extra_try_arguments 21:53:51 INFO - [mozharness: 2018-02-16 05:53:51.845235Z] Finished download-and-extract step (success) 21:53:51 INFO - [mozharness: 2018-02-16 05:53:51.845829Z] Running fetch-geckodriver step. 21:53:51 INFO - Running pre-action listener: _resource_record_pre_action 21:53:51 INFO - Running main action method: fetch_geckodriver 21:53:51 INFO - Running post-action listener: _resource_record_post_action 21:53:51 INFO - [mozharness: 2018-02-16 05:53:51.970215Z] Finished fetch-geckodriver step (success) 21:53:51 INFO - [mozharness: 2018-02-16 05:53:51.970894Z] Running create-virtualenv step. 21:53:51 INFO - Running pre-action listener: _pre_create_virtualenv 21:53:51 INFO - Running pre-action listener: _resource_record_pre_action 21:53:51 INFO - Running main action method: create_virtualenv 21:53:51 INFO - Creating virtualenv /builds/slave/test/build/venv 21:53:51 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:53:51 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv 21:53:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:53:55 INFO - Using real prefix '/usr' 21:53:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:53:55 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content 21:53:59 INFO - Installing setuptools, pip, wheel...done. 21:53:59 INFO - Return code: 0 21:53:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:53:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:53:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 21:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:53:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:53:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 21:53:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x90c5dc8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x91a38b8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9264288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f84a8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f67b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x904d158>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 21:53:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', 'psutil>=3.1.1'] in /builds/slave/test/build 21:53:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com --trusted-host pypi.pvt.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com psutil>=3.1.1 21:53:59 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 21:53:59 INFO - 'CCACHE_UMASK': '002', 21:53:59 INFO - 'DISPLAY': ':0', 21:53:59 INFO - 'HOME': '/home/cltbld', 21:53:59 INFO - 'LANG': 'en_US.UTF-8', 21:53:59 INFO - 'LOGNAME': 'cltbld', 21:53:59 INFO - 'MAIL': '/var/mail/cltbld', 21:53:59 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 21:53:59 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 21:53:59 INFO - 'MOZ_NO_REMOTE': '1', 21:53:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:53:59 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 21:53:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:53:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:53:59 INFO - 'PWD': '/builds/slave/test', 21:53:59 INFO - 'SHELL': '/bin/bash', 21:53:59 INFO - 'SHLVL': '1', 21:53:59 INFO - 'TERM': 'linux', 21:53:59 INFO - 'TMOUT': '86400', 21:53:59 INFO - 'USER': 'cltbld', 21:53:59 INFO - 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 21:53:59 INFO - '_': '/tools/buildbot/bin/python'} 21:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple 21:54:01 INFO - Collecting psutil>=3.1.1 21:55:08 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 21:56:11 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 21:57:16 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 21:58:21 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 21:59:29 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:01:35 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:02:39 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:03:43 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:04:49 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:05:56 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:07:00 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/psutil-5.4.3.tar.gz (412kB) 22:07:02 INFO - Building wheels for collected packages: psutil 22:07:02 INFO - Running setup.py bdist_wheel for psutil: started 22:07:06 INFO - Running setup.py bdist_wheel for psutil: finished with status 'done' 22:07:06 INFO - Stored in directory: /home/cltbld/.cache/pip/wheels/13/61/f1/5d5b58b76b3bcbb1ef1183a6deef329b976c98495cc560b9b6 22:07:07 INFO - Successfully built psutil 22:07:07 INFO - Installing collected packages: psutil 22:07:07 INFO - Successfully installed psutil-5.4.3 22:07:07 INFO - Return code: 0 22:07:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:07:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 22:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:07:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 22:07:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x90c5dc8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x91a38b8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9264288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f84a8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f67b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x904d158>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 22:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com --trusted-host pypi.pvt.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com mozsystemmonitor==0.3 22:07:08 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 22:07:08 INFO - 'CCACHE_UMASK': '002', 22:07:08 INFO - 'DISPLAY': ':0', 22:07:08 INFO - 'HOME': '/home/cltbld', 22:07:08 INFO - 'LANG': 'en_US.UTF-8', 22:07:08 INFO - 'LOGNAME': 'cltbld', 22:07:08 INFO - 'MAIL': '/var/mail/cltbld', 22:07:08 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 22:07:08 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 22:07:08 INFO - 'MOZ_NO_REMOTE': '1', 22:07:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:08 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 22:07:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:08 INFO - 'PWD': '/builds/slave/test', 22:07:08 INFO - 'SHELL': '/bin/bash', 22:07:08 INFO - 'SHLVL': '1', 22:07:08 INFO - 'TERM': 'linux', 22:07:08 INFO - 'TMOUT': '86400', 22:07:08 INFO - 'USER': 'cltbld', 22:07:08 INFO - 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 22:07:08 INFO - '_': '/tools/buildbot/bin/python'} 22:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple 22:07:09 INFO - Collecting mozsystemmonitor==0.3 22:08:15 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:09:19 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:10:24 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:11:29 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:12:36 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:14:43 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:15:47 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:16:51 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:17:56 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:19:04 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:20:07 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/mozsystemmonitor-0.3.tar.gz 22:20:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:20:08 INFO - Building wheels for collected packages: mozsystemmonitor 22:20:08 INFO - Running setup.py bdist_wheel for mozsystemmonitor: started 22:20:09 INFO - Running setup.py bdist_wheel for mozsystemmonitor: finished with status 'done' 22:20:09 INFO - Stored in directory: /home/cltbld/.cache/pip/wheels/47/5a/3c/5f867ad602010c58b7ee3f3f9bf6fc13ffa2bcf18ca0aa2907 22:20:09 INFO - Successfully built mozsystemmonitor 22:20:09 INFO - Installing collected packages: mozsystemmonitor 22:20:09 INFO - Successfully installed mozsystemmonitor-0.3 22:20:09 INFO - Return code: 0 22:20:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:20:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 22:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:20:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 22:20:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x90c5dc8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x91a38b8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9264288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f84a8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f67b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x904d158>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 22:20:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com --trusted-host pypi.pvt.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com jsonschema==2.5.1 22:20:10 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 22:20:10 INFO - 'CCACHE_UMASK': '002', 22:20:10 INFO - 'DISPLAY': ':0', 22:20:10 INFO - 'HOME': '/home/cltbld', 22:20:10 INFO - 'LANG': 'en_US.UTF-8', 22:20:10 INFO - 'LOGNAME': 'cltbld', 22:20:10 INFO - 'MAIL': '/var/mail/cltbld', 22:20:10 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 22:20:10 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 22:20:10 INFO - 'MOZ_NO_REMOTE': '1', 22:20:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:20:10 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 22:20:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:20:10 INFO - 'PWD': '/builds/slave/test', 22:20:10 INFO - 'SHELL': '/bin/bash', 22:20:10 INFO - 'SHLVL': '1', 22:20:10 INFO - 'TERM': 'linux', 22:20:10 INFO - 'TMOUT': '86400', 22:20:10 INFO - 'USER': 'cltbld', 22:20:10 INFO - 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 22:20:10 INFO - '_': '/tools/buildbot/bin/python'} 22:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple 22:20:11 INFO - Collecting jsonschema==2.5.1 22:21:18 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:22:21 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:23:26 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:24:31 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:25:39 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:27:45 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:28:49 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:29:53 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:30:59 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:32:06 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:33:10 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl 22:33:10 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1) 22:34:16 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:35:20 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:36:24 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:37:30 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:38:37 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:40:44 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:41:48 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:42:52 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:43:58 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:45:05 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:46:09 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/functools32-3.2.3-2.tar.gz 22:46:09 INFO - Building wheels for collected packages: functools32 22:46:09 INFO - Running setup.py bdist_wheel for functools32: started 22:46:10 INFO - Running setup.py bdist_wheel for functools32: finished with status 'done' 22:46:10 INFO - Stored in directory: /home/cltbld/.cache/pip/wheels/85/b7/5d/1b936273674e83df135dab69cd55da7c4a7482298149dae82f 22:46:10 INFO - Successfully built functools32 22:46:10 INFO - Installing collected packages: functools32, jsonschema 22:46:11 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1 22:46:11 INFO - Return code: 0 22:46:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 22:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 22:46:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x90c5dc8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x91a38b8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9264288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f84a8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f67b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x904d158>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 22:46:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com --trusted-host pypi.pvt.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com functools32==3.2.3-2 22:46:11 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 22:46:11 INFO - 'CCACHE_UMASK': '002', 22:46:11 INFO - 'DISPLAY': ':0', 22:46:11 INFO - 'HOME': '/home/cltbld', 22:46:11 INFO - 'LANG': 'en_US.UTF-8', 22:46:11 INFO - 'LOGNAME': 'cltbld', 22:46:11 INFO - 'MAIL': '/var/mail/cltbld', 22:46:11 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 22:46:11 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 22:46:11 INFO - 'MOZ_NO_REMOTE': '1', 22:46:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:11 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 22:46:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:11 INFO - 'PWD': '/builds/slave/test', 22:46:11 INFO - 'SHELL': '/bin/bash', 22:46:11 INFO - 'SHLVL': '1', 22:46:11 INFO - 'TERM': 'linux', 22:46:11 INFO - 'TMOUT': '86400', 22:46:11 INFO - 'USER': 'cltbld', 22:46:11 INFO - 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 22:46:11 INFO - '_': '/tools/buildbot/bin/python'} 22:46:12 INFO - Ignoring indexes: https://pypi.python.org/simple 22:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:46:12 INFO - Return code: 0 22:46:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 22:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 22:46:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x90c5dc8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x91a38b8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9264288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f84a8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f67b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x904d158>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 22:46:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:46:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com --trusted-host pypi.pvt.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com blobuploader==1.2.4 22:46:13 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 22:46:13 INFO - 'CCACHE_UMASK': '002', 22:46:13 INFO - 'DISPLAY': ':0', 22:46:13 INFO - 'HOME': '/home/cltbld', 22:46:13 INFO - 'LANG': 'en_US.UTF-8', 22:46:13 INFO - 'LOGNAME': 'cltbld', 22:46:13 INFO - 'MAIL': '/var/mail/cltbld', 22:46:13 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 22:46:13 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 22:46:13 INFO - 'MOZ_NO_REMOTE': '1', 22:46:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:13 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 22:46:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:13 INFO - 'PWD': '/builds/slave/test', 22:46:13 INFO - 'SHELL': '/bin/bash', 22:46:13 INFO - 'SHLVL': '1', 22:46:13 INFO - 'TERM': 'linux', 22:46:13 INFO - 'TMOUT': '86400', 22:46:13 INFO - 'USER': 'cltbld', 22:46:13 INFO - 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 22:46:13 INFO - '_': '/tools/buildbot/bin/python'} 22:46:14 INFO - Ignoring indexes: https://pypi.python.org/simple 22:46:14 INFO - Collecting blobuploader==1.2.4 22:47:20 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:48:24 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:49:29 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:50:34 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:51:41 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:53:48 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:54:52 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:55:56 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:57:02 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:58:09 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 22:59:13 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/blobuploader-1.2.4.tar.gz 22:59:14 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4) 23:00:20 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:01:24 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:02:28 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:03:33 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:04:41 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:06:48 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:07:51 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:08:56 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:10:01 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:11:08 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:12:12 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-1.2.3.tar.gz (348kB) 23:12:14 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4) 23:13:20 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:14:24 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:15:28 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:16:33 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:17:41 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:19:47 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:20:51 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:21:56 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:23:01 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:24:08 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:25:12 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/docopt-0.6.1.tar.gz 23:25:13 INFO - Building wheels for collected packages: blobuploader, requests, docopt 23:25:13 INFO - Running setup.py bdist_wheel for blobuploader: started 23:25:14 INFO - Running setup.py bdist_wheel for blobuploader: finished with status 'done' 23:25:14 INFO - Stored in directory: /home/cltbld/.cache/pip/wheels/8a/e0/bf/e181f912badb5b4270d297599c44127e80fe565650ebb82e69 23:25:14 INFO - Running setup.py bdist_wheel for requests: started 23:25:16 INFO - Running setup.py bdist_wheel for requests: finished with status 'done' 23:25:16 INFO - Stored in directory: /home/cltbld/.cache/pip/wheels/c0/e1/cb/e7b92894a50ecbdf8f3ee3bd8f47180c0326405eb1cd38d32f 23:25:16 INFO - Running setup.py bdist_wheel for docopt: started 23:25:17 INFO - Running setup.py bdist_wheel for docopt: finished with status 'done' 23:25:17 INFO - Stored in directory: /home/cltbld/.cache/pip/wheels/74/2b/0c/cac53a58de0a240937cf498f4226e6969f0d85091ac458ff48 23:25:17 INFO - Successfully built blobuploader requests docopt 23:25:17 INFO - Installing collected packages: requests, docopt, blobuploader 23:25:18 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3 23:25:18 INFO - Return code: 0 23:25:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 23:25:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 23:25:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/marionette_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x90c5dc8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x91a38b8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9264288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f84a8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f67b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x904d158>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 23:25:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/marionette_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com'] in /builds/slave/test/build/tests/config 23:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/marionette_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com --trusted-host pypi.pvt.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com 23:25:18 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 23:25:18 INFO - 'CCACHE_UMASK': '002', 23:25:18 INFO - 'DISPLAY': ':0', 23:25:18 INFO - 'HOME': '/home/cltbld', 23:25:18 INFO - 'LANG': 'en_US.UTF-8', 23:25:18 INFO - 'LOGNAME': 'cltbld', 23:25:18 INFO - 'MAIL': '/var/mail/cltbld', 23:25:18 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 23:25:18 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 23:25:18 INFO - 'MOZ_NO_REMOTE': '1', 23:25:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:25:18 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 23:25:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:25:18 INFO - 'PWD': '/builds/slave/test', 23:25:18 INFO - 'SHELL': '/bin/bash', 23:25:18 INFO - 'SHLVL': '1', 23:25:18 INFO - 'TERM': 'linux', 23:25:18 INFO - 'TMOUT': '86400', 23:25:18 INFO - 'USER': 'cltbld', 23:25:18 INFO - 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 23:25:18 INFO - '_': '/tools/buildbot/bin/python'} 23:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple 23:25:20 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser 23:25:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash 23:25:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug 23:25:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice 23:25:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile 23:25:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd 23:25:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo 23:25:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall 23:25:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak 23:25:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog 23:25:26 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork 23:25:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess 23:25:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile 23:25:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner 23:25:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot 23:25:29 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest 23:25:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion 23:25:30 INFO - Processing /builds/slave/test/build/tests/tools/wptserve 23:25:31 INFO - Processing /builds/slave/test/build/tests/marionette/client 23:25:31 INFO - Processing /builds/slave/test/build/tests/marionette/harness/marionette_harness/runner/mixins/browsermob-proxy-py 23:25:32 INFO - Processing /builds/slave/test/build/tests/marionette/harness 23:25:33 INFO - Processing /builds/slave/test/build/tests/marionette/puppeteer/firefox 23:25:33 INFO - Building wheels for collected packages: browsermob-proxy 23:25:33 INFO - Running setup.py bdist_wheel for browsermob-proxy: started 23:25:34 INFO - Running setup.py bdist_wheel for browsermob-proxy: finished with status 'done' 23:25:34 INFO - Stored in directory: /home/cltbld/.cache/pip/wheels/10/00/c1/79bb793b6a7eb4dca9dfd244010f550ae5044951bcd23c261e 23:25:34 INFO - Successfully built browsermob-proxy 23:25:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion, wptserve, marionette-driver, browsermob-proxy, marionette-harness, firefox-puppeteer 23:25:34 INFO - Running setup.py install for manifestparser: started 23:25:35 INFO - Running setup.py install for manifestparser: finished with status 'done' 23:25:35 INFO - Running setup.py install for mozcrash: started 23:25:35 INFO - Running setup.py install for mozcrash: finished with status 'done' 23:25:35 INFO - Running setup.py install for mozdebug: started 23:25:36 INFO - Running setup.py install for mozdebug: finished with status 'done' 23:25:36 INFO - Running setup.py install for mozdevice: started 23:25:37 INFO - Running setup.py install for mozdevice: finished with status 'done' 23:25:37 INFO - Running setup.py install for mozfile: started 23:25:38 INFO - Running setup.py install for mozfile: finished with status 'done' 23:25:38 INFO - Running setup.py install for mozhttpd: started 23:25:39 INFO - Running setup.py install for mozhttpd: finished with status 'done' 23:25:39 INFO - Running setup.py install for mozinfo: started 23:25:39 INFO - Running setup.py install for mozinfo: finished with status 'done' 23:25:39 INFO - Running setup.py install for mozInstall: started 23:25:40 INFO - Running setup.py install for mozInstall: finished with status 'done' 23:25:40 INFO - Running setup.py install for mozleak: started 23:25:41 INFO - Running setup.py install for mozleak: finished with status 'done' 23:25:41 INFO - Running setup.py install for mozlog: started 23:25:42 INFO - Running setup.py install for mozlog: finished with status 'done' 23:25:42 INFO - Running setup.py install for moznetwork: started 23:25:43 INFO - Running setup.py install for moznetwork: finished with status 'done' 23:25:43 INFO - Running setup.py install for mozprocess: started 23:25:43 INFO - Running setup.py install for mozprocess: finished with status 'done' 23:25:43 INFO - Running setup.py install for mozprofile: started 23:25:44 INFO - Running setup.py install for mozprofile: finished with status 'done' 23:25:44 INFO - Running setup.py install for mozrunner: started 23:25:45 INFO - Running setup.py install for mozrunner: finished with status 'done' 23:25:45 INFO - Running setup.py install for mozscreenshot: started 23:25:46 INFO - Running setup.py install for mozscreenshot: finished with status 'done' 23:25:46 INFO - Running setup.py install for moztest: started 23:25:47 INFO - Running setup.py install for moztest: finished with status 'done' 23:25:47 INFO - Running setup.py install for mozversion: started 23:25:48 INFO - Running setup.py install for mozversion: finished with status 'done' 23:25:48 INFO - Running setup.py install for wptserve: started 23:25:49 INFO - Running setup.py install for wptserve: finished with status 'done' 23:25:49 INFO - Running setup.py install for marionette-driver: started 23:25:50 INFO - Running setup.py install for marionette-driver: finished with status 'done' 23:25:50 INFO - Running setup.py install for marionette-harness: started 23:25:52 INFO - Running setup.py install for marionette-harness: finished with status 'done' 23:25:52 INFO - Running setup.py install for firefox-puppeteer: started 23:25:53 INFO - Running setup.py install for firefox-puppeteer: finished with status 'done' 23:25:54 INFO - Successfully installed browsermob-proxy-0.6.0 firefox-puppeteer-52.1.0 manifestparser-1.1 marionette-driver-2.2.0 marionette-harness-4.0.0 mozInstall-1.12 mozcrash-1.0 mozdebug-0.1 mozdevice-0.48 mozfile-1.2 mozhttpd-0.7 mozinfo-0.9 mozleak-0.1 mozlog-3.4 moznetwork-0.27 mozprocess-0.23 mozprofile-0.28 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4 wptserve-1.4.0 23:25:54 INFO - Return code: 0 23:25:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:25:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 23:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:25:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 23:25:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/marionette_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x90c5dc8>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x91a38b8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x9264288>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f84a8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f67b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x904d158>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 23:25:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/marionette_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com', '--trusted-host', 'pypi.pvt.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com'] in /builds/slave/test/build/tests/config 23:25:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/marionette_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com --trusted-host pypi.pvt.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com 23:25:55 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 23:25:55 INFO - 'CCACHE_UMASK': '002', 23:25:55 INFO - 'DISPLAY': ':0', 23:25:55 INFO - 'HOME': '/home/cltbld', 23:25:55 INFO - 'LANG': 'en_US.UTF-8', 23:25:55 INFO - 'LOGNAME': 'cltbld', 23:25:55 INFO - 'MAIL': '/var/mail/cltbld', 23:25:55 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 23:25:55 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 23:25:55 INFO - 'MOZ_NO_REMOTE': '1', 23:25:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:25:55 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 23:25:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:25:55 INFO - 'PWD': '/builds/slave/test', 23:25:55 INFO - 'SHELL': '/bin/bash', 23:25:55 INFO - 'SHLVL': '1', 23:25:55 INFO - 'TERM': 'linux', 23:25:55 INFO - 'TMOUT': '86400', 23:25:55 INFO - 'USER': 'cltbld', 23:25:55 INFO - 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 23:25:55 INFO - '_': '/tools/buildbot/bin/python'} 23:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple 23:25:56 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser 23:25:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:25:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash 23:25:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:25:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug 23:25:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:25:58 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice 23:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:25:59 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile 23:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:25:59 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd 23:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:26:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo 23:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:26:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall 23:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:26:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak 23:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:26:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog 23:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:26:03 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork 23:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:26:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess 23:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:26:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile 23:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:26:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner 23:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:26:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot 23:26:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:26:06 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest 23:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:26:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion 23:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:26:07 INFO - Processing /builds/slave/test/build/tests/tools/wptserve 23:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): wptserve==1.4.0 from file:///builds/slave/test/build/tests/tools/wptserve in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 3)) 23:26:08 INFO - Processing /builds/slave/test/build/tests/marionette/client 23:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): marionette-driver==2.2.0 from file:///builds/slave/test/build/tests/marionette/client in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 4)) 23:26:08 INFO - Processing /builds/slave/test/build/tests/marionette/harness/marionette_harness/runner/mixins/browsermob-proxy-py 23:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): browsermob-proxy==0.6.0 from file:///builds/slave/test/build/tests/marionette/harness/marionette_harness/runner/mixins/browsermob-proxy-py in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 5)) 23:26:09 INFO - Processing /builds/slave/test/build/tests/marionette/harness 23:26:10 INFO - Requirement already satisfied (use --upgrade to upgrade): marionette-harness==4.0.0 from file:///builds/slave/test/build/tests/marionette/harness in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 6)) 23:26:10 INFO - Processing /builds/slave/test/build/tests/marionette/puppeteer/firefox 23:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): firefox-puppeteer==52.1.0 from file:///builds/slave/test/build/tests/marionette/puppeteer/firefox in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 9)) 23:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:26:11 INFO - Collecting blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:27:18 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:28:21 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:29:26 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:30:31 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:31:39 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:33:45 INFO - Retrying (Retry(total=4, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:34:49 INFO - Retrying (Retry(total=3, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:35:53 INFO - Retrying (Retry(total=2, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:36:59 INFO - Retrying (Retry(total=1, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:38:06 INFO - Retrying (Retry(total=0, connect=None, read=None, redirect=None)) after connection broken by 'NewConnectionError(': Failed to establish a new connection: [Errno 110] Connection timed out',)': /pub 23:39:10 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/blessings-1.6.tar.gz 23:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): requests>=1.1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from browsermob-proxy==0.6.0->-r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 5)) 23:39:10 INFO - Building wheels for collected packages: blessings, browsermob-proxy 23:39:10 INFO - Running setup.py bdist_wheel for blessings: started 23:39:11 INFO - Running setup.py bdist_wheel for blessings: finished with status 'done' 23:39:11 INFO - Stored in directory: /home/cltbld/.cache/pip/wheels/95/23/df/6c20b86d723e9e33dcac5e1dbfd53aae1bce5c84d153369b7b 23:39:11 INFO - Running setup.py bdist_wheel for browsermob-proxy: started 23:39:12 INFO - Running setup.py bdist_wheel for browsermob-proxy: finished with status 'done' 23:39:12 INFO - Stored in directory: /home/cltbld/.cache/pip/wheels/10/00/c1/79bb793b6a7eb4dca9dfd244010f550ae5044951bcd23c261e 23:39:12 INFO - Successfully built blessings browsermob-proxy 23:39:12 INFO - Installing collected packages: blessings 23:39:12 INFO - Successfully installed blessings-1.6 23:39:13 INFO - Return code: 0 23:39:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:39:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:39:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:39:15 INFO - Reading from file tmpfile_stdout 23:39:15 INFO - Reading from file tmpfile_stderr 23:39:15 INFO - Current package versions: 23:39:15 INFO - blessings == 1.6 23:39:15 INFO - blobuploader == 1.2.4 23:39:15 INFO - browsermob-proxy == 0.6.0 23:39:15 INFO - docopt == 0.6.1 23:39:15 INFO - firefox-puppeteer == 52.1.0 23:39:15 INFO - functools32 == 3.2.3.post2 23:39:15 INFO - jsonschema == 2.5.1 23:39:15 INFO - manifestparser == 1.1 23:39:15 INFO - marionette-driver == 2.2.0 23:39:15 INFO - marionette-harness == 4.0.0 23:39:15 INFO - mozInstall == 1.12 23:39:15 INFO - mozcrash == 1.0 23:39:15 INFO - mozdebug == 0.1 23:39:15 INFO - mozdevice == 0.48 23:39:15 INFO - mozfile == 1.2 23:39:15 INFO - mozhttpd == 0.7 23:39:15 INFO - mozinfo == 0.9 23:39:15 INFO - mozleak == 0.1 23:39:15 INFO - mozlog == 3.4 23:39:15 INFO - moznetwork == 0.27 23:39:15 INFO - mozprocess == 0.23 23:39:15 INFO - mozprofile == 0.28 23:39:15 INFO - mozrunner == 6.13 23:39:15 INFO - mozscreenshot == 0.1 23:39:15 INFO - mozsystemmonitor == 0.3 23:39:15 INFO - moztest == 0.8 23:39:15 INFO - mozversion == 1.4 23:39:15 INFO - psutil == 5.4.3 23:39:15 INFO - requests == 1.2.3 23:39:15 INFO - wptserve == 1.4.0 23:39:15 INFO - Running post-action listener: _resource_record_post_action 23:39:15 INFO - Running post-action listener: _start_resource_monitoring 23:39:15 INFO - Starting resource monitoring. 23:39:15 INFO - [mozharness: 2018-02-16 07:39:15.196649Z] Finished create-virtualenv step (success) 23:39:15 INFO - [mozharness: 2018-02-16 07:39:15.222465Z] Running pull step. 23:39:15 INFO - Running pre-action listener: _resource_record_pre_action 23:39:15 INFO - Running main action method: pull 23:39:15 INFO - Pull has nothing to do! 23:39:15 INFO - Running post-action listener: _resource_record_post_action 23:39:15 INFO - [mozharness: 2018-02-16 07:39:15.230739Z] Finished pull step (success) 23:39:15 INFO - [mozharness: 2018-02-16 07:39:15.235259Z] Running install step. 23:39:15 INFO - Running pre-action listener: _resource_record_pre_action 23:39:15 INFO - Running main action method: install 23:39:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:39:16 INFO - Reading from file tmpfile_stdout 23:39:16 INFO - Reading from file tmpfile_stderr 23:39:16 INFO - Detecting whether we're running mozinstall >=1.0... 23:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:39:17 INFO - Reading from file tmpfile_stdout 23:39:17 INFO - Output received: 23:39:17 INFO - Usage: mozinstall [options] installer 23:39:17 INFO - Options: 23:39:17 INFO - -h, --help show this help message and exit 23:39:17 INFO - -d DEST, --destination=DEST 23:39:17 INFO - Directory to install application into. [default: 23:39:17 INFO - "/builds/slave/test"] 23:39:17 INFO - --app=APP Application being installed. [default: firefox] 23:39:17 INFO - mkdir: /builds/slave/test/build/application 23:39:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.6.1.en-US.linux-i686.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.6.1.en-US.linux-i686.tar.bz2 --destination /builds/slave/test/build/application 23:39:42 INFO - Reading from file tmpfile_stdout 23:39:42 INFO - Output received: 23:39:42 INFO - /builds/slave/test/build/application/firefox/firefox 23:39:42 INFO - Running post-action listener: _resource_record_post_action 23:39:42 INFO - [mozharness: 2018-02-16 07:39:42.969884Z] Finished install step (success) 23:39:42 INFO - [mozharness: 2018-02-16 07:39:42.970546Z] Running run-tests step. 23:39:42 INFO - Running pre-action listener: _resource_record_pre_action 23:39:42 INFO - Running main action method: run_tests 23:39:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:39:42 INFO - Minidump filename unknown. Determining based upon platform and architecture. 23:39:43 INFO - Minidump tooltool manifest unknown. Determining based upon platform and architecture. 23:39:43 INFO - grabbing minidump binary from tooltool 23:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:43 INFO - trying https://raw.githubusercontent.com/mozilla/build-tooltool/master/tooltool.py 23:39:43 INFO - Downloading https://raw.githubusercontent.com/mozilla/build-tooltool/master/tooltool.py to /builds/slave/test/build/tooltool.py 23:39:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://raw.githubusercontent.com/mozilla/build-tooltool/master/tooltool.py', 'file_name': '/builds/slave/test/build/tooltool.py'}, attempt #1 23:39:43 INFO - Downloaded 38066 bytes. 23:39:43 INFO - Chmoding /builds/slave/test/build/tooltool.py to 0755 23:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:43 INFO - retry: Calling run_command with args: (['/tools/buildbot/bin/python', '/builds/slave/test/build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux32/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f84a8>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x90f67b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x904d158>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:39:43 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux32/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:39:43 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux32/releng.manifest -o -c /builds/tooltool_cache 23:39:43 INFO - Calling ['/tools/buildbot/bin/python', '/builds/slave/test/build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux32/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:39:44 INFO - INFO - File linux32-minidump_stackwalk not present in local cache folder /builds/tooltool_cache 23:39:44 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 23:39:46 INFO - INFO - File linux32-minidump_stackwalk fetched from https://api.pub.build.mozilla.org/tooltool/ as /builds/slave/test/build/tmp4H78w_ 23:39:46 INFO - INFO - File integrity verified, renaming tmp4H78w_ to linux32-minidump_stackwalk 23:39:46 INFO - INFO - Updating local cache /builds/tooltool_cache... 23:39:46 INFO - INFO - Local cache /builds/tooltool_cache updated with linux32-minidump_stackwalk 23:39:46 INFO - Return code: 0 23:39:46 INFO - Chmoding /builds/slave/test/build/linux32-minidump_stackwalk to 0755 23:39:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:39:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '-u', '/builds/slave/test/build/tests/web-platform/runtests.py', '--log-raw=-', '--log-raw=/builds/slave/test/build/blobber_upload_dir/wpt_raw.log', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/wpt_errorsummary.log', '--binary=/builds/slave/test/build/application/firefox/firefox', '--symbols-path=https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.crashreporter-symbols.zip', '--stackwalk-binary=/builds/slave/test/build/linux32-minidump_stackwalk', '--stackfix-dir=/builds/slave/test/build/tests/bin', '--test-type=testharness', '--total-chunks=5', '--this-chunk=5', '--prefs-root=/builds/slave/test/build/tests/web-platform/prefs', '--processes=1', '--config=/builds/slave/test/build/tests/web-platform/wptrunner.ini', '--ca-cert-path=/builds/slave/test/build/tests/web-platform/certs/cacert.pem', '--host-key-path=/builds/slave/test/build/tests/web-platform/certs/web-platform.test.key', '--host-cert-path=/builds/slave/test/build/tests/web-platform/certs/web-platform.test.pem', '--certutil-binary=/builds/slave/test/build/tests/bin/certutil'] in /builds/slave/test/build 23:39:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python -u /builds/slave/test/build/tests/web-platform/runtests.py --log-raw=- --log-raw=/builds/slave/test/build/blobber_upload_dir/wpt_raw.log --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/wpt_errorsummary.log --binary=/builds/slave/test/build/application/firefox/firefox --symbols-path=https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.crashreporter-symbols.zip --stackwalk-binary=/builds/slave/test/build/linux32-minidump_stackwalk --stackfix-dir=/builds/slave/test/build/tests/bin --test-type=testharness --total-chunks=5 --this-chunk=5 --prefs-root=/builds/slave/test/build/tests/web-platform/prefs --processes=1 --config=/builds/slave/test/build/tests/web-platform/wptrunner.ini --ca-cert-path=/builds/slave/test/build/tests/web-platform/certs/cacert.pem --host-key-path=/builds/slave/test/build/tests/web-platform/certs/web-platform.test.key --host-cert-path=/builds/slave/test/build/tests/web-platform/certs/web-platform.test.pem --certutil-binary=/builds/slave/test/build/tests/bin/certutil 23:39:46 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 23:39:46 INFO - 'CCACHE_UMASK': '002', 23:39:46 INFO - 'DISPLAY': ':0', 23:39:46 INFO - 'HOME': '/home/cltbld', 23:39:46 INFO - 'LANG': 'en_US.UTF-8', 23:39:46 INFO - 'LOGNAME': 'cltbld', 23:39:46 INFO - 'MAIL': '/var/mail/cltbld', 23:39:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:39:46 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 23:39:46 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 23:39:46 INFO - 'MOZ_NO_REMOTE': '1', 23:39:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:39:46 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 23:39:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:46 INFO - 'PWD': '/builds/slave/test', 23:39:46 INFO - 'SHELL': '/bin/bash', 23:39:46 INFO - 'SHLVL': '1', 23:39:46 INFO - 'TERM': 'linux', 23:39:46 INFO - 'TMOUT': '86400', 23:39:46 INFO - 'USER': 'cltbld', 23:39:46 INFO - 'XDG_SESSION_COOKIE': '5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056', 23:39:46 INFO - '_': '/tools/buildbot/bin/python'} 23:39:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '-u', '/builds/slave/test/build/tests/web-platform/runtests.py', '--log-raw=-', '--log-raw=/builds/slave/test/build/blobber_upload_dir/wpt_raw.log', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/wpt_errorsummary.log', '--binary=/builds/slave/test/build/application/firefox/firefox', '--symbols-path=https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.crashreporter-symbols.zip', '--stackwalk-binary=/builds/slave/test/build/linux32-minidump_stackwalk', '--stackfix-dir=/builds/slave/test/build/tests/bin', '--test-type=testharness', '--total-chunks=5', '--this-chunk=5', '--prefs-root=/builds/slave/test/build/tests/web-platform/prefs', '--processes=1', '--config=/builds/slave/test/build/tests/web-platform/wptrunner.ini', '--ca-cert-path=/builds/slave/test/build/tests/web-platform/certs/cacert.pem', '--host-key-path=/builds/slave/test/build/tests/web-platform/certs/web-platform.test.key', '--host-cert-path=/builds/slave/test/build/tests/web-platform/certs/web-platform.test.pem', '--certutil-binary=/builds/slave/test/build/tests/bin/certutil'] with output_timeout 1000 23:39:48 INFO - STDERR: Opening manifest at /builds/slave/test/build/tests/web-platform/meta/MANIFEST.json 23:39:49 INFO - STDERR: Opening manifest at /builds/slave/test/build/tests/web-platform/mozilla/meta/MANIFEST.json 23:40:04 INFO - Using 1 client processes 23:40:05 INFO - wptserve Starting http server on 127.0.0.1:8000 23:40:06 INFO - wptserve Starting http server on 127.0.0.1:8001 23:40:06 INFO - wptserve Starting http server on 127.0.0.1:8443 23:40:08 INFO - SUITE-START | Running 1054 tests 23:40:08 INFO - Running testharness tests 23:40:08 INFO - TEST-START | /battery-status/battery-promise-iframe.html 23:40:08 INFO - TEST-SKIP | /battery-status/battery-promise-iframe.html | took 1ms 23:40:08 INFO - TEST-START | /battery-status/battery-promise-window.html 23:40:08 INFO - TEST-SKIP | /battery-status/battery-promise-window.html | took 1ms 23:40:08 INFO - TEST-START | /battery-status/battery-promise.html 23:40:08 INFO - TEST-SKIP | /battery-status/battery-promise.html | took 0ms 23:40:08 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_bmp.html 23:40:08 INFO - TEST-SKIP | /dom/nodes/Document-contentType/contentType/contenttype_bmp.html | took 1ms 23:40:08 INFO - TEST-START | /html/editing/focus/focus-management/focus-event-targets-simple.html 23:40:08 INFO - TEST-SKIP | /html/editing/focus/focus-management/focus-event-targets-simple.html | took 1ms 23:40:08 INFO - TEST-START | /html/editing/focus/focus-management/focus-events.html 23:40:08 INFO - TEST-SKIP | /html/editing/focus/focus-management/focus-events.html | took 0ms 23:40:08 INFO - TEST-START | /html/infrastructure/urls/resolving-urls/query-encoding/utf-16be.html 23:40:08 INFO - TEST-SKIP | /html/infrastructure/urls/resolving-urls/query-encoding/utf-16be.html | took 1ms 23:40:08 INFO - TEST-START | /html/infrastructure/urls/resolving-urls/query-encoding/utf-16le.html 23:40:08 INFO - TEST-SKIP | /html/infrastructure/urls/resolving-urls/query-encoding/utf-16le.html | took 1ms 23:40:08 INFO - TEST-START | /html/infrastructure/urls/resolving-urls/query-encoding/utf-8.html 23:40:08 INFO - TEST-SKIP | /html/infrastructure/urls/resolving-urls/query-encoding/utf-8.html | took 1ms 23:40:08 INFO - TEST-START | /html/infrastructure/urls/resolving-urls/query-encoding/windows-1251.html 23:40:08 INFO - TEST-SKIP | /html/infrastructure/urls/resolving-urls/query-encoding/windows-1251.html | took 1ms 23:40:08 INFO - TEST-START | /html/infrastructure/urls/resolving-urls/query-encoding/windows-1252.html 23:40:08 INFO - TEST-SKIP | /html/infrastructure/urls/resolving-urls/query-encoding/windows-1252.html | took 1ms 23:40:08 INFO - TEST-START | /mixed-content/blockable/http-csp/same-host-http/form-tag/top-level/no-redirect/opt-in-blocks.https.html 23:40:08 INFO - TEST-SKIP | /mixed-content/blockable/http-csp/same-host-http/form-tag/top-level/no-redirect/opt-in-blocks.https.html | took 1ms 23:40:08 INFO - TEST-START | /mixed-content/blockable/no-opt-in/same-host-http/form-tag/top-level/keep-scheme-redirect/no-opt-in-blocks.https.html 23:40:08 INFO - TEST-SKIP | /mixed-content/blockable/no-opt-in/same-host-http/form-tag/top-level/keep-scheme-redirect/no-opt-in-blocks.https.html | took 1ms 23:40:08 INFO - TEST-START | /mixed-content/blockable/no-opt-in/same-host-http/form-tag/top-level/no-redirect/no-opt-in-blocks.https.html 23:40:08 INFO - TEST-SKIP | /mixed-content/blockable/no-opt-in/same-host-http/form-tag/top-level/no-redirect/no-opt-in-blocks.https.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-http/img-tag/generic.keep-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-http/img-tag/generic.keep-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-http/img-tag/generic.no-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-http/img-tag/generic.no-redirect.http.html | took 0ms 23:40:08 INFO - TEST-START | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-http/img-tag/generic.swap-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-http/img-tag/generic.swap-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-https/img-tag/generic.keep-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-https/img-tag/generic.keep-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-https/img-tag/generic.no-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-https/img-tag/generic.no-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-https/img-tag/generic.swap-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/no-referrer/meta-referrer/cross-origin/http-https/img-tag/generic.swap-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-http/img-tag/same-origin-insecure.keep-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-http/img-tag/same-origin-insecure.keep-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-http/img-tag/same-origin-insecure.no-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-http/img-tag/same-origin-insecure.no-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-http/img-tag/same-origin-insecure.swap-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-http/img-tag/same-origin-insecure.swap-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-downgrade.keep-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-downgrade.keep-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-downgrade.no-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-downgrade.no-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-downgrade.swap-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-downgrade.swap-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-insecure.swap-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-insecure.swap-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-upgrade.keep-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-upgrade.keep-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-upgrade.no-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-upgrade.no-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-upgrade.swap-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/origin-when-cross-origin/meta-referrer/same-origin/http-https/img-tag/same-origin-upgrade.swap-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/unsafe-url/meta-referrer/same-origin/http-https/img-tag/generic.keep-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/unsafe-url/meta-referrer/same-origin/http-https/img-tag/generic.keep-origin-redirect.http.html | took 2ms 23:40:08 INFO - TEST-START | /referrer-policy/unsafe-url/meta-referrer/same-origin/http-https/img-tag/generic.no-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/unsafe-url/meta-referrer/same-origin/http-https/img-tag/generic.no-redirect.http.html | took 0ms 23:40:08 INFO - TEST-START | /referrer-policy/unsafe-url/meta-referrer/same-origin/http-https/img-tag/generic.swap-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/unsafe-url/meta-referrer/same-origin/http-https/img-tag/generic.swap-origin-redirect.http.html | took 0ms 23:40:08 INFO - TEST-START | /referrer-policy/unset-referrer-policy/meta-referrer/same-origin/http-https/img-tag/upgrade-protocol.keep-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/unset-referrer-policy/meta-referrer/same-origin/http-https/img-tag/upgrade-protocol.keep-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/unset-referrer-policy/meta-referrer/same-origin/http-https/img-tag/upgrade-protocol.no-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/unset-referrer-policy/meta-referrer/same-origin/http-https/img-tag/upgrade-protocol.no-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /referrer-policy/unset-referrer-policy/meta-referrer/same-origin/http-https/img-tag/upgrade-protocol.swap-origin-redirect.http.html 23:40:08 INFO - TEST-SKIP | /referrer-policy/unset-referrer-policy/meta-referrer/same-origin/http-https/img-tag/upgrade-protocol.swap-origin-redirect.http.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/buffers/buffer-bind-test.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/buffers/buffer-bind-test.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/buffers/buffer-data-array-buffer-delete.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/buffers/buffer-data-array-buffer-delete.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/buffers/buffer-data-array-buffer.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/buffers/buffer-data-array-buffer.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/buffers/element-array-buffer-delete-recreate.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/buffers/element-array-buffer-delete-recreate.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/buffers/index-validation-copies-indices.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/buffers/index-validation-copies-indices.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/buffers/index-validation-crash-with-buffer-sub-data.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/buffers/index-validation-crash-with-buffer-sub-data.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/buffers/index-validation-large-buffer.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/buffers/index-validation-large-buffer.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/buffers/index-validation-verifies-too-many-indices.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/buffers/index-validation-verifies-too-many-indices.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/buffers/index-validation-with-resized-buffer.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/buffers/index-validation-with-resized-buffer.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/buffers/index-validation.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/buffers/index-validation.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/angle-d3d11-compiler-error.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/angle-d3d11-compiler-error.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/angle-dx-variable-bug.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/angle-dx-variable-bug.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/array-of-struct-with-int-first-position.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/array-of-struct-with-int-first-position.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/compare-loop-index-to-uniform.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/compare-loop-index-to-uniform.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/complex-glsl-does-not-crash.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/complex-glsl-does-not-crash.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/conditional-discard-in-loop.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/conditional-discard-in-loop.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/conditional-discard-optimization.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/conditional-discard-optimization.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/constant-precision-qualifier.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/constant-precision-qualifier.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/essl3-shaders-with-webgl1.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/essl3-shaders-with-webgl1.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/floored-division-accuracy.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/floored-division-accuracy.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/fragcoord-linking-bug.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/fragcoord-linking-bug.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/long-expressions-should-not-crash.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/long-expressions-should-not-crash.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/modulo-arithmetic-accuracy.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/modulo-arithmetic-accuracy.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/multiplication-assignment.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/multiplication-assignment.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/nested-functions-should-not-crash.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/nested-functions-should-not-crash.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/sampler-array-using-loop-index.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/sampler-array-using-loop-index.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/temp-expressions-should-not-crash.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/temp-expressions-should-not-crash.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/glsl/bugs/uniforms-should-not-lose-values.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/glsl/bugs/uniforms-should-not-lose-values.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/limits/gl-max-texture-dimensions.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/limits/gl-max-texture-dimensions.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/limits/gl-min-attribs.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/limits/gl-min-attribs.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/limits/gl-min-textures.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/limits/gl-min-textures.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/limits/gl-min-uniforms.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/limits/gl-min-uniforms.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/asin/asin_001_to_006.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/asin/asin_001_to_006.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/ceil/ceil_001_to_006.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/ceil/ceil_001_to_006.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/control_flow/control_flow_001_to_008.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/control_flow/control_flow_001_to_008.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/control_flow/control_flow_009_to_010.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/control_flow/control_flow_009_to_010.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/exp/exp_001_to_008.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/exp/exp_001_to_008.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/exp/exp_009_to_012.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/exp/exp_009_to_012.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/length/length_001_to_006.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/length/length_001_to_006.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/lessThanEqual/lessThanEqual_001_to_008.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/lessThanEqual/lessThanEqual_001_to_008.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/log/log_001_to_008.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/log/log_001_to_008.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/log/log_009_to_012.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/log/log_009_to_012.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/matrixCompMult/matrixCompMult_001_to_004.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/matrixCompMult/matrixCompMult_001_to_004.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/sign/sign_001_to_006.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/sign/sign_001_to_006.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/step/step_001_to_006.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/step/step_001_to_006.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/ogles/GL/vec3/vec3_001_to_008.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/ogles/GL/vec3/vec3_001_to_008.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/state/gl-enable-enum-test.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/state/gl-enable-enum-test.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/state/gl-enum-tests.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/state/gl-enum-tests.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/state/gl-get-calls.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/state/gl-get-calls.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/state/gl-geterror.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/state/gl-geterror.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/state/gl-getstring.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/state/gl-getstring.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/state/gl-object-get-calls.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/state/gl-object-get-calls.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/state/state-uneffected-after-compositing.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/state/state-uneffected-after-compositing.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/uniforms/gl-uniform-arrays.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/uniforms/gl-uniform-arrays.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/uniforms/gl-uniform-bool.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/uniforms/gl-uniform-bool.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/uniforms/gl-uniformmatrix4fv.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/uniforms/gl-uniformmatrix4fv.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/uniforms/gl-unknown-uniform.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/uniforms/gl-unknown-uniform.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/uniforms/null-uniform-location.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/uniforms/null-uniform-location.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/uniforms/out-of-bounds-uniform-array-access.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/uniforms/out-of-bounds-uniform-array-access.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/uniforms/uniform-default-values.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/uniforms/uniform-default-values.html | took 0ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/uniforms/uniform-location.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/uniforms/uniform-location.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/uniforms/uniform-samplers-test.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/uniforms/uniform-samplers-test.html | took 1ms 23:40:08 INFO - TEST-START | /webgl/conformance-1.0.3/conformance/uniforms/uniform-values-per-program.html 23:40:08 INFO - TEST-SKIP | /webgl/conformance-1.0.3/conformance/uniforms/uniform-values-per-program.html | took 1ms 23:40:08 INFO - TEST-START | /websockets/interfaces/WebSocket/extensions/001.html 23:40:08 INFO - TEST-SKIP | /websockets/interfaces/WebSocket/extensions/001.html | took 0ms 23:40:08 INFO - TEST-START | /websockets/interfaces/WebSocket/extensions/001.html?wss 23:40:08 INFO - TEST-SKIP | /websockets/interfaces/WebSocket/extensions/001.html?wss | took 1ms 23:40:08 INFO - TEST-START | /webstorage/storage_local_setitem_quotaexceedederr.html 23:40:08 INFO - TEST-SKIP | /webstorage/storage_local_setitem_quotaexceedederr.html | took 1ms 23:40:08 INFO - TEST-START | /webstorage/storage_session_setitem_quotaexceedederr.html 23:40:08 INFO - TEST-SKIP | /webstorage/storage_session_setitem_quotaexceedederr.html | took 0ms 23:40:08 INFO - Setting up ssl 23:40:09 INFO - PROCESS | certutil | 23:40:09 INFO - PROCESS | certutil | 23:40:09 INFO - PROCESS | certutil | 23:40:09 INFO - Certificate Nickname Trust Attributes 23:40:09 INFO - SSL,S/MIME,JAR/XPI 23:40:09 INFO - 23:40:09 INFO - web-platform-tests CT,, 23:40:09 INFO - 23:40:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmprPVSiV.mozrunner 23:40:09 INFO - Starting runner 23:40:13 INFO - PROCESS | 2317 | 1518766813311 Marionette INFO Listening on port 2828 23:40:21 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.basic.html 23:40:22 INFO - . 23:40:22 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.basic.html | took 1351ms 23:40:22 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.clip.html 23:40:24 INFO - . 23:40:24 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.clip.html | took 2058ms 23:40:24 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.globalalpha.html 23:40:26 INFO - . 23:40:26 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.globalalpha.html | took 1442ms 23:40:26 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.globalcomposite.html 23:40:27 INFO - . 23:40:27 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.globalcomposite.html | took 1258ms 23:40:27 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.negative.html 23:40:28 INFO - . 23:40:28 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.negative.html | took 1196ms 23:40:28 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.nonfinite.html 23:40:30 INFO - . 23:40:30 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.nonfinite.html | took 1244ms 23:40:30 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.path.html 23:40:31 INFO - . 23:40:31 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.path.html | took 1547ms 23:40:31 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.shadow.html 23:40:33 INFO - . 23:40:33 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.shadow.html | took 1492ms 23:40:33 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.transform.html 23:40:34 INFO - . 23:40:34 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.transform.html | took 1248ms 23:40:34 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.zero.html 23:40:35 INFO - . 23:40:35 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.clearRect.zero.html | took 1296ms 23:40:35 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.basic.html 23:40:37 INFO - . 23:40:37 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.basic.html | took 1333ms 23:40:37 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.clip.html 23:40:38 INFO - . 23:40:38 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.clip.html | took 1395ms 23:40:38 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.negative.html 23:40:40 INFO - . 23:40:40 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.negative.html | took 1439ms 23:40:40 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.nonfinite.html 23:40:41 INFO - . 23:40:41 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.nonfinite.html | took 1382ms 23:40:41 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.path.html 23:40:42 INFO - . 23:40:42 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.path.html | took 1185ms 23:40:42 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.shadow.html 23:40:44 INFO - . 23:40:44 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.shadow.html | took 1345ms 23:40:44 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.transform.html 23:40:45 INFO - . 23:40:45 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.transform.html | took 1293ms 23:40:45 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.zero.html 23:40:46 INFO - . 23:40:46 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.fillRect.zero.html | took 1195ms 23:40:46 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.basic.html 23:40:47 INFO - . 23:40:47 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.basic.html | took 1235ms 23:40:47 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.clip.html 23:40:49 INFO - . 23:40:49 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.clip.html | took 1181ms 23:40:49 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.globalalpha.html 23:40:50 INFO - . 23:40:50 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.globalalpha.html | took 1186ms 23:40:50 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.globalcomposite.html 23:40:51 INFO - . 23:40:51 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.globalcomposite.html | took 1400ms 23:40:51 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.negative.html 23:40:53 INFO - . 23:40:53 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.negative.html | took 1382ms 23:40:53 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.nonfinite.html 23:40:54 INFO - . 23:40:54 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.nonfinite.html | took 1453ms 23:40:54 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.path.html 23:40:55 INFO - . 23:40:55 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.path.html | took 1148ms 23:40:55 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.shadow.html 23:40:57 INFO - . 23:40:57 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.shadow.html | took 1249ms 23:40:57 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.transform.html 23:40:58 INFO - . 23:40:58 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.transform.html | took 1392ms 23:40:58 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.zero.1.html 23:40:59 INFO - . 23:40:59 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.zero.1.html | took 1189ms 23:40:59 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.zero.2.html 23:41:01 INFO - . 23:41:01 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.zero.2.html | took 1335ms 23:41:01 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.zero.3.html 23:41:02 INFO - . 23:41:02 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.zero.3.html | took 1745ms 23:41:02 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.zero.4.html 23:41:04 INFO - . 23:41:04 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.zero.4.html | took 1195ms 23:41:04 INFO - TEST-START | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.zero.5.html 23:41:05 INFO - . 23:41:05 INFO - TEST-OK | /2dcontext/drawing-rectangles-to-the-canvas/2d.strokeRect.zero.5.html | took 1396ms 23:41:05 INFO - TEST-START | /WebCryptoAPI/sign_verify/ecdsa.worker 23:41:08 INFO - ........................................................................................................................................................................ 23:41:08 INFO - TEST-OK | /WebCryptoAPI/sign_verify/ecdsa.worker | took 3073ms 23:41:08 INFO - TEST-START | /WebCryptoAPI/sign_verify/hmac.worker 23:41:10 INFO - ........................................ 23:41:10 INFO - TEST-OK | /WebCryptoAPI/sign_verify/hmac.worker | took 1801ms 23:41:10 INFO - TEST-START | /WebCryptoAPI/sign_verify/rsa_pkcs.worker 23:41:12 INFO - ............................................ 23:41:12 INFO - TEST-OK | /WebCryptoAPI/sign_verify/rsa_pkcs.worker | took 1667ms 23:41:12 INFO - TEST-START | /WebCryptoAPI/sign_verify/rsa_pss.worker 23:41:14 INFO - ........................................................................................ 23:41:14 INFO - TEST-OK | /WebCryptoAPI/sign_verify/rsa_pss.worker | took 2475ms 23:41:14 INFO - TEST-START | /WebCryptoAPI/sign_verify/test_ecdsa.html 23:41:15 INFO - PROCESS | 2317 | JavaScript warning: http://web-platform.test:8000/WebCryptoAPI/sign_verify/ecdsa.js, line 478: unreachable code after return statement 23:41:15 INFO - PROCESS | 2317 | JavaScript warning: http://web-platform.test:8000/WebCryptoAPI/sign_verify/ecdsa.js, line 478: unreachable code after return statement 23:41:17 INFO - ..................... 23:41:17 INFO - TEST-OK | /WebCryptoAPI/sign_verify/test_ecdsa.html | took 2837ms 23:41:17 INFO - TEST-START | /WebCryptoAPI/sign_verify/test_hmac.html 23:41:19 INFO - PROCESS | 2317 | JavaScript warning: http://web-platform.test:8000/WebCryptoAPI/sign_verify/hmac.js, line 346: unreachable code after return statement 23:41:19 INFO - PROCESS | 2317 | JavaScript warning: http://web-platform.test:8000/WebCryptoAPI/sign_verify/hmac.js, line 346: unreachable code after return statement 23:41:19 INFO - ........................................ 23:41:19 INFO - TEST-OK | /WebCryptoAPI/sign_verify/test_hmac.html | took 2062ms 23:41:19 INFO - TEST-START | /WebCryptoAPI/sign_verify/test_rsa_pkcs.html 23:41:21 INFO - ............................................ 23:41:21 INFO - TEST-OK | /WebCryptoAPI/sign_verify/test_rsa_pkcs.html | took 1627ms 23:41:21 INFO - TEST-START | /WebCryptoAPI/sign_verify/test_rsa_pss.html 23:41:23 INFO - ........................................................................................ 23:41:23 INFO - TEST-OK | /WebCryptoAPI/sign_verify/test_rsa_pss.html | took 2685ms 23:41:23 INFO - TEST-START | /battery-status/battery-interface-idlharness.html 23:41:25 INFO - . 23:41:25 INFO - TEST-OK | /battery-status/battery-interface-idlharness.html | took 1256ms 23:41:25 INFO - TEST-START | /content-security-policy/generic/generic-0_1-img-src.html 23:41:26 INFO - .. 23:41:26 INFO - TEST-OK | /content-security-policy/generic/generic-0_1-img-src.html | took 1584ms 23:41:26 INFO - TEST-START | /content-security-policy/generic/generic-0_1-script-src.html 23:41:28 INFO - ... 23:41:28 INFO - TEST-OK | /content-security-policy/generic/generic-0_1-script-src.html | took 1393ms 23:41:28 INFO - TEST-START | /content-security-policy/generic/generic-0_10.html 23:41:34 INFO - . 23:41:34 INFO - TEST-OK | /content-security-policy/generic/generic-0_10.html | took 5965ms 23:41:34 INFO - TEST-START | /content-security-policy/generic/generic-0_10_1.sub.html 23:41:35 INFO - .. 23:41:35 INFO - TEST-OK | /content-security-policy/generic/generic-0_10_1.sub.html | took 1535ms 23:41:35 INFO - TEST-START | /content-security-policy/generic/generic-0_2.html 23:41:41 INFO - .. 23:41:41 INFO - TEST-OK | /content-security-policy/generic/generic-0_2.html | took 6012ms 23:41:41 INFO - TEST-START | /content-security-policy/generic/generic-0_2_2.sub.html 23:41:43 INFO - .. 23:41:43 INFO - TEST-OK | /content-security-policy/generic/generic-0_2_2.sub.html | took 1493ms 23:41:43 INFO - TEST-START | /content-security-policy/generic/generic-0_2_3.html 23:41:44 INFO - .. 23:41:44 INFO - TEST-OK | /content-security-policy/generic/generic-0_2_3.html | took 1637ms 23:41:44 INFO - TEST-START | /content-security-policy/generic/generic-0_8.html 23:41:50 INFO - .. 23:41:50 INFO - TEST-OK | /content-security-policy/generic/generic-0_8.html | took 5998ms 23:41:50 INFO - TEST-START | /content-security-policy/generic/generic-0_8_1.sub.html 23:41:52 INFO - .. 23:41:52 INFO - TEST-OK | /content-security-policy/generic/generic-0_8_1.sub.html | took 1430ms 23:41:52 INFO - TEST-START | /content-security-policy/generic/generic-0_9.sub.html 23:41:58 INFO - .. 23:41:58 INFO - TEST-OK | /content-security-policy/generic/generic-0_9.sub.html | took 6009ms 23:41:58 INFO - TEST-START | /content-security-policy/generic/no-default-src.sub.html 23:42:04 INFO - .. 23:42:04 INFO - TEST-OK | /content-security-policy/generic/no-default-src.sub.html | took 6009ms 23:42:04 INFO - TEST-START | /cookies/secure/set-from-dom.https.sub.html 23:42:06 INFO - .. 23:42:06 INFO - TEST-OK | /cookies/secure/set-from-dom.https.sub.html | took 1697ms 23:42:06 INFO - TEST-START | /cookies/secure/set-from-dom.sub.html 23:42:07 INFO - .. 23:42:07 INFO - TEST-OK | /cookies/secure/set-from-dom.sub.html | took 1331ms 23:42:07 INFO - TEST-START | /cookies/secure/set-from-http.https.sub.html 23:42:09 INFO - .. 23:42:09 INFO - TEST-OK | /cookies/secure/set-from-http.https.sub.html | took 1650ms 23:42:09 INFO - TEST-START | /cookies/secure/set-from-http.sub.html 23:42:10 INFO - .. 23:42:10 INFO - TEST-OK | /cookies/secure/set-from-http.sub.html | took 1349ms 23:42:10 INFO - TEST-START | /cookies/secure/set-from-ws.https.sub.html 23:42:11 INFO - . 23:42:11 INFO - TEST-OK | /cookies/secure/set-from-ws.https.sub.html | took 1283ms 23:42:11 INFO - TEST-START | /cookies/secure/set-from-wss.https.sub.html 23:42:13 INFO - . 23:42:13 INFO - TEST-OK | /cookies/secure/set-from-wss.https.sub.html | took 1444ms 23:42:13 INFO - TEST-START | /dom/historical.html 23:42:14 INFO - ................................................ 23:42:14 INFO - TEST-OK | /dom/historical.html | took 1286ms 23:42:14 INFO - TEST-START | /dom/interface-objects.html 23:42:15 INFO - ..................... 23:42:15 INFO - TEST-OK | /dom/interface-objects.html | took 1048ms 23:42:15 INFO - TEST-START | /dom/interfaces.html 23:42:15 INFO - Setting pref dom.node.rootNode.enabled (true) 23:42:19 INFO - ......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................... 23:42:19 INFO - TEST-OK | /dom/interfaces.html | took 4026ms 23:42:19 INFO - TEST-START | /dom/nodes/CharacterData-appendChild.html 23:42:19 INFO - Clearing pref dom.node.rootNode.enabled 23:42:20 INFO - ......... 23:42:20 INFO - TEST-OK | /dom/nodes/CharacterData-appendChild.html | took 1048ms 23:42:20 INFO - TEST-START | /dom/nodes/CharacterData-appendData.html 23:42:21 INFO - .............. 23:42:21 INFO - TEST-OK | /dom/nodes/CharacterData-appendData.html | took 998ms 23:42:21 INFO - TEST-START | /dom/nodes/CharacterData-data.html 23:42:22 INFO - ................ 23:42:22 INFO - TEST-OK | /dom/nodes/CharacterData-data.html | took 989ms 23:42:22 INFO - TEST-START | /dom/nodes/CharacterData-deleteData.html 23:42:23 INFO - .................. 23:42:23 INFO - TEST-OK | /dom/nodes/CharacterData-deleteData.html | took 984ms 23:42:23 INFO - TEST-START | /dom/nodes/CharacterData-insertData.html 23:42:24 INFO - .................. 23:42:24 INFO - TEST-OK | /dom/nodes/CharacterData-insertData.html | took 884ms 23:42:24 INFO - TEST-START | /dom/nodes/CharacterData-remove.html 23:42:25 INFO - ............ 23:42:25 INFO - TEST-OK | /dom/nodes/CharacterData-remove.html | took 893ms 23:42:25 INFO - TEST-START | /dom/nodes/CharacterData-replaceData.html 23:42:26 INFO - .................................. 23:42:26 INFO - TEST-OK | /dom/nodes/CharacterData-replaceData.html | took 856ms 23:42:26 INFO - TEST-START | /dom/nodes/CharacterData-substringData.html 23:42:27 INFO - ............................ 23:42:27 INFO - TEST-OK | /dom/nodes/CharacterData-substringData.html | took 1048ms 23:42:27 INFO - TEST-START | /dom/nodes/CharacterData-surrogates.html 23:42:28 INFO - ........ 23:42:28 INFO - TEST-OK | /dom/nodes/CharacterData-surrogates.html | took 1035ms 23:42:28 INFO - TEST-START | /dom/nodes/ChildNode-after.html 23:42:29 INFO - ............................................. 23:42:29 INFO - TEST-OK | /dom/nodes/ChildNode-after.html | took 964ms 23:42:29 INFO - TEST-START | /dom/nodes/ChildNode-before.html 23:42:30 INFO - ............................................. 23:42:30 INFO - TEST-OK | /dom/nodes/ChildNode-before.html | took 903ms 23:42:30 INFO - TEST-START | /dom/nodes/ChildNode-replaceWith.html 23:42:31 INFO - ................................. 23:42:31 INFO - TEST-OK | /dom/nodes/ChildNode-replaceWith.html | took 950ms 23:42:31 INFO - TEST-START | /dom/nodes/Comment-constructor.html 23:42:32 INFO - ................ 23:42:32 INFO - TEST-OK | /dom/nodes/Comment-constructor.html | took 1139ms 23:42:32 INFO - TEST-START | /dom/nodes/DOMImplementation-createDocument.html 23:42:34 INFO - ........................................................................................................................................................................................................................................................................................................................................................................................................... 23:42:34 INFO - TEST-OK | /dom/nodes/DOMImplementation-createDocument.html | took 1880ms 23:42:34 INFO - TEST-START | /dom/nodes/DOMImplementation-createDocumentType.html 23:42:35 INFO - .................................................................................. 23:42:35 INFO - TEST-OK | /dom/nodes/DOMImplementation-createDocumentType.html | took 1306ms 23:42:35 INFO - TEST-START | /dom/nodes/DOMImplementation-createHTMLDocument.html 23:42:36 INFO - ............ 23:42:36 INFO - TEST-OK | /dom/nodes/DOMImplementation-createHTMLDocument.html | took 1130ms 23:42:36 INFO - TEST-START | /dom/nodes/DOMImplementation-hasFeature.html 23:42:37 INFO - ......................................................................................................................................... 23:42:37 INFO - TEST-OK | /dom/nodes/DOMImplementation-hasFeature.html | took 1175ms 23:42:37 INFO - TEST-START | /dom/nodes/Document-URL.sub.html 23:42:38 INFO - . 23:42:38 INFO - TEST-OK | /dom/nodes/Document-URL.sub.html | took 1032ms 23:42:38 INFO - TEST-START | /dom/nodes/Document-adoptNode.html 23:42:39 INFO - .... 23:42:39 INFO - TEST-OK | /dom/nodes/Document-adoptNode.html | took 994ms 23:42:39 INFO - TEST-START | /dom/nodes/Document-characterSet-normalization.html 23:42:48 INFO - .............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................. 23:42:48 INFO - TEST-OK | /dom/nodes/Document-characterSet-normalization.html | took 8228ms 23:42:48 INFO - TEST-START | /dom/nodes/Document-constructor.html 23:42:49 INFO - ..... 23:42:49 INFO - TEST-OK | /dom/nodes/Document-constructor.html | took 929ms 23:42:49 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_css.html 23:42:50 INFO - . 23:42:50 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_css.html | took 996ms 23:42:50 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_datauri_01.html 23:42:51 INFO - . 23:42:51 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_datauri_01.html | took 1142ms 23:42:51 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_datauri_02.html 23:42:52 INFO - . 23:42:52 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_datauri_02.html | took 982ms 23:42:52 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_gif.html 23:42:53 INFO - . 23:42:53 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_gif.html | took 995ms 23:42:53 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_html.html 23:42:54 INFO - . 23:42:54 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_html.html | took 1105ms 23:42:54 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_javascripturi.html 23:42:55 INFO - . 23:42:55 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_javascripturi.html | took 1198ms 23:42:55 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_jpg.html 23:42:56 INFO - . 23:42:56 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_jpg.html | took 1146ms 23:42:56 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_mimeheader_01.html 23:42:57 INFO - . 23:42:57 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_mimeheader_01.html | took 1040ms 23:42:57 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_mimeheader_02.html 23:42:58 INFO - . 23:42:58 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_mimeheader_02.html | took 1029ms 23:42:58 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_png.html 23:43:00 INFO - . 23:43:00 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_png.html | took 1249ms 23:43:00 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_txt.html 23:43:01 INFO - . 23:43:01 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_txt.html | took 1543ms 23:43:01 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/contenttype_xml.html 23:43:02 INFO - . 23:43:02 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/contenttype_xml.html | took 1149ms 23:43:02 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/createDocument.html 23:43:03 INFO - . 23:43:03 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/createDocument.html | took 945ms 23:43:03 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/createHTMLDocument.html 23:43:04 INFO - . 23:43:04 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/createHTMLDocument.html | took 1033ms 23:43:04 INFO - TEST-START | /dom/nodes/Document-contentType/contentType/xhr_responseType_document.html 23:43:06 INFO - . 23:43:06 INFO - TEST-OK | /dom/nodes/Document-contentType/contentType/xhr_responseType_document.html | took 1450ms 23:43:06 INFO - TEST-START | /dom/nodes/Document-createAttribute.html 23:43:07 INFO - ...................... 23:43:07 INFO - TEST-OK | /dom/nodes/Document-createAttribute.html | took 984ms 23:43:07 INFO - TEST-START | /dom/nodes/Document-createComment.html 23:43:08 INFO - ...... 23:43:08 INFO - TEST-OK | /dom/nodes/Document-createComment.html | took 1146ms 23:43:08 INFO - TEST-START | /dom/nodes/Document-createElement-namespace.html 23:43:13 INFO - ................................................... 23:43:13 INFO - TEST-OK | /dom/nodes/Document-createElement-namespace.html | took 4844ms 23:43:13 INFO - TEST-START | /dom/nodes/Document-createElement.html 23:43:15 INFO - ................................................................................................................................................... 23:43:15 INFO - TEST-OK | /dom/nodes/Document-createElement.html | took 2321ms 23:43:15 INFO - TEST-START | /dom/nodes/Document-createElementNS.html 23:43:18 INFO - .............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................. 23:43:18 INFO - TEST-OK | /dom/nodes/Document-createElementNS.html | took 3336ms 23:43:19 INFO - TEST-START | /dom/nodes/Document-createEvent.html 23:43:21 INFO - .......................................................................................................................................................................................................................................................................................................................................................... 23:43:21 INFO - TEST-OK | /dom/nodes/Document-createEvent.html | took 2019ms 23:43:21 INFO - TEST-START | /dom/nodes/Document-createProcessingInstruction-xhtml.xhtml 23:43:22 INFO - ............ 23:43:22 INFO - TEST-OK | /dom/nodes/Document-createProcessingInstruction-xhtml.xhtml | took 1140ms 23:43:22 INFO - TEST-START | /dom/nodes/Document-createProcessingInstruction.html 23:43:23 INFO - ............ 23:43:23 INFO - TEST-OK | /dom/nodes/Document-createProcessingInstruction.html | took 1035ms 23:43:23 INFO - TEST-START | /dom/nodes/Document-createTextNode.html 23:43:24 INFO - ...... 23:43:24 INFO - TEST-OK | /dom/nodes/Document-createTextNode.html | took 1091ms 23:43:24 INFO - TEST-START | /dom/nodes/Document-createTreeWalker.html 23:43:25 INFO - ..... 23:43:25 INFO - TEST-OK | /dom/nodes/Document-createTreeWalker.html | took 1241ms 23:43:25 INFO - TEST-START | /dom/nodes/Document-doctype.html 23:43:26 INFO - .. 23:43:26 INFO - TEST-OK | /dom/nodes/Document-doctype.html | took 1003ms 23:43:26 INFO - TEST-START | /dom/nodes/Document-getElementById.html 23:43:27 INFO - .................. 23:43:27 INFO - TEST-OK | /dom/nodes/Document-getElementById.html | took 950ms 23:43:27 INFO - TEST-START | /dom/nodes/Document-getElementsByTagName-xhtml.xhtml 23:43:28 INFO - .......... 23:43:28 INFO - TEST-OK | /dom/nodes/Document-getElementsByTagName-xhtml.xhtml | took 932ms 23:43:28 INFO - TEST-START | /dom/nodes/Document-getElementsByTagName.html 23:43:29 INFO - ................. 23:43:29 INFO - TEST-OK | /dom/nodes/Document-getElementsByTagName.html | took 1097ms 23:43:29 INFO - TEST-START | /dom/nodes/Document-getElementsByTagNameNS.html 23:43:30 INFO - ............. 23:43:30 INFO - TEST-OK | /dom/nodes/Document-getElementsByTagNameNS.html | took 1049ms 23:43:30 INFO - TEST-START | /dom/nodes/Document-implementation.html 23:43:31 INFO - .. 23:43:31 INFO - TEST-OK | /dom/nodes/Document-implementation.html | took 931ms 23:43:31 INFO - TEST-START | /dom/nodes/Document-importNode.html 23:43:33 INFO - .... 23:43:33 INFO - TEST-OK | /dom/nodes/Document-importNode.html | took 1325ms 23:43:33 INFO - TEST-START | /dom/nodes/DocumentType-literal-xhtml.xhtml 23:43:34 INFO - . 23:43:34 INFO - TEST-OK | /dom/nodes/DocumentType-literal-xhtml.xhtml | took 1095ms 23:43:34 INFO - TEST-START | /dom/nodes/DocumentType-literal.html 23:43:35 INFO - . 23:43:35 INFO - TEST-OK | /dom/nodes/DocumentType-literal.html | took 928ms 23:43:35 INFO - TEST-START | /dom/nodes/DocumentType-remove.html 23:43:36 INFO - .... 23:43:36 INFO - TEST-OK | /dom/nodes/DocumentType-remove.html | took 940ms 23:43:36 INFO - TEST-START | /dom/nodes/Element-childElement-null-svg.svg 23:43:36 INFO - . 23:43:36 INFO - TEST-OK | /dom/nodes/Element-childElement-null-svg.svg | took 925ms 23:43:36 INFO - TEST-START | /dom/nodes/Element-childElement-null-xhtml.xhtml 23:43:38 INFO - . 23:43:38 INFO - TEST-OK | /dom/nodes/Element-childElement-null-xhtml.xhtml | took 1188ms 23:43:38 INFO - TEST-START | /dom/nodes/Element-childElement-null.html 23:43:39 INFO - . 23:43:39 INFO - TEST-OK | /dom/nodes/Element-childElement-null.html | took 1201ms 23:43:39 INFO - TEST-START | /dom/nodes/Element-childElementCount-dynamic-add-svg.svg 23:43:40 INFO - . 23:43:40 INFO - TEST-OK | /dom/nodes/Element-childElementCount-dynamic-add-svg.svg | took 1395ms 23:43:40 INFO - TEST-START | /dom/nodes/Element-childElementCount-dynamic-add-xhtml.xhtml 23:43:42 INFO - . 23:43:42 INFO - TEST-OK | /dom/nodes/Element-childElementCount-dynamic-add-xhtml.xhtml | took 1182ms 23:43:42 INFO - TEST-START | /dom/nodes/Element-childElementCount-dynamic-add.html 23:43:43 INFO - . 23:43:43 INFO - TEST-OK | /dom/nodes/Element-childElementCount-dynamic-add.html | took 1031ms 23:43:43 INFO - TEST-START | /dom/nodes/Element-childElementCount-dynamic-remove-svg.svg 23:43:44 INFO - . 23:43:44 INFO - TEST-OK | /dom/nodes/Element-childElementCount-dynamic-remove-svg.svg | took 1133ms 23:43:44 INFO - TEST-START | /dom/nodes/Element-childElementCount-dynamic-remove-xhtml.xhtml 23:43:45 INFO - . 23:43:45 INFO - TEST-OK | /dom/nodes/Element-childElementCount-dynamic-remove-xhtml.xhtml | took 1042ms 23:43:45 INFO - TEST-START | /dom/nodes/Element-childElementCount-dynamic-remove.html 23:43:46 INFO - . 23:43:46 INFO - TEST-OK | /dom/nodes/Element-childElementCount-dynamic-remove.html | took 1276ms 23:43:46 INFO - TEST-START | /dom/nodes/Element-childElementCount-nochild-svg.svg 23:43:48 INFO - . 23:43:48 INFO - TEST-OK | /dom/nodes/Element-childElementCount-nochild-svg.svg | took 1394ms 23:43:48 INFO - TEST-START | /dom/nodes/Element-childElementCount-nochild-xhtml.xhtml 23:43:49 INFO - . 23:43:49 INFO - TEST-OK | /dom/nodes/Element-childElementCount-nochild-xhtml.xhtml | took 1192ms 23:43:49 INFO - TEST-START | /dom/nodes/Element-childElementCount-nochild.html 23:43:50 INFO - . 23:43:50 INFO - TEST-OK | /dom/nodes/Element-childElementCount-nochild.html | took 1135ms 23:43:50 INFO - TEST-START | /dom/nodes/Element-childElementCount-svg.svg 23:43:51 INFO - . 23:43:51 INFO - TEST-OK | /dom/nodes/Element-childElementCount-svg.svg | took 1185ms 23:43:51 INFO - TEST-START | /dom/nodes/Element-childElementCount-xhtml.xhtml 23:43:52 INFO - . 23:43:52 INFO - TEST-OK | /dom/nodes/Element-childElementCount-xhtml.xhtml | took 940ms 23:43:52 INFO - TEST-START | /dom/nodes/Element-childElementCount.html 23:43:53 INFO - . 23:43:53 INFO - TEST-OK | /dom/nodes/Element-childElementCount.html | took 1203ms 23:43:53 INFO - TEST-START | /dom/nodes/Element-children.html 23:43:55 INFO - .. 23:43:55 INFO - TEST-OK | /dom/nodes/Element-children.html | took 1391ms 23:43:55 INFO - TEST-START | /dom/nodes/Element-classlist.html 23:43:56 INFO - .............................................................................. 23:43:56 INFO - TEST-OK | /dom/nodes/Element-classlist.html | took 1233ms 23:43:56 INFO - TEST-START | /dom/nodes/Element-closest.html 23:43:57 INFO - ............................. 23:43:57 INFO - TEST-OK | /dom/nodes/Element-closest.html | took 1078ms 23:43:57 INFO - TEST-START | /dom/nodes/Element-firstElementChild-entity-xhtml.xhtml 23:43:58 INFO - . 23:43:58 INFO - TEST-OK | /dom/nodes/Element-firstElementChild-entity-xhtml.xhtml | took 1089ms 23:43:58 INFO - TEST-START | /dom/nodes/Element-firstElementChild-entity.svg 23:43:59 INFO - . 23:43:59 INFO - TEST-OK | /dom/nodes/Element-firstElementChild-entity.svg | took 1051ms 23:43:59 INFO - TEST-START | /dom/nodes/Element-firstElementChild-namespace-svg.svg 23:44:00 INFO - . 23:44:00 INFO - TEST-OK | /dom/nodes/Element-firstElementChild-namespace-svg.svg | took 1044ms 23:44:00 INFO - TEST-START | /dom/nodes/Element-firstElementChild-namespace-xhtml.xhtml 23:44:02 INFO - . 23:44:02 INFO - TEST-OK | /dom/nodes/Element-firstElementChild-namespace-xhtml.xhtml | took 1534ms 23:44:02 INFO - TEST-START | /dom/nodes/Element-firstElementChild-namespace.html 23:44:03 INFO - . 23:44:03 INFO - TEST-OK | /dom/nodes/Element-firstElementChild-namespace.html | took 1233ms 23:44:03 INFO - TEST-START | /dom/nodes/Element-firstElementChild-svg.svg 23:44:04 INFO - . 23:44:04 INFO - TEST-OK | /dom/nodes/Element-firstElementChild-svg.svg | took 1104ms 23:44:04 INFO - TEST-START | /dom/nodes/Element-firstElementChild-xhtml.xhtml 23:44:05 INFO - . 23:44:05 INFO - TEST-OK | /dom/nodes/Element-firstElementChild-xhtml.xhtml | took 1136ms 23:44:05 INFO - TEST-START | /dom/nodes/Element-firstElementChild.html 23:44:07 INFO - . 23:44:07 INFO - TEST-OK | /dom/nodes/Element-firstElementChild.html | took 1077ms 23:44:07 INFO - TEST-START | /dom/nodes/Element-getElementsByClassName.html 23:44:07 INFO - .. 23:44:07 INFO - TEST-OK | /dom/nodes/Element-getElementsByClassName.html | took 886ms 23:44:07 INFO - TEST-START | /dom/nodes/Element-getElementsByTagName-change-document-HTMLNess.html 23:44:09 INFO - . 23:44:09 INFO - TEST-OK | /dom/nodes/Element-getElementsByTagName-change-document-HTMLNess.html | took 1382ms 23:44:09 INFO - TEST-START | /dom/nodes/Element-getElementsByTagName.html 23:44:10 INFO - .................. 23:44:10 INFO - TEST-OK | /dom/nodes/Element-getElementsByTagName.html | took 1197ms 23:44:10 INFO - TEST-START | /dom/nodes/Element-getElementsByTagNameNS.html 23:44:11 INFO - ............... 23:44:11 INFO - TEST-OK | /dom/nodes/Element-getElementsByTagNameNS.html | took 1257ms 23:44:11 INFO - TEST-START | /dom/nodes/Element-hasAttributes.html 23:44:12 INFO - .. 23:44:12 INFO - TEST-OK | /dom/nodes/Element-hasAttributes.html | took 928ms 23:44:12 INFO - TEST-START | /dom/nodes/Element-insertAdjacentElement.html 23:44:13 INFO - ...... 23:44:13 INFO - TEST-OK | /dom/nodes/Element-insertAdjacentElement.html | took 976ms 23:44:13 INFO - TEST-START | /dom/nodes/Element-insertAdjacentText.html 23:44:14 INFO - ...... 23:44:14 INFO - TEST-OK | /dom/nodes/Element-insertAdjacentText.html | took 977ms 23:44:14 INFO - TEST-START | /dom/nodes/Element-lastElementChild-svg.svg 23:44:16 INFO - . 23:44:16 INFO - TEST-OK | /dom/nodes/Element-lastElementChild-svg.svg | took 1581ms 23:44:16 INFO - TEST-START | /dom/nodes/Element-lastElementChild-xhtml.xhtml 23:44:17 INFO - . 23:44:17 INFO - TEST-OK | /dom/nodes/Element-lastElementChild-xhtml.xhtml | took 992ms 23:44:17 INFO - TEST-START | /dom/nodes/Element-lastElementChild.html 23:44:18 INFO - . 23:44:18 INFO - TEST-OK | /dom/nodes/Element-lastElementChild.html | took 1251ms 23:44:18 INFO - TEST-START | /dom/nodes/Element-matches.html 23:44:21 INFO - .......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................... 23:44:21 INFO - TEST-OK | /dom/nodes/Element-matches.html | took 2643ms 23:44:21 INFO - TEST-START | /dom/nodes/Element-nextElementSibling-svg.svg 23:44:22 INFO - . 23:44:22 INFO - TEST-OK | /dom/nodes/Element-nextElementSibling-svg.svg | took 1127ms 23:44:22 INFO - TEST-START | /dom/nodes/Element-nextElementSibling-xhtml.xhtml 23:44:23 INFO - . 23:44:23 INFO - TEST-OK | /dom/nodes/Element-nextElementSibling-xhtml.xhtml | took 1287ms 23:44:23 INFO - TEST-START | /dom/nodes/Element-nextElementSibling.html 23:44:25 INFO - . 23:44:25 INFO - TEST-OK | /dom/nodes/Element-nextElementSibling.html | took 1150ms 23:44:25 INFO - TEST-START | /dom/nodes/Element-previousElementSibling-svg.svg 23:44:25 INFO - . 23:44:25 INFO - TEST-OK | /dom/nodes/Element-previousElementSibling-svg.svg | took 935ms 23:44:25 INFO - TEST-START | /dom/nodes/Element-previousElementSibling-xhtml.xhtml 23:44:27 INFO - . 23:44:27 INFO - TEST-OK | /dom/nodes/Element-previousElementSibling-xhtml.xhtml | took 1123ms 23:44:27 INFO - TEST-START | /dom/nodes/Element-previousElementSibling.html 23:44:28 INFO - . 23:44:28 INFO - TEST-OK | /dom/nodes/Element-previousElementSibling.html | took 1125ms 23:44:28 INFO - TEST-START | /dom/nodes/Element-remove.html 23:44:29 INFO - .... 23:44:29 INFO - TEST-OK | /dom/nodes/Element-remove.html | took 1441ms 23:44:29 INFO - TEST-START | /dom/nodes/Element-removeAttributeNS.html 23:44:30 INFO - . 23:44:30 INFO - TEST-OK | /dom/nodes/Element-removeAttributeNS.html | took 1283ms 23:44:30 INFO - TEST-START | /dom/nodes/Element-siblingElement-null-svg.svg 23:44:32 INFO - . 23:44:32 INFO - TEST-OK | /dom/nodes/Element-siblingElement-null-svg.svg | took 1232ms 23:44:32 INFO - TEST-START | /dom/nodes/Element-siblingElement-null-xhtml.xhtml 23:44:33 INFO - . 23:44:33 INFO - TEST-OK | /dom/nodes/Element-siblingElement-null-xhtml.xhtml | took 1287ms 23:44:33 INFO - TEST-START | /dom/nodes/Element-siblingElement-null.html 23:44:34 INFO - . 23:44:34 INFO - TEST-OK | /dom/nodes/Element-siblingElement-null.html | took 1283ms 23:44:34 INFO - TEST-START | /dom/nodes/Element-tagName.html 23:44:35 INFO - ..... 23:44:35 INFO - TEST-OK | /dom/nodes/Element-tagName.html | took 1085ms 23:44:35 INFO - TEST-START | /dom/nodes/MutationObserver-attributes.html 23:44:37 INFO - .......................................... 23:44:37 INFO - TEST-OK | /dom/nodes/MutationObserver-attributes.html | took 1336ms 23:44:37 INFO - TEST-START | /dom/nodes/MutationObserver-characterData.html 23:44:38 INFO - ....................... 23:44:38 INFO - TEST-OK | /dom/nodes/MutationObserver-characterData.html | took 1117ms 23:44:38 INFO - TEST-START | /dom/nodes/MutationObserver-childList.html 23:44:39 INFO - ...................................... 23:44:39 INFO - TEST-OK | /dom/nodes/MutationObserver-childList.html | took 1260ms 23:44:39 INFO - TEST-START | /dom/nodes/MutationObserver-disconnect.html 23:44:40 INFO - .. 23:44:40 INFO - TEST-OK | /dom/nodes/MutationObserver-disconnect.html | took 1094ms 23:44:40 INFO - TEST-START | /dom/nodes/MutationObserver-document.html 23:44:42 INFO - .... 23:44:42 INFO - TEST-OK | /dom/nodes/MutationObserver-document.html | took 1294ms 23:44:42 INFO - TEST-START | /dom/nodes/MutationObserver-inner-outer.html 23:44:43 INFO - ... 23:44:43 INFO - TEST-OK | /dom/nodes/MutationObserver-inner-outer.html | took 1099ms 23:44:43 INFO - TEST-START | /dom/nodes/MutationObserver-takeRecords.html 23:44:44 INFO - ... 23:44:44 INFO - TEST-OK | /dom/nodes/MutationObserver-takeRecords.html | took 1345ms 23:44:44 INFO - TEST-START | /dom/nodes/Node-appendChild.html 23:44:45 INFO - ........... 23:44:45 INFO - TEST-OK | /dom/nodes/Node-appendChild.html | took 1183ms 23:44:45 INFO - TEST-START | /dom/nodes/Node-baseURI.html 23:44:46 INFO - .... 23:44:46 INFO - TEST-OK | /dom/nodes/Node-baseURI.html | took 977ms 23:44:46 INFO - TEST-START | /dom/nodes/Node-childNodes.html 23:44:47 INFO - ..... 23:44:47 INFO - TEST-OK | /dom/nodes/Node-childNodes.html | took 883ms 23:44:47 INFO - TEST-START | /dom/nodes/Node-cloneNode.html 23:44:48 INFO - ........................................ 23:44:48 INFO - TEST-OK | /dom/nodes/Node-cloneNode.html | took 1255ms 23:44:48 INFO - TEST-START | /dom/nodes/Node-compareDocumentPosition.html 23:44:52 INFO - ......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................... 23:44:52 INFO - TEST-OK | /dom/nodes/Node-compareDocumentPosition.html | took 3319ms 23:44:52 INFO - TEST-START | /dom/nodes/Node-constants.html 23:44:53 INFO - ........ 23:44:53 INFO - TEST-OK | /dom/nodes/Node-constants.html | took 1133ms 23:44:53 INFO - TEST-START | /dom/nodes/Node-contains-xml.xml 23:44:54 INFO - ........ 23:44:54 INFO - TEST-OK | /dom/nodes/Node-contains-xml.xml | took 1153ms 23:44:54 INFO - TEST-START | /dom/nodes/Node-contains.html 23:44:57 INFO - .............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................. 23:44:57 INFO - TEST-OK | /dom/nodes/Node-contains.html | took 2851ms 23:44:57 INFO - TEST-START | /dom/nodes/Node-insertBefore.html 23:44:58 INFO - .......................... 23:44:58 INFO - TEST-OK | /dom/nodes/Node-insertBefore.html | took 1103ms 23:44:58 INFO - TEST-START | /dom/nodes/Node-isConnected.html 23:44:59 INFO - .. 23:44:59 INFO - TEST-OK | /dom/nodes/Node-isConnected.html | took 999ms 23:44:59 INFO - TEST-START | /dom/nodes/Node-isEqualNode-xhtml.xhtml 23:45:01 INFO - .............. 23:45:01 INFO - TEST-OK | /dom/nodes/Node-isEqualNode-xhtml.xhtml | took 1403ms 23:45:01 INFO - TEST-START | /dom/nodes/Node-isEqualNode.html 23:45:02 INFO - ......... 23:45:02 INFO - TEST-OK | /dom/nodes/Node-isEqualNode.html | took 1046ms 23:45:02 INFO - TEST-START | /dom/nodes/Node-isSameNode.html 23:45:03 INFO - ........ 23:45:03 INFO - TEST-OK | /dom/nodes/Node-isSameNode.html | took 1024ms 23:45:03 INFO - TEST-START | /dom/nodes/Node-lookupNamespaceURI.html 23:45:04 INFO - .......................................................... 23:45:04 INFO - TEST-OK | /dom/nodes/Node-lookupNamespaceURI.html | took 1619ms 23:45:04 INFO - TEST-START | /dom/nodes/Node-lookupPrefix.xhtml 23:45:05 INFO - ........... 23:45:05 INFO - TEST-OK | /dom/nodes/Node-lookupPrefix.xhtml | took 1202ms 23:45:05 INFO - TEST-START | /dom/nodes/Node-nodeName-xhtml.xhtml 23:45:06 INFO - ....... 23:45:06 INFO - TEST-OK | /dom/nodes/Node-nodeName-xhtml.xhtml | took 986ms 23:45:06 INFO - TEST-START | /dom/nodes/Node-nodeName.html 23:45:07 INFO - ...... 23:45:07 INFO - TEST-OK | /dom/nodes/Node-nodeName.html | took 931ms 23:45:07 INFO - TEST-START | /dom/nodes/Node-nodeValue.html 23:45:08 INFO - ....... 23:45:08 INFO - TEST-OK | /dom/nodes/Node-nodeValue.html | took 990ms 23:45:08 INFO - TEST-START | /dom/nodes/Node-normalize.html 23:45:10 INFO - ... 23:45:10 INFO - TEST-OK | /dom/nodes/Node-normalize.html | took 1436ms 23:45:10 INFO - TEST-START | /dom/nodes/Node-parentElement.html 23:45:11 INFO - ............ 23:45:11 INFO - TEST-OK | /dom/nodes/Node-parentElement.html | took 1446ms 23:45:11 INFO - TEST-START | /dom/nodes/Node-parentNode.html 23:45:12 INFO - ..... 23:45:12 INFO - TEST-OK | /dom/nodes/Node-parentNode.html | took 1031ms 23:45:12 INFO - TEST-START | /dom/nodes/Node-properties.html 23:45:14 INFO - ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................... 23:45:14 INFO - TEST-OK | /dom/nodes/Node-properties.html | took 2131ms 23:45:15 INFO - TEST-START | /dom/nodes/Node-removeChild.html 23:45:16 INFO - ............................ 23:45:16 INFO - TEST-OK | /dom/nodes/Node-removeChild.html | took 1347ms 23:45:16 INFO - TEST-START | /dom/nodes/Node-replaceChild.html 23:45:17 INFO - ........................ 23:45:17 INFO - TEST-OK | /dom/nodes/Node-replaceChild.html | took 1094ms 23:45:17 INFO - TEST-START | /dom/nodes/Node-textContent.html 23:45:19 INFO - ................................................................................. 23:45:19 INFO - TEST-OK | /dom/nodes/Node-textContent.html | took 1500ms 23:45:19 INFO - TEST-START | /dom/nodes/NodeList-Iterable.html 23:45:20 INFO - ...... 23:45:20 INFO - TEST-OK | /dom/nodes/NodeList-Iterable.html | took 991ms 23:45:20 INFO - TEST-START | /dom/nodes/ParentNode-append.html 23:45:20 INFO - .............. 23:45:20 INFO - TEST-OK | /dom/nodes/ParentNode-append.html | took 945ms 23:45:21 INFO - TEST-START | /dom/nodes/ParentNode-prepend.html 23:45:21 INFO - .............. 23:45:21 INFO - TEST-OK | /dom/nodes/ParentNode-prepend.html | took 978ms 23:45:21 INFO - TEST-START | /dom/nodes/ParentNode-querySelector-All-xht.xht 23:45:25 INFO - ....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................... 23:45:25 INFO - TEST-OK | /dom/nodes/ParentNode-querySelector-All-xht.xht | took 3916ms 23:45:25 INFO - TEST-START | /dom/nodes/ParentNode-querySelector-All.html 23:45:30 INFO - ....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................... 23:45:30 INFO - TEST-OK | /dom/nodes/ParentNode-querySelector-All.html | took 4091ms 23:45:30 INFO - TEST-START | /dom/nodes/ProcessingInstruction-escapes-1.xhtml 23:45:31 INFO - . 23:45:31 INFO - TEST-OK | /dom/nodes/ProcessingInstruction-escapes-1.xhtml | took 1137ms 23:45:31 INFO - TEST-START | /dom/nodes/ProcessingInstruction-literal-1.xhtml 23:45:32 INFO - . 23:45:32 INFO - TEST-OK | /dom/nodes/ProcessingInstruction-literal-1.xhtml | took 1489ms 23:45:32 INFO - TEST-START | /dom/nodes/ProcessingInstruction-literal-2.xhtml 23:45:33 INFO - . 23:45:33 INFO - TEST-OK | /dom/nodes/ProcessingInstruction-literal-2.xhtml | took 1031ms 23:45:33 INFO - TEST-START | /dom/nodes/Text-constructor.html 23:45:34 INFO - ................ 23:45:34 INFO - TEST-OK | /dom/nodes/Text-constructor.html | took 1240ms 23:45:35 INFO - TEST-START | /dom/nodes/Text-splitText.html 23:45:36 INFO - ...... 23:45:36 INFO - TEST-OK | /dom/nodes/Text-splitText.html | took 1044ms 23:45:36 INFO - TEST-START | /dom/nodes/append-on-Document.html 23:45:37 INFO - ..... 23:45:37 INFO - TEST-OK | /dom/nodes/append-on-Document.html | took 900ms 23:45:37 INFO - TEST-START | /dom/nodes/attributes.html 23:45:38 INFO - ........................................................ 23:45:38 INFO - TEST-OK | /dom/nodes/attributes.html | took 1316ms 23:45:38 INFO - TEST-START | /dom/nodes/case.html 23:45:39 INFO - PROCESS | 2317 | JavaScript warning: http://web-platform.test:8000/dom/nodes/case.js, line 168: unreachable code after return statement 23:45:39 INFO - PROCESS | 2317 | JavaScript warning: http://web-platform.test:8000/dom/nodes/case.js, line 168: unreachable code after return statement 23:45:39 INFO - ............................................................................................................................................................................................................................................................................................. 23:45:39 INFO - TEST-OK | /dom/nodes/case.html | took 1473ms 23:45:39 INFO - TEST-START | /dom/nodes/getElementsByClassName-01.htm 23:45:40 INFO - . 23:45:40 INFO - TEST-OK | /dom/nodes/getElementsByClassName-01.htm | took 975ms 23:45:40 INFO - TEST-START | /dom/nodes/getElementsByClassName-02.htm 23:45:41 INFO - . 23:45:41 INFO - TEST-OK | /dom/nodes/getElementsByClassName-02.htm | took 923ms 23:45:41 INFO - TEST-START | /dom/nodes/getElementsByClassName-03.htm 23:45:42 INFO - . 23:45:42 INFO - TEST-OK | /dom/nodes/getElementsByClassName-03.htm | took 831ms 23:45:42 INFO - TEST-START | /dom/nodes/getElementsByClassName-04.htm 23:45:43 INFO - . 23:45:43 INFO - TEST-OK | /dom/nodes/getElementsByClassName-04.htm | took 923ms 23:45:43 INFO - TEST-START | /dom/nodes/getElementsByClassName-05.htm 23:45:44 INFO - . 23:45:44 INFO - TEST-OK | /dom/nodes/getElementsByClassName-05.htm | took 976ms 23:45:44 INFO - TEST-START | /dom/nodes/getElementsByClassName-06.htm 23:45:45 INFO - . 23:45:45 INFO - TEST-OK | /dom/nodes/getElementsByClassName-06.htm | took 879ms 23:45:45 INFO - TEST-START | /dom/nodes/getElementsByClassName-07.htm 23:45:46 INFO - . 23:45:46 INFO - TEST-OK | /dom/nodes/getElementsByClassName-07.htm | took 837ms 23:45:46 INFO - TEST-START | /dom/nodes/getElementsByClassName-08.htm 23:45:47 INFO - . 23:45:47 INFO - TEST-OK | /dom/nodes/getElementsByClassName-08.htm | took 930ms 23:45:47 INFO - TEST-START | /dom/nodes/getElementsByClassName-09.htm 23:45:48 INFO - . 23:45:48 INFO - TEST-OK | /dom/nodes/getElementsByClassName-09.htm | took 835ms 23:45:48 INFO - TEST-START | /dom/nodes/getElementsByClassName-10.xml 23:45:48 INFO - . 23:45:48 INFO - TEST-OK | /dom/nodes/getElementsByClassName-10.xml | took 878ms 23:45:48 INFO - TEST-START | /dom/nodes/getElementsByClassName-11.xml 23:45:49 INFO - . 23:45:49 INFO - TEST-OK | /dom/nodes/getElementsByClassName-11.xml | took 841ms 23:45:49 INFO - TEST-START | /dom/nodes/getElementsByClassName-12.htm 23:45:50 INFO - . 23:45:50 INFO - TEST-OK | /dom/nodes/getElementsByClassName-12.htm | took 909ms 23:45:50 INFO - TEST-START | /dom/nodes/getElementsByClassName-13.htm 23:45:51 INFO - . 23:45:51 INFO - TEST-OK | /dom/nodes/getElementsByClassName-13.htm | took 1029ms 23:45:51 INFO - TEST-START | /dom/nodes/getElementsByClassName-14.htm 23:45:52 INFO - . 23:45:52 INFO - TEST-OK | /dom/nodes/getElementsByClassName-14.htm | took 1011ms 23:45:52 INFO - TEST-START | /dom/nodes/getElementsByClassName-15.htm 23:45:53 INFO - . 23:45:53 INFO - TEST-OK | /dom/nodes/getElementsByClassName-15.htm | took 890ms 23:45:53 INFO - TEST-START | /dom/nodes/getElementsByClassName-16.htm 23:45:54 INFO - . 23:45:54 INFO - TEST-OK | /dom/nodes/getElementsByClassName-16.htm | took 986ms 23:45:54 INFO - TEST-START | /dom/nodes/getElementsByClassName-17.htm 23:45:55 INFO - . 23:45:55 INFO - TEST-OK | /dom/nodes/getElementsByClassName-17.htm | took 1030ms 23:45:55 INFO - TEST-START | /dom/nodes/getElementsByClassName-18.htm 23:45:56 INFO - . 23:45:56 INFO - TEST-OK | /dom/nodes/getElementsByClassName-18.htm | took 932ms 23:45:56 INFO - TEST-START | /dom/nodes/getElementsByClassName-19.htm 23:45:57 INFO - . 23:45:57 INFO - TEST-OK | /dom/nodes/getElementsByClassName-19.htm | took 944ms 23:45:57 INFO - TEST-START | /dom/nodes/getElementsByClassName-20.htm 23:45:58 INFO - . 23:45:58 INFO - TEST-OK | /dom/nodes/getElementsByClassName-20.htm | took 1132ms 23:45:58 INFO - TEST-START | /dom/nodes/getElementsByClassName-21.htm 23:45:59 INFO - . 23:45:59 INFO - TEST-OK | /dom/nodes/getElementsByClassName-21.htm | took 1079ms 23:45:59 INFO - TEST-START | /dom/nodes/getElementsByClassName-22.htm 23:46:00 INFO - . 23:46:00 INFO - TEST-OK | /dom/nodes/getElementsByClassName-22.htm | took 997ms 23:46:00 INFO - TEST-START | /dom/nodes/getElementsByClassName-23.htm 23:46:01 INFO - . 23:46:01 INFO - TEST-OK | /dom/nodes/getElementsByClassName-23.htm | took 980ms 23:46:01 INFO - TEST-START | /dom/nodes/getElementsByClassName-24.htm 23:46:02 INFO - . 23:46:02 INFO - TEST-OK | /dom/nodes/getElementsByClassName-24.htm | took 1191ms 23:46:03 INFO - TEST-START | /dom/nodes/getElementsByClassName-25.htm 23:46:04 INFO - . 23:46:04 INFO - TEST-OK | /dom/nodes/getElementsByClassName-25.htm | took 1395ms 23:46:04 INFO - TEST-START | /dom/nodes/getElementsByClassName-26.htm 23:46:05 INFO - . 23:46:05 INFO - TEST-OK | /dom/nodes/getElementsByClassName-26.htm | took 1023ms 23:46:05 INFO - TEST-START | /dom/nodes/getElementsByClassName-27.htm 23:46:06 INFO - . 23:46:06 INFO - TEST-OK | /dom/nodes/getElementsByClassName-27.htm | took 1044ms 23:46:06 INFO - TEST-START | /dom/nodes/getElementsByClassName-28.htm 23:46:07 INFO - . 23:46:07 INFO - TEST-OK | /dom/nodes/getElementsByClassName-28.htm | took 1093ms 23:46:07 INFO - TEST-START | /dom/nodes/getElementsByClassName-29.htm 23:46:08 INFO - . 23:46:08 INFO - TEST-OK | /dom/nodes/getElementsByClassName-29.htm | took 1094ms 23:46:08 INFO - TEST-START | /dom/nodes/getElementsByClassName-30.htm 23:46:09 INFO - . 23:46:09 INFO - TEST-OK | /dom/nodes/getElementsByClassName-30.htm | took 1088ms 23:46:09 INFO - TEST-START | /dom/nodes/getElementsByClassName-31.htm 23:46:11 INFO - . 23:46:11 INFO - TEST-OK | /dom/nodes/getElementsByClassName-31.htm | took 1249ms 23:46:11 INFO - TEST-START | /dom/nodes/insert-adjacent.html 23:46:12 INFO - .............. 23:46:12 INFO - TEST-OK | /dom/nodes/insert-adjacent.html | took 1085ms 23:46:12 INFO - TEST-START | /dom/nodes/prepend-on-Document.html 23:46:13 INFO - ..... 23:46:13 INFO - TEST-OK | /dom/nodes/prepend-on-Document.html | took 888ms 23:46:13 INFO - TEST-START | /dom/nodes/remove-unscopable.html 23:46:13 INFO - ...... 23:46:13 INFO - TEST-OK | /dom/nodes/remove-unscopable.html | took 798ms 23:46:13 INFO - TEST-START | /dom/nodes/rootNode.html 23:46:13 INFO - Setting pref dom.node.rootNode.enabled (true) 23:46:14 INFO - .... 23:46:14 INFO - TEST-OK | /dom/nodes/rootNode.html | took 977ms 23:46:14 INFO - TEST-START | /eventsource/shared-worker/eventsource-close.htm 23:46:14 INFO - Clearing pref dom.node.rootNode.enabled 23:46:15 INFO - . 23:46:15 INFO - TEST-OK | /eventsource/shared-worker/eventsource-close.htm | took 1045ms 23:46:15 INFO - TEST-START | /eventsource/shared-worker/eventsource-constructor-non-same-origin.htm 23:46:17 INFO - ...... 23:46:17 INFO - TEST-OK | /eventsource/shared-worker/eventsource-constructor-non-same-origin.htm | took 1406ms 23:46:17 INFO - TEST-START | /eventsource/shared-worker/eventsource-constructor-url-bogus.htm 23:46:18 INFO - . 23:46:18 INFO - TEST-OK | /eventsource/shared-worker/eventsource-constructor-url-bogus.htm | took 1144ms 23:46:18 INFO - TEST-START | /eventsource/shared-worker/eventsource-eventtarget.htm 23:46:19 INFO - . 23:46:19 INFO - TEST-OK | /eventsource/shared-worker/eventsource-eventtarget.htm | took 990ms 23:46:19 INFO - TEST-START | /eventsource/shared-worker/eventsource-onmesage.htm 23:46:20 INFO - . 23:46:20 INFO - TEST-OK | /eventsource/shared-worker/eventsource-onmesage.htm | took 938ms 23:46:20 INFO - TEST-START | /eventsource/shared-worker/eventsource-onopen.htm 23:46:21 INFO - . 23:46:21 INFO - TEST-OK | /eventsource/shared-worker/eventsource-onopen.htm | took 988ms 23:46:21 INFO - TEST-START | /eventsource/shared-worker/eventsource-prototype.htm 23:46:22 INFO - . 23:46:22 INFO - TEST-OK | /eventsource/shared-worker/eventsource-prototype.htm | took 984ms 23:46:22 INFO - TEST-START | /eventsource/shared-worker/eventsource-url.htm 23:46:23 INFO - . 23:46:23 INFO - TEST-OK | /eventsource/shared-worker/eventsource-url.htm | took 1099ms 23:46:23 INFO - TEST-START | /fetch/api/cors/cors-basic-worker.html 23:46:25 INFO - ............... 23:46:25 INFO - TEST-OK | /fetch/api/cors/cors-basic-worker.html | took 1759ms 23:46:25 INFO - TEST-START | /fetch/api/cors/cors-basic.html 23:46:26 INFO - ............... 23:46:26 INFO - TEST-OK | /fetch/api/cors/cors-basic.html | took 1539ms 23:46:26 INFO - TEST-START | /fetch/api/cors/cors-cookies-worker.html 23:46:28 INFO - ...... 23:46:28 INFO - TEST-OK | /fetch/api/cors/cors-cookies-worker.html | took 1485ms 23:46:28 INFO - TEST-START | /fetch/api/cors/cors-cookies.html 23:46:29 INFO - ...... 23:46:29 INFO - TEST-OK | /fetch/api/cors/cors-cookies.html | took 1397ms 23:46:29 INFO - TEST-START | /fetch/api/cors/cors-filtering-worker.html 23:46:31 INFO - ................ 23:46:31 INFO - TEST-OK | /fetch/api/cors/cors-filtering-worker.html | took 1997ms 23:46:31 INFO - TEST-START | /fetch/api/cors/cors-filtering.html 23:46:33 INFO - ................ 23:46:33 INFO - TEST-OK | /fetch/api/cors/cors-filtering.html | took 1907ms 23:46:33 INFO - TEST-START | /fetch/api/cors/cors-multiple-origins-worker.html 23:46:35 INFO - ...... 23:46:35 INFO - TEST-OK | /fetch/api/cors/cors-multiple-origins-worker.html | took 1803ms 23:46:35 INFO - TEST-START | /fetch/api/cors/cors-multiple-origins.html 23:46:36 INFO - ...... 23:46:36 INFO - TEST-OK | /fetch/api/cors/cors-multiple-origins.html | took 1346ms 23:46:36 INFO - TEST-START | /fetch/api/cors/cors-no-preflight-worker.html 23:46:39 INFO - ............... 23:46:39 INFO - TEST-OK | /fetch/api/cors/cors-no-preflight-worker.html | took 2396ms 23:46:39 INFO - TEST-START | /fetch/api/cors/cors-no-preflight.html 23:46:41 INFO - ............... 23:46:41 INFO - TEST-OK | /fetch/api/cors/cors-no-preflight.html | took 2405ms 23:46:41 INFO - TEST-START | /fetch/api/cors/cors-origin-worker.html 23:46:44 INFO - ................. 23:46:44 INFO - TEST-OK | /fetch/api/cors/cors-origin-worker.html | took 2444ms 23:46:44 INFO - TEST-START | /fetch/api/cors/cors-origin.html 23:46:46 INFO - ................. 23:46:46 INFO - TEST-OK | /fetch/api/cors/cors-origin.html | took 2294ms 23:46:46 INFO - TEST-START | /fetch/api/cors/cors-preflight-redirect-worker.html 23:46:48 INFO - .......... 23:46:48 INFO - TEST-OK | /fetch/api/cors/cors-preflight-redirect-worker.html | took 1692ms 23:46:48 INFO - TEST-START | /fetch/api/cors/cors-preflight-redirect.html 23:46:50 INFO - .......... 23:46:50 INFO - TEST-OK | /fetch/api/cors/cors-preflight-redirect.html | took 2057ms 23:46:50 INFO - TEST-START | /fetch/api/cors/cors-preflight-referrer-worker.html 23:46:52 INFO - .......... 23:46:52 INFO - TEST-OK | /fetch/api/cors/cors-preflight-referrer-worker.html | took 1962ms 23:46:52 INFO - TEST-START | /fetch/api/cors/cors-preflight-referrer.html 23:46:54 INFO - .......... 23:46:54 INFO - TEST-OK | /fetch/api/cors/cors-preflight-referrer.html | took 1800ms 23:46:54 INFO - TEST-START | /fetch/api/cors/cors-preflight-status-worker.html 23:46:56 INFO - ........................... 23:46:56 INFO - TEST-OK | /fetch/api/cors/cors-preflight-status-worker.html | took 2232ms 23:46:56 INFO - TEST-START | /fetch/api/cors/cors-preflight-status.html 23:46:59 INFO - ........................... 23:46:59 INFO - TEST-OK | /fetch/api/cors/cors-preflight-status.html | took 2561ms 23:46:59 INFO - TEST-START | /fetch/api/cors/cors-preflight-worker.html 23:47:01 INFO - .............. 23:47:01 INFO - TEST-OK | /fetch/api/cors/cors-preflight-worker.html | took 2253ms 23:47:01 INFO - TEST-START | /fetch/api/cors/cors-preflight.html 23:47:03 INFO - .............. 23:47:03 INFO - TEST-OK | /fetch/api/cors/cors-preflight.html | took 2517ms 23:47:03 INFO - TEST-START | /fetch/api/cors/cors-redirect-credentials-worker.html 23:47:08 INFO - ............................................................ 23:47:08 INFO - TEST-OK | /fetch/api/cors/cors-redirect-credentials-worker.html | took 4778ms 23:47:08 INFO - TEST-START | /fetch/api/cors/cors-redirect-credentials.html 23:47:12 INFO - ............................................................ 23:47:12 INFO - TEST-OK | /fetch/api/cors/cors-redirect-credentials.html | took 3977ms 23:47:12 INFO - TEST-START | /fetch/api/cors/cors-redirect-preflight-worker.html 23:47:16 INFO - .............................. 23:47:16 INFO - TEST-OK | /fetch/api/cors/cors-redirect-preflight-worker.html | took 3357ms 23:47:16 INFO - TEST-START | /fetch/api/cors/cors-redirect-preflight.html 23:47:18 INFO - .............................. 23:47:18 INFO - TEST-OK | /fetch/api/cors/cors-redirect-preflight.html | took 2707ms 23:47:18 INFO - TEST-START | /fetch/api/cors/cors-redirect-worker.html 23:47:20 INFO - .................... 23:47:20 INFO - TEST-OK | /fetch/api/cors/cors-redirect-worker.html | took 1600ms 23:47:20 INFO - TEST-START | /fetch/api/cors/cors-redirect.html 23:47:22 INFO - .................... 23:47:22 INFO - TEST-OK | /fetch/api/cors/cors-redirect.html | took 2345ms 23:47:22 INFO - TEST-START | /fetch/api/headers/headers-basic.html 23:47:23 INFO - .................. 23:47:23 INFO - TEST-OK | /fetch/api/headers/headers-basic.html | took 952ms 23:47:23 INFO - TEST-START | /fetch/api/headers/headers-casing.html 23:47:24 INFO - .... 23:47:24 INFO - TEST-OK | /fetch/api/headers/headers-casing.html | took 935ms 23:47:24 INFO - TEST-START | /fetch/api/headers/headers-combine.html 23:47:25 INFO - .... 23:47:25 INFO - TEST-OK | /fetch/api/headers/headers-combine.html | took 881ms 23:47:25 INFO - TEST-START | /fetch/api/headers/headers-errors.html 23:47:26 INFO - .................. 23:47:26 INFO - TEST-OK | /fetch/api/headers/headers-errors.html | took 885ms 23:47:26 INFO - TEST-START | /fetch/api/headers/headers-idl.html 23:47:27 INFO - ...................... 23:47:27 INFO - TEST-OK | /fetch/api/headers/headers-idl.html | took 1193ms 23:47:27 INFO - TEST-START | /fetch/api/headers/headers-normalize.html 23:47:28 INFO - ... 23:47:28 INFO - TEST-OK | /fetch/api/headers/headers-normalize.html | took 1046ms 23:47:28 INFO - TEST-START | /fetch/api/headers/headers-structure.html 23:47:29 INFO - ........ 23:47:29 INFO - TEST-OK | /fetch/api/headers/headers-structure.html | took 1154ms 23:47:29 INFO - TEST-START | /fetch/api/policies/csp-blocked-worker.html 23:47:30 INFO - . 23:47:30 INFO - TEST-OK | /fetch/api/policies/csp-blocked-worker.html | took 1087ms 23:47:30 INFO - TEST-START | /fetch/api/policies/csp-blocked.html 23:47:31 INFO - . 23:47:31 INFO - TEST-OK | /fetch/api/policies/csp-blocked.html | took 983ms 23:47:31 INFO - TEST-START | /fetch/api/policies/referrer-no-referrer-worker.html 23:47:33 INFO - . 23:47:33 INFO - TEST-OK | /fetch/api/policies/referrer-no-referrer-worker.html | took 1185ms 23:47:33 INFO - TEST-START | /fetch/api/policies/referrer-no-referrer.html 23:47:34 INFO - . 23:47:34 INFO - TEST-OK | /fetch/api/policies/referrer-no-referrer.html | took 1133ms 23:47:34 INFO - TEST-START | /fetch/api/policies/referrer-origin-when-cross-origin-worker.html 23:47:35 INFO - . 23:47:35 INFO - TEST-OK | /fetch/api/policies/referrer-origin-when-cross-origin-worker.html | took 1076ms 23:47:35 INFO - TEST-START | /fetch/api/policies/referrer-origin-when-cross-origin.html 23:47:36 INFO - . 23:47:36 INFO - TEST-OK | /fetch/api/policies/referrer-origin-when-cross-origin.html | took 1132ms 23:47:36 INFO - TEST-START | /fetch/api/policies/referrer-origin-worker.html 23:47:37 INFO - .. 23:47:37 INFO - TEST-OK | /fetch/api/policies/referrer-origin-worker.html | took 1228ms 23:47:37 INFO - TEST-START | /fetch/api/policies/referrer-origin.html 23:47:38 INFO - .. 23:47:38 INFO - TEST-OK | /fetch/api/policies/referrer-origin.html | took 985ms 23:47:38 INFO - TEST-START | /fetch/api/policies/referrer-unsafe-url-worker.html 23:47:39 INFO - . 23:47:39 INFO - TEST-OK | /fetch/api/policies/referrer-unsafe-url-worker.html | took 1079ms 23:47:39 INFO - TEST-START | /fetch/api/policies/referrer-unsafe-url.html 23:47:40 INFO - . 23:47:40 INFO - TEST-OK | /fetch/api/policies/referrer-unsafe-url.html | took 1027ms 23:47:40 INFO - TEST-START | /fetch/api/response/response-cancel-stream.html 23:47:47 INFO - ...... 23:47:47 INFO - TEST-OK | /fetch/api/response/response-cancel-stream.html | took 7166ms 23:47:48 INFO - TEST-START | /fetch/api/response/response-clone.html 23:47:52 INFO - ....... 23:47:52 INFO - TEST-OK | /fetch/api/response/response-clone.html | took 4254ms 23:47:52 INFO - TEST-START | /fetch/api/response/response-consume-empty.html 23:47:53 INFO - ......... 23:47:53 INFO - TEST-OK | /fetch/api/response/response-consume-empty.html | took 1047ms 23:47:53 INFO - TEST-START | /fetch/api/response/response-consume-stream.html 23:47:54 INFO - ........ 23:47:54 INFO - TEST-OK | /fetch/api/response/response-consume-stream.html | took 984ms 23:47:54 INFO - TEST-START | /fetch/api/response/response-consume.html 23:47:55 INFO - ................ 23:47:55 INFO - TEST-OK | /fetch/api/response/response-consume.html | took 1091ms 23:47:55 INFO - TEST-START | /fetch/api/response/response-error.html 23:47:56 INFO - .......... 23:47:56 INFO - TEST-OK | /fetch/api/response/response-error.html | took 896ms 23:47:56 INFO - TEST-START | /fetch/api/response/response-idl.html 23:47:57 INFO - ........................................ 23:47:57 INFO - TEST-OK | /fetch/api/response/response-idl.html | took 1417ms 23:47:57 INFO - TEST-START | /fetch/api/response/response-init-001.html 23:47:58 INFO - ........ 23:47:58 INFO - TEST-OK | /fetch/api/response/response-init-001.html | took 942ms 23:47:58 INFO - TEST-START | /fetch/api/response/response-init-002.html 23:47:59 INFO - ....... 23:47:59 INFO - TEST-OK | /fetch/api/response/response-init-002.html | took 888ms 23:47:59 INFO - TEST-START | /fetch/api/response/response-static-error.html 23:48:00 INFO - . 23:48:00 INFO - TEST-OK | /fetch/api/response/response-static-error.html | took 983ms 23:48:00 INFO - TEST-START | /fetch/api/response/response-static-redirect.html 23:48:01 INFO - ........... 23:48:01 INFO - TEST-OK | /fetch/api/response/response-static-redirect.html | took 927ms 23:48:01 INFO - TEST-START | /fetch/api/response/response-stream-disturbed-1.html 23:48:02 INFO - .... 23:48:02 INFO - TEST-OK | /fetch/api/response/response-stream-disturbed-1.html | took 1386ms 23:48:02 INFO - TEST-START | /fetch/api/response/response-stream-disturbed-2.html 23:48:04 INFO - .... 23:48:04 INFO - TEST-OK | /fetch/api/response/response-stream-disturbed-2.html | took 1185ms 23:48:04 INFO - TEST-START | /fetch/api/response/response-stream-disturbed-3.html 23:48:05 INFO - .... 23:48:05 INFO - TEST-OK | /fetch/api/response/response-stream-disturbed-3.html | took 1250ms 23:48:05 INFO - TEST-START | /fetch/api/response/response-stream-disturbed-4.html 23:48:06 INFO - .... 23:48:06 INFO - TEST-OK | /fetch/api/response/response-stream-disturbed-4.html | took 1047ms 23:48:06 INFO - TEST-START | /fetch/api/response/response-stream-disturbed-5.html 23:48:07 INFO - .... 23:48:07 INFO - TEST-OK | /fetch/api/response/response-stream-disturbed-5.html | took 997ms 23:48:07 INFO - TEST-START | /hr-time/basic.html 23:48:10 INFO - ..... 23:48:10 INFO - TEST-OK | /hr-time/basic.html | took 2844ms 23:48:10 INFO - TEST-START | /hr-time/basic.worker 23:48:11 INFO - ... 23:48:11 INFO - TEST-OK | /hr-time/basic.worker | took 1138ms 23:48:11 INFO - TEST-START | /hr-time/idlharness.html 23:48:12 INFO - .... 23:48:12 INFO - TEST-OK | /hr-time/idlharness.html | took 1237ms 23:48:12 INFO - TEST-START | /hr-time/monotonic-clock.html 23:48:14 INFO - .. 23:48:14 INFO - TEST-OK | /hr-time/monotonic-clock.html | took 1238ms 23:48:14 INFO - TEST-START | /hr-time/test_cross_frame_start.html 23:48:16 INFO - ..... 23:48:16 INFO - TEST-OK | /hr-time/test_cross_frame_start.html | took 2244ms 23:48:16 INFO - TEST-START | /html-imports/html-link-element/import-attribute.html 23:48:17 INFO - .... 23:48:17 INFO - TEST-OK | /html-imports/html-link-element/import-attribute.html | took 1234ms 23:48:17 INFO - TEST-START | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/resume-timer-on-history-back.html 23:48:23 INFO - .. 23:48:23 INFO - TEST-OK | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/resume-timer-on-history-back.html | took 5793ms 23:48:23 INFO - TEST-START | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/scroll-restoration-basic.html 23:48:24 INFO - ... 23:48:24 INFO - TEST-OK | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/scroll-restoration-basic.html | took 1049ms 23:48:24 INFO - TEST-START | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/scroll-restoration-fragment-scrolling-cross-origin.html 23:48:25 INFO - PROCESS | 2317 | JavaScript error: , line 0: Error: assert_equals: navigating back should retain scrollRestoration value expected "manual" but got "auto" 23:49:24 INFO - PROCESS | 2317 | MARIONETTE LOG: INFO: Timeout fired 23:49:24 INFO - . 23:49:24 INFO - TEST-ERROR | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/scroll-restoration-fragment-scrolling-cross-origin.html | took 60593ms 23:49:24 INFO - TEST-START | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/scroll-restoration-fragment-scrolling-samedoc.html 23:49:26 INFO - . 23:49:26 INFO - TEST-OK | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/scroll-restoration-fragment-scrolling-samedoc.html | took 1312ms 23:49:26 INFO - TEST-START | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/scroll-restoration-navigation-cross-origin.html 23:49:28 INFO - . 23:49:28 INFO - TEST-OK | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/scroll-restoration-navigation-cross-origin.html | took 1762ms 23:49:28 INFO - TEST-START | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/scroll-restoration-navigation-samedoc.html 23:49:29 INFO - . 23:49:29 INFO - TEST-OK | /html/browsers/browsing-the-web/history-traversal/persisted-user-state-restoration/scroll-restoration-navigation-samedoc.html | took 1307ms 23:49:29 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/001.html 23:49:30 INFO - . 23:49:30 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/001.html | took 1224ms 23:49:30 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/002.html 23:49:32 INFO - . 23:49:32 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/002.html | took 1645ms 23:49:32 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/003.html 23:49:34 INFO - . 23:49:34 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/003.html | took 1866ms 23:49:34 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/004.html 23:49:35 INFO - . 23:49:35 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/004.html | took 1487ms 23:49:35 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/005.html 23:49:36 INFO - . 23:49:36 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/005.html | took 1295ms 23:49:36 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/006.html 23:49:38 INFO - . 23:49:38 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/006.html | took 1179ms 23:49:38 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/007.html 23:49:39 INFO - . 23:49:39 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/007.html | took 1024ms 23:49:39 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/008.html 23:49:40 INFO - . 23:49:40 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/008.html | took 1231ms 23:49:40 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/009.html 23:49:41 INFO - . 23:49:41 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/009.html | took 1232ms 23:49:41 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/010.html 23:49:42 INFO - PROCESS | 2317 | JavaScript error: javascript:(function()%20{var%20x%20=%20new%20XMLHttpRequest();%20x.open('GET',%20'blank.html?pipe=trickle(d2)',%20false);%20x.send();%20document.write('');%20return%20''})(), line 1: NetworkError: A network error occurred. 23:49:42 INFO - . 23:49:42 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/010.html | took 1283ms 23:49:42 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/011.html 23:49:44 INFO - . 23:49:44 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/011.html | took 1188ms 23:49:44 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/012.html 23:49:47 INFO - . 23:49:47 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/012.html | took 3152ms 23:49:47 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/013.html 23:49:48 INFO - . 23:49:48 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/013.html | took 1191ms 23:49:48 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/014.html 23:49:49 INFO - . 23:49:49 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/014.html | took 1236ms 23:49:49 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/015.html 23:49:50 INFO - . 23:49:50 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/015.html | took 1080ms 23:49:50 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/child_navigates_parent_location.html 23:49:52 INFO - . 23:49:52 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/child_navigates_parent_location.html | took 1496ms 23:49:52 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/child_navigates_parent_submit.html 23:49:53 INFO - . 23:49:53 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/child_navigates_parent_submit.html | took 1443ms 23:49:53 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/empty_fragment.html 23:49:56 INFO - . 23:49:56 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/empty_fragment.html | took 2899ms 23:49:56 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/javascript-url-query-fragment-components.html 23:49:57 INFO - . 23:49:57 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/javascript-url-query-fragment-components.html | took 1129ms 23:49:57 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/javascript-url-return-value-handling.html 23:49:59 INFO - . 23:49:59 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/javascript-url-return-value-handling.html | took 1399ms 23:49:59 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/navigation_unload_data_url.html 23:50:00 INFO - . 23:50:00 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/navigation_unload_data_url.html | took 1228ms 23:50:00 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/navigation_unload_same_origin.html 23:50:02 INFO - . 23:50:02 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/navigation_unload_same_origin.html | took 1501ms 23:50:02 INFO - TEST-START | /html/browsers/browsing-the-web/read-media/pageload-image.html 23:50:03 INFO - . 23:50:03 INFO - TEST-OK | /html/browsers/browsing-the-web/read-media/pageload-image.html | took 1240ms 23:50:03 INFO - TEST-START | /html/browsers/browsing-the-web/read-media/pageload-video.html 23:50:04 INFO - . 23:50:04 INFO - TEST-OK | /html/browsers/browsing-the-web/read-media/pageload-video.html | took 1384ms 23:50:04 INFO - TEST-START | /html/browsers/browsing-the-web/unloading-documents/unload/001.html 23:50:06 INFO - . 23:50:06 INFO - TEST-OK | /html/browsers/browsing-the-web/unloading-documents/unload/001.html | took 1588ms 23:50:06 INFO - TEST-START | /html/browsers/browsing-the-web/unloading-documents/unload/002.html 23:50:07 INFO - PROCESS | 2317 | JavaScript error: http://web-platform.test:8000/html/browsers/browsing-the-web/unloading-documents/unload/002-1.html?0.29789545358511826, line 3: TypeError: parent.do_test is not a function 23:50:07 INFO - . 23:50:07 INFO - TEST-OK | /html/browsers/browsing-the-web/unloading-documents/unload/002.html | took 1497ms 23:50:07 INFO - TEST-START | /html/browsers/browsing-the-web/unloading-documents/unload/003.html 23:50:09 INFO - . 23:50:09 INFO - TEST-OK | /html/browsers/browsing-the-web/unloading-documents/unload/003.html | took 1296ms 23:50:09 INFO - TEST-START | /html/browsers/browsing-the-web/unloading-documents/unload/004.html 23:50:10 INFO - . 23:50:10 INFO - TEST-OK | /html/browsers/browsing-the-web/unloading-documents/unload/004.html | took 1592ms 23:50:10 INFO - TEST-START | /html/browsers/browsing-the-web/unloading-documents/unload/006.html 23:50:21 INFO - PROCESS | 2317 | MARIONETTE LOG: INFO: Timeout fired 23:50:21 INFO - . 23:50:21 INFO - TEST-TIMEOUT | /html/browsers/browsing-the-web/unloading-documents/unload/006.html | took 10783ms 23:50:21 INFO - TEST-START | /html/browsers/browsing-the-web/unloading-documents/unload/007.html 23:50:23 INFO - . 23:50:23 INFO - TEST-OK | /html/browsers/browsing-the-web/unloading-documents/unload/007.html | took 2499ms 23:50:24 INFO - TEST-START | /html/browsers/browsing-the-web/unloading-documents/unload/008.html 23:50:25 INFO - . 23:50:25 INFO - TEST-OK | /html/browsers/browsing-the-web/unloading-documents/unload/008.html | took 1134ms 23:50:25 INFO - TEST-START | /html/browsers/browsing-the-web/unloading-documents/unload/009.html 23:50:26 INFO - . 23:50:26 INFO - TEST-OK | /html/browsers/browsing-the-web/unloading-documents/unload/009.html | took 1292ms 23:50:26 INFO - TEST-START | /html/browsers/history/the-history-interface/joint_session_history/001.html 23:50:37 INFO - PROCESS | 2317 | MARIONETTE LOG: INFO: Timeout fired 23:50:37 INFO - ....... 23:50:37 INFO - TEST-TIMEOUT | /html/browsers/history/the-history-interface/joint_session_history/001.html | took 10698ms 23:50:37 INFO - TEST-START | /html/browsers/history/the-history-interface/joint_session_history/002.html 23:50:44 INFO - PROCESS | 2317 | ************************* 23:50:44 INFO - PROCESS | 2317 | A coding exception was thrown and uncaught in a Task. 23:50:44 INFO - PROCESS | 2317 | 23:50:44 INFO - PROCESS | 2317 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:50:44 INFO - PROCESS | 2317 | Full stack: 23:50:44 INFO - PROCESS | 2317 | ************************* 23:50:47 INFO - PROCESS | 2317 | MARIONETTE LOG: INFO: Timeout fired 23:50:47 INFO - ... 23:50:47 INFO - TEST-TIMEOUT | /html/browsers/history/the-history-interface/joint_session_history/002.html | took 10697ms 23:50:47 INFO - TEST-START | /html/browsers/offline/introduction-4/event_cached.html 23:50:48 INFO - . 23:50:48 INFO - TEST-OK | /html/browsers/offline/introduction-4/event_cached.html | took 1085ms 23:50:49 INFO - TEST-START | /html/browsers/offline/introduction-4/event_checking.html 23:50:50 INFO - PROCESS | 2317 | JavaScript error: chrome://browser/content/content.js, line 1494: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIMessageSender.sendAsyncMessage] 23:50:50 INFO - . 23:50:50 INFO - TEST-OK | /html/browsers/offline/introduction-4/event_checking.html | took 1031ms 23:50:50 INFO - TEST-START | /html/browsers/offline/introduction-4/event_noupdate.html 23:50:51 INFO - . 23:50:51 INFO - TEST-OK | /html/browsers/offline/introduction-4/event_noupdate.html | took 974ms 23:50:51 INFO - PROCESS | 2317 | JavaScript error: chrome://browser/content/content.js, line 1494: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIMessageSender.sendAsyncMessage] 23:50:51 INFO - TEST-START | /html/browsers/offline/introduction-4/event_progress.html 23:50:51 INFO - PROCESS | 2317 | JavaScript error: chrome://browser/content/content.js, line 1494: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIMessageSender.sendAsyncMessage] 23:50:52 INFO - . 23:50:52 INFO - TEST-OK | /html/browsers/offline/introduction-4/event_progress.html | took 929ms 23:50:52 INFO - PROCESS | 2317 | JavaScript error: chrome://browser/content/content.js, line 1494: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIMessageSender.sendAsyncMessage] 23:50:52 INFO - TEST-START | /html/browsers/the-window-object/named-access-on-the-window-object/named-objects.html 23:50:52 INFO - PROCESS | 2317 | JavaScript error: chrome://browser/content/content.js, line 1494: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIMessageSender.sendAsyncMessage] 23:50:52 INFO - PROCESS | 2317 | JavaScript error: chrome://browser/content/content.js, line 1494: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIMessageSender.sendAsyncMessage] 23:50:53 INFO - ...... 23:50:53 INFO - TEST-OK | /html/browsers/the-window-object/named-access-on-the-window-object/named-objects.html | took 992ms 23:50:53 INFO - TEST-START | /html/browsers/the-window-object/named-access-on-the-window-object/window-null-names.html 23:50:54 INFO - . 23:50:54 INFO - TEST-OK | /html/browsers/the-window-object/named-access-on-the-window-object/window-null-names.html | took 1090ms 23:50:54 INFO - TEST-START | /html/browsers/the-window-object/security-window/window-security.sub.html 23:50:55 INFO - ............................................................................................................................... 23:50:55 INFO - TEST-OK | /html/browsers/the-window-object/security-window/window-security.sub.html | took 1695ms 23:50:55 INFO - TEST-START | /html/dom/dynamic-markup-insertion/document-writeln/document.writeln-01.xhtml 23:50:56 INFO - . 23:50:56 INFO - TEST-OK | /html/dom/dynamic-markup-insertion/document-writeln/document.writeln-01.xhtml | took 893ms 23:50:56 INFO - TEST-START | /html/dom/dynamic-markup-insertion/document-writeln/document.writeln-02.html 23:50:57 INFO - ... 23:50:57 INFO - TEST-OK | /html/dom/dynamic-markup-insertion/document-writeln/document.writeln-02.html | took 987ms 23:50:57 INFO - TEST-START | /html/dom/dynamic-markup-insertion/document-writeln/document.writeln-03.html 23:50:58 INFO - . 23:50:58 INFO - TEST-OK | /html/dom/dynamic-markup-insertion/document-writeln/document.writeln-03.html | took 1143ms 23:50:58 INFO - TEST-START | /html/rendering/replaced-elements/svg-inline-sizing/svg-inline.html 23:51:01 INFO - ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ 23:51:01 INFO - TEST-OK | /html/rendering/replaced-elements/svg-inline-sizing/svg-inline.html | took 2730ms 23:51:01 INFO - TEST-START | /html/semantics/document-metadata/the-base-element/base_about_blank.html 23:51:02 INFO - . 23:51:02 INFO - TEST-OK | /html/semantics/document-metadata/the-base-element/base_about_blank.html | took 1136ms 23:51:02 INFO - TEST-START | /html/semantics/document-metadata/the-base-element/base_href_empty.html 23:51:03 INFO - .. 23:51:03 INFO - TEST-OK | /html/semantics/document-metadata/the-base-element/base_href_empty.html | took 1134ms 23:51:03 INFO - TEST-START | /html/semantics/document-metadata/the-base-element/base_href_invalid.html 23:51:04 INFO - . 23:51:04 INFO - TEST-OK | /html/semantics/document-metadata/the-base-element/base_href_invalid.html | took 846ms 23:51:04 INFO - TEST-START | /html/semantics/document-metadata/the-base-element/base_href_specified.sub.html 23:51:05 INFO - .. 23:51:05 INFO - TEST-OK | /html/semantics/document-metadata/the-base-element/base_href_specified.sub.html | took 880ms 23:51:05 INFO - TEST-START | /html/semantics/document-metadata/the-base-element/base_href_unspecified.html 23:51:06 INFO - .. 23:51:06 INFO - TEST-OK | /html/semantics/document-metadata/the-base-element/base_href_unspecified.html | took 973ms 23:51:06 INFO - TEST-START | /html/semantics/document-metadata/the-base-element/base_multiple.html 23:51:07 INFO - . 23:51:07 INFO - TEST-OK | /html/semantics/document-metadata/the-base-element/base_multiple.html | took 1129ms 23:51:07 INFO - TEST-START | /html/semantics/document-metadata/the-base-element/base_srcdoc.html 23:51:08 INFO - . 23:51:08 INFO - TEST-OK | /html/semantics/document-metadata/the-base-element/base_srcdoc.html | took 975ms 23:51:08 INFO - TEST-START | /html/semantics/embedded-content/media-elements/seeking/seek-to-currentTime.html 23:51:09 INFO - . 23:51:09 INFO - TEST-OK | /html/semantics/embedded-content/media-elements/seeking/seek-to-currentTime.html | took 1137ms 23:51:09 INFO - TEST-START | /html/semantics/embedded-content/media-elements/seeking/seek-to-max-value.htm 23:51:10 INFO - . 23:51:10 INFO - TEST-OK | /html/semantics/embedded-content/media-elements/seeking/seek-to-max-value.htm | took 980ms 23:51:10 INFO - TEST-START | /html/semantics/embedded-content/media-elements/seeking/seek-to-negative-time.htm 23:51:12 INFO - . 23:51:12 INFO - TEST-OK | /html/semantics/embedded-content/media-elements/seeking/seek-to-negative-time.htm | took 1234ms 23:51:12 INFO - TEST-START | /html/semantics/embedded-content/media-elements/track/track-element/cloneNode.html 23:51:15 INFO - .... 23:51:15 INFO - TEST-OK | /html/semantics/embedded-content/media-elements/track/track-element/cloneNode.html | took 2907ms 23:51:15 INFO - TEST-START | /html/semantics/embedded-content/media-elements/track/track-element/src-clear-cues.html 23:51:15 INFO - ... 23:51:15 INFO - TEST-OK | /html/semantics/embedded-content/media-elements/track/track-element/src-clear-cues.html | took 886ms 23:51:15 INFO - TEST-START | /html/semantics/embedded-content/media-elements/track/track-element/track-api-texttracks.html 23:51:17 INFO - ... 23:51:17 INFO - TEST-OK | /html/semantics/embedded-content/media-elements/track/track-element/track-api-texttracks.html | took 1587ms 23:51:17 INFO - TEST-START | /html/semantics/embedded-content/the-audio-element/audio_constructor.html 23:51:18 INFO - .......... 23:51:18 INFO - TEST-OK | /html/semantics/embedded-content/the-audio-element/audio_constructor.html | took 1035ms 23:51:18 INFO - TEST-START | /html/semantics/embedded-content/the-img-element/environment-changes/viewport-change.html 23:51:21 INFO - ............................................ 23:51:21 INFO - TEST-OK | /html/semantics/embedded-content/the-img-element/environment-changes/viewport-change.html | took 3219ms 23:51:21 INFO - TEST-START | /html/semantics/embedded-content/the-img-element/sizes/parse-a-sizes-attribute.html 23:51:31 INFO - ................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ 23:51:31 INFO - TEST-OK | /html/semantics/embedded-content/the-img-element/sizes/parse-a-sizes-attribute.html | took 9776ms 23:51:31 INFO - TEST-START | /html/semantics/embedded-content/the-img-element/update-the-image-data/fail-to-resolve.html 23:51:32 INFO - .... 23:51:32 INFO - TEST-OK | /html/semantics/embedded-content/the-img-element/update-the-image-data/fail-to-resolve.html | took 1045ms 23:51:32 INFO - TEST-START | /html/semantics/forms/the-label-element/label-attributes.html 23:51:33 INFO - ............. 23:51:33 INFO - TEST-OK | /html/semantics/forms/the-label-element/label-attributes.html | took 990ms 23:51:33 INFO - TEST-START | /html/semantics/forms/the-label-element/labelable-elements.html 23:51:34 INFO - .......................... 23:51:34 INFO - TEST-OK | /html/semantics/forms/the-label-element/labelable-elements.html | took 941ms 23:51:34 INFO - TEST-START | /html/semantics/forms/the-output-element/output.html 23:51:35 INFO - . 23:51:35 INFO - TEST-OK | /html/semantics/forms/the-output-element/output.html | took 933ms 23:51:35 INFO - TEST-START | /html/semantics/forms/the-textarea-element/cloning-steps.html 23:51:36 INFO - .. 23:51:36 INFO - TEST-OK | /html/semantics/forms/the-textarea-element/cloning-steps.html | took 883ms 23:51:36 INFO - TEST-START | /html/semantics/forms/the-textarea-element/textarea-type.html 23:51:37 INFO - . 23:51:37 INFO - TEST-OK | /html/semantics/forms/the-textarea-element/textarea-type.html | took 1032ms 23:51:37 INFO - TEST-START | /html/semantics/grouping-content/the-dd-element/grouping-dd.html 23:51:38 INFO - . 23:51:38 INFO - TEST-OK | /html/semantics/grouping-content/the-dd-element/grouping-dd.html | took 1052ms 23:51:38 INFO - TEST-START | /html/semantics/grouping-content/the-dl-element/grouping-dl.html 23:51:39 INFO - . 23:51:39 INFO - TEST-OK | /html/semantics/grouping-content/the-dl-element/grouping-dl.html | took 1138ms 23:51:39 INFO - TEST-START | /html/semantics/grouping-content/the-figcaption-element/grouping-figcaption.html 23:51:40 INFO - . 23:51:40 INFO - TEST-OK | /html/semantics/grouping-content/the-figcaption-element/grouping-figcaption.html | took 1045ms 23:51:40 INFO - TEST-START | /html/semantics/grouping-content/the-hr-element/grouping-hr.html 23:51:41 INFO - . 23:51:41 INFO - TEST-OK | /html/semantics/grouping-content/the-hr-element/grouping-hr.html | took 998ms 23:51:41 INFO - TEST-START | /html/semantics/grouping-content/the-li-element/grouping-li.html 23:51:42 INFO - .......... 23:51:42 INFO - TEST-OK | /html/semantics/grouping-content/the-li-element/grouping-li.html | took 1152ms 23:51:42 INFO - TEST-START | /html/semantics/grouping-content/the-ol-element/grouping-ol.html 23:51:44 INFO - ......................... 23:51:44 INFO - TEST-OK | /html/semantics/grouping-content/the-ol-element/grouping-ol.html | took 1318ms 23:51:44 INFO - TEST-START | /html/semantics/grouping-content/the-ol-element/ol.start-reflection-1.html 23:51:45 INFO - . 23:51:45 INFO - TEST-OK | /html/semantics/grouping-content/the-ol-element/ol.start-reflection-1.html | took 993ms 23:51:45 INFO - TEST-START | /html/semantics/grouping-content/the-ol-element/ol.start-reflection-2.html 23:51:46 INFO - . 23:51:46 INFO - TEST-OK | /html/semantics/grouping-content/the-ol-element/ol.start-reflection-2.html | took 932ms 23:51:46 INFO - TEST-START | /html/semantics/interactive-elements/the-dialog-element/dialog-close.html 23:51:47 INFO - ..... 23:51:47 INFO - TEST-OK | /html/semantics/interactive-elements/the-dialog-element/dialog-close.html | took 994ms 23:51:47 INFO - TEST-START | /html/semantics/interactive-elements/the-dialog-element/dialog-open.html 23:51:48 INFO - .. 23:51:48 INFO - TEST-OK | /html/semantics/interactive-elements/the-dialog-element/dialog-open.html | took 1089ms 23:51:48 INFO - TEST-START | /html/semantics/interactive-elements/the-dialog-element/dialog-showModal.html 23:51:49 INFO - ....... 23:51:49 INFO - TEST-OK | /html/semantics/interactive-elements/the-dialog-element/dialog-showModal.html | took 1296ms 23:51:49 INFO - TEST-START | /html/semantics/scripting-1/the-template-element/definitions/template-contents-owner-document-type.html 23:51:50 INFO - .... 23:51:50 INFO - TEST-OK | /html/semantics/scripting-1/the-template-element/definitions/template-contents-owner-document-type.html | took 1132ms 23:51:50 INFO - TEST-START | /html/semantics/scripting-1/the-template-element/definitions/template-contents-owner-test-001.html 23:51:51 INFO - .. 23:51:51 INFO - TEST-OK | /html/semantics/scripting-1/the-template-element/definitions/template-contents-owner-test-001.html | took 876ms 23:51:51 INFO - TEST-START | /html/semantics/scripting-1/the-template-element/definitions/template-contents-owner-test-002.html 23:51:52 INFO - ... 23:51:52 INFO - TEST-OK | /html/semantics/scripting-1/the-template-element/definitions/template-contents-owner-test-002.html | took 1081ms 23:51:52 INFO - TEST-START | /html/semantics/scripting-1/the-template-element/definitions/template-contents.html 23:51:54 INFO - ......... 23:51:54 INFO - TEST-OK | /html/semantics/scripting-1/the-template-element/definitions/template-contents.html | took 1311ms 23:51:54 INFO - TEST-START | /html/semantics/text-level-semantics/the-data-element/data.value-001.html 23:51:55 INFO - ......... 23:51:55 INFO - TEST-OK | /html/semantics/text-level-semantics/the-data-element/data.value-001.html | took 1028ms 23:51:55 INFO - TEST-START | /html/syntax/parsing/template/clearing-the-stack-back-to-a-given-context/clearing-stack-back-to-a-table-body-context.html 23:51:56 INFO - ................................. 23:51:56 INFO - TEST-OK | /html/syntax/parsing/template/clearing-the-stack-back-to-a-given-context/clearing-stack-back-to-a-table-body-context.html | took 1052ms 23:51:56 INFO - TEST-START | /html/syntax/parsing/template/clearing-the-stack-back-to-a-given-context/clearing-stack-back-to-a-table-context.html 23:51:57 INFO - ...... 23:51:57 INFO - TEST-OK | /html/syntax/parsing/template/clearing-the-stack-back-to-a-given-context/clearing-stack-back-to-a-table-context.html | took 942ms 23:51:57 INFO - TEST-START | /html/syntax/parsing/template/clearing-the-stack-back-to-a-given-context/clearing-stack-back-to-a-table-row-context.html 23:51:58 INFO - ... 23:51:58 INFO - TEST-OK | /html/syntax/parsing/template/clearing-the-stack-back-to-a-given-context/clearing-stack-back-to-a-table-row-context.html | took 930ms 23:51:58 INFO - TEST-START | /html/webappapis/timers/evil-spec-example.html 23:51:58 INFO - . 23:51:58 INFO - TEST-OK | /html/webappapis/timers/evil-spec-example.html | took 927ms 23:51:58 INFO - TEST-START | /http/content_length.html 23:51:59 INFO - . 23:51:59 INFO - TEST-OK | /http/content_length.html | took 886ms 23:51:59 INFO - TEST-START | /js/behaviours/SetPrototypeOf-window.html 23:52:00 INFO - .... 23:52:00 INFO - TEST-OK | /js/behaviours/SetPrototypeOf-window.html | took 1093ms 23:52:00 INFO - TEST-START | /mediacapture-streams/GUM-api.https.html 23:52:02 INFO - . 23:52:02 INFO - TEST-OK | /mediacapture-streams/GUM-api.https.html | took 1776ms 23:52:02 INFO - TEST-START | /mediacapture-streams/GUM-deny.https.html 23:52:03 INFO - . 23:52:03 INFO - TEST-OK | /mediacapture-streams/GUM-deny.https.html | took 1125ms 23:52:03 INFO - TEST-START | /mediacapture-streams/GUM-empty-option-param.https.html 23:52:05 INFO - . 23:52:05 INFO - TEST-OK | /mediacapture-streams/GUM-empty-option-param.https.html | took 1241ms 23:52:05 INFO - TEST-START | /mediacapture-streams/GUM-impossible-constraint.https.html 23:52:06 INFO - . 23:52:06 INFO - TEST-OK | /mediacapture-streams/GUM-impossible-constraint.https.html | took 1350ms 23:52:06 INFO - TEST-START | /mediacapture-streams/GUM-optional-constraint.https.html 23:52:07 INFO - . 23:52:07 INFO - TEST-OK | /mediacapture-streams/GUM-optional-constraint.https.html | took 1397ms 23:52:07 INFO - TEST-START | /mediacapture-streams/GUM-trivial-constraint.https.html 23:52:09 INFO - . 23:52:09 INFO - TEST-OK | /mediacapture-streams/GUM-trivial-constraint.https.html | took 1283ms 23:52:09 INFO - TEST-START | /mediacapture-streams/GUM-unknownkey-option-param.https.html 23:52:10 INFO - . 23:52:10 INFO - TEST-OK | /mediacapture-streams/GUM-unknownkey-option-param.https.html | took 1243ms 23:52:10 INFO - TEST-START | /mediacapture-streams/MediaDevices-enumerateDevices.https.html 23:52:11 INFO - . 23:52:11 INFO - TEST-OK | /mediacapture-streams/MediaDevices-enumerateDevices.https.html | took 989ms 23:52:11 INFO - TEST-START | /mediacapture-streams/MediaDevices-getUserMedia.https.html 23:52:12 INFO - . 23:52:12 INFO - TEST-OK | /mediacapture-streams/MediaDevices-getUserMedia.https.html | took 1131ms 23:52:12 INFO - TEST-START | /mediacapture-streams/MediaStream-MediaElement-preload-none.https.html 23:52:13 INFO - .. 23:52:13 INFO - TEST-OK | /mediacapture-streams/MediaStream-MediaElement-preload-none.https.html | took 1211ms 23:52:13 INFO - TEST-START | /mediacapture-streams/MediaStream-MediaElement-srcObject.https.html 23:52:15 INFO - . 23:52:15 INFO - TEST-OK | /mediacapture-streams/MediaStream-MediaElement-srcObject.https.html | took 1385ms 23:52:15 INFO - TEST-START | /mediacapture-streams/MediaStream-add-audio-track.https.html 23:52:16 INFO - . 23:52:16 INFO - TEST-OK | /mediacapture-streams/MediaStream-add-audio-track.https.html | took 1135ms 23:52:16 INFO - TEST-START | /mediacapture-streams/MediaStream-audio-only.https.html 23:52:17 INFO - . 23:52:17 INFO - TEST-OK | /mediacapture-streams/MediaStream-audio-only.https.html | took 1181ms 23:52:17 INFO - TEST-START | /mediacapture-streams/MediaStream-finished-add.https.html 23:52:18 INFO - . 23:52:18 INFO - TEST-OK | /mediacapture-streams/MediaStream-finished-add.https.html | took 1292ms 23:52:18 INFO - TEST-START | /mediacapture-streams/MediaStream-gettrackid.https.html 23:52:19 INFO - . 23:52:19 INFO - TEST-OK | /mediacapture-streams/MediaStream-gettrackid.https.html | took 1185ms 23:52:20 INFO - TEST-START | /mediacapture-streams/MediaStream-id-manual.https.html 23:52:30 INFO - PROCESS | 2317 | MARIONETTE LOG: INFO: Timeout fired 23:52:30 INFO - . 23:52:30 INFO - TEST-TIMEOUT | /mediacapture-streams/MediaStream-id-manual.https.html | took 10521ms 23:52:30 INFO - TEST-START | /mediacapture-streams/MediaStream-idl.https.html 23:52:31 INFO - . 23:52:31 INFO - TEST-OK | /mediacapture-streams/MediaStream-idl.https.html | took 1181ms 23:52:31 INFO - TEST-START | /mediacapture-streams/MediaStream-removetrack.https.html 23:52:32 INFO - . 23:52:32 INFO - TEST-OK | /mediacapture-streams/MediaStream-removetrack.https.html | took 1241ms 23:52:32 INFO - TEST-START | /mediacapture-streams/MediaStream-video-only.https.html 23:52:34 INFO - . 23:52:34 INFO - TEST-OK | /mediacapture-streams/MediaStream-video-only.https.html | took 1283ms 23:52:34 INFO - TEST-START | /mediacapture-streams/MediaStreamTrack-MediaElement-disabled-audio-is-silence.https.html 23:52:35 INFO - . 23:52:35 INFO - TEST-OK | /mediacapture-streams/MediaStreamTrack-MediaElement-disabled-audio-is-silence.https.html | took 1235ms 23:52:35 INFO - TEST-START | /mediacapture-streams/MediaStreamTrack-MediaElement-disabled-video-is-black.https.html 23:52:36 INFO - . 23:52:36 INFO - TEST-OK | /mediacapture-streams/MediaStreamTrack-MediaElement-disabled-video-is-black.https.html | took 1285ms 23:52:36 INFO - TEST-START | /mediacapture-streams/MediaStreamTrack-end.https.html 23:52:37 INFO - . 23:52:37 INFO - TEST-OK | /mediacapture-streams/MediaStreamTrack-end.https.html | took 1085ms 23:52:37 INFO - TEST-START | /mediacapture-streams/MediaStreamTrack-id.https.html 23:52:39 INFO - . 23:52:39 INFO - TEST-OK | /mediacapture-streams/MediaStreamTrack-id.https.html | took 1209ms 23:52:39 INFO - TEST-START | /mediacapture-streams/MediaStreamTrack-init.https.html 23:52:40 INFO - . 23:52:40 INFO - TEST-OK | /mediacapture-streams/MediaStreamTrack-init.https.html | took 1537ms 23:52:40 INFO - TEST-START | /mediacapture-streams/MediaStreamTrackEvent-constructor.https.html 23:52:41 INFO - ... 23:52:41 INFO - TEST-OK | /mediacapture-streams/MediaStreamTrackEvent-constructor.https.html | took 1046ms 23:52:41 INFO - TEST-START | /mixed-content/allowed/http-csp/same-host-https/link-css-tag/top-level/keep-scheme-redirect/allowed.https.html 23:52:43 INFO - . 23:52:43 INFO - TEST-OK | /mixed-content/allowed/http-csp/same-host-https/link-css-tag/top-level/keep-scheme-redirect/allowed.https.html | took 1389ms 23:52:43 INFO - TEST-START | /mixed-content/allowed/http-csp/same-host-https/link-prefetch-tag/top-level/keep-scheme-redirect/allowed.https.html 23:52:44 INFO - . 23:52:44 INFO - TEST-OK | /mixed-content/allowed/http-csp/same-host-https/link-prefetch-tag/top-level/keep-scheme-redirect/allowed.https.html | took 1528ms 23:52:44 INFO - TEST-START | /mixed-content/allowed/http-csp/same-host-https/object-tag/top-level/no-redirect/allowed.https.html 23:52:46 INFO - . 23:52:46 INFO - TEST-OK | /mixed-content/allowed/http-csp/same-host-https/object-tag/top-level/no-redirect/allowed.https.html | took 1480ms 23:52:46 INFO - TEST-START | /mixed-content/allowed/http-csp/same-host-https/script-tag/top-level/no-redirect/allowed.https.html 23:52:47 INFO - . 23:52:47 INFO - TEST-OK | /mixed-content/allowed/http-csp/same-host-https/script-tag/top-level/no-redirect/allowed.https.html | took 1544ms 23:52:47 INFO - TEST-START | /mixed-content/allowed/http-csp/same-host-https/video-tag/top-level/no-redirect/allowed.https.html command timed out: 7200 seconds elapsed running ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/web_platform_tests.py', '--cfg', 'web_platform_tests/prod_config.py', '--test-type=testharness', '--e10s', '--total-chunks', '5', '--this-chunk', '5', '--blob-upload-branch', 'mozilla-esr52', '--download-symbols', 'ondemand'], attempting to kill process killed by signal 9 program finished with exit code -1 elapsedTime=7200.056485 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' failed (results: 2, elapsed: 2 hrs, 0 secs) (at 2018-02-15 23:52:48.845378) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2018-02-15 23:52:48.846437) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056 _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.tar.bz2 program finished with exit code 0 elapsedTime=0.261529 build_url: 'https://queue.taskcluster.net/v1/task/WtnRxjXZSfmeKPXW3L-Qww/artifacts/public/build/firefox-52.6.1.en-US.linux-i686.tar.bz2' ========= master_lag: 0.03 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2018-02-15 23:52:49.141806) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2018-02-15 23:52:49.142342) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5a152710dd62cc268aad214e000002d8-1518760359.455106-869999056 _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.269099 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2018-02-15 23:52:49.448794) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2018-02-15 23:52:49.449307) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2018-02-15 23:52:49.449786) ========= ========= Total master_lag: 0.37 =========