builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-022 starttime: 1504257502.83 results: success (0) revision: 583e73fb8e3c734dbf3a5e13913df7617f5c492c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:22.826213) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:22.826716) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:22.827002) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:22.846745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:22.847141) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 02:18:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.8M=0s 2017-09-01 02:18:23 (45.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.160746 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:23.023107) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:23.023490) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015374 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:23.082706) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:23.083042) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 583e73fb8e3c734dbf3a5e13913df7617f5c492c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 583e73fb8e3c734dbf3a5e13913df7617f5c492c --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 02:18:23,139 truncating revision to first 12 chars 2017-09-01 02:18:23,139 Setting DEBUG logging. 2017-09-01 02:18:23,139 attempt 1/10 2017-09-01 02:18:23,139 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/583e73fb8e3c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 02:18:23,596 unpacking tar archive at: mozilla-central-583e73fb8e3c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.836754 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:23.936756) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:23.937092) ========= script_repo_revision: 583e73fb8e3c734dbf3a5e13913df7617f5c492c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:23.937458) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:23.937719) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 02:18:23.969791) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-09-01 02:18:23.970088) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=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=930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:18:24 INFO - MultiFileLogger online at 20170901 02:18:24 in /builds/slave/test 02:18:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:18:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:18:24 INFO - {'append_to_log': False, 02:18:24 INFO - 'base_work_dir': '/builds/slave/test', 02:18:24 INFO - 'blob_upload_branch': 'Firefox', 02:18:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:18:24 INFO - 'branch': 'Firefox', 02:18:24 INFO - 'buildbot_json_path': 'buildprops.json', 02:18:24 INFO - 'code_coverage': False, 02:18:24 INFO - 'config_files': ('talos/linux_config.py',), 02:18:24 INFO - 'default_actions': ('clobber', 02:18:24 INFO - 'read-buildbot-config', 02:18:24 INFO - 'download-and-extract', 02:18:24 INFO - 'populate-webroot', 02:18:24 INFO - 'create-virtualenv', 02:18:24 INFO - 'install', 02:18:24 INFO - 'setup-mitmproxy', 02:18:24 INFO - 'run-tests'), 02:18:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:18:24 INFO - 'disable_ccov_upload': False, 02:18:24 INFO - 'disable_stylo': False, 02:18:24 INFO - 'download_minidump_stackwalk': True, 02:18:24 INFO - 'download_symbols': 'ondemand', 02:18:24 INFO - 'enable_stylo': False, 02:18:24 INFO - 'enable_webrender': False, 02:18:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:18:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:18:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:18:24 INFO - 'gecko_profile': False, 02:18:24 INFO - 'gecko_profile_interval': 0, 02:18:24 INFO - 'installer_path': 'installer.exe', 02:18:24 INFO - 'log_level': 'info', 02:18:24 INFO - 'log_name': 'talos', 02:18:24 INFO - 'log_to_console': True, 02:18:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:18:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:18:24 INFO - 'opt_config_files': (), 02:18:24 INFO - 'pip_index': False, 02:18:24 INFO - 'suite': 'dromaeojs-e10s', 02:18:24 INFO - 'system_bits': '32', 02:18:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:18:24 INFO - 'title': 'talos-linux64-ix-022', 02:18:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:18:24 INFO - 'use_talos_json': True, 02:18:24 INFO - 'verify': 'False', 02:18:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:18:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:18:24 INFO - 'work_dir': 'build'} 02:18:24 INFO - [mozharness: 2017-09-01 09:18:24.160366Z] Running clobber step. 02:18:24 INFO - Running pre-action listener: _resource_record_pre_action 02:18:24 INFO - Running main action method: clobber 02:18:24 INFO - rmtree: /builds/slave/test/build 02:18:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:18:25 INFO - Running post-action listener: _resource_record_post_action 02:18:25 INFO - [mozharness: 2017-09-01 09:18:25.178305Z] Finished clobber step (success) 02:18:25 INFO - [mozharness: 2017-09-01 09:18:25.178403Z] Running read-buildbot-config step. 02:18:25 INFO - Running pre-action listener: _resource_record_pre_action 02:18:25 INFO - Running main action method: read_buildbot_config 02:18:25 INFO - Using buildbot properties: 02:18:25 INFO - { 02:18:25 INFO - "project": "", 02:18:25 INFO - "product": "firefox", 02:18:25 INFO - "who": "archaeopteryx@coole-files.de", 02:18:25 INFO - "installer_path": "public/build/target.tar.bz2", 02:18:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 02:18:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:18:25 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 02:18:25 INFO - "stage_platform": "linux64-qr", 02:18:25 INFO - "basedir": "/builds/slave/test", 02:18:25 INFO - "buildnumber": 24, 02:18:25 INFO - "platform": "ubuntu64_hw_qr", 02:18:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:18:25 INFO - "slavebuilddir": "test", 02:18:25 INFO - "taskId": "IROkZbbWTEa9cxx61E9SYA", 02:18:25 INFO - "branch": "mozilla-central", 02:18:25 INFO - "script_repo_revision": "production", 02:18:25 INFO - "revision": "583e73fb8e3c734dbf3a5e13913df7617f5c492c", 02:18:25 INFO - "slavename": "talos-linux64-ix-022", 02:18:25 INFO - "repo_path": "mozilla-central" 02:18:25 INFO - } 02:18:25 INFO - Finding installer, test and symbols from parent task. 02:18:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IROkZbbWTEa9cxx61E9SYA'}, attempt #1 02:18:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IROkZbbWTEa9cxx61E9SYA'}, attempt #1 02:18:26 INFO - Task dependencies: EVBwkXv4QIqaI8RoA8-sCA 02:18:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA'}, attempt #1 02:18:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.tar.bz2 02:18:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.test_packages.json 02:18:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.crashreporter-symbols.zip 02:18:26 INFO - Running post-action listener: _resource_record_post_action 02:18:26 INFO - [mozharness: 2017-09-01 09:18:26.515607Z] Finished read-buildbot-config step (success) 02:18:26 INFO - [mozharness: 2017-09-01 09:18:26.515824Z] Running download-and-extract step. 02:18:26 INFO - Running pre-action listener: _resource_record_pre_action 02:18:26 INFO - Running main action method: download_and_extract 02:18:26 INFO - mkdir: /builds/slave/test/build/tests 02:18:26 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')]} 02:18:26 INFO - trying https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.test_packages.json 02:18:26 INFO - Downloading https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:18:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:18:27 INFO - Downloaded 989 bytes. 02:18:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:18:27 INFO - Using the following test package requirements: 02:18:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:18:27 INFO - u'common': [u'target.common.tests.zip'], 02:18:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:18:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:18:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:18:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:18:27 INFO - u'mozbase': [u'target.common.tests.zip'], 02:18:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:18:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:18:27 INFO - u'web-platform': [u'target.common.tests.zip', 02:18:27 INFO - u'target.web-platform.tests.tar.gz'], 02:18:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:18:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:18:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.common.tests.zip 02:18:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:18:27 INFO - Fetch https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.common.tests.zip into memory 02:18:31 INFO - Content-Length response header: 54933742 02:18:31 INFO - Bytes received: 54933742 02:18:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:18:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.common.tests.zip 02:18:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:18:42 INFO - Fetch https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.common.tests.zip into memory 02:18:44 INFO - Content-Length response header: 54933742 02:18:44 INFO - Bytes received: 54933742 02:18:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.talos.tests.zip 02:18:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:18:58 INFO - Fetch https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.talos.tests.zip into memory 02:18:58 INFO - Content-Length response header: 14496650 02:18:58 INFO - Bytes received: 14496650 02:18: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')]} 02:18:59 INFO - trying https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.tar.bz2 02:18:59 INFO - Downloading https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:18:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:19:02 INFO - Downloaded 62501484 bytes. 02:19:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.tar.bz2 02:19:02 INFO - mkdir: /builds/slave/test/properties 02:19:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:19:02 INFO - Writing to file /builds/slave/test/properties/build_url 02:19:02 INFO - Contents: 02:19:02 INFO - build_url:https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.tar.bz2 02:19:02 INFO - Running post-action listener: _resource_record_post_action 02:19:02 INFO - Running post-action listener: find_tests_for_verification 02:19:02 INFO - Running post-action listener: set_extra_try_arguments 02:19:02 INFO - [mozharness: 2017-09-01 09:19:02.332771Z] Finished download-and-extract step (success) 02:19:02 INFO - [mozharness: 2017-09-01 09:19:02.332998Z] Running populate-webroot step. 02:19:02 INFO - Running pre-action listener: _resource_record_pre_action 02:19:02 INFO - Running main action method: populate_webroot 02:19:02 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:19:02 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:19:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:19:02 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:19:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:19:02 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:19:02 INFO - 'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:19:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:19:02 INFO - 'tests': ['damp', 'tps']}, 02:19:02 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:19:02 INFO - 'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['damp', 'tps']}, 02:19:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:19:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:19:02 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:19:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:19:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:19:02 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:19:02 INFO - 'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:19:02 INFO - 'other-e10s': {'tests': ['a11yr', 02:19:02 INFO - 'ts_paint', 02:19:02 INFO - 'tpaint', 02:19:02 INFO - 'sessionrestore', 02:19:02 INFO - 'sessionrestore_many_windows', 02:19:02 INFO - 'sessionrestore_no_auto_restore', 02:19:02 INFO - 'tabpaint']}, 02:19:02 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['a11yr', 02:19:02 INFO - 'ts_paint', 02:19:02 INFO - 'tpaint', 02:19:02 INFO - 'sessionrestore', 02:19:02 INFO - 'sessionrestore_many_windows', 02:19:02 INFO - 'sessionrestore_no_auto_restore', 02:19:02 INFO - 'tabpaint']}, 02:19:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:19:02 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:19:02 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['bloom_basic_singleton']}, 02:19:02 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['bloom_basic']}, 02:19:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:19:02 INFO - 'tsvgr_opacity', 02:19:02 INFO - 'tart', 02:19:02 INFO - 'tscrollx', 02:19:02 INFO - 'tsvg_static']}, 02:19:02 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['tsvgx', 02:19:02 INFO - 'tsvgr_opacity', 02:19:02 INFO - 'tart', 02:19:02 INFO - 'tscrollx', 02:19:02 INFO - 'tsvg_static']}, 02:19:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:19:02 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:19:02 INFO - 'talos_options': ['--enable-stylo'], 02:19:02 INFO - 'tests': ['tp5o']}, 02:19:02 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:19:02 INFO - 'talos_options': ['--mitmproxy', 02:19:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:19:02 INFO - '--firstNonBlankPaint'], 02:19:02 INFO - 'tests': ['tp6_google', 02:19:02 INFO - 'tp6_youtube', 02:19:02 INFO - 'tp6_amazon', 02:19:02 INFO - 'tp6_facebook']}, 02:19:02 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:19:02 INFO - 'talos_options': ['--enable-stylo', 02:19:02 INFO - '--mitmproxy', 02:19:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:19:02 INFO - '--firstNonBlankPaint'], 02:19:02 INFO - 'tests': ['tp6_google', 02:19:02 INFO - 'tp6_youtube', 02:19:02 INFO - 'tp6_amazon', 02:19:02 INFO - 'tp6_facebook']}, 02:19:02 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:19:02 INFO - 'talos_options': ['--enable-stylo', 02:19:02 INFO - '--stylo-threads=1', 02:19:02 INFO - '--mitmproxy', 02:19:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:19:02 INFO - '--firstNonBlankPaint'], 02:19:02 INFO - 'tests': ['tp6_google', 02:19:02 INFO - 'tp6_youtube', 02:19:02 INFO - 'tp6_amazon', 02:19:02 INFO - 'tp6_facebook']}, 02:19:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:19:02 INFO - 'talos_options': ['--xperf_path', 02:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:19:02 INFO - 'tests': ['tp5n']}, 02:19:02 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:19:02 INFO - 'talos_options': ['--enable-stylo', 02:19:02 INFO - '--xperf_path', 02:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:19:02 INFO - 'tests': ['tp5n']}}} 02:19:02 INFO - Running post-action listener: _resource_record_post_action 02:19:02 INFO - [mozharness: 2017-09-01 09:19:02.355275Z] Finished populate-webroot step (success) 02:19:02 INFO - [mozharness: 2017-09-01 09:19:02.355365Z] Running create-virtualenv step. 02:19:02 INFO - Running pre-action listener: _resource_record_pre_action 02:19:02 INFO - Running main action method: create_virtualenv 02:19:02 INFO - Creating virtualenv /builds/slave/test/build/venv 02:19:02 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:19:02 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:19:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:19:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:19:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:19:03 INFO - Installing distribute.............................................................................................................................................................................................done. 02:19:03 INFO - Installing pip...............done. 02:19:03 INFO - Return code: 0 02:19:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:19:03 INFO - Reading from file tmpfile_stdout 02:19:03 INFO - Output received: 02:19:03 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:19:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:19:03 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')]} 02:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:03 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')]} 02:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:03 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1be11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:19:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:19:03 INFO - Using env: {'DISPLAY': ':0', 02:19:03 INFO - 'HOME': '/home/cltbld', 02:19:03 INFO - 'LANG': 'en_US.UTF-8', 02:19:03 INFO - 'LANGUAGE': 'en_US:en', 02:19:03 INFO - 'LOGNAME': 'cltbld', 02:19:03 INFO - 'MAIL': '/var/mail/cltbld', 02:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:03 INFO - 'MOZ_NO_REMOTE': '1', 02:19:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:03 INFO - 'NO_EM_RESTART': '1', 02:19:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:03 INFO - 'PWD': '/builds/slave/test', 02:19:03 INFO - 'SHELL': '/bin/bash', 02:19:03 INFO - 'SHLVL': '1', 02:19:03 INFO - 'TERM': 'linux', 02:19:03 INFO - 'TMOUT': '86400', 02:19:03 INFO - 'USER': 'cltbld', 02:19:03 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:03 INFO - '_': '/tools/buildbot/bin/python'} 02:19:04 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:19:04 INFO - Downloading/unpacking pip>=1.5 02:19:04 INFO - Running setup.py egg_info for package pip 02:19:04 INFO - warning: no files found matching 'pip/cacert.pem' 02:19:04 INFO - warning: no files found matching '*.html' under directory 'docs' 02:19:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:19:04 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:19:04 INFO - Installing collected packages: pip 02:19:04 INFO - Found existing installation: pip 1.1 02:19:04 INFO - Uninstalling pip: 02:19:04 INFO - Successfully uninstalled pip 02:19:04 INFO - Running setup.py install for pip 02:19:04 INFO - warning: no files found matching 'pip/cacert.pem' 02:19:04 INFO - warning: no files found matching '*.html' under directory 'docs' 02:19:04 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:19:04 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:19:04 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:19:04 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:19:04 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:19:04 INFO - Successfully installed pip 02:19:04 INFO - Cleaning up... 02:19:04 INFO - Return code: 0 02:19:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:19:04 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')]} 02:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:04 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')]} 02:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:04 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1be11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:19:04 INFO - Using env: {'DISPLAY': ':0', 02:19:04 INFO - 'HOME': '/home/cltbld', 02:19:04 INFO - 'LANG': 'en_US.UTF-8', 02:19:04 INFO - 'LANGUAGE': 'en_US:en', 02:19:04 INFO - 'LOGNAME': 'cltbld', 02:19:04 INFO - 'MAIL': '/var/mail/cltbld', 02:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:04 INFO - 'MOZ_NO_REMOTE': '1', 02:19:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:04 INFO - 'NO_EM_RESTART': '1', 02:19:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:04 INFO - 'PWD': '/builds/slave/test', 02:19:04 INFO - 'SHELL': '/bin/bash', 02:19:04 INFO - 'SHLVL': '1', 02:19:04 INFO - 'TERM': 'linux', 02:19:04 INFO - 'TMOUT': '86400', 02:19:04 INFO - 'USER': 'cltbld', 02:19:04 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:04 INFO - '_': '/tools/buildbot/bin/python'} 02:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:04 INFO - Downloading/unpacking psutil>=3.1.1 02:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:19:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:19:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:19:06 INFO - Installing collected packages: psutil 02:19:06 INFO - Running setup.py install for psutil 02:19:06 INFO - building 'psutil._psutil_linux' extension 02:19:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:19:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:19:06 INFO - building 'psutil._psutil_posix' extension 02:19:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 02:19:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 02:19:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:19:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:19:06 INFO - Successfully installed psutil 02:19:06 INFO - Cleaning up... 02:19:06 INFO - Return code: 0 02:19:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:19:06 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')]} 02:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:06 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')]} 02:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:06 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1be11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:19:06 INFO - Using env: {'DISPLAY': ':0', 02:19:06 INFO - 'HOME': '/home/cltbld', 02:19:06 INFO - 'LANG': 'en_US.UTF-8', 02:19:06 INFO - 'LANGUAGE': 'en_US:en', 02:19:06 INFO - 'LOGNAME': 'cltbld', 02:19:06 INFO - 'MAIL': '/var/mail/cltbld', 02:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:06 INFO - 'MOZ_NO_REMOTE': '1', 02:19:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:06 INFO - 'NO_EM_RESTART': '1', 02:19:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:06 INFO - 'PWD': '/builds/slave/test', 02:19:06 INFO - 'SHELL': '/bin/bash', 02:19:06 INFO - 'SHLVL': '1', 02:19:06 INFO - 'TERM': 'linux', 02:19:06 INFO - 'TMOUT': '86400', 02:19:06 INFO - 'USER': 'cltbld', 02:19:06 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:06 INFO - '_': '/tools/buildbot/bin/python'} 02:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:19:08 INFO - Installing collected packages: mozsystemmonitor 02:19:08 INFO - Running setup.py install for mozsystemmonitor 02:19:08 INFO - Successfully installed mozsystemmonitor 02:19:08 INFO - Cleaning up... 02:19:08 INFO - Return code: 0 02:19:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:19:08 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')]} 02:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:08 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')]} 02:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19: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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1be11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:19:08 INFO - Using env: {'DISPLAY': ':0', 02:19:08 INFO - 'HOME': '/home/cltbld', 02:19:08 INFO - 'LANG': 'en_US.UTF-8', 02:19:08 INFO - 'LANGUAGE': 'en_US:en', 02:19:08 INFO - 'LOGNAME': 'cltbld', 02:19:08 INFO - 'MAIL': '/var/mail/cltbld', 02:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:08 INFO - 'MOZ_NO_REMOTE': '1', 02:19:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:08 INFO - 'NO_EM_RESTART': '1', 02:19:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:08 INFO - 'PWD': '/builds/slave/test', 02:19:08 INFO - 'SHELL': '/bin/bash', 02:19:08 INFO - 'SHLVL': '1', 02:19:08 INFO - 'TERM': 'linux', 02:19:08 INFO - 'TMOUT': '86400', 02:19:08 INFO - 'USER': 'cltbld', 02:19:08 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:08 INFO - '_': '/tools/buildbot/bin/python'} 02:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:08 INFO - Downloading/unpacking jsonschema==2.5.1 02:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:19:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:09 INFO - Downloading functools32-3.2.3-2.tar.gz 02:19:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:19:09 INFO - warning: no files found matching '*.txt' 02:19:09 INFO - no previously-included directories found matching 'build' 02:19:09 INFO - no previously-included directories found matching 'dist' 02:19:09 INFO - no previously-included directories found matching '.git*' 02:19:09 INFO - Installing collected packages: jsonschema, functools32 02:19:09 INFO - Running setup.py install for functools32 02:19:09 INFO - warning: no files found matching '*.txt' 02:19:09 INFO - no previously-included directories found matching 'build' 02:19:09 INFO - no previously-included directories found matching 'dist' 02:19:09 INFO - no previously-included directories found matching '.git*' 02:19:09 INFO - Successfully installed jsonschema functools32 02:19:09 INFO - Cleaning up... 02:19:09 INFO - Return code: 0 02:19:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:19: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')]} 02:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19: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')]} 02:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:09 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1be11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:19:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:19:09 INFO - Using env: {'DISPLAY': ':0', 02:19:09 INFO - 'HOME': '/home/cltbld', 02:19:09 INFO - 'LANG': 'en_US.UTF-8', 02:19:09 INFO - 'LANGUAGE': 'en_US:en', 02:19:09 INFO - 'LOGNAME': 'cltbld', 02:19:09 INFO - 'MAIL': '/var/mail/cltbld', 02:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:09 INFO - 'MOZ_NO_REMOTE': '1', 02:19:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:09 INFO - 'NO_EM_RESTART': '1', 02:19:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:09 INFO - 'PWD': '/builds/slave/test', 02:19:09 INFO - 'SHELL': '/bin/bash', 02:19:09 INFO - 'SHLVL': '1', 02:19:09 INFO - 'TERM': 'linux', 02:19:09 INFO - 'TMOUT': '86400', 02:19:09 INFO - 'USER': 'cltbld', 02:19:09 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:09 INFO - '_': '/tools/buildbot/bin/python'} 02:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:19:10 INFO - Cleaning up... 02:19:10 INFO - Return code: 0 02:19:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:19:10 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')]} 02:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:10 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')]} 02:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19: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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1be11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:19:10 INFO - Using env: {'DISPLAY': ':0', 02:19:10 INFO - 'HOME': '/home/cltbld', 02:19:10 INFO - 'LANG': 'en_US.UTF-8', 02:19:10 INFO - 'LANGUAGE': 'en_US:en', 02:19:10 INFO - 'LOGNAME': 'cltbld', 02:19:10 INFO - 'MAIL': '/var/mail/cltbld', 02:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:10 INFO - 'MOZ_NO_REMOTE': '1', 02:19:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:10 INFO - 'NO_EM_RESTART': '1', 02:19:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:10 INFO - 'PWD': '/builds/slave/test', 02:19:10 INFO - 'SHELL': '/bin/bash', 02:19:10 INFO - 'SHLVL': '1', 02:19:10 INFO - 'TERM': 'linux', 02:19:10 INFO - 'TMOUT': '86400', 02:19:10 INFO - 'USER': 'cltbld', 02:19:10 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:10 INFO - '_': '/tools/buildbot/bin/python'} 02:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:10 INFO - Downloading/unpacking blobuploader==1.2.4 02:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:11 INFO - Downloading blobuploader-1.2.4.tar.gz 02:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:19:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:19:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:11 INFO - Downloading docopt-0.6.1.tar.gz 02:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:19:12 INFO - Installing collected packages: blobuploader, requests, docopt 02:19:12 INFO - Running setup.py install for blobuploader 02:19:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:19:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:19:12 INFO - Running setup.py install for requests 02:19:12 INFO - Running setup.py install for docopt 02:19:12 INFO - Successfully installed blobuploader requests docopt 02:19:12 INFO - Cleaning up... 02:19:12 INFO - Return code: 0 02:19:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19: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')]} 02:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19: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')]} 02:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:12 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/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1be11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:19:12 INFO - Using env: {'DISPLAY': ':0', 02:19:12 INFO - 'HOME': '/home/cltbld', 02:19:12 INFO - 'LANG': 'en_US.UTF-8', 02:19:12 INFO - 'LANGUAGE': 'en_US:en', 02:19:12 INFO - 'LOGNAME': 'cltbld', 02:19:12 INFO - 'MAIL': '/var/mail/cltbld', 02:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:12 INFO - 'MOZ_NO_REMOTE': '1', 02:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:12 INFO - 'NO_EM_RESTART': '1', 02:19:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:12 INFO - 'PWD': '/builds/slave/test', 02:19:12 INFO - 'SHELL': '/bin/bash', 02:19:12 INFO - 'SHLVL': '1', 02:19:12 INFO - 'TERM': 'linux', 02:19:12 INFO - 'TMOUT': '86400', 02:19:12 INFO - 'USER': 'cltbld', 02:19:12 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:12 INFO - '_': '/tools/buildbot/bin/python'} 02:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:19:12 INFO - Running setup.py (path:/tmp/pip-JASjRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:19:12 INFO - Running setup.py (path:/tmp/pip-GhJE0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:19:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:19:12 INFO - Running setup.py (path:/tmp/pip-Tz3GWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:19:13 INFO - Running setup.py (path:/tmp/pip-P9r0Rc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:19:13 INFO - Running setup.py (path:/tmp/pip-63npda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:19:13 INFO - Running setup.py (path:/tmp/pip-HVJGZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:19:13 INFO - Running setup.py (path:/tmp/pip-vPb0ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:19:13 INFO - Running setup.py (path:/tmp/pip-qGTw3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:19:13 INFO - Running setup.py (path:/tmp/pip-bIX7_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:19:13 INFO - Running setup.py (path:/tmp/pip-swrBz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:19:13 INFO - Running setup.py (path:/tmp/pip-ZjLSQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:19:13 INFO - Running setup.py (path:/tmp/pip-RkGthl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:19:13 INFO - Running setup.py (path:/tmp/pip-MMk1kv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:19:13 INFO - Running setup.py (path:/tmp/pip-Pip3Hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:13 INFO - Running setup.py (path:/tmp/pip-7guixl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:19:13 INFO - Running setup.py (path:/tmp/pip-a9Q5nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:19:13 INFO - Running setup.py (path:/tmp/pip-i_EUtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:19:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:19:14 INFO - Running setup.py install for manifestparser 02:19:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:19:14 INFO - Running setup.py install for mozcrash 02:19:14 INFO - Running setup.py install for mozdebug 02:19:14 INFO - Running setup.py install for mozdevice 02:19:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:19:14 INFO - Running setup.py install for mozfile 02:19:14 INFO - Running setup.py install for mozhttpd 02:19:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:19:14 INFO - Running setup.py install for mozinfo 02:19:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:19:14 INFO - Running setup.py install for mozInstall 02:19:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:19:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:19:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:19:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:19:14 INFO - Running setup.py install for mozleak 02:19:14 INFO - Running setup.py install for mozlog 02:19:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:19:15 INFO - Running setup.py install for moznetwork 02:19:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:19:15 INFO - Running setup.py install for mozprocess 02:19:15 INFO - Running setup.py install for mozprofile 02:19:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:19:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:19:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:19:15 INFO - Running setup.py install for mozrunner 02:19:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:19:15 INFO - Running setup.py install for mozscreenshot 02:19:15 INFO - Running setup.py install for moztest 02:19:15 INFO - Running setup.py install for mozversion 02:19:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:19:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:19:15 INFO - Cleaning up... 02:19:15 INFO - Return code: 0 02:19:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19:15 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')]} 02:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:15 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')]} 02:19:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1be11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:19:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:19:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:19:15 INFO - Using env: {'DISPLAY': ':0', 02:19:15 INFO - 'HOME': '/home/cltbld', 02:19:15 INFO - 'LANG': 'en_US.UTF-8', 02:19:15 INFO - 'LANGUAGE': 'en_US:en', 02:19:15 INFO - 'LOGNAME': 'cltbld', 02:19:15 INFO - 'MAIL': '/var/mail/cltbld', 02:19:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:15 INFO - 'MOZ_NO_REMOTE': '1', 02:19:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:15 INFO - 'NO_EM_RESTART': '1', 02:19:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:15 INFO - 'PWD': '/builds/slave/test', 02:19:15 INFO - 'SHELL': '/bin/bash', 02:19:15 INFO - 'SHLVL': '1', 02:19:15 INFO - 'TERM': 'linux', 02:19:15 INFO - 'TMOUT': '86400', 02:19:15 INFO - 'USER': 'cltbld', 02:19:15 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:15 INFO - '_': '/tools/buildbot/bin/python'} 02:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:19:16 INFO - Running setup.py (path:/tmp/pip-XFN2Ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:19:16 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:19:16 INFO - Running setup.py (path:/tmp/pip-ofcayy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:19:16 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:19:16 INFO - Running setup.py (path:/tmp/pip-BGFPjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:19:16 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:19:16 INFO - Running setup.py (path:/tmp/pip-rKxiey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:19:16 INFO - Running setup.py (path:/tmp/pip-LoumM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:19:16 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:19:16 INFO - Running setup.py (path:/tmp/pip-todedo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:19:16 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:19:16 INFO - Running setup.py (path:/tmp/pip-RGM1UH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:19:16 INFO - Running setup.py (path:/tmp/pip-_DJTiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:19:16 INFO - Running setup.py (path:/tmp/pip-EeFQl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:19:16 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:19:16 INFO - Running setup.py (path:/tmp/pip-L3wVjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:19:16 INFO - Running setup.py (path:/tmp/pip-6KuCX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:19:16 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:19:16 INFO - Running setup.py (path:/tmp/pip-FagOFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:19:16 INFO - Running setup.py (path:/tmp/pip-ufGdSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:19:16 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)) 02:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:19:16 INFO - Running setup.py (path:/tmp/pip-hEmHq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:19:17 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)) 02:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:17 INFO - Running setup.py (path:/tmp/pip-lsGNf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:17 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)) 02:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:19:17 INFO - Running setup.py (path:/tmp/pip-wZTeOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:19:17 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)) 02:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:19:17 INFO - Running setup.py (path:/tmp/pip-IdwRGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:19:17 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)) 02:19:17 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)) 02:19:17 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)) 02:19:17 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)) 02:19:17 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:19:17 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:19:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:18 INFO - Downloading blessings-1.6.tar.gz 02:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:19:18 INFO - Installing collected packages: blessings 02:19:18 INFO - Running setup.py install for blessings 02:19:18 INFO - Successfully installed blessings 02:19:18 INFO - Cleaning up... 02:19:18 INFO - Return code: 0 02:19:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:19:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:19:18 INFO - Reading from file tmpfile_stdout 02:19:18 INFO - Current package versions: 02:19:18 INFO - argparse == 1.2.1 02:19:18 INFO - blessings == 1.6 02:19:18 INFO - blobuploader == 1.2.4 02:19:18 INFO - docopt == 0.6.1 02:19:18 INFO - functools32 == 3.2.3-2 02:19:18 INFO - jsonschema == 2.5.1 02:19:18 INFO - manifestparser == 1.1 02:19:18 INFO - mozInstall == 1.13 02:19:18 INFO - mozcrash == 1.0 02:19:18 INFO - mozdebug == 0.1 02:19:18 INFO - mozdevice == 0.51 02:19:18 INFO - mozfile == 1.2 02:19:18 INFO - mozhttpd == 0.7 02:19:18 INFO - mozinfo == 0.10 02:19:18 INFO - mozleak == 0.1 02:19:18 INFO - mozlog == 3.5 02:19:18 INFO - moznetwork == 0.27 02:19:18 INFO - mozprocess == 0.25 02:19:18 INFO - mozprofile == 0.28 02:19:18 INFO - mozrunner == 6.13 02:19:18 INFO - mozscreenshot == 0.1 02:19:18 INFO - mozsystemmonitor == 0.3 02:19:18 INFO - moztest == 0.8 02:19:18 INFO - mozversion == 1.4 02:19:18 INFO - psutil == 3.1.1 02:19:18 INFO - requests == 1.2.3 02:19:18 INFO - wsgiref == 0.1.2 02:19:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19: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')]} 02:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19: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')]} 02:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1be11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:19:18 INFO - Using env: {'DISPLAY': ':0', 02:19:18 INFO - 'HOME': '/home/cltbld', 02:19:18 INFO - 'LANG': 'en_US.UTF-8', 02:19:18 INFO - 'LANGUAGE': 'en_US:en', 02:19:18 INFO - 'LOGNAME': 'cltbld', 02:19:18 INFO - 'MAIL': '/var/mail/cltbld', 02:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:18 INFO - 'MOZ_NO_REMOTE': '1', 02:19:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:18 INFO - 'NO_EM_RESTART': '1', 02:19:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:18 INFO - 'PWD': '/builds/slave/test', 02:19:18 INFO - 'SHELL': '/bin/bash', 02:19:18 INFO - 'SHLVL': '1', 02:19:18 INFO - 'TERM': 'linux', 02:19:18 INFO - 'TMOUT': '86400', 02:19:18 INFO - 'USER': 'cltbld', 02:19:18 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:18 INFO - '_': '/tools/buildbot/bin/python'} 02:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:19:19 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:19:20 INFO - Installing collected packages: simplejson 02:19:20 INFO - Running setup.py install for simplejson 02:19:20 INFO - building 'simplejson._speedups' extension 02:19:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 02:19:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 02:19:21 INFO - Successfully installed simplejson 02:19:21 INFO - Cleaning up... 02:19:21 INFO - Return code: 0 02:19:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:19:21 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')]} 02:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:21 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')]} 02:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:21 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1be11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1daaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f4da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:19:21 INFO - Using env: {'DISPLAY': ':0', 02:19:21 INFO - 'HOME': '/home/cltbld', 02:19:21 INFO - 'LANG': 'en_US.UTF-8', 02:19:21 INFO - 'LANGUAGE': 'en_US:en', 02:19:21 INFO - 'LOGNAME': 'cltbld', 02:19:21 INFO - 'MAIL': '/var/mail/cltbld', 02:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:21 INFO - 'MOZ_NO_REMOTE': '1', 02:19:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:21 INFO - 'NO_EM_RESTART': '1', 02:19:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:21 INFO - 'PWD': '/builds/slave/test', 02:19:21 INFO - 'SHELL': '/bin/bash', 02:19:21 INFO - 'SHLVL': '1', 02:19:21 INFO - 'TERM': 'linux', 02:19:21 INFO - 'TMOUT': '86400', 02:19:21 INFO - 'USER': 'cltbld', 02:19:21 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:21 INFO - '_': '/tools/buildbot/bin/python'} 02:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:19:21 INFO - Cleaning up... 02:19:21 INFO - Return code: 0 02:19:21 INFO - Running post-action listener: _resource_record_post_action 02:19:21 INFO - Running post-action listener: _start_resource_monitoring 02:19:21 INFO - Starting resource monitoring. 02:19:21 INFO - [mozharness: 2017-09-01 09:19:21.608148Z] Finished create-virtualenv step (success) 02:19:21 INFO - [mozharness: 2017-09-01 09:19:21.608618Z] Running install step. 02:19:21 INFO - Running pre-action listener: _resource_record_pre_action 02:19:21 INFO - Running main action method: install 02:19:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:19:21 INFO - Reading from file tmpfile_stdout 02:19:21 INFO - Detecting whether we're running mozinstall >=1.0... 02:19:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:19:21 INFO - Reading from file tmpfile_stdout 02:19:21 INFO - Output received: 02:19:21 INFO - Usage: mozinstall [options] installer 02:19:21 INFO - Options: 02:19:21 INFO - -h, --help show this help message and exit 02:19:21 INFO - -d DEST, --destination=DEST 02:19:21 INFO - Directory to install application into. [default: 02:19:21 INFO - "/builds/slave/test"] 02:19:21 INFO - --app=APP Application being installed. [default: firefox] 02:19:21 INFO - mkdir: /builds/slave/test/build/application 02:19:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:19:38 INFO - Reading from file tmpfile_stdout 02:19:38 INFO - Output received: 02:19:38 INFO - /builds/slave/test/build/application/firefox/firefox 02:19:38 INFO - Running post-action listener: _resource_record_post_action 02:19:38 INFO - [mozharness: 2017-09-01 09:19:38.419425Z] Finished install step (success) 02:19:38 INFO - [mozharness: 2017-09-01 09:19:38.419678Z] Running setup-mitmproxy step. 02:19:38 INFO - Running pre-action listener: _resource_record_pre_action 02:19:38 INFO - Running main action method: setup_mitmproxy 02:19:38 INFO - Skipping: mitmproxy is not required 02:19:38 INFO - Running post-action listener: _resource_record_post_action 02:19:38 INFO - [mozharness: 2017-09-01 09:19:38.420800Z] Finished setup-mitmproxy step (success) 02:19:38 INFO - [mozharness: 2017-09-01 09:19:38.421019Z] Running run-tests step. 02:19:38 INFO - Running pre-action listener: _resource_record_pre_action 02:19:38 INFO - Running pre-action listener: _set_gcov_prefix 02:19:38 INFO - Running main action method: run_tests 02:19:38 WARNING - Try message not found. 02:19:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:19:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:19:38 INFO - Python 2.7.3 02:19:38 INFO - Return code: 0 02:19:38 INFO - grabbing minidump binary from tooltool 02:19:38 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')]} 02:19:38 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/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 0x1f51090>, '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 0x1f51d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f4bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:19:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:19:38 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 02:19:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:19:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:19:38 INFO - Return code: 0 02:19:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:19:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:19:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:19:38 INFO - ENV: RUST_BACKTRACE is now full 02:19:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:19:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:19:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:19:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:19:38 INFO - Using env: {'DISPLAY': ':0', 02:19:38 INFO - 'HOME': '/home/cltbld', 02:19:38 INFO - 'LANG': 'en_US.UTF-8', 02:19:38 INFO - 'LANGUAGE': 'en_US:en', 02:19:38 INFO - 'LOGNAME': 'cltbld', 02:19:38 INFO - 'MAIL': '/var/mail/cltbld', 02:19:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:19:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:19:38 INFO - 'MOZ_ACCELERATED': '1', 02:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:38 INFO - 'MOZ_NO_REMOTE': '1', 02:19:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:19:38 INFO - 'MOZ_WEBRENDER': '1', 02:19:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:38 INFO - 'NO_EM_RESTART': '1', 02:19:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:38 INFO - 'PWD': '/builds/slave/test', 02:19:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:19:38 INFO - 'RUST_BACKTRACE': 'full', 02:19:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:19:38 INFO - 'SHELL': '/bin/bash', 02:19:38 INFO - 'SHLVL': '1', 02:19:38 INFO - 'TERM': 'linux', 02:19:38 INFO - 'TMOUT': '86400', 02:19:38 INFO - 'USER': 'cltbld', 02:19:38 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:38 INFO - '_': '/tools/buildbot/bin/python'} 02:19:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:19:38 INFO - mozversion application_buildid: 20170901083126 02:19:38 INFO - mozversion application_changeset: 583e73fb8e3c734dbf3a5e13913df7617f5c492c 02:19:38 INFO - mozversion application_display_name: Nightly 02:19:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:19:38 INFO - mozversion application_name: Firefox 02:19:38 INFO - mozversion application_remotingname: firefox 02:19:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:19:38 INFO - mozversion application_vendor: Mozilla 02:19:38 INFO - mozversion application_version: 57.0a1 02:19:38 INFO - mozversion platform_buildid: 20170901083126 02:19:38 INFO - mozversion platform_changeset: 583e73fb8e3c734dbf3a5e13913df7617f5c492c 02:19:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:19:38 INFO - mozversion platform_version: 57.0a1 02:19:38 INFO - using testdate: 1504257578 02:19:38 INFO - actual date: 1504257578 02:19:38 INFO - starting webserver on 'localhost:57988' 02:19:38 INFO - SUITE-START | Running 2 tests 02:19:38 INFO - TEST-START | dromaeo_css 02:19:38 INFO - Initialising browser for dromaeo_css test... 02:19:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57988/getInfo.html -profile /tmp/tmp2DZ4n6/profile 02:19:38 INFO - TEST-INFO | started process 3140 (/builds/slave/test/build/application/firefox/firefox http://localhost:57988/getInfo.html) 02:19:45 INFO - TEST-INFO | 3140: exit 0 02:19:45 INFO - Browser initialized. 02:19:45 INFO - Running cycle 1/1 for dromaeo_css test... 02:19:45 INFO - Using env: {'DISPLAY': ':0', 02:19:45 INFO - 'HOME': '/home/cltbld', 02:19:45 INFO - 'JSGC_DISABLE_POISONING': '1', 02:19:45 INFO - 'LANG': 'en_US.UTF-8', 02:19:45 INFO - 'LANGUAGE': 'en_US:en', 02:19:45 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:19:45 INFO - 'LOGNAME': 'cltbld', 02:19:45 INFO - 'MAIL': '/var/mail/cltbld', 02:19:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:19:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:19:45 INFO - 'MOZ_ACCELERATED': '1', 02:19:45 INFO - 'MOZ_CRASHREPORTER': '1', 02:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:45 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:19:45 INFO - 'MOZ_NO_REMOTE': '1', 02:19:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:19:45 INFO - 'MOZ_WEBRENDER': '1', 02:19:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:45 INFO - 'NO_EM_RESTART': '1', 02:19:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:45 INFO - 'PWD': '/builds/slave/test', 02:19:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:19:45 INFO - 'RUST_BACKTRACE': 'full', 02:19:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:19:45 INFO - 'SHELL': '/bin/bash', 02:19:45 INFO - 'SHLVL': '1', 02:19:45 INFO - 'TERM': 'linux', 02:19:45 INFO - 'TMOUT': '86400', 02:19:45 INFO - 'USER': 'cltbld', 02:19:45 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:45 INFO - '_': '/tools/buildbot/bin/python'} 02:19:45 INFO - TEST-INFO | started process 3336 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2DZ4n6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:19:46 INFO - PID 3336 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:19:46 INFO - PID 3336 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:19:46 INFO - PID 3336 | 02:19:46 INFO - PID 3336 | (/builds/slave/test/build/application/firefox/firefox:3397): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:19:46 INFO - PID 3336 | 02:19:57 INFO - PID 3336 | RSS: Main: 207212544 02:19:57 INFO - PID 3336 | 02:20:00 INFO - PID 3336 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:22:56 INFO - PID 3336 | Cycle 1(1): loaded http://localhost:57988/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57988/tests/dromaeo/cssquery-ext.html) 02:22:58 INFO - PID 3336 | RSS: Main: 220311552 02:22:58 INFO - PID 3336 | 02:26:00 INFO - PID 3336 | Cycle 1(1): loaded http://localhost:57988/tests/dromaeo/cssquery-ext.html (next: http://localhost:57988/tests/dromaeo/cssquery-jquery.html) 02:26:00 INFO - PID 3336 | RSS: Main: 217120768 02:26:00 INFO - PID 3336 | 02:29:03 INFO - PID 3336 | Cycle 1(1): loaded http://localhost:57988/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57988/tests/dromaeo/cssquery-mootools.html) 02:29:03 INFO - PID 3336 | RSS: Main: 220516352 02:29:03 INFO - PID 3336 | 02:32:05 INFO - PID 3336 | Cycle 1(1): loaded http://localhost:57988/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57988/tests/dromaeo/cssquery-prototype.html) 02:32:06 INFO - PID 3336 | RSS: Main: 218370048 02:32:06 INFO - PID 3336 | 02:35:10 INFO - PID 3336 | Cycle 1(1): loaded http://localhost:57988/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57988/tests/dromaeo/cssquery-yui.html) 02:35:10 INFO - PID 3336 | RSS: Main: 222060544 02:35:10 INFO - PID 3336 | 02:38:13 INFO - PID 3336 | Cycle 1(1): loaded http://localhost:57988/tests/dromaeo/cssquery-yui.html (next: http://localhost:57988/tests/dromaeo/cssquery-dojo.html) 02:38:13 INFO - PID 3336 | RSS: Main: 223072256 02:38:13 INFO - PID 3336 | 02:38:13 INFO - PID 3336 | __start_tp_report 02:38:13 INFO - PID 3336 | _x_x_mozilla_page_load 02:38:13 INFO - PID 3336 | _x_x_mozilla_page_load_details 02:38:13 INFO - PID 3336 | |i|pagename|runs| 02:38:13 INFO - PID 3336 | |0;dojo.html;3211.1811488070553;3272.198311413704;3338.298957218984;3339.1652086978256;3376.67921547453;6227.68861556922;6276.466500347465;6279.277883043452;6294.370562943704;6294.559380843337;6267.310595834466;6268.122462855204;6286.46565041972;6298.3386744391755;6313.905291420621;5781.768729250825;5800.738966746507;5807.274090738668;5823.06830907055;5882.5588080893895;5858.56060795442;5905.173275741399;5941.524678025748;6010.729517171738;6058.39416058395;5955.255593050869;5979.342272350038;6032.788852390146;6040.305364883051;6063.029915213567;5846.912296315559;5935.821925342247;5936.732847021873;5936.931352356574;6067.120267561201;5627.746751396197;5746.224259724925;5760.884782304331;5802.5938184327115;5937.881242375129;6365.80902572919;6487.772927947546;6534.575252608565;6541.803745887631;6556;6903.068085808452;6920.861582768367;6990.161180658302;7004.929950700479;7009.4392448603985;6632.104665870094;6781.49138814586;6782.220044694874;6964.756233531802;7027.332403421667;6303.590266632654;6311.0217916223055;6318.210223721989;6331.936680633226;6334.841628959267;6873.896891546631;7003.509754317251;7007.929920700728;7013.579185248901;7019.052427922267;6635.071090047397;6675.132232809704;6680;6681.365270299314;6685.364890335411;6794.694238759268;6812.080369150101;6817.454603631698;6920.342567456084;6928.237893831674;6570.868582628416;6853.623050732258;6860.382565569123;6957.095577574883;6977.7208911642965;6761.087253220752;6769.695363708645;6790.354916283044;6792.490563207779;6797.082393876863;5310.973445132827;5334.119870221448;5358.223057656618;5370.4361042090795;5382.30030096085;4364.1708075466195;4694.155052090657;4711.528847115261;4718.339432479455;4752.1921273383605;5823.5341172706085;5862.648241105548;5879.882402351929;5887.381824908409;5894.2926848778425;6723.596584204865;6748.29142939994;6764.391204791593;6768.661566921634;6776.186857577122;5859.414058594107;5866.2373891393845;5871.148683440877;5871.295444546597;5871.471567558855;5748.770049197985;5792.594518383757;6006.849828754316;6007.759689612454;6009.489193418608;6636.5354425190735;6674.332566743286;6689.464842812563;6779.2881747416795;6779.62712050832;6663.500237482305;6748.122744043243;6759.594424334556;6787.151606049244;6913.204981426999;6472.190976127984;6486.837829054311;6491.091247225487;6512.218533775977;6516.380943810331;6624.403803657694;6627.403533681992;6644.534882558368;6660.500462465431;6741;6924.688389022607;6939.583624982315;6943.583384996714;7059.611721355374;7069.469789765891;6469.5794773339585;6565.80302590923;6704.363085506869;6783.559068660521;6785.796426107225;6607.108040414482;6634.402903738686;6647.634380108947;6698.631575263407;6758.594484330956;6569.4744420445295;6630.701618427279;6658.43403310724;6659.4006539411685;6687.76592819249;6766.4248538874745;6766.966165169143;6821.556598821061;6822.488313376417;6858;6236.407541283571;6379.936200637934;6415.422611964946;6433.517486188648;6598.208215014229 02:38:13 INFO - PID 3336 | |1;ext.html;788.3653658804664;821.1706176761469;857.9056303806582;876.347121394561;880.1286726141122;40545.3918191227;40704.79647601758;41314.38028429572;41641.16717665648;41722.582774172246;37722;37930;38072.80963595178;38151.80924095382;38189.23621527575;6847.863042739143;6961.477889158308;6966.37302642762;6967.1291088613925;6968.233494315621;6844.5208835381545;6929.341712537326;6933.445324374038;6934.89597656033;6935.133108361455;6897.275786042468;6925.688344024531;7384.926150738477;7663.463557550974;7670.424718146133;7541.09506859175;7541.6983320667105;7587.658555365022;7608.315251627325;7653.693852245903;7461.328480436787;7519.812004699927;7530.171681115073;7544.849103017909;7547.320741133324;36849.26301473982;37779.05552361185;37795.81102094472;37969;37969.62030379689;3445.6898879101;3482.181687303482;3499.9650003500146;3505.4391297392294;3510.069831494656;31260.843695781376;31334.373312533622;31375.37249255025;31466.37067258665;31474.527882081788;5077.314562534048;5088.643794934356;5105.693658380472;5115.309433226504;5153.2270159476;220421;221995;222612;222635;223331.88334058388;45731.5426845734;45747.31379029317;45895.770521147184;46000.539994599625;46034.30948535775;48725.26912096321;48963.26555101676;49161;49171.75414122907;49214.50785492171;15033.624159395884;15432.38270469191;15440.922795385952;15444.613884652974;15445.536633901002;12635.1787133836;12644.304563248925;12645.367731613382;12656.493740250287;12685.93657031709;13610.659733506543;13672.906167506575;13774.104683195561;13879.514217002357;14060.015798893979;5645.350784659817;5669.376368599534;5704.401037891044;5718.599698021182;5826.82519524415;988.229181238635;990.4799980010553;1000.1598657127902;1008.3496144986443;1029.3052189771875;22859.652274587246;22914.65628015581;23381.883090584437;23485.295441136794;23779.643305350433;1096.4627332607124;1102.6967583914488;1118.8657361116555;1128.0975219824109;1138.9886101138882;15872.841271587136;16017.839821601869;16297.918510407371;16314.184290785413;16550.6689866202;216016;216836;217183;217336;217636;44592.55407445949;44609.77695111569;44761.776191118835;53313;53906.574204160905;1415.7947097670985;1416.2226405860579;1424.1526291856328;1434.7202295552268;1438.467766926223;27144.321391964568;27398.45203095887;27673.861630691717;27686.86156569204;28054;36058.63941360553;36370.4544431839;36394.45408318824;36407.635923640424;36411;183375;184469;184947;185269;186095;32472.67527324791;32475.18812029718;32535.18662033468;32707.34585308328;32808.359582020326;40275;40418;40685.38971915483;41280.58719412888;41319.1736165281;30613.846930765205;30951.69048309488;31298.21754456157;32629.18427039343;34116.488252675736;33131.83434082814;33307.500387493725;33822.66177338293;34159.487607685405;34578.82710586431;36639;36940.26119477649;37116.44325335176;37190.512379504515;37202.06994825043;32465.675343246265;32539.51190732188;32561.18597035093;32617;32694.67305326916;24656.753432465444;24704;25032.499350013262;25302;25416.745832541437 02:38:13 INFO - PID 3336 | |2;jquery.html;557.5539568345323;569.2855466389681;573.123121623915;578.3869098755318;580.4630716587155;20604.17583296668;21293.25473608422;21298.680519792197;21390.465238369048;22535.436614084614;24991.375215619617;26062.869685651593;26070.08754693586;26081.47837043258;26082.478350433026;6965.651717414147;7114.750983715545;7120.572765634052;7164.355208031277;7164.426845852346;4285.5928686774705;5682.204491371212;7125.42996560274;7175.425965922714;7176.497645164841;4279.422277992478;4284.314509678452;6957.0955775749335;6992.265812089734;6992.6154061526595;7010.1587809462935;7020.157581090276;7022;7028.437724981989;7033.50765446419;6852.931470685279;6856.074429951943;6862.107925969642;6862.313768623148;6883.621400822953;29838.25404364908;30747;30936;31267.37465250705;31480.842595786875;2034.6235946349825;2077.5637116205617;2083.2292051940726;2083.8332933365296;2093.7487501499763;19948.70076948847;20615.484612884648;20672.58654826895;20681.172753089857;20729.378118656463;2466.1491785334033;2492.6011838105815;2552.4895020995873;2559.2706078767646;2567.075852693029;133042.3347883264;138007.99999999898;139801;140048;140937.29531352277;20004.599908001757;21643.567128657338;21911.890440548013;22081.779182208295;22304.665430018562;22179.445513862283;23138.3058508245;23213.53572928532;42129.36805947913;42332.788336058125;9820.901790982141;10045.447500387549;10128.949355253177;10299.279050466395;10335.22485813567;9227.815443691223;9260.953695231481;9308.720738377859;9399.624015039322;9459.52702364879;8949.328800340001;8967.775805604912;9004;9299.860502092473;9419.811603767886;2725.986370068137;2735.3571910600995;2735.972640273572;2781.0822428598512;2897.3046468847747;971.3589031239349;981.3228872078244;988.7231575158967;989.4113002763344;989.7525618595351;20010.799892001185;20143.597128057358;20215.39353819388;20385.499487526635;20469.2835750748;1041.156663102889;1045.4563626913962;1055.0873494427265;1057.471264367816;1058.523664351045;11150.832737508943;11245.212835101463;11290.153238507144;11336.829947550794;11441.771164576661;459618;470609;472283;480808;482582;106233;108669.45665271781;111880;114182;116520.41739791246;1163.790517706821;1182.7516221593562;1187.893089621938;1199.5621598116625;1201.0991756182864;17792.55518612045;18012;18020.909895450437;18119.818801811813;18388.540286492946;45235;49317.75341123271;51365;54577.72711136578;57357;66888.6655566719;67536.32463675301;67926.32073679398;68972.31027689659;69128.3087169142;14278.2146981917;14602.561923142322;14715.705685886436;14759.188244646648;14842.777358339417;20908.895455522626;21075.78924210738;21227.575448490636;22772.975216114985;23021;17775.377861774778;18445;18682.813171868653;18804.717929231345;18977.33579324717;17030.57423564421;17257.223424945656;17450.47648570545;17861.642767144844;17869.106544672974;18537.629247414705;18971.52571185676;19121.330753423565;19169.904150479157;19346.903265483583;14028.649283767987;14629.707405852036;14720.263986800832;14752.704945901236;14753.336099875316;17997.820021799613;18509.167087480826;18584.535386615444;18617.348392806187;18640.906795465937 02:38:13 INFO - PID 3336 | |3;mootools.html;1317.216256327485;1374.1205628397827;1379.910305830121;1387.382614736442;1392.6587985943445;15051.548453546384;15103.773443398344;15726.52820415388;15732.685346293081;15766.448174313902;15634;15941.362345506195;15977.520674379788;15987.920060399681;16008.359665613361;2732.9726702733014;2780;2781.3463835998523;2782.6382570265837;2788.776897848177;2613.451175253199;2691;2692.2730862667067;2693.3132051326984;2693.488237234919;2592.5333439980795;2702.8648567571545;2704.756571908528;2704.9594256086175;2708.7832973362083;2727.6726792784793;2734.371094648232;2760.944781104387;2761.337279053033;2762.350847550824;2671.479061582992;2700.054980756739;2708.404151086758;2708.7426694464193;2710.295323215978;14695.926520367437;14908.254587270592;15007.099574025484;15064;15098.54704358871;7965.203479651988;8196.098429172785;8228.547429891354;8231.46495477798;8252.628631711528;5204.635675502716;5344.866378340573;5400.108982017962;5467.20725494805;5479.6164268501225;1854.4158590044228;1876.5777700017522;1877.0581778265532;1941.8931958742264;1956.1295223625493;23519.4120146996;24328.27015189547;25951.351216219744;26811.597826032626;26851;6664.833379165462;6695.631740254235;6699.464042876558;6797.898031529531;6871.312868713088;17079.82920170807;17130;17211.56971075708;17282.82717172837;17292.740608890876;7676.57778822159;7890.053193616688;7924.088729796032;7927.8810817837775;7932.127465978853;7127.607981561064;7299.854002919912;7335;7367.968484412187;7384.556926584315;4971.925421118686;5130.333056702605;5176.81881134166;5181.4559471255725;5182.922256166161;1422.11118051218;1423.978640320396;1424.77203647418;1427.978580321296;1448;882.9735107946772;895.1853813030242;916.8212198621205;919.5310391700281;920.9171174594319;7708.653110610036;7713.112992005879;7737.767866964;8683.131686831393;8713.25937295337;681.3118750062473;683.2143035509204;683.2859661653584;691.5608588546208;695.9025736396909;5392.56859451243;5443.564514838803;5450.645708028966;5481.314835645544;5621.241132447068;20302.69545956812;21836.781632183793;22169.556608867733;22264;22303.107875685117;20176.596468070555;21511.46221344479;21597.67603485949;21683.67474487884;21777.891110544348;10711.83932241017;10736.624218152327;10915.23593348458;10982.835257471144;11109.666709998713;8213.507189568645;8228.382871284797;8295.460795048302;8387.538685372363;8463.195996380335;17122.14389280506;17198.398056067977;17285.91357043207;17408.129593520527;17556;17442;17934.641307173522;17974.730379044064;17992.91003544974;18887.811121888604;11299.604513841976;11652.30086194831;11674.124440666814;11702.590409335973;11703.23928944616;13244.735105297646;13570.321483925567;13693.520726774515;13704.177749335071;13724.451021959409;10784;11033.889661103287;11058.22592418523;11366.829497557708;11386.715332116431;10597.205209609156;10712.517936693024;10810.729731756453;10906.672799816019;10994.065504432212;9579;9756.170725488413;9782.85325720129;9815;9823.754406139904;6397.96801015992;6522.6412547308455;6590.8352291193105;6616.900746488903;7186.6704659638135;7131.572105673677;7182.348010059629;7226.674799634135;7233;7255.455840811855 02:38:13 INFO - PID 3336 | |4;prototype.html;488.56781112683404;499.715730572429;530.3000039947271;530.4324372920975;533.4130960392829;6161.075838624209;6362.9819228923325;6415.711292991815;6418.775342863;6463;6143;6191.659458729768;6206.2552493700705;6227.065940108974;6263.812085637427;5059.924101138467;5089.109405853979;5091.0072535855525;5134.820281290155;5150.3047088642925;5005.1491246488;5017.523335283142;5052.9747351263195;5057.5585958001975;5065.341505604266;4852.344933433977;4970.453250142481;5012.423571289312;5040.294358789773;5043.503465784442;4952.380952380952;5025.497450254945;5043.167877300278;5059.367579052637;5072.2391641253735;4431.534688857688;4527.683062185648;4529.433820772403;4535.9546404536195;4580.9541904580865;6128.816135515942;6159.476444502223;6172.691365431755;6190.876182476325;6232.563720539564;5785.855353616151;5805.796797112081;5811.360750317462;5811.4479124483105;5847.649141051551;6687.49531355446;6693.263740988487;6702.798916032527;6731.461483081342;6743.5953842769595;4869.513048695102;4934.4572097069295;4935.407751069859;4937.555619994182;4946.752662366904;6871.869720386696;7026.365113082594;7043.190033146151;7060.717571297142;7066.152061752571;5009.323741294878;5039.596832253411;5072.199369223946;5084.237364395371;5088.338515992898;5122.797197205163;5162.896742065138;5164.2770012198325;5171.198464238049;5184.377874655065;5722.341930677942;5750.942490575125;5758.452946970031;5769.423057694281;5780.42195780427;6625.569337993015;6632.270450250565;6636.800895973129;6650.135482387259;6685.598864068172;1780.5103596510803;1781.9287228510714;1782.2069179215125;1799.7840259168984;1800.396867049527;1151.4818331750578;1164.980641938304;1169.6491052684162;1219.9573014944435;1233.1306428967557;5816.941830581724;5849.391417360175;5858.50202732764;5865.035213203624;5931;6686.3313668632745;6729.798106056826;6732.360425759643;6771.62756048422;6817.079694241214;5684.6020778545935;5798;5861.505316144441;5925.644461332334;5980.714146458446;3588.300281445145;3611.8916432507067;3644.2711457708565;3651.8904432867057;3656.177360094011;6504.544681872224;6509.544331896816;6535.509836762262;6577.2241494796135;6683.565568238049;6946;7027.402670773042;7045.330693584101;7047.506674532733;7116.252793456716;6642.568233064835;6661.367170118831;6663.800085997331;6742.157230346207;6744.494162937799;6786.558873673195;6872.6907289173105;6874.621895795779;6899.93100069013;6939.583624982517;6784.59292442455;6791.252962174256;6814.693338799667;6820.215675197217;6839.897401538881;6513.348665133638;6547.672616369067;6669.265990842513;6678;6679.56582822115;4955.603551715926;4986.975065124651;5058.54473097423;5059.544640982329;5093.617978651542;6869.210040845167;6876.621785801829;6901.930980690329;6953.130858642669;7008.334208250412;6563.868722625616;6602.273749887605;6663.26704062543;6666.200055993312;6719.529632925648;6519.869602608016;6555.6066636002;6632.673465307054;6664.966675166593;6665.266820649627;6246.656433896179;6395.360463953567;6444.45222156122;6511.186101737283;6554.4756419485375;6514.1531600891585;6590;6653.933460665525;6708.42978346846;6710.295418981132;6343.016832390987;6402.487800975818;6409.948816378714;6497.870042599027;6521.184851893513 02:38:13 INFO - PID 3336 | |5;yui.html;889.9332550058747;898.6001229452894;910.7131253655102;913.9170083451111;921.8663293822395;18137.727934080984;18798.43604691858;18825.435236942896;18862;18877.433676989695;15749.370025199007;15884.84115158848;18235.99702016392;21823.781762182374;21876.781232187674;939.3706216834703;967.7919247361816;971.8687977123069;974.9171320437771;976.1897624971257;982.778874753182;985.6500942165535;986.9703908882746;988.5502096546054;994.6220436234221;914.9542522873863;1016.0702956794561;1018.9337693049965;1024.3085917006028;1026.6355443817413;1019.3578045831116;1021.6934919524113;1022.4938655365642;1022.6829682798319;1023.3399457350042;742.7289039500597;745.2324106170653;746.4550877859139;748.5845355827513;748.7716246544802;18240.817591824176;18877.622447550973;18911.716324255147;19050.52373690655;20298.797012029838;21093.367198984055;21205.893970530204;21559.568808623895;21637;21637;16204.108774017424;16220.51338459848;16465.506034818976;16632.168391580373;16646.75029874553;3710.3877860153043;3717.3308804415196;3741.494898188737;3760.7743535387963;3765.5669597996307;121589.39205304005;122308.38845805803;123376.6818161369;126476.36761816224;131057;16029;16048.75926861098;16291;16298;17389.478315650296;28727.569086463718;29290.267743306587;29536.261593459905;29572.40855182884;29607.703922960496;9020.05289444612;9039.502827344559;9043.773905652411;9087.863682044775;9130.26957843371;4125.360569111793;4208.789560521962;4222.873313800529;4230.963413963537;4237.576242375738;5075.1625981712605;5274.3143391359645;5279.683219006872;5300.12547929595;5338.786448542015;937.3625934364698;960.9567569459251;966.8066386722684;970.8737864077625;984.3060642247199;671.6071098407486;674.2482132422314;690.7685925215109;694.2398074108843;694.6804469943883;11563.942180289045;11649.068074554016;11860.110491713156;12123.393830308449;12310.322932238812;680.4080450008524;686.255412877034;686.9587824730532;698.2563569798149;700.2717174138838;5136.203888397305;5190.6107041972;5255;5336.493033161843;5362.624616276823;111877.44061279642;112861;113431;114339;115621.42189289168;100552.3149744829;101385.49307253564;101873.94378168667;104926;106567;89173.55413222892;89966.55016724874;90665.54667226621;91719.5414022939;92384;11259.211855170059;11982.460789264329;12106.213096148722;12367.814482782585;12515.413378063302;40923.7953810229;41004.79497602493;41155.1768964625;41215.7939210314;43340;68344.31655683578;70610.29389706242;71773.56452871018;72082.63958680173;74798.6260068718;18054.368097116538;18348.08259587042;18446.262149514405;18591.81408185955;18619.720704189178;17456.73814892803;18336;18932.053397330354;18975.620487590448;19156.80843191606;17497.56256093608;17666.823331766518;18212.36256731008;18423.53941151482;18423.72364414508;17263.913680431517;17507.649847003242;17520.123993800004;18100.72848907241;18209.180586873357;16086.35654573804;16157.67684646277;16265.918670406572;16487.83512164911;16524.256408461406;11907.642770716893;12184.695382615506;12535.373231338213;12548.937255314031;12737.29944853042;12595.622131335707;12741.872581274069;12835.614931552069;12884.033697472914;13614.591562252752 02:38:13 INFO - PID 3336 | __end_tp_report 02:38:13 INFO - PID 3336 | __start_cc_report 02:38:13 INFO - PID 3336 | _x_x_mozilla_cycle_collect,366 02:38:13 INFO - PID 3336 | __end_cc_report 02:38:13 INFO - PID 3336 | __startTimestamp1504258693255__endTimestamp 02:38:13 INFO - PID 3336 | 02:38:13 INFO - PID 3336 | ------- Summary: start ------- 02:38:13 INFO - PID 3336 | Number of tests: 6 02:38:13 INFO - PID 3336 | 02:38:13 INFO - PID 3336 | [#0] dojo.html Cycles:165 Average:6283.63 Median:6534.58 stddev:745.10 (11.4%) stddev-sans-first:707.32 02:38:13 INFO - PID 3336 | Values: 3211.2 3272.2 3338.3 3339.2 3376.7 6227.7 6276.5 6279.3 6294.4 6294.6 6267.3 6268.1 6286.5 6298.3 6313.9 5781.8 5800.7 5807.3 5823.1 5882.6 5858.6 5905.2 5941.5 6010.7 6058.4 5955.3 5979.3 6032.8 6040.3 6063.0 5846.9 5935.8 5936.7 5936.9 6067.1 5627.7 5746.2 5760.9 5802.6 5937.9 6365.8 6487.8 6534.6 6541.8 6556.0 6903.1 6920.9 6990.2 7004.9 7009.4 6632.1 6781.5 6782.2 6964.8 7027.3 6303.6 6311.0 6318.2 6331.9 6334.8 6873.9 7003.5 7007.9 7013.6 7019.1 6635.1 6675.1 6680.0 6681.4 6685.4 6794.7 6812.1 6817.5 6920.3 6928.2 6570.9 6853.6 6860.4 6957.1 6977.7 6761.1 6769.7 6790.4 6792.5 6797.1 5311.0 5334.1 5358.2 5370.4 5382.3 4364.2 4694.2 4711.5 4718.3 4752.2 5823.5 5862.6 5879.9 5887.4 5894.3 6723.6 6748.3 6764.4 6768.7 6776.2 5859.4 5866.2 5871.1 5871.3 5871.5 5748.8 5792.6 6006.8 6007.8 6009.5 6636.5 6674.3 6689.5 6779.3 6779.6 6663.5 6748.1 6759.6 6787.2 6913.2 6472.2 6486.8 6491.1 6512.2 6516.4 6624.4 6627.4 6644.5 6660.5 6741.0 6924.7 6939.6 6943.6 7059.6 7069.5 6469.6 6565.8 6704.4 6783.6 6785.8 6607.1 6634.4 6647.6 6698.6 6758.6 6569.5 6630.7 6658.4 6659.4 6687.8 6766.4 6767.0 6821.6 6822.5 6858.0 6236.4 6379.9 6415.4 6433.5 6598.2 02:38:13 INFO - PID 3336 | 02:38:13 INFO - PID 3336 | [#1] ext.html Cycles:180 Average:37482.91 Median:3502.70 stddev:53952.99 (1540.3%) stddev-sans-first:54033.98 02:38:13 INFO - PID 3336 | Values: 788.4 821.2 857.9 876.3 880.1 40545.4 40704.8 41314.4 41641.2 41722.6 37722.0 37930.0 38072.8 38151.8 38189.2 6847.9 6961.5 6966.4 6967.1 6968.2 6844.5 6929.3 6933.4 6934.9 6935.1 6897.3 6925.7 7384.9 7663.5 7670.4 7541.1 7541.7 7587.7 7608.3 7653.7 7461.3 7519.8 7530.2 7544.8 7547.3 36849.3 37779.1 37795.8 37969.0 37969.6 3445.7 3482.2 3500.0 3505.4 3510.1 31260.8 31334.4 31375.4 31466.4 31474.5 5077.3 5088.6 5105.7 5115.3 5153.2 220421.0 221995.0 222612.0 222635.0 223331.9 45731.5 45747.3 45895.8 46000.5 46034.3 48725.3 48963.3 49161.0 49171.8 49214.5 15033.6 15432.4 15440.9 15444.6 15445.5 12635.2 12644.3 12645.4 12656.5 12685.9 13610.7 13672.9 13774.1 13879.5 14060.0 5645.4 5669.4 5704.4 5718.6 5826.8 988.2 990.5 1000.2 1008.3 1029.3 22859.7 22914.7 23381.9 23485.3 23779.6 1096.5 1102.7 1118.9 1128.1 1139.0 15872.8 16017.8 16297.9 16314.2 16550.7 216016.0 216836.0 217183.0 217336.0 217636.0 44592.6 44609.8 44761.8 53313.0 53906.6 1415.8 1416.2 1424.2 1434.7 1438.5 27144.3 27398.5 27673.9 27686.9 28054.0 36058.6 36370.5 36394.5 36407.6 36411.0 183375.0 184469.0 184947.0 185269.0 186095.0 32472.7 32475.2 32535.2 32707.3 32808.4 40275.0 40418.0 40685.4 41280.6 41319.2 30613.8 30951.7 31298.2 32629.2 34116.5 33131.8 33307.5 33822.7 34159.5 34578.8 36639.0 36940.3 37116.4 37190.5 37202.1 32465.7 32539.5 32561.2 32617.0 32694.7 24656.8 24704.0 25032.5 25302.0 25416.7 02:38:13 INFO - PID 3336 | 02:38:13 INFO - PID 3336 | [#2] jquery.html Cycles:180 Average:34462.52 Median:22242.06 stddev:79762.05 (358.6%) stddev-sans-first:79945.18 02:38:13 INFO - PID 3336 | Values: 557.6 569.3 573.1 578.4 580.5 20604.2 21293.3 21298.7 21390.5 22535.4 24991.4 26062.9 26070.1 26081.5 26082.5 6965.7 7114.8 7120.6 7164.4 7164.4 4285.6 5682.2 7125.4 7175.4 7176.5 4279.4 4284.3 6957.1 6992.3 6992.6 7010.2 7020.2 7022.0 7028.4 7033.5 6852.9 6856.1 6862.1 6862.3 6883.6 29838.3 30747.0 30936.0 31267.4 31480.8 2034.6 2077.6 2083.2 2083.8 2093.7 19948.7 20615.5 20672.6 20681.2 20729.4 2466.1 2492.6 2552.5 2559.3 2567.1 133042.3 138008.0 139801.0 140048.0 140937.3 20004.6 21643.6 21911.9 22081.8 22304.7 22179.4 23138.3 23213.5 42129.4 42332.8 9820.9 10045.4 10128.9 10299.3 10335.2 9227.8 9261.0 9308.7 9399.6 9459.5 8949.3 8967.8 9004.0 9299.9 9419.8 2726.0 2735.4 2736.0 2781.1 2897.3 971.4 981.3 988.7 989.4 989.8 20010.8 20143.6 20215.4 20385.5 20469.3 1041.2 1045.5 1055.1 1057.5 1058.5 11150.8 11245.2 11290.2 11336.8 11441.8 459618.0 470609.0 472283.0 480808.0 482582.0 106233.0 108669.5 111880.0 114182.0 116520.4 1163.8 1182.8 1187.9 1199.6 1201.1 17792.6 18012.0 18020.9 18119.8 18388.5 45235.0 49317.8 51365.0 54577.7 57357.0 66888.7 67536.3 67926.3 68972.3 69128.3 14278.2 14602.6 14715.7 14759.2 14842.8 20908.9 21075.8 21227.6 22773.0 23021.0 17775.4 18445.0 18682.8 18804.7 18977.3 17030.6 17257.2 17450.5 17861.6 17869.1 18537.6 18971.5 19121.3 19169.9 19346.9 14028.6 14629.7 14720.3 14752.7 14753.3 17997.8 18509.2 18584.5 18617.3 18640.9 02:38:13 INFO - PID 3336 | 02:38:13 INFO - PID 3336 | [#3] mootools.html Cycles:180 Average:9209.40 Median:2693.40 stddev:6539.94 (242.8%) stddev-sans-first:6531.40 02:38:13 INFO - PID 3336 | Values: 1317.2 1374.1 1379.9 1387.4 1392.7 15051.5 15103.8 15726.5 15732.7 15766.4 15634.0 15941.4 15977.5 15987.9 16008.4 2733.0 2780.0 2781.3 2782.6 2788.8 2613.5 2691.0 2692.3 2693.3 2693.5 2592.5 2702.9 2704.8 2705.0 2708.8 2727.7 2734.4 2760.9 2761.3 2762.4 2671.5 2700.1 2708.4 2708.7 2710.3 14695.9 14908.3 15007.1 15064.0 15098.5 7965.2 8196.1 8228.5 8231.5 8252.6 5204.6 5344.9 5400.1 5467.2 5479.6 1854.4 1876.6 1877.1 1941.9 1956.1 23519.4 24328.3 25951.4 26811.6 26851.0 6664.8 6695.6 6699.5 6797.9 6871.3 17079.8 17130.0 17211.6 17282.8 17292.7 7676.6 7890.1 7924.1 7927.9 7932.1 7127.6 7299.9 7335.0 7368.0 7384.6 4971.9 5130.3 5176.8 5181.5 5182.9 1422.1 1424.0 1424.8 1428.0 1448.0 883.0 895.2 916.8 919.5 920.9 7708.7 7713.1 7737.8 8683.1 8713.3 681.3 683.2 683.3 691.6 695.9 5392.6 5443.6 5450.6 5481.3 5621.2 20302.7 21836.8 22169.6 22264.0 22303.1 20176.6 21511.5 21597.7 21683.7 21777.9 10711.8 10736.6 10915.2 10982.8 11109.7 8213.5 8228.4 8295.5 8387.5 8463.2 17122.1 17198.4 17285.9 17408.1 17556.0 17442.0 17934.6 17974.7 17992.9 18887.8 11299.6 11652.3 11674.1 11702.6 11703.2 13244.7 13570.3 13693.5 13704.2 13724.5 10784.0 11033.9 11058.2 11366.8 11386.7 10597.2 10712.5 10810.7 10906.7 10994.1 9579.0 9756.2 9782.9 9815.0 9823.8 6398.0 6522.6 6590.8 6616.9 7186.7 7131.6 7182.3 7226.7 7233.0 7255.5 02:38:13 INFO - PID 3336 | 02:38:13 INFO - PID 3336 | [#4] prototype.html Cycles:180 Average:5594.28 Median:6191.27 stddev:1578.92 (25.5%) stddev-sans-first:1536.14 02:38:13 INFO - PID 3336 | Values: 488.6 499.7 530.3 530.4 533.4 6161.1 6363.0 6415.7 6418.8 6463.0 6143.0 6191.7 6206.3 6227.1 6263.8 5059.9 5089.1 5091.0 5134.8 5150.3 5005.1 5017.5 5053.0 5057.6 5065.3 4852.3 4970.5 5012.4 5040.3 5043.5 4952.4 5025.5 5043.2 5059.4 5072.2 4431.5 4527.7 4529.4 4536.0 4581.0 6128.8 6159.5 6172.7 6190.9 6232.6 5785.9 5805.8 5811.4 5811.4 5847.6 6687.5 6693.3 6702.8 6731.5 6743.6 4869.5 4934.5 4935.4 4937.6 4946.8 6871.9 7026.4 7043.2 7060.7 7066.2 5009.3 5039.6 5072.2 5084.2 5088.3 5122.8 5162.9 5164.3 5171.2 5184.4 5722.3 5750.9 5758.5 5769.4 5780.4 6625.6 6632.3 6636.8 6650.1 6685.6 1780.5 1781.9 1782.2 1799.8 1800.4 1151.5 1165.0 1169.6 1220.0 1233.1 5816.9 5849.4 5858.5 5865.0 5931.0 6686.3 6729.8 6732.4 6771.6 6817.1 5684.6 5798.0 5861.5 5925.6 5980.7 3588.3 3611.9 3644.3 3651.9 3656.2 6504.5 6509.5 6535.5 6577.2 6683.6 6946.0 7027.4 7045.3 7047.5 7116.3 6642.6 6661.4 6663.8 6742.2 6744.5 6786.6 6872.7 6874.6 6899.9 6939.6 6784.6 6791.3 6814.7 6820.2 6839.9 6513.3 6547.7 6669.3 6678.0 6679.6 4955.6 4987.0 5058.5 5059.5 5093.6 6869.2 6876.6 6901.9 6953.1 7008.3 6563.9 6602.3 6663.3 6666.2 6719.5 6519.9 6555.6 6632.7 6665.0 6665.3 6246.7 6395.4 6444.5 6511.2 6554.5 6514.2 6590.0 6653.9 6708.4 6710.3 6343.0 6402.5 6409.9 6497.9 6521.2 02:38:13 INFO - PID 3336 | 02:38:13 INFO - PID 3336 | [#5] yui.html Cycles:180 Average:23872.05 Median:19727.80 stddev:33025.00 (167.4%) stddev-sans-first:33072.55 02:38:13 INFO - PID 3336 | Values: 889.9 898.6 910.7 913.9 921.9 18137.7 18798.4 18825.4 18862.0 18877.4 15749.4 15884.8 18236.0 21823.8 21876.8 939.4 967.8 971.9 974.9 976.2 982.8 985.7 987.0 988.6 994.6 915.0 1016.1 1018.9 1024.3 1026.6 1019.4 1021.7 1022.5 1022.7 1023.3 742.7 745.2 746.5 748.6 748.8 18240.8 18877.6 18911.7 19050.5 20298.8 21093.4 21205.9 21559.6 21637.0 21637.0 16204.1 16220.5 16465.5 16632.2 16646.8 3710.4 3717.3 3741.5 3760.8 3765.6 121589.4 122308.4 123376.7 126476.4 131057.0 16029.0 16048.8 16291.0 16298.0 17389.5 28727.6 29290.3 29536.3 29572.4 29607.7 9020.1 9039.5 9043.8 9087.9 9130.3 4125.4 4208.8 4222.9 4231.0 4237.6 5075.2 5274.3 5279.7 5300.1 5338.8 937.4 961.0 966.8 970.9 984.3 671.6 674.2 690.8 694.2 694.7 11563.9 11649.1 11860.1 12123.4 12310.3 680.4 686.3 687.0 698.3 700.3 5136.2 5190.6 5255.0 5336.5 5362.6 111877.4 112861.0 113431.0 114339.0 115621.4 100552.3 101385.5 101873.9 104926.0 106567.0 89173.6 89966.6 90665.5 91719.5 92384.0 11259.2 11982.5 12106.2 12367.8 12515.4 40923.8 41004.8 41155.2 41215.8 43340.0 68344.3 70610.3 71773.6 72082.6 74798.6 18054.4 18348.1 18446.3 18591.8 18619.7 17456.7 18336.0 18932.1 18975.6 19156.8 17497.6 17666.8 18212.4 18423.5 18423.7 17263.9 17507.6 17520.1 18100.7 18209.2 16086.4 16157.7 16265.9 16487.8 16524.3 11907.6 12184.7 12535.4 12548.9 12737.3 12595.6 12741.9 12835.6 12884.0 13614.6 02:38:13 INFO - PID 3336 | -------- Summary: end -------- 02:38:13 INFO - PID 3336 | 02:38:14 INFO - TEST-INFO | 3336: exit 0 02:38:14 INFO - TEST-OK | dromaeo_css | took 1115367ms 02:38:14 INFO - TEST-START | kraken 02:38:14 INFO - Initialising browser for kraken test... 02:38:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57988/getInfo.html -profile /tmp/tmpJ1_xuk/profile 02:38:14 INFO - TEST-INFO | started process 3729 (/builds/slave/test/build/application/firefox/firefox http://localhost:57988/getInfo.html) 02:38:19 INFO - TEST-INFO | 3729: exit 0 02:38:19 INFO - Browser initialized. 02:38:19 INFO - Running cycle 1/1 for kraken test... 02:38:19 INFO - Using env: {'DISPLAY': ':0', 02:38:19 INFO - 'HOME': '/home/cltbld', 02:38:19 INFO - 'JSGC_DISABLE_POISONING': '1', 02:38:19 INFO - 'LANG': 'en_US.UTF-8', 02:38:19 INFO - 'LANGUAGE': 'en_US:en', 02:38:19 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:38:19 INFO - 'LOGNAME': 'cltbld', 02:38:19 INFO - 'MAIL': '/var/mail/cltbld', 02:38:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:38:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:38:19 INFO - 'MOZ_ACCELERATED': '1', 02:38:19 INFO - 'MOZ_CRASHREPORTER': '1', 02:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:38:19 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:38:19 INFO - 'MOZ_NO_REMOTE': '1', 02:38:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:38:19 INFO - 'MOZ_WEBRENDER': '1', 02:38:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:38:19 INFO - 'NO_EM_RESTART': '1', 02:38:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:38:19 INFO - 'PWD': '/builds/slave/test', 02:38:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:38:19 INFO - 'RUST_BACKTRACE': 'full', 02:38:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:38:19 INFO - 'SHELL': '/bin/bash', 02:38:19 INFO - 'SHLVL': '1', 02:38:19 INFO - 'TERM': 'linux', 02:38:19 INFO - 'TMOUT': '86400', 02:38:19 INFO - 'USER': 'cltbld', 02:38:19 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827', 02:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:38:19 INFO - '_': '/tools/buildbot/bin/python'} 02:38:19 INFO - TEST-INFO | started process 3924 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ1_xuk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:38:20 INFO - PID 3924 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:38:21 INFO - PID 3924 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 02:38:21 INFO - PID 3924 | 02:38:21 INFO - PID 3924 | (/builds/slave/test/build/application/firefox/firefox:3985): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:38:21 INFO - PID 3924 | 02:38:31 INFO - PID 3924 | RSS: Main: 212131840 02:38:31 INFO - PID 3924 | 02:38:34 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57988/tests/kraken/driver.html?testName=audio-beat-detection) 02:38:35 INFO - PID 3924 | RSS: Main: 218468352 02:38:35 INFO - PID 3924 | 02:38:37 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57988/tests/kraken/driver.html?testName=audio-dft) 02:38:38 INFO - PID 3924 | RSS: Main: 219492352 02:38:38 INFO - PID 3924 | 02:38:41 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57988/tests/kraken/driver.html?testName=audio-fft) 02:38:42 INFO - PID 3924 | RSS: Main: 213204992 02:38:42 INFO - PID 3924 | 02:38:44 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57988/tests/kraken/driver.html?testName=audio-oscillator) 02:38:45 INFO - PID 3924 | RSS: Main: 214523904 02:38:45 INFO - PID 3924 | 02:38:47 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57988/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:38:47 INFO - PID 3924 | RSS: Main: 213368832 02:38:47 INFO - PID 3924 | 02:38:51 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57988/tests/kraken/driver.html?testName=imaging-darkroom) 02:38:51 INFO - PID 3924 | RSS: Main: 217448448 02:38:51 INFO - PID 3924 | 02:38:55 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57988/tests/kraken/driver.html?testName=imaging-desaturate) 02:38:55 INFO - PID 3924 | RSS: Main: 215539712 02:38:55 INFO - PID 3924 | 02:38:58 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57988/tests/kraken/driver.html?testName=json-parse-financial) 02:38:59 INFO - PID 3924 | RSS: Main: 216354816 02:38:59 INFO - PID 3924 | 02:39:01 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57988/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:39:01 INFO - PID 3924 | RSS: Main: 216829952 02:39:01 INFO - PID 3924 | 02:39:03 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57988/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:39:04 INFO - PID 3924 | RSS: Main: 217399296 02:39:04 INFO - PID 3924 | 02:39:06 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57988/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:39:06 INFO - PID 3924 | RSS: Main: 214413312 02:39:06 INFO - PID 3924 | 02:39:09 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57988/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:39:10 INFO - PID 3924 | RSS: Main: 214368256 02:39:10 INFO - PID 3924 | 02:39:13 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57988/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:39:13 INFO - PID 3924 | RSS: Main: 214802432 02:39:13 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | Cycle 1(1): loaded http://localhost:57988/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57988/tests/kraken/driver.html?testName=ai-astar) 02:39:15 INFO - PID 3924 | RSS: Main: 216567808 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | __start_tp_report 02:39:15 INFO - PID 3924 | _x_x_mozilla_page_load 02:39:15 INFO - PID 3924 | _x_x_mozilla_page_load_details 02:39:15 INFO - PID 3924 | |i|pagename|runs| 02:39:15 INFO - PID 3924 | |0;ai-astar;108;108;101;114;112;113;111;99;108;109 02:39:15 INFO - PID 3924 | |1;audio-beat-detection;149;116;148;166;133;150;146;118;177;117 02:39:15 INFO - PID 3924 | |2;audio-dft;171;163;164;163;171;167;167;163;170;166 02:39:15 INFO - PID 3924 | |3;audio-fft;81;76;84;181;81;84;81;80;153;80 02:39:15 INFO - PID 3924 | |4;audio-oscillator;80;91;144;79;85;86;142;84;80;129 02:39:15 INFO - PID 3924 | |5;imaging-gaussian-blur;120;116;116;120;122;116;119;135;114;122 02:39:15 INFO - PID 3924 | |6;imaging-darkroom;148;154;149;153;156;147;150;155;148;148 02:39:15 INFO - PID 3924 | |7;imaging-desaturate;101;93;106;102;94;96;94;95;95;95 02:39:15 INFO - PID 3924 | |8;json-parse-financial;61;54;58;62;54;55;64;55;52;52 02:39:15 INFO - PID 3924 | |9;json-stringify-tinderbox;39;39;48;40;38;50;38;52;38;38 02:39:15 INFO - PID 3924 | |10;stanford-crypto-aes;83;79;79;81;79;75;80;74;82;75 02:39:15 INFO - PID 3924 | |11;stanford-crypto-ccm;128;128;126;119;128;135;116;132;118;154 02:39:15 INFO - PID 3924 | |12;stanford-crypto-pbkdf2;173;168;169;170;167;169;173;174;176;168 02:39:15 INFO - PID 3924 | |13;stanford-crypto-sha256-iterative;70;65;64;63;64;64;63;63;64;70 02:39:15 INFO - PID 3924 | __end_tp_report 02:39:15 INFO - PID 3924 | __start_cc_report 02:39:15 INFO - PID 3924 | _x_x_mozilla_cycle_collect,918 02:39:15 INFO - PID 3924 | __end_cc_report 02:39:15 INFO - PID 3924 | __startTimestamp1504258755984__endTimestamp 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | ------- Summary: start ------- 02:39:15 INFO - PID 3924 | Number of tests: 14 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | [#0] ai-astar Cycles:10 Average:108.30 Median:111.50 stddev:4.90 (4.4%) stddev-sans-first:5.20 02:39:15 INFO - PID 3924 | Values: 108.0 108.0 101.0 114.0 112.0 113.0 111.0 99.0 108.0 109.0 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:148.50 stddev:20.88 (14.1%) stddev-sans-first:21.99 02:39:15 INFO - PID 3924 | Values: 149.0 116.0 148.0 166.0 133.0 150.0 146.0 118.0 177.0 117.0 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | [#2] audio-dft Cycles:10 Average:166.50 Median:167.00 stddev:3.27 (2.0%) stddev-sans-first:3.04 02:39:15 INFO - PID 3924 | Values: 171.0 163.0 164.0 163.0 171.0 167.0 167.0 163.0 170.0 166.0 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | [#3] audio-fft Cycles:10 Average:98.10 Median:81.00 stddev:36.98 (45.6%) stddev-sans-first:38.70 02:39:15 INFO - PID 3924 | Values: 81.0 76.0 84.0 181.0 81.0 84.0 81.0 80.0 153.0 80.0 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | [#4] audio-oscillator Cycles:10 Average:100.00 Median:82.00 stddev:26.96 (32.9%) stddev-sans-first:27.60 02:39:15 INFO - PID 3924 | Values: 80.0 91.0 144.0 79.0 85.0 86.0 142.0 84.0 80.0 129.0 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | [#5] imaging-gaussian-blur Cycles:10 Average:120.00 Median:120.00 stddev:5.94 (5.0%) stddev-sans-first:6.30 02:39:15 INFO - PID 3924 | Values: 120.0 116.0 116.0 120.0 122.0 116.0 119.0 135.0 114.0 122.0 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.50 stddev:3.36 (2.2%) stddev-sans-first:3.41 02:39:15 INFO - PID 3924 | Values: 148.0 154.0 149.0 153.0 156.0 147.0 150.0 155.0 148.0 148.0 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:94.50 stddev:4.33 (4.6%) stddev-sans-first:4.36 02:39:15 INFO - PID 3924 | Values: 101.0 93.0 106.0 102.0 94.0 96.0 94.0 95.0 95.0 95.0 02:39:15 INFO - PID 3924 | 02:39:15 INFO - PID 3924 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:56.50 stddev:4.30 (7.6%) stddev-sans-first:4.27 02:39:15 INFO - PID 3924 | Values: 61.0 54.0 58.0 62.0 54.0 55.0 64.0 55.0 52.0 52.0 02:39:16 INFO - PID 3924 | 02:39:16 INFO - PID 3924 | [#9] json-stringify-tinderbox Cycles:10 Average:42.00 Median:39.50 stddev:5.64 (14.3%) stddev-sans-first:5.87 02:39:16 INFO - PID 3924 | Values: 39.0 39.0 48.0 40.0 38.0 50.0 38.0 52.0 38.0 38.0 02:39:16 INFO - PID 3924 | 02:39:16 INFO - PID 3924 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.50 stddev:3.09 (3.9%) stddev-sans-first:2.86 02:39:16 INFO - PID 3924 | Values: 83.0 79.0 79.0 81.0 79.0 75.0 80.0 74.0 82.0 75.0 02:39:16 INFO - PID 3924 | 02:39:16 INFO - PID 3924 | [#11] stanford-crypto-ccm Cycles:10 Average:128.40 Median:128.00 stddev:10.90 (8.5%) stddev-sans-first:11.56 02:39:16 INFO - PID 3924 | Values: 128.0 128.0 126.0 119.0 128.0 135.0 116.0 132.0 118.0 154.0 02:39:16 INFO - PID 3924 | 02:39:16 INFO - PID 3924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:171.50 stddev:3.06 (1.8%) stddev-sans-first:3.13 02:39:16 INFO - PID 3924 | Values: 173.0 168.0 169.0 170.0 167.0 169.0 173.0 174.0 176.0 168.0 02:39:16 INFO - PID 3924 | 02:39:16 INFO - PID 3924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:64.00 stddev:2.71 (4.2%) stddev-sans-first:2.19 02:39:16 INFO - PID 3924 | Values: 70.0 65.0 64.0 63.0 64.0 64.0 63.0 63.0 64.0 70.0 02:39:16 INFO - PID 3924 | -------- Summary: end -------- 02:39:16 INFO - PID 3924 | 02:39:16 INFO - PID 3924 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 02:39:16 INFO - TEST-INFO | 3924: exit 0 02:39:16 INFO - TEST-OK | kraken | took 62758ms 02:39:16 INFO - SUITE-END | took 1178s 02:39:17 INFO - Completed test suite (00:19:38) 02:39:17 INFO - javascript benchmark 02:39:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8558.119663742787, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3211.1811488070553, 3272.198311413704, 3338.298957218984, 3339.1652086978256, 3376.67921547453, 6227.68861556922, 6276.466500347465, 6279.277883043452, 6294.370562943704, 6294.559380843337, 6267.310595834466, 6268.122462855204, 6286.46565041972, 6298.3386744391755, 6313.905291420621, 5781.768729250825, 5800.738966746507, 5807.274090738668, 5823.06830907055, 5882.5588080893895, 5858.56060795442, 5905.173275741399, 5941.524678025748, 6010.729517171738, 6058.39416058395, 5955.255593050869, 5979.342272350038, 6032.788852390146, 6040.305364883051, 6063.029915213567, 5846.912296315559, 5935.821925342247, 5936.732847021873, 5936.931352356574, 6067.120267561201, 5627.746751396197, 5746.224259724925, 5760.884782304331, 5802.5938184327115, 5937.881242375129, 6365.80902572919, 6487.772927947546, 6534.575252608565, 6541.803745887631, 6556.0, 6903.068085808452, 6920.861582768367, 6990.161180658302, 7004.929950700479, 7009.4392448603985, 6632.104665870094, 6781.49138814586, 6782.220044694874, 6964.756233531802, 7027.332403421667, 6303.590266632654, 6311.0217916223055, 6318.210223721989, 6331.936680633226, 6334.841628959267, 6873.896891546631, 7003.509754317251, 7007.929920700728, 7013.579185248901, 7019.052427922267, 6635.071090047397, 6675.132232809704, 6680.0, 6681.365270299314, 6685.364890335411, 6794.694238759268, 6812.080369150101, 6817.454603631698, 6920.342567456084, 6928.237893831674, 6570.868582628416, 6853.623050732258, 6860.382565569123, 6957.095577574883, 6977.7208911642965, 6761.087253220752, 6769.695363708645, 6790.354916283044, 6792.490563207779, 6797.082393876863, 5310.973445132827, 5334.119870221448, 5358.223057656618, 5370.4361042090795, 5382.30030096085, 4364.1708075466195, 4694.155052090657, 4711.528847115261, 4718.339432479455, 4752.1921273383605, 5823.5341172706085, 5862.648241105548, 5879.882402351929, 5887.381824908409, 5894.2926848778425, 6723.596584204865, 6748.29142939994, 6764.391204791593, 6768.661566921634, 6776.186857577122, 5859.414058594107, 5866.2373891393845, 5871.148683440877, 5871.295444546597, 5871.471567558855, 5748.770049197985, 5792.594518383757, 6006.849828754316, 6007.759689612454, 6009.489193418608, 6636.5354425190735, 6674.332566743286, 6689.464842812563, 6779.2881747416795, 6779.62712050832, 6663.500237482305, 6748.122744043243, 6759.594424334556, 6787.151606049244, 6913.204981426999, 6472.190976127984, 6486.837829054311, 6491.091247225487, 6512.218533775977, 6516.380943810331, 6624.403803657694, 6627.403533681992, 6644.534882558368, 6660.500462465431, 6741.0, 6924.688389022607, 6939.583624982315, 6943.583384996714, 7059.611721355374, 7069.469789765891, 6469.5794773339585, 6565.80302590923, 6704.363085506869, 6783.559068660521, 6785.796426107225, 6607.108040414482, 6634.402903738686, 6647.634380108947, 6698.631575263407, 6758.594484330956, 6569.4744420445295, 6630.701618427279, 6658.43403310724, 6659.4006539411685, 6687.76592819249, 6766.4248538874745, 6766.966165169143, 6821.556598821061, 6822.488313376417, 6858.0, 6236.407541283571, 6379.936200637934, 6415.422611964946, 6433.517486188648, 6598.208215014229], "value": 6228.52992804729, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.3653658804664, 821.1706176761469, 857.9056303806582, 876.347121394561, 880.1286726141122, 40545.3918191227, 40704.79647601758, 41314.38028429572, 41641.16717665648, 41722.582774172246, 37722.0, 37930.0, 38072.80963595178, 38151.80924095382, 38189.23621527575, 6847.863042739143, 6961.477889158308, 6966.37302642762, 6967.1291088613925, 6968.233494315621, 6844.5208835381545, 6929.341712537326, 6933.445324374038, 6934.89597656033, 6935.133108361455, 6897.275786042468, 6925.688344024531, 7384.926150738477, 7663.463557550974, 7670.424718146133, 7541.09506859175, 7541.6983320667105, 7587.658555365022, 7608.315251627325, 7653.693852245903, 7461.328480436787, 7519.812004699927, 7530.171681115073, 7544.849103017909, 7547.320741133324, 36849.26301473982, 37779.05552361185, 37795.81102094472, 37969.0, 37969.62030379689, 3445.6898879101, 3482.181687303482, 3499.9650003500146, 3505.4391297392294, 3510.069831494656, 31260.843695781376, 31334.373312533622, 31375.37249255025, 31466.37067258665, 31474.527882081788, 5077.314562534048, 5088.643794934356, 5105.693658380472, 5115.309433226504, 5153.2270159476, 220421.0, 221995.0, 222612.0, 222635.0, 223331.88334058388, 45731.5426845734, 45747.31379029317, 45895.770521147184, 46000.539994599625, 46034.30948535775, 48725.26912096321, 48963.26555101676, 49161.0, 49171.75414122907, 49214.50785492171, 15033.624159395884, 15432.38270469191, 15440.922795385952, 15444.613884652974, 15445.536633901002, 12635.1787133836, 12644.304563248925, 12645.367731613382, 12656.493740250287, 12685.93657031709, 13610.659733506543, 13672.906167506575, 13774.104683195561, 13879.514217002357, 14060.015798893979, 5645.350784659817, 5669.376368599534, 5704.401037891044, 5718.599698021182, 5826.82519524415, 988.229181238635, 990.4799980010553, 1000.1598657127902, 1008.3496144986443, 1029.3052189771875, 22859.652274587246, 22914.65628015581, 23381.883090584437, 23485.295441136794, 23779.643305350433, 1096.4627332607124, 1102.6967583914488, 1118.8657361116555, 1128.0975219824109, 1138.9886101138882, 15872.841271587136, 16017.839821601869, 16297.918510407371, 16314.184290785413, 16550.6689866202, 216016.0, 216836.0, 217183.0, 217336.0, 217636.0, 44592.55407445949, 44609.77695111569, 44761.776191118835, 53313.0, 53906.574204160905, 1415.7947097670985, 1416.2226405860579, 1424.1526291856328, 1434.7202295552268, 1438.467766926223, 27144.321391964568, 27398.45203095887, 27673.861630691717, 27686.86156569204, 28054.0, 36058.63941360553, 36370.4544431839, 36394.45408318824, 36407.635923640424, 36411.0, 183375.0, 184469.0, 184947.0, 185269.0, 186095.0, 32472.67527324791, 32475.18812029718, 32535.18662033468, 32707.34585308328, 32808.359582020326, 40275.0, 40418.0, 40685.38971915483, 41280.58719412888, 41319.1736165281, 30613.846930765205, 30951.69048309488, 31298.21754456157, 32629.18427039343, 34116.488252675736, 33131.83434082814, 33307.500387493725, 33822.66177338293, 34159.487607685405, 34578.82710586431, 36639.0, 36940.26119477649, 37116.44325335176, 37190.512379504515, 37202.06994825043, 32465.675343246265, 32539.51190732188, 32561.18597035093, 32617.0, 32694.67305326916, 24656.753432465444, 24704.0, 25032.499350013262, 25302.0, 25416.745832541437], "value": 17044.43753639258, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.5539568345323, 569.2855466389681, 573.123121623915, 578.3869098755318, 580.4630716587155, 20604.17583296668, 21293.25473608422, 21298.680519792197, 21390.465238369048, 22535.436614084614, 24991.375215619617, 26062.869685651593, 26070.08754693586, 26081.47837043258, 26082.478350433026, 6965.651717414147, 7114.750983715545, 7120.572765634052, 7164.355208031277, 7164.426845852346, 4285.5928686774705, 5682.204491371212, 7125.42996560274, 7175.425965922714, 7176.497645164841, 4279.422277992478, 4284.314509678452, 6957.0955775749335, 6992.265812089734, 6992.6154061526595, 7010.1587809462935, 7020.157581090276, 7022.0, 7028.437724981989, 7033.50765446419, 6852.931470685279, 6856.074429951943, 6862.107925969642, 6862.313768623148, 6883.621400822953, 29838.25404364908, 30747.0, 30936.0, 31267.37465250705, 31480.842595786875, 2034.6235946349825, 2077.5637116205617, 2083.2292051940726, 2083.8332933365296, 2093.7487501499763, 19948.70076948847, 20615.484612884648, 20672.58654826895, 20681.172753089857, 20729.378118656463, 2466.1491785334033, 2492.6011838105815, 2552.4895020995873, 2559.2706078767646, 2567.075852693029, 133042.3347883264, 138007.99999999898, 139801.0, 140048.0, 140937.29531352277, 20004.599908001757, 21643.567128657338, 21911.890440548013, 22081.779182208295, 22304.665430018562, 22179.445513862283, 23138.3058508245, 23213.53572928532, 42129.36805947913, 42332.788336058125, 9820.901790982141, 10045.447500387549, 10128.949355253177, 10299.279050466395, 10335.22485813567, 9227.815443691223, 9260.953695231481, 9308.720738377859, 9399.624015039322, 9459.52702364879, 8949.328800340001, 8967.775805604912, 9004.0, 9299.860502092473, 9419.811603767886, 2725.986370068137, 2735.3571910600995, 2735.972640273572, 2781.0822428598512, 2897.3046468847747, 971.3589031239349, 981.3228872078244, 988.7231575158967, 989.4113002763344, 989.7525618595351, 20010.799892001185, 20143.597128057358, 20215.39353819388, 20385.499487526635, 20469.2835750748, 1041.156663102889, 1045.4563626913962, 1055.0873494427265, 1057.471264367816, 1058.523664351045, 11150.832737508943, 11245.212835101463, 11290.153238507144, 11336.829947550794, 11441.771164576661, 459618.0, 470609.0, 472283.0, 480808.0, 482582.0, 106233.0, 108669.45665271781, 111880.0, 114182.0, 116520.41739791246, 1163.790517706821, 1182.7516221593562, 1187.893089621938, 1199.5621598116625, 1201.0991756182864, 17792.55518612045, 18012.0, 18020.909895450437, 18119.818801811813, 18388.540286492946, 45235.0, 49317.75341123271, 51365.0, 54577.72711136578, 57357.0, 66888.6655566719, 67536.32463675301, 67926.32073679398, 68972.31027689659, 69128.3087169142, 14278.2146981917, 14602.561923142322, 14715.705685886436, 14759.188244646648, 14842.777358339417, 20908.895455522626, 21075.78924210738, 21227.575448490636, 22772.975216114985, 23021.0, 17775.377861774778, 18445.0, 18682.813171868653, 18804.717929231345, 18977.33579324717, 17030.57423564421, 17257.223424945656, 17450.47648570545, 17861.642767144844, 17869.106544672974, 18537.629247414705, 18971.52571185676, 19121.330753423565, 19169.904150479157, 19346.903265483583, 14028.649283767987, 14629.707405852036, 14720.263986800832, 14752.704945901236, 14753.336099875316, 17997.820021799613, 18509.167087480826, 18584.535386615444, 18617.348392806187, 18640.906795465937], "value": 12244.553151849066, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.216256327485, 1374.1205628397827, 1379.910305830121, 1387.382614736442, 1392.6587985943445, 15051.548453546384, 15103.773443398344, 15726.52820415388, 15732.685346293081, 15766.448174313902, 15634.0, 15941.362345506195, 15977.520674379788, 15987.920060399681, 16008.359665613361, 2732.9726702733014, 2780.0, 2781.3463835998523, 2782.6382570265837, 2788.776897848177, 2613.451175253199, 2691.0, 2692.2730862667067, 2693.3132051326984, 2693.488237234919, 2592.5333439980795, 2702.8648567571545, 2704.756571908528, 2704.9594256086175, 2708.7832973362083, 2727.6726792784793, 2734.371094648232, 2760.944781104387, 2761.337279053033, 2762.350847550824, 2671.479061582992, 2700.054980756739, 2708.404151086758, 2708.7426694464193, 2710.295323215978, 14695.926520367437, 14908.254587270592, 15007.099574025484, 15064.0, 15098.54704358871, 7965.203479651988, 8196.098429172785, 8228.547429891354, 8231.46495477798, 8252.628631711528, 5204.635675502716, 5344.866378340573, 5400.108982017962, 5467.20725494805, 5479.6164268501225, 1854.4158590044228, 1876.5777700017522, 1877.0581778265532, 1941.8931958742264, 1956.1295223625493, 23519.4120146996, 24328.27015189547, 25951.351216219744, 26811.597826032626, 26851.0, 6664.833379165462, 6695.631740254235, 6699.464042876558, 6797.898031529531, 6871.312868713088, 17079.82920170807, 17130.0, 17211.56971075708, 17282.82717172837, 17292.740608890876, 7676.57778822159, 7890.053193616688, 7924.088729796032, 7927.8810817837775, 7932.127465978853, 7127.607981561064, 7299.854002919912, 7335.0, 7367.968484412187, 7384.556926584315, 4971.925421118686, 5130.333056702605, 5176.81881134166, 5181.4559471255725, 5182.922256166161, 1422.11118051218, 1423.978640320396, 1424.77203647418, 1427.978580321296, 1448.0, 882.9735107946772, 895.1853813030242, 916.8212198621205, 919.5310391700281, 920.9171174594319, 7708.653110610036, 7713.112992005879, 7737.767866964, 8683.131686831393, 8713.25937295337, 681.3118750062473, 683.2143035509204, 683.2859661653584, 691.5608588546208, 695.9025736396909, 5392.56859451243, 5443.564514838803, 5450.645708028966, 5481.314835645544, 5621.241132447068, 20302.69545956812, 21836.781632183793, 22169.556608867733, 22264.0, 22303.107875685117, 20176.596468070555, 21511.46221344479, 21597.67603485949, 21683.67474487884, 21777.891110544348, 10711.83932241017, 10736.624218152327, 10915.23593348458, 10982.835257471144, 11109.666709998713, 8213.507189568645, 8228.382871284797, 8295.460795048302, 8387.538685372363, 8463.195996380335, 17122.14389280506, 17198.398056067977, 17285.91357043207, 17408.129593520527, 17556.0, 17442.0, 17934.641307173522, 17974.730379044064, 17992.91003544974, 18887.811121888604, 11299.604513841976, 11652.30086194831, 11674.124440666814, 11702.590409335973, 11703.23928944616, 13244.735105297646, 13570.321483925567, 13693.520726774515, 13704.177749335071, 13724.451021959409, 10784.0, 11033.889661103287, 11058.22592418523, 11366.829497557708, 11386.715332116431, 10597.205209609156, 10712.517936693024, 10810.729731756453, 10906.672799816019, 10994.065504432212, 9579.0, 9756.170725488413, 9782.85325720129, 9815.0, 9823.754406139904, 6397.96801015992, 6522.6412547308455, 6590.8352291193105, 6616.900746488903, 7186.6704659638135, 7131.572105673677, 7182.348010059629, 7226.674799634135, 7233.0, 7255.455840811855], "value": 6557.139997296351, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.56781112683404, 499.715730572429, 530.3000039947271, 530.4324372920975, 533.4130960392829, 6161.075838624209, 6362.9819228923325, 6415.711292991815, 6418.775342863, 6463.0, 6143.0, 6191.659458729768, 6206.2552493700705, 6227.065940108974, 6263.812085637427, 5059.924101138467, 5089.109405853979, 5091.0072535855525, 5134.820281290155, 5150.3047088642925, 5005.1491246488, 5017.523335283142, 5052.9747351263195, 5057.5585958001975, 5065.341505604266, 4852.344933433977, 4970.453250142481, 5012.423571289312, 5040.294358789773, 5043.503465784442, 4952.380952380952, 5025.497450254945, 5043.167877300278, 5059.367579052637, 5072.2391641253735, 4431.534688857688, 4527.683062185648, 4529.433820772403, 4535.9546404536195, 4580.9541904580865, 6128.816135515942, 6159.476444502223, 6172.691365431755, 6190.876182476325, 6232.563720539564, 5785.855353616151, 5805.796797112081, 5811.360750317462, 5811.4479124483105, 5847.649141051551, 6687.49531355446, 6693.263740988487, 6702.798916032527, 6731.461483081342, 6743.5953842769595, 4869.513048695102, 4934.4572097069295, 4935.407751069859, 4937.555619994182, 4946.752662366904, 6871.869720386696, 7026.365113082594, 7043.190033146151, 7060.717571297142, 7066.152061752571, 5009.323741294878, 5039.596832253411, 5072.199369223946, 5084.237364395371, 5088.338515992898, 5122.797197205163, 5162.896742065138, 5164.2770012198325, 5171.198464238049, 5184.377874655065, 5722.341930677942, 5750.942490575125, 5758.452946970031, 5769.423057694281, 5780.42195780427, 6625.569337993015, 6632.270450250565, 6636.800895973129, 6650.135482387259, 6685.598864068172, 1780.5103596510803, 1781.9287228510714, 1782.2069179215125, 1799.7840259168984, 1800.396867049527, 1151.4818331750578, 1164.980641938304, 1169.6491052684162, 1219.9573014944435, 1233.1306428967557, 5816.941830581724, 5849.391417360175, 5858.50202732764, 5865.035213203624, 5931.0, 6686.3313668632745, 6729.798106056826, 6732.360425759643, 6771.62756048422, 6817.079694241214, 5684.6020778545935, 5798.0, 5861.505316144441, 5925.644461332334, 5980.714146458446, 3588.300281445145, 3611.8916432507067, 3644.2711457708565, 3651.8904432867057, 3656.177360094011, 6504.544681872224, 6509.544331896816, 6535.509836762262, 6577.2241494796135, 6683.565568238049, 6946.0, 7027.402670773042, 7045.330693584101, 7047.506674532733, 7116.252793456716, 6642.568233064835, 6661.367170118831, 6663.800085997331, 6742.157230346207, 6744.494162937799, 6786.558873673195, 6872.6907289173105, 6874.621895795779, 6899.93100069013, 6939.583624982517, 6784.59292442455, 6791.252962174256, 6814.693338799667, 6820.215675197217, 6839.897401538881, 6513.348665133638, 6547.672616369067, 6669.265990842513, 6678.0, 6679.56582822115, 4955.603551715926, 4986.975065124651, 5058.54473097423, 5059.544640982329, 5093.617978651542, 6869.210040845167, 6876.621785801829, 6901.930980690329, 6953.130858642669, 7008.334208250412, 6563.868722625616, 6602.273749887605, 6663.26704062543, 6666.200055993312, 6719.529632925648, 6519.869602608016, 6555.6066636002, 6632.673465307054, 6664.966675166593, 6665.266820649627, 6246.656433896179, 6395.360463953567, 6444.45222156122, 6511.186101737283, 6554.4756419485375, 6514.1531600891585, 6590.0, 6653.933460665525, 6708.42978346846, 6710.295418981132, 6343.016832390987, 6402.487800975818, 6409.948816378714, 6497.870042599027, 6521.184851893513], "value": 5125.612676578291, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [889.9332550058747, 898.6001229452894, 910.7131253655102, 913.9170083451111, 921.8663293822395, 18137.727934080984, 18798.43604691858, 18825.435236942896, 18862.0, 18877.433676989695, 15749.370025199007, 15884.84115158848, 18235.99702016392, 21823.781762182374, 21876.781232187674, 939.3706216834703, 967.7919247361816, 971.8687977123069, 974.9171320437771, 976.1897624971257, 982.778874753182, 985.6500942165535, 986.9703908882746, 988.5502096546054, 994.6220436234221, 914.9542522873863, 1016.0702956794561, 1018.9337693049965, 1024.3085917006028, 1026.6355443817413, 1019.3578045831116, 1021.6934919524113, 1022.4938655365642, 1022.6829682798319, 1023.3399457350042, 742.7289039500597, 745.2324106170653, 746.4550877859139, 748.5845355827513, 748.7716246544802, 18240.817591824176, 18877.622447550973, 18911.716324255147, 19050.52373690655, 20298.797012029838, 21093.367198984055, 21205.893970530204, 21559.568808623895, 21637.0, 21637.0, 16204.108774017424, 16220.51338459848, 16465.506034818976, 16632.168391580373, 16646.75029874553, 3710.3877860153043, 3717.3308804415196, 3741.494898188737, 3760.7743535387963, 3765.5669597996307, 121589.39205304005, 122308.38845805803, 123376.6818161369, 126476.36761816224, 131057.0, 16029.0, 16048.75926861098, 16291.0, 16298.0, 17389.478315650296, 28727.569086463718, 29290.267743306587, 29536.261593459905, 29572.40855182884, 29607.703922960496, 9020.05289444612, 9039.502827344559, 9043.773905652411, 9087.863682044775, 9130.26957843371, 4125.360569111793, 4208.789560521962, 4222.873313800529, 4230.963413963537, 4237.576242375738, 5075.1625981712605, 5274.3143391359645, 5279.683219006872, 5300.12547929595, 5338.786448542015, 937.3625934364698, 960.9567569459251, 966.8066386722684, 970.8737864077625, 984.3060642247199, 671.6071098407486, 674.2482132422314, 690.7685925215109, 694.2398074108843, 694.6804469943883, 11563.942180289045, 11649.068074554016, 11860.110491713156, 12123.393830308449, 12310.322932238812, 680.4080450008524, 686.255412877034, 686.9587824730532, 698.2563569798149, 700.2717174138838, 5136.203888397305, 5190.6107041972, 5255.0, 5336.493033161843, 5362.624616276823, 111877.44061279642, 112861.0, 113431.0, 114339.0, 115621.42189289168, 100552.3149744829, 101385.49307253564, 101873.94378168667, 104926.0, 106567.0, 89173.55413222892, 89966.55016724874, 90665.54667226621, 91719.5414022939, 92384.0, 11259.211855170059, 11982.460789264329, 12106.213096148722, 12367.814482782585, 12515.413378063302, 40923.7953810229, 41004.79497602493, 41155.1768964625, 41215.7939210314, 43340.0, 68344.31655683578, 70610.29389706242, 71773.56452871018, 72082.63958680173, 74798.6260068718, 18054.368097116538, 18348.08259587042, 18446.262149514405, 18591.81408185955, 18619.720704189178, 17456.73814892803, 18336.0, 18932.053397330354, 18975.620487590448, 19156.80843191606, 17497.56256093608, 17666.823331766518, 18212.36256731008, 18423.53941151482, 18423.72364414508, 17263.913680431517, 17507.649847003242, 17520.123993800004, 18100.72848907241, 18209.180586873357, 16086.35654573804, 16157.67684646277, 16265.918670406572, 16487.83512164911, 16524.256408461406, 11907.642770716893, 12184.695382615506, 12535.373231338213, 12548.937255314031, 12737.29944853042, 12595.622131335707, 12741.872581274069, 12835.614931552069, 12884.033697472914, 13614.591562252752], "value": 8992.351358328973, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 101.0, 114.0, 112.0, 113.0, 111.0, 99.0, 108.0, 109.0], "value": 108.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 148.0, 166.0, 133.0, 150.0, 146.0, 118.0, 177.0, 117.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 163.0, 164.0, 163.0, 171.0, 167.0, 167.0, 163.0, 170.0, 166.0], "value": 166.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 76.0, 84.0, 181.0, 81.0, 84.0, 81.0, 80.0, 153.0, 80.0], "value": 98.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 91.0, 144.0, 79.0, 85.0, 86.0, 142.0, 84.0, 80.0, 129.0], "value": 100.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 116.0, 116.0, 120.0, 122.0, 116.0, 119.0, 135.0, 114.0, 122.0], "value": 120.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 154.0, 149.0, 153.0, 156.0, 147.0, 150.0, 155.0, 148.0, 148.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 93.0, 106.0, 102.0, 94.0, 96.0, 94.0, 95.0, 95.0, 95.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 54.0, 58.0, 62.0, 54.0, 55.0, 64.0, 55.0, 52.0, 52.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 48.0, 40.0, 38.0, 50.0, 38.0, 52.0, 38.0, 38.0], "value": 42.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 79.0, 81.0, 79.0, 75.0, 80.0, 74.0, 82.0, 75.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 126.0, 119.0, 128.0, 135.0, 116.0, 132.0, 118.0, 154.0], "value": 128.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 168.0, 169.0, 170.0, 167.0, 169.0, 173.0, 174.0, 176.0, 168.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 64.0, 63.0, 64.0, 64.0, 63.0, 63.0, 64.0, 70.0], "value": 65.0, "unit": "score"}]}]} 02:39:17 INFO - Return code: 0 02:39:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:39:17 INFO - # TBPL SUCCESS # 02:39:17 INFO - Running post-action listener: _package_coverage_data 02:39:17 INFO - Running post-action listener: _resource_record_post_action 02:39:17 INFO - [mozharness: 2017-09-01 09:39:17.254105Z] Finished run-tests step (success) 02:39:17 INFO - Running post-run listener: _resource_record_post_run 02:39:17 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 388825088; Read time: 12; Write time: 303448 02:39:17 INFO - TinderboxPrint: CPU usage
13.3% 02:39:17 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 02:39:17 INFO - TinderboxPrint: I/O write bytes / time
388,825,088 / 303,448 02:39:17 INFO - TinderboxPrint: CPU idle
8,258.3 (86.7%) 02:39:17 INFO - TinderboxPrint: CPU user
1,203.4 (12.6%) 02:39:17 INFO - TinderboxPrint: Swap in / out
0 / 0 02:39:17 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 98926592; Read time: 0; Write time: 94924 02:39:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 02:39:17 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 280555520; Read time: 12; Write time: 206776 02:39:17 INFO - Running post-run listener: _upload_blobber_files 02:39:17 INFO - Blob upload gear active. 02:39:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:39:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:39:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:39:18 INFO - (blobuploader) - INFO - Open directory for files ... 02:39:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:39:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:39:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:19 INFO - (blobuploader) - INFO - Done attempting. 02:39:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:39:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:39:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:22 INFO - (blobuploader) - INFO - Done attempting. 02:39:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:39:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:39:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:23 INFO - (blobuploader) - INFO - Done attempting. 02:39:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:39:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:39:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:24 INFO - (blobuploader) - INFO - Done attempting. 02:39:24 INFO - (blobuploader) - INFO - Iteration through files over. 02:39:24 INFO - Return code: 0 02:39:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:39:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:39:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85cb8175e01524f35287abeb25449d592bed0d6a4fe357a8fcd1b19d09dfc89111dd764d5b2d11a58943c4c63acdf81ecc3b2f20f61583035966617f19b50ec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3b9ea15eddc5c8ab2ee9dc116fa87fd911b4f957b51aa3ee5e4295c07f1c85d54b1920130cd8d8c0e09645c2b7c081452d6857b1955ffaf77280467da473dec", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/396c12575da2c398c59b6b3c342eac2fb5b243fb373630d9f27ae36f5cd2f6c2801a2f794e24e89a3aa5b1c757d8902a9171c5194dea27738279586c36e33e1c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0edbebaed72ba3bea7c4f73b0de9d58380813e174348bc28bee3ac9702c2da3ed825949cfe6de6460d085a9afa1df26ea6e93ed664b1c5361b7f198b2baef9eb"} 02:39:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:39:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:39:24 INFO - Contents: 02:39:24 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85cb8175e01524f35287abeb25449d592bed0d6a4fe357a8fcd1b19d09dfc89111dd764d5b2d11a58943c4c63acdf81ecc3b2f20f61583035966617f19b50ec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3b9ea15eddc5c8ab2ee9dc116fa87fd911b4f957b51aa3ee5e4295c07f1c85d54b1920130cd8d8c0e09645c2b7c081452d6857b1955ffaf77280467da473dec", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/396c12575da2c398c59b6b3c342eac2fb5b243fb373630d9f27ae36f5cd2f6c2801a2f794e24e89a3aa5b1c757d8902a9171c5194dea27738279586c36e33e1c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0edbebaed72ba3bea7c4f73b0de9d58380813e174348bc28bee3ac9702c2da3ed825949cfe6de6460d085a9afa1df26ea6e93ed664b1c5361b7f198b2baef9eb"} 02:39:24 INFO - Running post-run listener: copy_logs_to_upload_dir 02:39:24 INFO - Copying logs to upload dir... 02:39:24 INFO - mkdir: /builds/slave/test/build/upload/logs 02:39:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.596113 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-09-01 02:39:24.597815) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 02:39:24.600863) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85cb8175e01524f35287abeb25449d592bed0d6a4fe357a8fcd1b19d09dfc89111dd764d5b2d11a58943c4c63acdf81ecc3b2f20f61583035966617f19b50ec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3b9ea15eddc5c8ab2ee9dc116fa87fd911b4f957b51aa3ee5e4295c07f1c85d54b1920130cd8d8c0e09645c2b7c081452d6857b1955ffaf77280467da473dec", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/396c12575da2c398c59b6b3c342eac2fb5b243fb373630d9f27ae36f5cd2f6c2801a2f794e24e89a3aa5b1c757d8902a9171c5194dea27738279586c36e33e1c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0edbebaed72ba3bea7c4f73b0de9d58380813e174348bc28bee3ac9702c2da3ed825949cfe6de6460d085a9afa1df26ea6e93ed664b1c5361b7f198b2baef9eb"} build_url:https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011414 build_url: 'https://queue.taskcluster.net/v1/task/EVBwkXv4QIqaI8RoA8-sCA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f85cb8175e01524f35287abeb25449d592bed0d6a4fe357a8fcd1b19d09dfc89111dd764d5b2d11a58943c4c63acdf81ecc3b2f20f61583035966617f19b50ec", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d3b9ea15eddc5c8ab2ee9dc116fa87fd911b4f957b51aa3ee5e4295c07f1c85d54b1920130cd8d8c0e09645c2b7c081452d6857b1955ffaf77280467da473dec", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/396c12575da2c398c59b6b3c342eac2fb5b243fb373630d9f27ae36f5cd2f6c2801a2f794e24e89a3aa5b1c757d8902a9171c5194dea27738279586c36e33e1c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0edbebaed72ba3bea7c4f73b0de9d58380813e174348bc28bee3ac9702c2da3ed825949cfe6de6460d085a9afa1df26ea6e93ed664b1c5361b7f198b2baef9eb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 02:39:24.627710) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:39:24.628032) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=930c87414c89fc706ee2024c000001c6-1504244650.38759-1273083827 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004520 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 02:39:24.713832) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 02:39:24.714211) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 02:39:24.714664) ========= ========= Total master_lag: 0.20 =========